builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-037 starttime: 1504955335.97 results: success (0) revision: c71b01e993510268bab7d60154b2f80692fd507d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-09 04:08:55.965639) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-09 04:08:55.966098) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-09 04:08:55.966378) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-09 04:08:55.983978) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-09 04:08:55.984342) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-09 04:08:55-- 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% 41.9M=0s 2017-09-09 04:08:56 (41.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121497 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-09 04:08:56.120965) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-09 04:08:56.121281) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019083 ========= master_lag: 0.28 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-09 04:08:56.424351) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-09 04:08:56.424697) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c71b01e993510268bab7d60154b2f80692fd507d --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 c71b01e993510268bab7d60154b2f80692fd507d --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=56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-09 04:08:56,432 truncating revision to first 12 chars 2017-09-09 04:08:56,432 Setting DEBUG logging. 2017-09-09 04:08:56,432 attempt 1/10 2017-09-09 04:08:56,432 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c71b01e99351?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-09 04:08:56,882 unpacking tar archive at: mozilla-central-c71b01e99351/testing/mozharness/ program finished with exit code 0 elapsedTime=0.762605 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-09 04:08:57.205396) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-09 04:08:57.205722) ========= script_repo_revision: c71b01e993510268bab7d60154b2f80692fd507d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-09 04:08:57.206106) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-09 04:08:57.206364) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-09 04:08:57.226667) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-09 04:08:57.227034) ========= /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=56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:08:57 INFO - MultiFileLogger online at 20170909 04:08:57 in /builds/slave/test 04:08:57 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 04:08:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:08:57 INFO - {'append_to_log': False, 04:08:57 INFO - 'base_work_dir': '/builds/slave/test', 04:08:57 INFO - 'blob_upload_branch': 'Firefox', 04:08:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:08:57 INFO - 'branch': 'Firefox', 04:08:57 INFO - 'buildbot_json_path': 'buildprops.json', 04:08:57 INFO - 'code_coverage': False, 04:08:57 INFO - 'config_files': ('talos/linux_config.py',), 04:08:57 INFO - 'default_actions': ('clobber', 04:08:57 INFO - 'read-buildbot-config', 04:08:57 INFO - 'download-and-extract', 04:08:57 INFO - 'populate-webroot', 04:08:57 INFO - 'create-virtualenv', 04:08:57 INFO - 'install', 04:08:57 INFO - 'setup-mitmproxy', 04:08:57 INFO - 'run-tests'), 04:08:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:08:57 INFO - 'disable_ccov_upload': False, 04:08:57 INFO - 'disable_stylo': False, 04:08:57 INFO - 'download_minidump_stackwalk': True, 04:08:57 INFO - 'download_symbols': 'ondemand', 04:08:57 INFO - 'enable_stylo': False, 04:08:57 INFO - 'enable_webrender': False, 04:08:57 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:08:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:08:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:08:57 INFO - 'gecko_profile': False, 04:08:57 INFO - 'gecko_profile_interval': 0, 04:08:57 INFO - 'installer_path': 'installer.exe', 04:08:57 INFO - 'log_level': 'info', 04:08:57 INFO - 'log_name': 'talos', 04:08:57 INFO - 'log_to_console': True, 04:08:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:08:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:08:57 INFO - 'opt_config_files': (), 04:08:57 INFO - 'pip_index': False, 04:08:57 INFO - 'suite': 'dromaeojs-e10s', 04:08:57 INFO - 'system_bits': '32', 04:08:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:08:57 INFO - 'title': 'talos-linux64-ix-037', 04:08:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:08:57 INFO - 'use_talos_json': True, 04:08:57 INFO - 'verify': 'False', 04:08:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:08:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:08:57 INFO - 'work_dir': 'build'} 04:08:57 INFO - [mozharness: 2017-09-09 11:08:57.382195Z] Running clobber step. 04:08:57 INFO - Running pre-action listener: _resource_record_pre_action 04:08:57 INFO - Running main action method: clobber 04:08:57 INFO - rmtree: /builds/slave/test/build 04:08:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:08:58 INFO - Running post-action listener: _resource_record_post_action 04:08:58 INFO - [mozharness: 2017-09-09 11:08:58.395462Z] Finished clobber step (success) 04:08:58 INFO - [mozharness: 2017-09-09 11:08:58.395558Z] Running read-buildbot-config step. 04:08:58 INFO - Running pre-action listener: _resource_record_pre_action 04:08:58 INFO - Running main action method: read_buildbot_config 04:08:58 INFO - Using buildbot properties: 04:08:58 INFO - { 04:08:58 INFO - "project": "", 04:08:58 INFO - "product": "firefox", 04:08:58 INFO - "who": "archaeopteryx@coole-files.de", 04:08:58 INFO - "installer_path": "public/build/target.tar.bz2", 04:08:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:08:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:08:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 04:08:58 INFO - "stage_platform": "linux64-pgo", 04:08:58 INFO - "basedir": "/builds/slave/test", 04:08:58 INFO - "buildnumber": 146, 04:08:58 INFO - "platform": "ubuntu64_hw", 04:08:58 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:08:58 INFO - "slavebuilddir": "test", 04:08:58 INFO - "taskId": "YOZCJw-cQgCCtA_Ln3l6dg", 04:08:58 INFO - "branch": "mozilla-central", 04:08:58 INFO - "script_repo_revision": "production", 04:08:58 INFO - "revision": "c71b01e993510268bab7d60154b2f80692fd507d", 04:08:58 INFO - "slavename": "talos-linux64-ix-037", 04:08:58 INFO - "repo_path": "mozilla-central" 04:08:58 INFO - } 04:08:58 INFO - Finding installer, test and symbols from parent task. 04:08:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YOZCJw-cQgCCtA_Ln3l6dg'}, attempt #1 04:08:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YOZCJw-cQgCCtA_Ln3l6dg'}, attempt #1 04:08:59 INFO - Task dependencies: XvLPM4gBSoCLFs5hXh0goQ 04:08:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ'}, attempt #1 04:08:59 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.tar.bz2 04:08:59 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.test_packages.json 04:08:59 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.crashreporter-symbols.zip 04:08:59 INFO - Running post-action listener: _resource_record_post_action 04:08:59 INFO - [mozharness: 2017-09-09 11:08:59.781421Z] Finished read-buildbot-config step (success) 04:08:59 INFO - [mozharness: 2017-09-09 11:08:59.781637Z] Running download-and-extract step. 04:08:59 INFO - Running pre-action listener: _resource_record_pre_action 04:08:59 INFO - Running main action method: download_and_extract 04:08:59 INFO - mkdir: /builds/slave/test/build/tests 04:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:08:59 INFO - trying https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.test_packages.json 04:08:59 INFO - Downloading https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:08:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:09:00 INFO - Downloaded 989 bytes. 04:09:00 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:09:00 INFO - Using the following test package requirements: 04:09:00 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:09:00 INFO - u'common': [u'target.common.tests.zip'], 04:09:00 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:09:00 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:09:00 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:09:00 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:09:00 INFO - u'mozbase': [u'target.common.tests.zip'], 04:09:00 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:09:00 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:09:00 INFO - u'web-platform': [u'target.common.tests.zip', 04:09:00 INFO - u'target.web-platform.tests.tar.gz'], 04:09:00 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:09:00 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 04:09:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.common.tests.zip 04:09:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:09:00 INFO - Fetch https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.common.tests.zip into memory 04:09:01 INFO - Content-Length response header: 55703354 04:09:01 INFO - Bytes received: 55703354 04:09:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.talos.tests.zip 04:09:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:09:12 INFO - Fetch https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.talos.tests.zip into memory 04:09:12 INFO - Content-Length response header: 14497963 04:09:12 INFO - Bytes received: 14497963 04:09:13 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')]} 04:09:13 INFO - trying https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.tar.bz2 04:09:13 INFO - Downloading https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:09:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:09:14 INFO - Downloaded 63691075 bytes. 04:09:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.tar.bz2 04:09:14 INFO - mkdir: /builds/slave/test/properties 04:09:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:09:14 INFO - Writing to file /builds/slave/test/properties/build_url 04:09:14 INFO - Contents: 04:09:14 INFO - build_url:https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.tar.bz2 04:09:14 INFO - Running post-action listener: _resource_record_post_action 04:09:14 INFO - Running post-action listener: find_tests_for_verification 04:09:14 INFO - Running post-action listener: set_extra_try_arguments 04:09:14 INFO - [mozharness: 2017-09-09 11:09:14.697948Z] Finished download-and-extract step (success) 04:09:14 INFO - [mozharness: 2017-09-09 11:09:14.698049Z] Running populate-webroot step. 04:09:14 INFO - Running pre-action listener: _resource_record_pre_action 04:09:14 INFO - Running main action method: populate_webroot 04:09:14 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:09:14 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:14 INFO - 'tests': ['tresize', 04:09:14 INFO - 'tcanvasmark']}, 04:09:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:09:14 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:14 INFO - 'tests': ['dromaeo_css', 04:09:14 INFO - 'kraken']}, 04:09:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:09:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:09:14 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:14 INFO - 'talos_options': ['--disable-stylo'], 04:09:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:09:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:09:14 INFO - 'tests': ['damp', 'tps']}, 04:09:14 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:14 INFO - 'talos_options': ['--disable-stylo'], 04:09:14 INFO - 'tests': ['damp', 'tps']}, 04:09:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:09:14 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:14 INFO - 'tests': ['dromaeo_dom']}, 04:09:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:09:14 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:14 INFO - 'tests': ['basic_compositor_video', 04:09:14 INFO - 'glvideo']}, 04:09:14 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:09:14 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:09:14 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:14 INFO - 'talos_options': ['--disable-stylo'], 04:09:14 INFO - 'tests': ['ts_paint_webext', 04:09:14 INFO - 'tp5o_webext']}, 04:09:14 INFO - 'other-e10s': {'tests': ['a11yr', 04:09:14 INFO - 'ts_paint', 04:09:14 INFO - 'tpaint', 04:09:14 INFO - 'sessionrestore', 04:09:14 INFO - 'sessionrestore_many_windows', 04:09:14 INFO - 'sessionrestore_no_auto_restore', 04:09:14 INFO - 'tabpaint']}, 04:09:14 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:14 INFO - 'tests': ['a11yr', 04:09:14 INFO - 'ts_paint', 04:09:14 INFO - 'tpaint', 04:09:14 INFO - 'sessionrestore', 04:09:14 INFO - 'sessionrestore_many_windows', 04:09:14 INFO - 'sessionrestore_no_auto_restore', 04:09:14 INFO - 'tabpaint']}, 04:09:14 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 04:09:14 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 04:09:14 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:14 INFO - 'tests': ['bloom_basic_singleton']}, 04:09:14 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:14 INFO - 'tests': ['bloom_basic']}, 04:09:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:09:14 INFO - 'tsvgr_opacity', 04:09:14 INFO - 'tart', 04:09:14 INFO - 'tscrollx', 04:09:14 INFO - 'tsvg_static']}, 04:09:14 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:14 INFO - 'tests': ['tsvgx', 04:09:14 INFO - 'tsvgr_opacity', 04:09:14 INFO - 'tart', 04:09:14 INFO - 'tscrollx', 04:09:14 INFO - 'tsvg_static']}, 04:09:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:09:14 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:14 INFO - 'talos_options': ['--disable-stylo'], 04:09:14 INFO - 'tests': ['tp5o']}, 04:09:14 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:09:14 INFO - 'talos_options': ['--mitmproxy', 04:09:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:09:14 INFO - '--firstNonBlankPaint'], 04:09:14 INFO - 'tests': ['tp6_google', 04:09:14 INFO - 'tp6_youtube', 04:09:14 INFO - 'tp6_amazon', 04:09:14 INFO - 'tp6_facebook']}, 04:09:14 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:09:14 INFO - 'talos_options': ['--disable-stylo', 04:09:14 INFO - '--mitmproxy', 04:09:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:09:14 INFO - '--firstNonBlankPaint'], 04:09:14 INFO - 'tests': ['tp6_google', 04:09:14 INFO - 'tp6_youtube', 04:09:14 INFO - 'tp6_amazon', 04:09:14 INFO - 'tp6_facebook']}, 04:09:14 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:09:14 INFO - 'talos_options': ['--stylo-threads=1', 04:09:14 INFO - '--mitmproxy', 04:09:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:09:14 INFO - '--firstNonBlankPaint'], 04:09:14 INFO - 'tests': ['tp6_google', 04:09:14 INFO - 'tp6_youtube', 04:09:14 INFO - 'tp6_amazon', 04:09:14 INFO - 'tp6_facebook']}, 04:09:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:09:14 INFO - 'talos_options': ['--xperf_path', 04:09:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:09:14 INFO - 'tests': ['tp5n']}, 04:09:14 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:14 INFO - 'talos_options': ['--disable-stylo', 04:09:14 INFO - '--xperf_path', 04:09:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:09:14 INFO - 'tests': ['tp5n']}}} 04:09:14 INFO - Running post-action listener: _resource_record_post_action 04:09:14 INFO - [mozharness: 2017-09-09 11:09:14.713278Z] Finished populate-webroot step (success) 04:09:14 INFO - [mozharness: 2017-09-09 11:09:14.713365Z] Running create-virtualenv step. 04:09:14 INFO - Running pre-action listener: _resource_record_pre_action 04:09:14 INFO - Running main action method: create_virtualenv 04:09:14 INFO - Creating virtualenv /builds/slave/test/build/venv 04:09:14 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:09:14 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:09:14 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:09:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:09:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:09:15 INFO - Installing distribute.............................................................................................................................................................................................done. 04:09:16 INFO - Installing pip...............done. 04:09:16 INFO - Return code: 0 04:09:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:09:16 INFO - Reading from file tmpfile_stdout 04:09:16 INFO - Output received: 04:09:16 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 04:09:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:09:16 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')]} 04:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:16 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')]} 04:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x28a71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a70a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c13a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c17090>, '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 0x2c17d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c11f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', '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 04:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:09:16 INFO - Using env: {'DISPLAY': ':0', 04:09:16 INFO - 'HOME': '/home/cltbld', 04:09:16 INFO - 'LANG': 'en_US.UTF-8', 04:09:16 INFO - 'LANGUAGE': 'en_US:en', 04:09:16 INFO - 'LOGNAME': 'cltbld', 04:09:16 INFO - 'MAIL': '/var/mail/cltbld', 04:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:16 INFO - 'MOZ_NO_REMOTE': '1', 04:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:16 INFO - 'NO_EM_RESTART': '1', 04:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:16 INFO - 'PWD': '/builds/slave/test', 04:09:16 INFO - 'SHELL': '/bin/bash', 04:09:16 INFO - 'SHLVL': '1', 04:09:16 INFO - 'TERM': 'linux', 04:09:16 INFO - 'TMOUT': '86400', 04:09:16 INFO - 'USER': 'cltbld', 04:09:16 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', 04:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:16 INFO - '_': '/tools/buildbot/bin/python'} 04:09:17 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:09:17 INFO - Downloading/unpacking pip>=1.5 04:09:17 INFO - Running setup.py egg_info for package pip 04:09:17 INFO - warning: no files found matching 'pip/cacert.pem' 04:09:17 INFO - warning: no files found matching '*.html' under directory 'docs' 04:09:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:09:17 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:09:17 INFO - Installing collected packages: pip 04:09:17 INFO - Found existing installation: pip 1.1 04:09:17 INFO - Uninstalling pip: 04:09:17 INFO - Successfully uninstalled pip 04:09:17 INFO - Running setup.py install for pip 04:09:17 INFO - warning: no files found matching 'pip/cacert.pem' 04:09:17 INFO - warning: no files found matching '*.html' under directory 'docs' 04:09:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:09:17 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:09:17 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:09:17 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:09:17 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:09:17 INFO - Successfully installed pip 04:09:17 INFO - Cleaning up... 04:09:17 INFO - Return code: 0 04:09:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x28a71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a70a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c13a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c17090>, '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 0x2c17d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c11f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', '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 04:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:09:17 INFO - Using env: {'DISPLAY': ':0', 04:09:17 INFO - 'HOME': '/home/cltbld', 04:09:17 INFO - 'LANG': 'en_US.UTF-8', 04:09:17 INFO - 'LANGUAGE': 'en_US:en', 04:09:17 INFO - 'LOGNAME': 'cltbld', 04:09:17 INFO - 'MAIL': '/var/mail/cltbld', 04:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:17 INFO - 'MOZ_NO_REMOTE': '1', 04:09:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:17 INFO - 'NO_EM_RESTART': '1', 04:09:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:17 INFO - 'PWD': '/builds/slave/test', 04:09:17 INFO - 'SHELL': '/bin/bash', 04:09:17 INFO - 'SHLVL': '1', 04:09:17 INFO - 'TERM': 'linux', 04:09:17 INFO - 'TMOUT': '86400', 04:09:17 INFO - 'USER': 'cltbld', 04:09:17 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', 04:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:17 INFO - '_': '/tools/buildbot/bin/python'} 04:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:17 INFO - Downloading/unpacking psutil>=3.1.1 04:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:09:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:09:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:09:18 INFO - Installing collected packages: psutil 04:09:18 INFO - Running setup.py install for psutil 04:09:18 INFO - building 'psutil._psutil_linux' extension 04:09:18 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 04:09:19 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 04:09:19 INFO - building 'psutil._psutil_posix' extension 04:09:19 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 04:09:19 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 04:09:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:09:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:09:19 INFO - Successfully installed psutil 04:09:19 INFO - Cleaning up... 04:09:19 INFO - Return code: 0 04:09:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a70a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c13a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c17090>, '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 0x2c17d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c11f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', '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 04:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:09:19 INFO - Using env: {'DISPLAY': ':0', 04:09:19 INFO - 'HOME': '/home/cltbld', 04:09:19 INFO - 'LANG': 'en_US.UTF-8', 04:09:19 INFO - 'LANGUAGE': 'en_US:en', 04:09:19 INFO - 'LOGNAME': 'cltbld', 04:09:19 INFO - 'MAIL': '/var/mail/cltbld', 04:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:19 INFO - 'MOZ_NO_REMOTE': '1', 04:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:19 INFO - 'NO_EM_RESTART': '1', 04:09:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:19 INFO - 'PWD': '/builds/slave/test', 04:09:19 INFO - 'SHELL': '/bin/bash', 04:09:19 INFO - 'SHLVL': '1', 04:09:19 INFO - 'TERM': 'linux', 04:09:19 INFO - 'TMOUT': '86400', 04:09:19 INFO - 'USER': 'cltbld', 04:09:19 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', 04:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:19 INFO - '_': '/tools/buildbot/bin/python'} 04:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:09:20 INFO - Installing collected packages: mozsystemmonitor 04:09:20 INFO - Running setup.py install for mozsystemmonitor 04:09:20 INFO - Successfully installed mozsystemmonitor 04:09:20 INFO - Cleaning up... 04:09:21 INFO - Return code: 0 04:09:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:09:21 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')]} 04:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:21 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')]} 04:09:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:21 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 0x28a71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a70a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c13a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c17090>, '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 0x2c17d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c11f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', '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 04:09:21 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 04:09:21 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 04:09:21 INFO - Using env: {'DISPLAY': ':0', 04:09:21 INFO - 'HOME': '/home/cltbld', 04:09:21 INFO - 'LANG': 'en_US.UTF-8', 04:09:21 INFO - 'LANGUAGE': 'en_US:en', 04:09:21 INFO - 'LOGNAME': 'cltbld', 04:09:21 INFO - 'MAIL': '/var/mail/cltbld', 04:09:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:21 INFO - 'MOZ_NO_REMOTE': '1', 04:09:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:21 INFO - 'NO_EM_RESTART': '1', 04:09:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:21 INFO - 'PWD': '/builds/slave/test', 04:09:21 INFO - 'SHELL': '/bin/bash', 04:09:21 INFO - 'SHLVL': '1', 04:09:21 INFO - 'TERM': 'linux', 04:09:21 INFO - 'TMOUT': '86400', 04:09:21 INFO - 'USER': 'cltbld', 04:09:21 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', 04:09:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:21 INFO - '_': '/tools/buildbot/bin/python'} 04:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:22 INFO - Downloading/unpacking jsonschema==2.5.1 04:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:09:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:23 INFO - Downloading functools32-3.2.3-2.tar.gz 04:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:09:23 INFO - warning: no files found matching '*.txt' 04:09:23 INFO - no previously-included directories found matching 'build' 04:09:23 INFO - no previously-included directories found matching 'dist' 04:09:23 INFO - no previously-included directories found matching '.git*' 04:09:23 INFO - Installing collected packages: jsonschema, functools32 04:09:23 INFO - Running setup.py install for functools32 04:09:23 INFO - warning: no files found matching '*.txt' 04:09:23 INFO - no previously-included directories found matching 'build' 04:09:23 INFO - no previously-included directories found matching 'dist' 04:09:23 INFO - no previously-included directories found matching '.git*' 04:09:23 INFO - Successfully installed jsonschema functools32 04:09:23 INFO - Cleaning up... 04:09:23 INFO - Return code: 0 04:09:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:09:23 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')]} 04:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:23 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')]} 04:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:23 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 0x28a71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a70a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c13a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c17090>, '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 0x2c17d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c11f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', '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 04:09:23 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 04:09:23 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 04:09:23 INFO - Using env: {'DISPLAY': ':0', 04:09:23 INFO - 'HOME': '/home/cltbld', 04:09:23 INFO - 'LANG': 'en_US.UTF-8', 04:09:23 INFO - 'LANGUAGE': 'en_US:en', 04:09:23 INFO - 'LOGNAME': 'cltbld', 04:09:23 INFO - 'MAIL': '/var/mail/cltbld', 04:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:23 INFO - 'MOZ_NO_REMOTE': '1', 04:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:23 INFO - 'NO_EM_RESTART': '1', 04:09:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:23 INFO - 'PWD': '/builds/slave/test', 04:09:23 INFO - 'SHELL': '/bin/bash', 04:09:23 INFO - 'SHLVL': '1', 04:09:23 INFO - 'TERM': 'linux', 04:09:23 INFO - 'TMOUT': '86400', 04:09:23 INFO - 'USER': 'cltbld', 04:09:23 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', 04:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:23 INFO - '_': '/tools/buildbot/bin/python'} 04:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:09:23 INFO - Cleaning up... 04:09:23 INFO - Return code: 0 04:09:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:09:23 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')]} 04:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:23 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')]} 04:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:23 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 0x28a71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a70a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c13a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c17090>, '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 0x2c17d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c11f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', '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 04:09:23 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 04:09:23 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 04:09:23 INFO - Using env: {'DISPLAY': ':0', 04:09:23 INFO - 'HOME': '/home/cltbld', 04:09:23 INFO - 'LANG': 'en_US.UTF-8', 04:09:23 INFO - 'LANGUAGE': 'en_US:en', 04:09:23 INFO - 'LOGNAME': 'cltbld', 04:09:23 INFO - 'MAIL': '/var/mail/cltbld', 04:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:23 INFO - 'MOZ_NO_REMOTE': '1', 04:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:23 INFO - 'NO_EM_RESTART': '1', 04:09:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:23 INFO - 'PWD': '/builds/slave/test', 04:09:23 INFO - 'SHELL': '/bin/bash', 04:09:23 INFO - 'SHLVL': '1', 04:09:23 INFO - 'TERM': 'linux', 04:09:23 INFO - 'TMOUT': '86400', 04:09:23 INFO - 'USER': 'cltbld', 04:09:23 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', 04:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:23 INFO - '_': '/tools/buildbot/bin/python'} 04:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:23 INFO - Downloading/unpacking blobuploader==1.2.4 04:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:25 INFO - Downloading blobuploader-1.2.4.tar.gz 04:09:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:09:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:09:25 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 04:09:25 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 04:09:25 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 04:09:25 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 04:09:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:09:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:09:25 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 04:09:25 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 04:09:25 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 04:09:25 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 04:09:25 INFO - Downloading docopt-0.6.1.tar.gz 04:09:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:09:25 INFO - Installing collected packages: blobuploader, requests, docopt 04:09:25 INFO - Running setup.py install for blobuploader 04:09:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:09:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:09:25 INFO - Running setup.py install for requests 04:09:26 INFO - Running setup.py install for docopt 04:09:26 INFO - Successfully installed blobuploader requests docopt 04:09:26 INFO - Cleaning up... 04:09:26 INFO - Return code: 0 04:09:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x28a71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a70a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c13a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c17090>, '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 0x2c17d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c11f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', '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 04:09:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:09:26 INFO - Using env: {'DISPLAY': ':0', 04:09:26 INFO - 'HOME': '/home/cltbld', 04:09:26 INFO - 'LANG': 'en_US.UTF-8', 04:09:26 INFO - 'LANGUAGE': 'en_US:en', 04:09:26 INFO - 'LOGNAME': 'cltbld', 04:09:26 INFO - 'MAIL': '/var/mail/cltbld', 04:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:26 INFO - 'MOZ_NO_REMOTE': '1', 04:09:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:26 INFO - 'NO_EM_RESTART': '1', 04:09:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:26 INFO - 'PWD': '/builds/slave/test', 04:09:26 INFO - 'SHELL': '/bin/bash', 04:09:26 INFO - 'SHLVL': '1', 04:09:26 INFO - 'TERM': 'linux', 04:09:26 INFO - 'TMOUT': '86400', 04:09:26 INFO - 'USER': 'cltbld', 04:09:26 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', 04:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:26 INFO - '_': '/tools/buildbot/bin/python'} 04:09:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:09:26 INFO - Running setup.py (path:/tmp/pip-_yEwvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:09:26 INFO - Running setup.py (path:/tmp/pip-iK5yhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:09:26 INFO - Running setup.py (path:/tmp/pip-JanNTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:09:26 INFO - Running setup.py (path:/tmp/pip-hVH5NH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:09:26 INFO - Running setup.py (path:/tmp/pip-yaaj38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:09:26 INFO - Running setup.py (path:/tmp/pip-_btteu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:09:26 INFO - Running setup.py (path:/tmp/pip-nI7hAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:09:26 INFO - Running setup.py (path:/tmp/pip-rJe41p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:09:27 INFO - Running setup.py (path:/tmp/pip-YhYvhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:09:27 INFO - Running setup.py (path:/tmp/pip-wIHDDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:09:27 INFO - Running setup.py (path:/tmp/pip-B1AldN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:09:27 INFO - Running setup.py (path:/tmp/pip-b5fCrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:09:27 INFO - Running setup.py (path:/tmp/pip-_AqXHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:09:27 INFO - Running setup.py (path:/tmp/pip-TGWPSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:27 INFO - Running setup.py (path:/tmp/pip-2ZuNwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:09:27 INFO - Running setup.py (path:/tmp/pip-Xjx87I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:09:27 INFO - Running setup.py (path:/tmp/pip-3qycOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:09:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:09:27 INFO - Running setup.py install for manifestparser 04:09:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:09:27 INFO - Running setup.py install for mozcrash 04:09:27 INFO - Running setup.py install for mozdebug 04:09:27 INFO - Running setup.py install for mozdevice 04:09:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:09:28 INFO - Running setup.py install for mozfile 04:09:28 INFO - Running setup.py install for mozhttpd 04:09:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:09:28 INFO - Running setup.py install for mozinfo 04:09:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:09:28 INFO - Running setup.py install for mozInstall 04:09:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:09:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:09:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:09:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:09:28 INFO - Running setup.py install for mozleak 04:09:28 INFO - Running setup.py install for mozlog 04:09:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:09:28 INFO - Running setup.py install for moznetwork 04:09:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:09:28 INFO - Running setup.py install for mozprocess 04:09:28 INFO - Running setup.py install for mozprofile 04:09:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:09:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:09:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:09:28 INFO - Running setup.py install for mozrunner 04:09:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:09:29 INFO - Running setup.py install for mozscreenshot 04:09:29 INFO - Running setup.py install for moztest 04:09:29 INFO - Running setup.py install for mozversion 04:09:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:09:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:09:29 INFO - Cleaning up... 04:09:29 INFO - Return code: 0 04:09:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:29 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 0x28a71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a70a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c13a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c17090>, '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 0x2c17d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c11f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', '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 04:09:29 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 04:09:29 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 04:09:29 INFO - Using env: {'DISPLAY': ':0', 04:09:29 INFO - 'HOME': '/home/cltbld', 04:09:29 INFO - 'LANG': 'en_US.UTF-8', 04:09:29 INFO - 'LANGUAGE': 'en_US:en', 04:09:29 INFO - 'LOGNAME': 'cltbld', 04:09:29 INFO - 'MAIL': '/var/mail/cltbld', 04:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:29 INFO - 'MOZ_NO_REMOTE': '1', 04:09:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:29 INFO - 'NO_EM_RESTART': '1', 04:09:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:29 INFO - 'PWD': '/builds/slave/test', 04:09:29 INFO - 'SHELL': '/bin/bash', 04:09:29 INFO - 'SHLVL': '1', 04:09:29 INFO - 'TERM': 'linux', 04:09:29 INFO - 'TMOUT': '86400', 04:09:29 INFO - 'USER': 'cltbld', 04:09:29 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', 04:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:29 INFO - '_': '/tools/buildbot/bin/python'} 04:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:09:29 INFO - Running setup.py (path:/tmp/pip-FToF_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:09:29 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)) 04:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:09:29 INFO - Running setup.py (path:/tmp/pip-d90B6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:09:29 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)) 04:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:09:29 INFO - Running setup.py (path:/tmp/pip-Hq5whg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:09:29 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)) 04:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:09:29 INFO - Running setup.py (path:/tmp/pip-61ggpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:09:29 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)) 04:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:09:29 INFO - Running setup.py (path:/tmp/pip-d7kxKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:09:29 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)) 04:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:09:29 INFO - Running setup.py (path:/tmp/pip-wZy5Jb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:09:30 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)) 04:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:09:30 INFO - Running setup.py (path:/tmp/pip-d9_P_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:09:30 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)) 04:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:09:30 INFO - Running setup.py (path:/tmp/pip-dPExyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:09:30 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)) 04:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:09:30 INFO - Running setup.py (path:/tmp/pip-HMQwmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:09:30 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)) 04:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:09:30 INFO - Running setup.py (path:/tmp/pip-bvT3Jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:09:30 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)) 04:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:09:30 INFO - Running setup.py (path:/tmp/pip-2ObApD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:09:30 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)) 04:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:09:30 INFO - Running setup.py (path:/tmp/pip-WppPF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:09:30 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)) 04:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:09:30 INFO - Running setup.py (path:/tmp/pip-s1CkxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:09:30 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)) 04:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:09:30 INFO - Running setup.py (path:/tmp/pip-0kiFbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:09:30 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)) 04:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:30 INFO - Running setup.py (path:/tmp/pip-JPB9ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:30 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)) 04:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:09:30 INFO - Running setup.py (path:/tmp/pip-p8NHLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:09:30 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)) 04:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:09:30 INFO - Running setup.py (path:/tmp/pip-eV6vRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:09:30 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)) 04:09:30 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)) 04:09:30 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)) 04:09:30 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)) 04:09:30 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)) 04:09:30 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)) 04:09:30 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)) 04:09:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:09:30 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 04:09:30 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 04:09:30 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 04:09:30 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 04:09:31 INFO - Downloading blessings-1.6.tar.gz 04:09:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:09:32 INFO - Installing collected packages: blessings 04:09:32 INFO - Running setup.py install for blessings 04:09:32 INFO - Successfully installed blessings 04:09:32 INFO - Cleaning up... 04:09:32 INFO - Return code: 0 04:09:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:09:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:09:32 INFO - Reading from file tmpfile_stdout 04:09:32 INFO - Current package versions: 04:09:32 INFO - argparse == 1.2.1 04:09:32 INFO - blessings == 1.6 04:09:32 INFO - blobuploader == 1.2.4 04:09:32 INFO - docopt == 0.6.1 04:09:32 INFO - functools32 == 3.2.3-2 04:09:32 INFO - jsonschema == 2.5.1 04:09:32 INFO - manifestparser == 1.1 04:09:32 INFO - mozInstall == 1.13 04:09:32 INFO - mozcrash == 1.0 04:09:32 INFO - mozdebug == 0.1 04:09:32 INFO - mozdevice == 0.51 04:09:32 INFO - mozfile == 1.2 04:09:32 INFO - mozhttpd == 0.7 04:09:32 INFO - mozinfo == 0.10 04:09:32 INFO - mozleak == 0.1 04:09:32 INFO - mozlog == 3.5 04:09:32 INFO - moznetwork == 0.27 04:09:32 INFO - mozprocess == 0.25 04:09:32 INFO - mozprofile == 0.28 04:09:32 INFO - mozrunner == 6.13 04:09:32 INFO - mozscreenshot == 0.1 04:09:32 INFO - mozsystemmonitor == 0.3 04:09:32 INFO - moztest == 0.8 04:09:32 INFO - mozversion == 1.4 04:09:32 INFO - psutil == 3.1.1 04:09:32 INFO - requests == 1.2.3 04:09:32 INFO - wsgiref == 0.1.2 04:09:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:09: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')]} 04:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09: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')]} 04:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:32 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 0x28a71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a70a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c13a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c17090>, '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 0x2c17d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c11f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', '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 04:09:32 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 04:09:32 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 04:09:32 INFO - Using env: {'DISPLAY': ':0', 04:09:32 INFO - 'HOME': '/home/cltbld', 04:09:32 INFO - 'LANG': 'en_US.UTF-8', 04:09:32 INFO - 'LANGUAGE': 'en_US:en', 04:09:32 INFO - 'LOGNAME': 'cltbld', 04:09:32 INFO - 'MAIL': '/var/mail/cltbld', 04:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:32 INFO - 'MOZ_NO_REMOTE': '1', 04:09:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:32 INFO - 'NO_EM_RESTART': '1', 04:09:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:32 INFO - 'PWD': '/builds/slave/test', 04:09:32 INFO - 'SHELL': '/bin/bash', 04:09:32 INFO - 'SHLVL': '1', 04:09:32 INFO - 'TERM': 'linux', 04:09:32 INFO - 'TMOUT': '86400', 04:09:32 INFO - 'USER': 'cltbld', 04:09:32 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', 04:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:32 INFO - '_': '/tools/buildbot/bin/python'} 04:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:32 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)) 04:09:32 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)) 04:09:32 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)) 04:09:32 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)) 04:09:32 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)) 04:09:32 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)) 04:09:32 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)) 04:09:32 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)) 04:09:32 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)) 04:09:32 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:09:33 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)) 04:09:33 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)) 04:09:33 INFO - Installing collected packages: simplejson 04:09:33 INFO - Running setup.py install for simplejson 04:09:33 INFO - building 'simplejson._speedups' extension 04:09:33 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 04:09:34 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 04:09:34 INFO - Successfully installed simplejson 04:09:34 INFO - Cleaning up... 04:09:34 INFO - Return code: 0 04:09:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:09: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')]} 04:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09: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')]} 04:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28a71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a70a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c13a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c17090>, '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 0x2c17d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c11f90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', '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 04:09: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', 'jsonschema'] in /builds/slave/test/build 04:09: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 jsonschema 04:09:34 INFO - Using env: {'DISPLAY': ':0', 04:09:34 INFO - 'HOME': '/home/cltbld', 04:09:34 INFO - 'LANG': 'en_US.UTF-8', 04:09:34 INFO - 'LANGUAGE': 'en_US:en', 04:09:34 INFO - 'LOGNAME': 'cltbld', 04:09:34 INFO - 'MAIL': '/var/mail/cltbld', 04:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:34 INFO - 'MOZ_NO_REMOTE': '1', 04:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:34 INFO - 'NO_EM_RESTART': '1', 04:09:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:34 INFO - 'PWD': '/builds/slave/test', 04:09:34 INFO - 'SHELL': '/bin/bash', 04:09:34 INFO - 'SHLVL': '1', 04:09:34 INFO - 'TERM': 'linux', 04:09:34 INFO - 'TMOUT': '86400', 04:09:34 INFO - 'USER': 'cltbld', 04:09:34 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', 04:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:34 INFO - '_': '/tools/buildbot/bin/python'} 04:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:09:35 INFO - Cleaning up... 04:09:35 INFO - Return code: 0 04:09:35 INFO - Running post-action listener: _resource_record_post_action 04:09:35 INFO - Running post-action listener: _start_resource_monitoring 04:09:35 INFO - Starting resource monitoring. 04:09:35 INFO - [mozharness: 2017-09-09 11:09:35.120945Z] Finished create-virtualenv step (success) 04:09:35 INFO - [mozharness: 2017-09-09 11:09:35.121469Z] Running install step. 04:09:35 INFO - Running pre-action listener: _resource_record_pre_action 04:09:35 INFO - Running main action method: install 04:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:09:35 INFO - Reading from file tmpfile_stdout 04:09:35 INFO - Detecting whether we're running mozinstall >=1.0... 04:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:09:35 INFO - Reading from file tmpfile_stdout 04:09:35 INFO - Output received: 04:09:35 INFO - Usage: mozinstall [options] installer 04:09:35 INFO - Options: 04:09:35 INFO - -h, --help show this help message and exit 04:09:35 INFO - -d DEST, --destination=DEST 04:09:35 INFO - Directory to install application into. [default: 04:09:35 INFO - "/builds/slave/test"] 04:09:35 INFO - --app=APP Application being installed. [default: firefox] 04:09:35 INFO - mkdir: /builds/slave/test/build/application 04:09:35 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'] 04:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:09:51 INFO - Reading from file tmpfile_stdout 04:09:51 INFO - Output received: 04:09:51 INFO - /builds/slave/test/build/application/firefox/firefox 04:09:51 INFO - Running post-action listener: _resource_record_post_action 04:09:51 INFO - [mozharness: 2017-09-09 11:09:51.930839Z] Finished install step (success) 04:09:51 INFO - [mozharness: 2017-09-09 11:09:51.931092Z] Running setup-mitmproxy step. 04:09:51 INFO - Running pre-action listener: _resource_record_pre_action 04:09:51 INFO - Running main action method: setup_mitmproxy 04:09:51 INFO - Skipping: mitmproxy is not required 04:09:51 INFO - Running post-action listener: _resource_record_post_action 04:09:51 INFO - [mozharness: 2017-09-09 11:09:51.932219Z] Finished setup-mitmproxy step (success) 04:09:51 INFO - [mozharness: 2017-09-09 11:09:51.932486Z] Running run-tests step. 04:09:51 INFO - Running pre-action listener: _resource_record_pre_action 04:09:51 INFO - Running pre-action listener: _set_gcov_prefix 04:09:51 INFO - Running main action method: run_tests 04:09:51 WARNING - Try message not found. 04:09:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:09:51 INFO - Python 2.7.3 04:09:51 INFO - Return code: 0 04:09:51 INFO - grabbing minidump binary from tooltool 04:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:51 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 0x2c17090>, '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 0x2c17d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c11f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:09:51 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 04:09:51 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 04:09:51 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 04:09:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:09:52 INFO - Return code: 0 04:09:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:09:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:09:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:09:52 INFO - ENV: RUST_BACKTRACE is now full 04:09:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:09:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:09:52 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/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 04:09:52 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/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 04:09:52 INFO - Using env: {'DISPLAY': ':0', 04:09:52 INFO - 'HOME': '/home/cltbld', 04:09:52 INFO - 'LANG': 'en_US.UTF-8', 04:09:52 INFO - 'LANGUAGE': 'en_US:en', 04:09:52 INFO - 'LOGNAME': 'cltbld', 04:09:52 INFO - 'MAIL': '/var/mail/cltbld', 04:09:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:09:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:52 INFO - 'MOZ_NO_REMOTE': '1', 04:09:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:09:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:52 INFO - 'NO_EM_RESTART': '1', 04:09:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:52 INFO - 'PWD': '/builds/slave/test', 04:09:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:09:52 INFO - 'RUST_BACKTRACE': 'full', 04:09:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:09:52 INFO - 'SHELL': '/bin/bash', 04:09:52 INFO - 'SHLVL': '1', 04:09:52 INFO - 'TERM': 'linux', 04:09:52 INFO - 'TMOUT': '86400', 04:09:52 INFO - 'USER': 'cltbld', 04:09:52 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', 04:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:52 INFO - '_': '/tools/buildbot/bin/python'} 04:09:52 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/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 04:09:52 INFO - mozversion application_buildid: 20170909094504 04:09:52 INFO - mozversion application_changeset: c71b01e993510268bab7d60154b2f80692fd507d 04:09:52 INFO - mozversion application_display_name: Nightly 04:09:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:09:52 INFO - mozversion application_name: Firefox 04:09:52 INFO - mozversion application_remotingname: firefox 04:09:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:09:52 INFO - mozversion application_vendor: Mozilla 04:09:52 INFO - mozversion application_version: 57.0a1 04:09:52 INFO - mozversion platform_buildid: 20170909094504 04:09:52 INFO - mozversion platform_changeset: c71b01e993510268bab7d60154b2f80692fd507d 04:09:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:09:52 INFO - mozversion platform_version: 57.0a1 04:09:52 INFO - using testdate: 1504955392 04:09:52 INFO - actual date: 1504955392 04:09:52 INFO - starting webserver on 'localhost:54178' 04:09:52 INFO - SUITE-START | Running 2 tests 04:09:52 INFO - TEST-START | dromaeo_css 04:09:52 INFO - Initialising browser for dromaeo_css test... 04:09:52 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54178/getInfo.html -profile /tmp/tmpgSEgfN/profile 04:09:52 INFO - TEST-INFO | started process 15604 (/builds/slave/test/build/application/firefox/firefox http://localhost:54178/getInfo.html) 04:09:58 INFO - TEST-INFO | 15604: exit 0 04:09:58 INFO - Browser initialized. 04:09:58 INFO - Running cycle 1/1 for dromaeo_css test... 04:09:58 INFO - Using env: {'DISPLAY': ':0', 04:09:58 INFO - 'HOME': '/home/cltbld', 04:09:58 INFO - 'JSGC_DISABLE_POISONING': '1', 04:09:58 INFO - 'LANG': 'en_US.UTF-8', 04:09:58 INFO - 'LANGUAGE': 'en_US:en', 04:09:58 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:09:58 INFO - 'LOGNAME': 'cltbld', 04:09:58 INFO - 'MAIL': '/var/mail/cltbld', 04:09:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:09:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:09:58 INFO - 'MOZ_CRASHREPORTER': '1', 04:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:58 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:09:58 INFO - 'MOZ_NO_REMOTE': '1', 04:09:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:09:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:58 INFO - 'NO_EM_RESTART': '1', 04:09:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:58 INFO - 'PWD': '/builds/slave/test', 04:09:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:09:58 INFO - 'RUST_BACKTRACE': 'full', 04:09:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:09:58 INFO - 'SHELL': '/bin/bash', 04:09:58 INFO - 'SHLVL': '1', 04:09:58 INFO - 'TERM': 'linux', 04:09:58 INFO - 'TMOUT': '86400', 04:09:58 INFO - 'USER': 'cltbld', 04:09:58 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', 04:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:58 INFO - '_': '/tools/buildbot/bin/python'} 04:09:58 INFO - TEST-INFO | started process 15806 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgSEgfN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:09:59 INFO - PID 15806 | 04:09:59 INFO - PID 15806 | (/builds/slave/test/build/application/firefox/firefox:15856): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:09:59 INFO - PID 15806 | 04:10:09 INFO - PID 15806 | RSS: Main: 164196352 04:10:09 INFO - PID 15806 | 04:13:07 INFO - PID 15806 | Cycle 1(1): loaded http://localhost:54178/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54178/tests/dromaeo/cssquery-ext.html) 04:13:08 INFO - PID 15806 | RSS: Main: 167682048 04:13:08 INFO - PID 15806 | 04:16:09 INFO - PID 15806 | Cycle 1(1): loaded http://localhost:54178/tests/dromaeo/cssquery-ext.html (next: http://localhost:54178/tests/dromaeo/cssquery-jquery.html) 04:16:10 INFO - PID 15806 | RSS: Main: 164515840 04:16:10 INFO - PID 15806 | 04:19:12 INFO - PID 15806 | Cycle 1(1): loaded http://localhost:54178/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54178/tests/dromaeo/cssquery-mootools.html) 04:19:12 INFO - PID 15806 | RSS: Main: 166768640 04:19:12 INFO - PID 15806 | 04:22:14 INFO - PID 15806 | Cycle 1(1): loaded http://localhost:54178/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54178/tests/dromaeo/cssquery-prototype.html) 04:22:14 INFO - PID 15806 | RSS: Main: 165441536 04:22:14 INFO - PID 15806 | 04:25:17 INFO - PID 15806 | Cycle 1(1): loaded http://localhost:54178/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54178/tests/dromaeo/cssquery-yui.html) 04:25:18 INFO - PID 15806 | RSS: Main: 166739968 04:25:18 INFO - PID 15806 | 04:28:20 INFO - PID 15806 | Cycle 1(1): loaded http://localhost:54178/tests/dromaeo/cssquery-yui.html (next: http://localhost:54178/tests/dromaeo/cssquery-dojo.html) 04:28:20 INFO - PID 15806 | RSS: Main: 169218048 04:28:20 INFO - PID 15806 | 04:28:20 INFO - PID 15806 | __start_tp_report 04:28:20 INFO - PID 15806 | _x_x_mozilla_page_load 04:28:20 INFO - PID 15806 | _x_x_mozilla_page_load_details 04:28:20 INFO - PID 15806 | |i|pagename|runs| 04:28:20 INFO - PID 15806 | |0;dojo.html;3873.205992771482;3875.2443273561657;3891.0272431892026;3919.3924941634054;3921.27456420562;6278.811635650926;6279.730769989932;6354.968225158874;6407.391297826711;6414.871702565946;6380.04299355097;6398.776042838502;6404.839879003027;6412.03819427085;6428.646424446665;5666.51834594058;5745.36800951897;5789.247397838275;5797.826065218029;5807.912881306784;712.9513341242957;5786.56600754943;5817.854553636151;5854.443827836349;5869.794557190499;5708.343540492834;5722.54219662426;5724;5821.15593238978;5836.270466191726;5712.428757124296;5718.914216286759;5772.6536407815665;5779.219805326269;5791.18923350727;5711.257536520226;5739.426057394269;5810.157527158581;5811.476967072984;5823.272090988627;6357.300696923335;6378.840528986766;6380.776672816475;6433.7426502939825;6456.192975878015;6795.2185498667795;6849.280825513349;6869.519133660646;6923.792286231421;7020.754273600451;6959.756408525677;6964.547304425196;6974.860502789967;6988;6989.650517474106;6374.681265936731;6421.4220720134945;6452.548321617489;6464.353564643498;6480.449161821298;7129.358357747724;7197.1363436387965;7215.675294611755;7239.855202895913;7243.782686519413;6802.353776391334;6819.147606549182;6878.759243426456;6913.032175495435;6974.930250697529;6835.213950395669;6905.965470172617;6937.445004399636;6999.615021173782;7074.045003924507;6877.243503214642;6897.241303456616;6899.793006209822;6901.344372284625;6907.896381554281;6901.965490172617;6920.619365934821;6920.9653951729915;6926.307369263033;6927.618980956096;4961.280614310905;4962.255661650781;5016.473270306566;5021.84934451967;5023.547880690755;4485.865424037284;4501.437320334958;4524.728516289033;4542.1824071667415;4591.4949355571525;6338.080978258126;6346.650934198571;6349.904751428732;6350.650714210763;6363.204599425072;6899.862002759916;6936.93063069373;6950.79147625572;6966.12923384577;6974.197967233732;6335.778247761306;6409.903851442232;6419.903701444482;6422.775202867877;6434.742610295536;5539.723013849291;5550.639208451437;5620.325560932714;5621.269234999424;5643.097104463266;7090.893636595455;7174.210836807947;7215.711371545184;7250;7273.3453989141235;6891.862162756817;6910.861782764317;6942.06282151903;6990.335918087773;6995.685194166173;7198.71205151788;7208.279172082959;7210.567365958059;7294.781156565312;7301.342879140902;6746.089277947414;6827.044213809972;6849.657517124224;6941.757038503628;6944.51388402807;6965.930340696528;7026.437884969292;7031.964840175971;7043.859122817617;7052.788416347517;6525.608463492205;6649.501287403367;6680.0647909291765;6688.6990085445295;6702.798916032527;6637.867242655217;6639.203295604559;6670.699818508082;6713.597184168965;6739.42714869222;6643.70103345341;6671;6677.532572720057;6679.365460281266;6698.83252918681;6704.664766761545;6789.253182149861;6805.251422343439;6816.250212476524;6832.487563432663;6505.089287499848;6550.639714815731;6558.606483610999;6568.868622627616;6649 04:28:20 INFO - PID 15806 | |1;ext.html;932.6083045121051;947.9146876781089;966.4153187321672;997.2670087485701;1001.3291094966372;43421.56578434215;45556.544434555726;45582.40461583841;45651.08697826041;45712.08575828481;42449.575504244945;42556;42656.36015459771;42661.57338426615;42685.35971960415;7518.548887066766;7642.694292228304;7643;7644.5031072980355;7647.50291231071;7428.962855185743;7521.548707077593;7526.021617189757;7527.0967483902;7531.3221810037085;7518.135414427356;7543.245675432468;8303.95848020759;8470.534120623362;8471.661133554651;8386.245237928586;8444.493330400164;8452.408331416804;8454.788630284262;8459.11179326168;8293;8387;8394.538300393477;8395.328373730148;8402.747917562483;42704.359434608195;42826.35760463595;43499.56500435018;44074.11851762947;44207.33688994667;3685.834137463794;3707.1102935295603;3709.7959612221316;3711.573169085536;3715.925681486382;35051.29897402038;35138.824305878305;35177.648223517695;35179.82410087959;35185.1203719909;5526.613137080406;5585.525230355424;5596.3284405871555;5596.552275817925;5600.131979543177;219647;221112;221383;221671;221934.89032554894;52266.47733522692;52288.73855630698;52348.73825630924;52403.73798131061;52411.73794131005;54557.72721136448;54616.453835461136;54642.726786366606;54751.178732319044;54789;15686;15957.361705531881;16002.599935001484;16052.839471605135;16054.839451605334;12885.61343159688;12988.285644289655;13009.284489352987;13012.609621711175;13029.348532573333;14697.706045879237;14911.701765964837;14955.102693838404;15067.321970511353;15110.773338399933;5714.7428365723645;5880.235569375954;5882.264716910387;5913.556483263773;6025.939740602538;1083.853679753239;1091.1161958813386;1112.9499172537255;1122.180808010157;1123.8201887698092;26115.608265876028;26124;26143.477130457286;26235.737642623328;26359.73640263611;1211.4548453196096;1224.9510019599115;1229.33001514175;1237.696764292754;1257.7673130470891;13666.590002299947;13885.722285554233;13964.441422343196;13971.441142354395;14033.368498417596;282104;287381;287863;287911;288330;61459.69270153621;63018.68490657609;68084.6595767018;68129.65935170293;68237.65881170661;1535.4684226279512;1541.083055581927;1542.8071491063617;1545.6831349573536;1558.5012795905427;31513.527297090102;31591.526127107652;31788;32242.193945150622;32498.83750581232;44717;44732.55267447284;44913.550864490935;44969;44986;252229;253085;253375;253645;254011;39382;40562.59437405588;40661;40729;40766.388504173054;49971;50515;50546.74726626343;50838.491615083374;50935;36189.81905090563;36798.286041702144;36916;36941.630583694896;37077.81461092785;36720.81639591785;36841.447378289886;37092;37531.624683752816;37591.81204093962;43734.12531749411;43838.12323753571;43944.56055439533;44212.11575768531;44263;34766.30467390588;34895.12762180966;34937.47593786146;35538;35586.46620300749;26423.735762642897;26449.206523804318;26652.466950661266;26880.865595671898;26945 04:28:20 INFO - PID 15806 | |2;jquery.html;586.3374386942754;591.9852003699909;601.7262145723696;617.1452538234547;618.6411881108957;23486.177983770573;24149.63775543364;24184.75815241847;24201;24206.757932420693;24611;25803.48393032138;25978.74021259787;25982.610260846104;25989.870050649766;6579.7039133238995;6721.932780672179;6735.528513004092;6739.326067393246;6739.831504212385;6250.312465628777;6426.646534440604;6457.45111665509;6494.285628580852;6554.508411869105;6413.262474815409;6440.742370305182;6442.194725659293;6444.871102577946;6459.935400645981;6231.127642130105;6428.582142160792;6434.871302573945;6447.7098530566;6458.128152699373;5998.070299103646;6014.368491308437;6026.548008899306;6030.396960303979;6037.607555508878;30158.396832063456;30756.384872302653;30785.230369240726;30804.2298942526;30821.69178308233;2121.077331360863;2142.2930432957087;2143.2820005298245;2146.4848436375314;2148.4414052346347;19998;21186.5762684747;21259.68110478344;21342.466438339165;21357.893210533846;2500.1499490173246;2503.3616427810975;2576.497582971321;2586.4697736963876;2593.5072336256053;137929;146097;150600;153017;158368.4163158365;15445;15731.764023539656;15979.360825567079;16017.839821601869;16343.591410214602;25047.62428563573;25182.748172518408;25195.496090078097;25233.747662523507;25281.494370112494;10613.893861061291;10818.837717434244;10837.891621083845;10865.728356791144;10911.781764364827;9832;10038;10051.798964020825;10102.444365559964;10145.898541014643;9498.762530936643;9616.95191524038;9677.70966870981;9737.853932191023;9778.559964801601;2709.282040259333;2734.767544758678;2794.7345002224756;2860.427914417125;2900.4344152889985;1028.547439126782;1046.3721766939775;1053.0943388685723;1056.75166335911;1061.368485750977;20275.618092059463;20563.588728225353;20611.71674377255;21216.575668486545;21217.78782212158;1093.5297821936645;1111.8776934537195;1118.8433619293305;1131.9207655464204;1134.0813940708053;11318.830217546743;11325.490352934137;11590.884091159149;11653.41732913351;11677.124215683858;573793;596987;612031;613365;619208;124243.37878310731;127718;129020.70979290275;130165;131088;1110.2672236323988;1133.3483247132767;1216.8053111502118;1265.5001274496522;1269.6444995401487;21699.457513562287;22294.21970231034;22388.21641242549;22409.103635854382;22444.5511089774;59687;61469.692651536454;61799.382006181164;62419.37580624317;63997.680011599645;82136.58931705303;82164.58917705613;82233;82410.58794705987;83737;15023.849761502683;15110.697786044437;15253.694926101638;15360.923195383952;15362.38550458014;24482.387940301636;24490.265292041266;24736;24831.627525587493;24842.25473235806;19354.225830966603;20172.19311227593;20245.088970996047;20327.79672203259;20329.186832526535;16981.15094245258;17522.91238543799;17639.823601764332;17679.558011049314;17839.375621853174;20330.491737706674;20617.896910515352;20744;20797.792022079586;20863.47841303919;14940.327685254408;14992.100473971597;15090.54728358151;15153.393864245307;15166.24168791578;18534.25862965466;19423.90288048607;19659;19661.803381965998;19698.40904772859 04:28:20 INFO - PID 15806 | |3;mootools.html;1358.4973559782882;1421.3959067396354;1450.6010847017076;1473.3517252408933;1474.0787008119926;16325.346986120556;17030.23363948622;17039.659206815857;17041.233144508496;17046.659066818655;16823.747643785355;16939.83060169398;16959;16965.745513817303;16968.74546881798;2797.4684809886157;2836.475252078367;2882.0777351247607;2887.56686497025;2891.176014835772;2809.3117186289387;2825.420788738314;2839.205022593677;2844;2846.985765071172;2685.677718673762;2719.02115238514;2925;2925.078600240928;2925.400292939942;2899.043315705812;2903.433830403072;2915.9125226243245;2929.3555417808047;2935.0461100142434;2767.349672826884;2783.707710690389;2784.7076057013924;2785.749282564579;2795.73440523152;16054.83945160545;16371.754423683535;16429;16430.589235269093;16449.25978330966;7997.1602981686665;8239.670413183465;8243.587820609006;8296.83406331876;8302.750917472486;5254.921176182322;5405.459454054602;5437.8368648940595;5535.501804837584;5546.417626149277;2023.2412845183057;2035.369035598969;2054.8047935446107;2061.165228082629;2081.08432289792;29773.10680679583;30058.098257052323;30063.849680751457;30069.248268793235;30079.398412031638;6687.866242675119;6717.798466046026;6724.865502690017;6874.931250687529;6891.207511136183;18980.43058708241;19042.143103560375;19058.61882762337;19107.90446047761;19112.2355105797;7861.2531809478005;7987.121416644164;7990.081140668781;7990.520568765893;7993.560354180576;7301.050863387725;7321.63391830417;7349.375303099189;7375.44684148691;7418.851622967511;5411.675299482044;5642.6614403135945;5642.774289028475;5677.914831277534;5724.227229324071;1463.2244910197612;1486.2388070136922;1623.7320842061165;1650.3233674193523;1668.0742188085728;968.4915419404728;1006.582268358623;1007.4408703169664;1019.1337363241186;1022.8261195596617;9756.268279879037;9872.570215903294;10051.698449046395;10080.546375413123;10185.847212291821;777.859985202669;789.8380831929555;799.5842162075688;807.6163822184416;866.5234121233296;5792.565941267073;5958.195643588147;6297.434360088991;6341.143945567382;6343.492520598341;23614.763852361255;25210.36974075663;25257.368565785633;25265;25273.494530109663;23789.7621023791;24407;24733.87633061823;24767.380815479395;24908.501829963297;12217.266963982189;12316.261024338568;12547.058970577244;12552.748945021049;12735.55425560101;9686.564104615169;9691.2731545133;9903.653372131941;9912.653057142965;9947.303688741718;20554.28060017892;20627;20734.688979665014;21354.57290854143;21534.892325538898;21537.461563461038;21608.783912161307;21698.674519881897;21703.891480542497;21806.45483862916;11425.60010399632;11749.941250293694;11795.11536634773;11905.702357441134;11911;15174.772378414553;15282.235888205323;15433.768493472382;15434.45979390716;15500.992435491657;12225.69435764113;12572.245665259748;12676.619701408972;12741.617751467471;12846.229226246081;12261;12343.444544995677;12548.43532041042;12663.493460261487;12712.74574508523;10324.845127322946;10548.947255263674;10581.206409519471;10582.629607963685;10590.152787777113;8230.176982301722;8369.581520924052;8405.15948405179;8415.8737618937;8490;8563.743087707378;8689.695860644848;8695.130486951255;8725.869111963453;8782.121787821166 04:28:20 INFO - PID 15806 | |4;prototype.html;533.115029051774;542.5442628192318;562.3673367461606;563.3296377311;575.6718670357898;6352.269489008765;6361.363863613637;6393.433608765848;6406.263279722822;6406.679666016698;6309.62142271464;6328.208973878265;6335.366463353674;6345.397187267202;6348.777792777254;5070.062038522867;5115.9512299978605;5119.5136462036235;5133.666311689747;5144.588432925367;5048.545630893219;5072.807890145813;5082.009008243394;5092.747555790919;5112.97443512782;4788.97605511972;4879.560839524442;4976.054549635575;4983.177775667029;5076.746162691869;4889.535494128052;4893.437254715717;4913.14020046494;4944.936838579704;4961.429435614915;4422.314541246112;4439.311906654474;4443.822247110112;4466.82132714691;4474.955250447487;5998.460138587505;6013.218281623361;6055.788047418319;6061.757529698807;6094.421030002142;5697.316322041382;5781.566382521286;5783.8554036149435;5794.5074668653215;5866.618669786493;6527.673616319213;6567.901481477782;6588.945768676988;6647.734090636369;6693.431058360045;4792;4901.632377571696;4998.12532806761;5003.099442100421;5015.749212539358;6767.4924380671155;6790.891510636044;6816.7614133505585;6817.761378351734;6844.965775171142;5122.487751224922;5141.434442211354;5150.974245128826;5163.870903227449;5180.274761533388;5179.689218646893;5220.504052115043;5237.9476205238225;5240.449752775904;5242.868928276824;5733.88532229353;5770.2498675171955;5771.422857714279;5860.472557469848;5892.734826932712;6712.563683360566;6730.27423893324;6730.697118629673;6735.797926062226;6745.156855393076;1818.1363852170114;1822.6719190545564;1823.7629108215847;1838.512794109562;1845.0959030075435;1237.8638349781518;1278.341654048166;1281.3342654549049;1287.1183239480986;1295.9287239201747;6237.532185086136;6277.121203031579;6281.528885333618;6288.496920246369;6305.873882522324;6627.966860165669;6693.832654183684;6806.353396427331;6864.313568643096;6893.862122757517;6140.416660417253;6166.1983942087245;6195.75590720358;6212.192414986023;6284.780032698834;3553.218291975761;3568.5360903082433;3608.736942070307;3608.9278214435562;3643.0163855758797;7003.26465721092;7026.543274687231;7055.823604409931;7069.187043489962;7074.05463497532;6913.170419549686;6929.099217101744;6954.191910103409;6994.965025174842;7011.29887011305;7055.541389809646;7101.715931362688;7103.112110986127;7113.57318560888;7122.7507037253445;6828.931710682829;6831.658417079226;6842.55523390978;6877.415419689382;6900.965495172492;6717.361850624183;6718.899216511659;6723.562968407038;6754.2570317266045;6755.527113102232;7036.472264580075;7056.918209128422;7090.432765378654;7094.787156385317;7116.8932466014085;5181.590560389437;5198.532132108128;5202.505761952609;5213.504717051874;5216.243644671577;6661.233958094881;6674.766383176566;6807.965960170365;6879.174499060145;6889.758858440131;6517.576357536745;6528.543001990005;6541.149650545399;6568.211814582281;6574.342565743387;6479.177285401635;6537.738490460519;6558.376344677086;6564.5076619254705;6580.440662543738;6313.589616674902;6427.421532062137;6480.481561474976;6561.934380656323;6571.5399922005;6653.2348779889;6701.932980670131;6705.8264803658185;6711.395974362135;6722.983105068362;6467.547271690936;6467.611943283418;6479.416852483299;6506.154199953976;6546.559756853485 04:28:20 INFO - PID 15806 | |5;yui.html;942.0626476655729;949.3164921256692;960.1550635440811;964.4936408385597;988.2143695761869;16127.596810079755;16168.83831161688;16182.352705891764;19160.712589311137;19372.418827435165;20219.898900505512;20309.49226269344;20789.896050519725;21450.785492145074;21652.242171524;1047.1988928469727;1064.3613831700957;1069.5294070608945;1070.812607793637;1074.156786922266;1050.7373156710823;1056.392574269798;1056.7463808685936;1056.8943105689445;1057.2493529593999;1120.63019203663;1127.2898074213235;1127.413744852676;1128.4527004402858;1128.6331942118802;1101.1961268274165;1109.3953795181606;1112.232559533919;1113.0427832064418;1113.092829344082;815.804206990318;817.5421763812284;818.6357071103362;819.19309480162;820.3765138494728;16906.492805215865;17230.483085507454;17392.130393480402;17480.562985925328;17546.122693865254;22338.441538961495;22354.776452235434;22420.6636900445;22523.77476225233;22839.31482055541;16944.729145314028;17273.568160795956;17394.21726022332;17450.738238926428;17921.551961200945;3858.131920317934;3895.844166233347;3896.610338966109;3896.7661940283674;3897.4543563900793;143880;145127.27436362856;146009.80985285336;146621;147468;14740.926295368454;14746.557603271918;14834;14912;14946.775798363033;25368.873155634104;25644;25649.102281420062;25663.615045774328;25826.225213243633;8898.243649289754;9055.36612437123;9098.681546145854;9101.089891010846;9107.453552786721;4284.485861696617;4355;4363.956360436418;4378.912421751547;4380.605745482858;5689.77240910359;5778;5790.10253410722;5840.620359676607;5860.501857342088;958.2142643032646;970.2529052629436;988.8071825993862;989.7921436498272;995.7709726762885;645.6642545876213;699.9545029573061;701.3442431326725;717.2110678253879;726.5096060159372;13533.226497326557;13643;13843.16940983524;13976.720465590632;14010.509632162826;707.5931339479716;711.1856923822195;711.3562226185311;711.5303899426353;724.4602770935687;6236.6258024518675;6313.084602732578;6326.367363263636;6361.713722882481;6462.903056454157;140417;141192;143413;143438;149659.25170374295;120195.39902300433;123789;126005.36997314954;127201;130488;106207.46896265469;108396;110166;110846;111715.88284117218;12226.694332641398;12287.631371058882;12518.937405312916;12674.936625316814;12706.72932706722;52544;52644;52927;54469;54626.726866367004;70922.64538677274;71296;71631;71706;73629;16696;17163.91418042902;17274.82725172732;17508.737368939732;17524.649507009533;18185.818141818414;18301.54246143857;18421.35525256663;18460;18556.536086597942;17669.64660706753;17784.733229001835;17840.91079544646;17872.195751191488;17965.281388744304;17149;17440.040797755737;17485.562860928578;17544.73682894732;17569.385071522436;17294.135293235035;17375.304987800348;17419.738703919196;17478.563035924202;17499.737503937708;13320.666983325495;13345.399457024263;13419.731605368033;13661.248631325372;13769.655758606115;13975.231362275172;14007.229602371968;14088.859111408754;14184.858151418353;14201.644958876112 04:28:20 INFO - PID 15806 | __end_tp_report 04:28:20 INFO - PID 15806 | __start_cc_report 04:28:20 INFO - PID 15806 | _x_x_mozilla_cycle_collect,350 04:28:20 INFO - PID 15806 | __end_cc_report 04:28:20 INFO - PID 15806 | __startTimestamp1504956500290__endTimestamp 04:28:20 INFO - PID 15806 | 04:28:20 INFO - PID 15806 | ------- Summary: start ------- 04:28:20 INFO - PID 15806 | Number of tests: 6 04:28:20 INFO - PID 15806 | 04:28:20 INFO - PID 15806 | [#0] dojo.html Cycles:165 Average:6349.31 Median:6649.00 stddev:889.14 (13.4%) stddev-sans-first:870.39 04:28:20 INFO - PID 15806 | Values: 3873.2 3875.2 3891.0 3919.4 3921.3 6278.8 6279.7 6355.0 6407.4 6414.9 6380.0 6398.8 6404.8 6412.0 6428.6 5666.5 5745.4 5789.2 5797.8 5807.9 713.0 5786.6 5817.9 5854.4 5869.8 5708.3 5722.5 5724.0 5821.2 5836.3 5712.4 5718.9 5772.7 5779.2 5791.2 5711.3 5739.4 5810.2 5811.5 5823.3 6357.3 6378.8 6380.8 6433.7 6456.2 6795.2 6849.3 6869.5 6923.8 7020.8 6959.8 6964.5 6974.9 6988.0 6989.7 6374.7 6421.4 6452.5 6464.4 6480.4 7129.4 7197.1 7215.7 7239.9 7243.8 6802.4 6819.1 6878.8 6913.0 6974.9 6835.2 6906.0 6937.4 6999.6 7074.0 6877.2 6897.2 6899.8 6901.3 6907.9 6902.0 6920.6 6921.0 6926.3 6927.6 4961.3 4962.3 5016.5 5021.8 5023.5 4485.9 4501.4 4524.7 4542.2 4591.5 6338.1 6346.7 6349.9 6350.7 6363.2 6899.9 6936.9 6950.8 6966.1 6974.2 6335.8 6409.9 6419.9 6422.8 6434.7 5539.7 5550.6 5620.3 5621.3 5643.1 7090.9 7174.2 7215.7 7250.0 7273.3 6891.9 6910.9 6942.1 6990.3 6995.7 7198.7 7208.3 7210.6 7294.8 7301.3 6746.1 6827.0 6849.7 6941.8 6944.5 6965.9 7026.4 7032.0 7043.9 7052.8 6525.6 6649.5 6680.1 6688.7 6702.8 6637.9 6639.2 6670.7 6713.6 6739.4 6643.7 6671.0 6677.5 6679.4 6698.8 6704.7 6789.3 6805.3 6816.3 6832.5 6505.1 6550.6 6558.6 6568.9 6649.0 04:28:20 INFO - PID 15806 | 04:28:20 INFO - PID 15806 | [#1] ext.html Cycles:180 Average:44218.55 Median:38486.91 stddev:66369.31 (172.4%) stddev-sans-first:66475.91 04:28:20 INFO - PID 15806 | Values: 932.6 947.9 966.4 997.3 1001.3 43421.6 45556.5 45582.4 45651.1 45712.1 42449.6 42556.0 42656.4 42661.6 42685.4 7518.5 7642.7 7643.0 7644.5 7647.5 7429.0 7521.5 7526.0 7527.1 7531.3 7518.1 7543.2 8304.0 8470.5 8471.7 8386.2 8444.5 8452.4 8454.8 8459.1 8293.0 8387.0 8394.5 8395.3 8402.7 42704.4 42826.4 43499.6 44074.1 44207.3 3685.8 3707.1 3709.8 3711.6 3715.9 35051.3 35138.8 35177.6 35179.8 35185.1 5526.6 5585.5 5596.3 5596.6 5600.1 219647.0 221112.0 221383.0 221671.0 221934.9 52266.5 52288.7 52348.7 52403.7 52411.7 54557.7 54616.5 54642.7 54751.2 54789.0 15686.0 15957.4 16002.6 16052.8 16054.8 12885.6 12988.3 13009.3 13012.6 13029.3 14697.7 14911.7 14955.1 15067.3 15110.8 5714.7 5880.2 5882.3 5913.6 6025.9 1083.9 1091.1 1112.9 1122.2 1123.8 26115.6 26124.0 26143.5 26235.7 26359.7 1211.5 1225.0 1229.3 1237.7 1257.8 13666.6 13885.7 13964.4 13971.4 14033.4 282104.0 287381.0 287863.0 287911.0 288330.0 61459.7 63018.7 68084.7 68129.7 68237.7 1535.5 1541.1 1542.8 1545.7 1558.5 31513.5 31591.5 31788.0 32242.2 32498.8 44717.0 44732.6 44913.6 44969.0 44986.0 252229.0 253085.0 253375.0 253645.0 254011.0 39382.0 40562.6 40661.0 40729.0 40766.4 49971.0 50515.0 50546.7 50838.5 50935.0 36189.8 36798.3 36916.0 36941.6 37077.8 36720.8 36841.4 37092.0 37531.6 37591.8 43734.1 43838.1 43944.6 44212.1 44263.0 34766.3 34895.1 34937.5 35538.0 35586.5 26423.7 26449.2 26652.5 26880.9 26945.0 04:28:20 INFO - PID 15806 | 04:28:20 INFO - PID 15806 | [#2] jquery.html Cycles:180 Average:39688.89 Median:22341.22 stddev:100890.94 (451.6%) stddev-sans-first:101131.24 04:28:20 INFO - PID 15806 | Values: 586.3 592.0 601.7 617.1 618.6 23486.2 24149.6 24184.8 24201.0 24206.8 24611.0 25803.5 25978.7 25982.6 25989.9 6579.7 6721.9 6735.5 6739.3 6739.8 6250.3 6426.6 6457.5 6494.3 6554.5 6413.3 6440.7 6442.2 6444.9 6459.9 6231.1 6428.6 6434.9 6447.7 6458.1 5998.1 6014.4 6026.5 6030.4 6037.6 30158.4 30756.4 30785.2 30804.2 30821.7 2121.1 2142.3 2143.3 2146.5 2148.4 19998.0 21186.6 21259.7 21342.5 21357.9 2500.1 2503.4 2576.5 2586.5 2593.5 137929.0 146097.0 150600.0 153017.0 158368.4 15445.0 15731.8 15979.4 16017.8 16343.6 25047.6 25182.7 25195.5 25233.7 25281.5 10613.9 10818.8 10837.9 10865.7 10911.8 9832.0 10038.0 10051.8 10102.4 10145.9 9498.8 9617.0 9677.7 9737.9 9778.6 2709.3 2734.8 2794.7 2860.4 2900.4 1028.5 1046.4 1053.1 1056.8 1061.4 20275.6 20563.6 20611.7 21216.6 21217.8 1093.5 1111.9 1118.8 1131.9 1134.1 11318.8 11325.5 11590.9 11653.4 11677.1 573793.0 596987.0 612031.0 613365.0 619208.0 124243.4 127718.0 129020.7 130165.0 131088.0 1110.3 1133.3 1216.8 1265.5 1269.6 21699.5 22294.2 22388.2 22409.1 22444.6 59687.0 61469.7 61799.4 62419.4 63997.7 82136.6 82164.6 82233.0 82410.6 83737.0 15023.8 15110.7 15253.7 15360.9 15362.4 24482.4 24490.3 24736.0 24831.6 24842.3 19354.2 20172.2 20245.1 20327.8 20329.2 16981.2 17522.9 17639.8 17679.6 17839.4 20330.5 20617.9 20744.0 20797.8 20863.5 14940.3 14992.1 15090.5 15153.4 15166.2 18534.3 19423.9 19659.0 19661.8 19698.4 04:28:20 INFO - PID 15806 | 04:28:20 INFO - PID 15806 | [#3] mootools.html Cycles:180 Average:10335.42 Median:25269.25 stddev:7603.22 (30.1%) stddev-sans-first:7594.64 04:28:20 INFO - PID 15806 | Values: 1358.5 1421.4 1450.6 1473.4 1474.1 16325.3 17030.2 17039.7 17041.2 17046.7 16823.7 16939.8 16959.0 16965.7 16968.7 2797.5 2836.5 2882.1 2887.6 2891.2 2809.3 2825.4 2839.2 2844.0 2847.0 2685.7 2719.0 2925.0 2925.1 2925.4 2899.0 2903.4 2915.9 2929.4 2935.0 2767.3 2783.7 2784.7 2785.7 2795.7 16054.8 16371.8 16429.0 16430.6 16449.3 7997.2 8239.7 8243.6 8296.8 8302.8 5254.9 5405.5 5437.8 5535.5 5546.4 2023.2 2035.4 2054.8 2061.2 2081.1 29773.1 30058.1 30063.8 30069.2 30079.4 6687.9 6717.8 6724.9 6874.9 6891.2 18980.4 19042.1 19058.6 19107.9 19112.2 7861.3 7987.1 7990.1 7990.5 7993.6 7301.1 7321.6 7349.4 7375.4 7418.9 5411.7 5642.7 5642.8 5677.9 5724.2 1463.2 1486.2 1623.7 1650.3 1668.1 968.5 1006.6 1007.4 1019.1 1022.8 9756.3 9872.6 10051.7 10080.5 10185.8 777.9 789.8 799.6 807.6 866.5 5792.6 5958.2 6297.4 6341.1 6343.5 23614.8 25210.4 25257.4 25265.0 25273.5 23789.8 24407.0 24733.9 24767.4 24908.5 12217.3 12316.3 12547.1 12552.7 12735.6 9686.6 9691.3 9903.7 9912.7 9947.3 20554.3 20627.0 20734.7 21354.6 21534.9 21537.5 21608.8 21698.7 21703.9 21806.5 11425.6 11749.9 11795.1 11905.7 11911.0 15174.8 15282.2 15433.8 15434.5 15501.0 12225.7 12572.2 12676.6 12741.6 12846.2 12261.0 12343.4 12548.4 12663.5 12712.7 10324.8 10548.9 10581.2 10582.6 10590.2 8230.2 8369.6 8405.2 8415.9 8490.0 8563.7 8689.7 8695.1 8725.9 8782.1 04:28:20 INFO - PID 15806 | 04:28:20 INFO - PID 15806 | [#4] prototype.html Cycles:180 Average:5648.62 Median:6311.61 stddev:1590.72 (25.2%) stddev-sans-first:1548.15 04:28:20 INFO - PID 15806 | Values: 533.1 542.5 562.4 563.3 575.7 6352.3 6361.4 6393.4 6406.3 6406.7 6309.6 6328.2 6335.4 6345.4 6348.8 5070.1 5116.0 5119.5 5133.7 5144.6 5048.5 5072.8 5082.0 5092.7 5113.0 4789.0 4879.6 4976.1 4983.2 5076.7 4889.5 4893.4 4913.1 4944.9 4961.4 4422.3 4439.3 4443.8 4466.8 4475.0 5998.5 6013.2 6055.8 6061.8 6094.4 5697.3 5781.6 5783.9 5794.5 5866.6 6527.7 6567.9 6588.9 6647.7 6693.4 4792.0 4901.6 4998.1 5003.1 5015.7 6767.5 6790.9 6816.8 6817.8 6845.0 5122.5 5141.4 5151.0 5163.9 5180.3 5179.7 5220.5 5237.9 5240.4 5242.9 5733.9 5770.2 5771.4 5860.5 5892.7 6712.6 6730.3 6730.7 6735.8 6745.2 1818.1 1822.7 1823.8 1838.5 1845.1 1237.9 1278.3 1281.3 1287.1 1295.9 6237.5 6277.1 6281.5 6288.5 6305.9 6628.0 6693.8 6806.4 6864.3 6893.9 6140.4 6166.2 6195.8 6212.2 6284.8 3553.2 3568.5 3608.7 3608.9 3643.0 7003.3 7026.5 7055.8 7069.2 7074.1 6913.2 6929.1 6954.2 6995.0 7011.3 7055.5 7101.7 7103.1 7113.6 7122.8 6828.9 6831.7 6842.6 6877.4 6901.0 6717.4 6718.9 6723.6 6754.3 6755.5 7036.5 7056.9 7090.4 7094.8 7116.9 5181.6 5198.5 5202.5 5213.5 5216.2 6661.2 6674.8 6808.0 6879.2 6889.8 6517.6 6528.5 6541.1 6568.2 6574.3 6479.2 6537.7 6558.4 6564.5 6580.4 6313.6 6427.4 6480.5 6561.9 6571.5 6653.2 6701.9 6705.8 6711.4 6723.0 6467.5 6467.6 6479.4 6506.2 6546.6 04:28:20 INFO - PID 15806 | 04:28:20 INFO - PID 15806 | [#5] yui.html Cycles:180 Average:26828.12 Median:17557.75 stddev:39895.73 (227.2%) stddev-sans-first:39960.30 04:28:20 INFO - PID 15806 | Values: 942.1 949.3 960.2 964.5 988.2 16127.6 16168.8 16182.4 19160.7 19372.4 20219.9 20309.5 20789.9 21450.8 21652.2 1047.2 1064.4 1069.5 1070.8 1074.2 1050.7 1056.4 1056.7 1056.9 1057.2 1120.6 1127.3 1127.4 1128.5 1128.6 1101.2 1109.4 1112.2 1113.0 1113.1 815.8 817.5 818.6 819.2 820.4 16906.5 17230.5 17392.1 17480.6 17546.1 22338.4 22354.8 22420.7 22523.8 22839.3 16944.7 17273.6 17394.2 17450.7 17921.6 3858.1 3895.8 3896.6 3896.8 3897.5 143880.0 145127.3 146009.8 146621.0 147468.0 14740.9 14746.6 14834.0 14912.0 14946.8 25368.9 25644.0 25649.1 25663.6 25826.2 8898.2 9055.4 9098.7 9101.1 9107.5 4284.5 4355.0 4364.0 4378.9 4380.6 5689.8 5778.0 5790.1 5840.6 5860.5 958.2 970.3 988.8 989.8 995.8 645.7 700.0 701.3 717.2 726.5 13533.2 13643.0 13843.2 13976.7 14010.5 707.6 711.2 711.4 711.5 724.5 6236.6 6313.1 6326.4 6361.7 6462.9 140417.0 141192.0 143413.0 143438.0 149659.3 120195.4 123789.0 126005.4 127201.0 130488.0 106207.5 108396.0 110166.0 110846.0 111715.9 12226.7 12287.6 12518.9 12674.9 12706.7 52544.0 52644.0 52927.0 54469.0 54626.7 70922.6 71296.0 71631.0 71706.0 73629.0 16696.0 17163.9 17274.8 17508.7 17524.6 18185.8 18301.5 18421.4 18460.0 18556.5 17669.6 17784.7 17840.9 17872.2 17965.3 17149.0 17440.0 17485.6 17544.7 17569.4 17294.1 17375.3 17419.7 17478.6 17499.7 13320.7 13345.4 13419.7 13661.2 13769.7 13975.2 14007.2 14088.9 14184.9 14201.6 04:28:20 INFO - PID 15806 | -------- Summary: end -------- 04:28:20 INFO - PID 15806 | 04:28:20 INFO - TEST-INFO | 15806: exit 0 04:28:20 INFO - TEST-OK | dromaeo_css | took 1108646ms 04:28:20 INFO - TEST-START | kraken 04:28:20 INFO - Initialising browser for kraken test... 04:28:20 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54178/getInfo.html -profile /tmp/tmp653l0S/profile 04:28:20 INFO - TEST-INFO | started process 16192 (/builds/slave/test/build/application/firefox/firefox http://localhost:54178/getInfo.html) 04:28:26 INFO - TEST-INFO | 16192: exit 0 04:28:26 INFO - Browser initialized. 04:28:26 INFO - Running cycle 1/1 for kraken test... 04:28:26 INFO - Using env: {'DISPLAY': ':0', 04:28:26 INFO - 'HOME': '/home/cltbld', 04:28:26 INFO - 'JSGC_DISABLE_POISONING': '1', 04:28:26 INFO - 'LANG': 'en_US.UTF-8', 04:28:26 INFO - 'LANGUAGE': 'en_US:en', 04:28:26 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:28:26 INFO - 'LOGNAME': 'cltbld', 04:28:26 INFO - 'MAIL': '/var/mail/cltbld', 04:28:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:28:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:28:26 INFO - 'MOZ_CRASHREPORTER': '1', 04:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:26 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:28:26 INFO - 'MOZ_NO_REMOTE': '1', 04:28:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:28:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:26 INFO - 'NO_EM_RESTART': '1', 04:28:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:26 INFO - 'PWD': '/builds/slave/test', 04:28:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:28:26 INFO - 'RUST_BACKTRACE': 'full', 04:28:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:28:26 INFO - 'SHELL': '/bin/bash', 04:28:26 INFO - 'SHLVL': '1', 04:28:26 INFO - 'TERM': 'linux', 04:28:26 INFO - 'TMOUT': '86400', 04:28:26 INFO - 'USER': 'cltbld', 04:28:26 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387', 04:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:26 INFO - '_': '/tools/buildbot/bin/python'} 04:28:26 INFO - TEST-INFO | started process 16390 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp653l0S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:28:27 INFO - PID 16390 | 04:28:27 INFO - PID 16390 | (/builds/slave/test/build/application/firefox/firefox:16442): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:28:27 INFO - PID 16390 | 04:28:38 INFO - PID 16390 | RSS: Main: 160841728 04:28:38 INFO - PID 16390 | 04:28:40 INFO - PID 16390 | Cycle 1(1): loaded http://localhost:54178/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54178/tests/kraken/driver.html?testName=audio-beat-detection) 04:28:41 INFO - PID 16390 | RSS: Main: 165249024 04:28:41 INFO - PID 16390 | 04:28:43 INFO - PID 16390 | Cycle 1(1): loaded http://localhost:54178/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54178/tests/kraken/driver.html?testName=audio-dft) 04:28:44 INFO - PID 16390 | RSS: Main: 166916096 04:28:44 INFO - PID 16390 | 04:28:47 INFO - PID 16390 | Cycle 1(1): loaded http://localhost:54178/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54178/tests/kraken/driver.html?testName=audio-fft) 04:28:48 INFO - PID 16390 | RSS: Main: 161902592 04:28:48 INFO - PID 16390 | 04:28:50 INFO - PID 16390 | Cycle 1(1): loaded http://localhost:54178/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54178/tests/kraken/driver.html?testName=audio-oscillator) 04:28:50 INFO - PID 16390 | RSS: Main: 163360768 04:28:50 INFO - PID 16390 | 04:28:52 INFO - PID 16390 | Cycle 1(1): loaded http://localhost:54178/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54178/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:28:53 INFO - PID 16390 | RSS: Main: 163250176 04:28:53 INFO - PID 16390 | 04:28:56 INFO - PID 16390 | Cycle 1(1): loaded http://localhost:54178/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54178/tests/kraken/driver.html?testName=imaging-darkroom) 04:28:57 INFO - PID 16390 | RSS: Main: 163463168 04:28:57 INFO - PID 16390 | 04:29:00 INFO - PID 16390 | Cycle 1(1): loaded http://localhost:54178/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54178/tests/kraken/driver.html?testName=imaging-desaturate) 04:29:01 INFO - PID 16390 | RSS: Main: 166109184 04:29:01 INFO - PID 16390 | 04:29:04 INFO - PID 16390 | Cycle 1(1): loaded http://localhost:54178/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54178/tests/kraken/driver.html?testName=json-parse-financial) 04:29:04 INFO - PID 16390 | RSS: Main: 166379520 04:29:04 INFO - PID 16390 | 04:29:06 INFO - PID 16390 | Cycle 1(1): loaded http://localhost:54178/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54178/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:29:06 INFO - PID 16390 | RSS: Main: 165892096 04:29:06 INFO - PID 16390 | 04:29:08 INFO - PID 16390 | Cycle 1(1): loaded http://localhost:54178/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54178/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:29:09 INFO - PID 16390 | RSS: Main: 166035456 04:29:09 INFO - PID 16390 | 04:29:11 INFO - PID 16390 | Cycle 1(1): loaded http://localhost:54178/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54178/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:29:11 INFO - PID 16390 | RSS: Main: 166354944 04:29:11 INFO - PID 16390 | 04:29:14 INFO - PID 16390 | Cycle 1(1): loaded http://localhost:54178/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54178/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:29:14 INFO - PID 16390 | RSS: Main: 166514688 04:29:14 INFO - PID 16390 | 04:29:17 INFO - PID 16390 | Cycle 1(1): loaded http://localhost:54178/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54178/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:29:18 INFO - PID 16390 | RSS: Main: 167038976 04:29:18 INFO - PID 16390 | 04:29:19 INFO - PID 16390 | Cycle 1(1): loaded http://localhost:54178/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54178/tests/kraken/driver.html?testName=ai-astar) 04:29:20 INFO - PID 16390 | RSS: Main: 168923136 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | __start_tp_report 04:29:20 INFO - PID 16390 | _x_x_mozilla_page_load 04:29:20 INFO - PID 16390 | _x_x_mozilla_page_load_details 04:29:20 INFO - PID 16390 | |i|pagename|runs| 04:29:20 INFO - PID 16390 | |0;ai-astar;106;106;106;105;106;106;105;108;105;105 04:29:20 INFO - PID 16390 | |1;audio-beat-detection;116;116;112;111;114;113;114;115;112;116 04:29:20 INFO - PID 16390 | |2;audio-dft;162;162;164;162;164;163;160;162;162;169 04:29:20 INFO - PID 16390 | |3;audio-fft;73;73;74;72;73;73;73;75;73;75 04:29:20 INFO - PID 16390 | |4;audio-oscillator;76;78;77;77;77;77;79;77;78;78 04:29:20 INFO - PID 16390 | |5;imaging-gaussian-blur;113;114;115;117;117;117;114;115;114;110 04:29:20 INFO - PID 16390 | |6;imaging-darkroom;149;149;149;150;150;150;149;151;150;150 04:29:20 INFO - PID 16390 | |7;imaging-desaturate;96;94;93;94;93;94;94;93;98;95 04:29:20 INFO - PID 16390 | |8;json-parse-financial;54;48;44;45;44;47;45;45;48;44 04:29:20 INFO - PID 16390 | |9;json-stringify-tinderbox;37;37;36;36;45;37;37;37;36;37 04:29:20 INFO - PID 16390 | |10;stanford-crypto-aes;76;74;75;73;73;70;71;73;71;71 04:29:20 INFO - PID 16390 | |11;stanford-crypto-ccm;108;108;105;110;110;111;115;120;109;108 04:29:20 INFO - PID 16390 | |12;stanford-crypto-pbkdf2;163;155;153;156;166;152;154;161;160;167 04:29:20 INFO - PID 16390 | |13;stanford-crypto-sha256-iterative;63;65;59;57;59;57;58;57;58;62 04:29:20 INFO - PID 16390 | __end_tp_report 04:29:20 INFO - PID 16390 | __start_cc_report 04:29:20 INFO - PID 16390 | _x_x_mozilla_cycle_collect,890 04:29:20 INFO - PID 16390 | __end_cc_report 04:29:20 INFO - PID 16390 | __startTimestamp1504956560175__endTimestamp 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | ------- Summary: start ------- 04:29:20 INFO - PID 16390 | Number of tests: 14 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | [#0] ai-astar Cycles:10 Average:105.80 Median:106.00 stddev:0.92 (0.9%) stddev-sans-first:0.97 04:29:20 INFO - PID 16390 | Values: 106.0 106.0 106.0 105.0 106.0 106.0 105.0 108.0 105.0 105.0 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | [#1] audio-beat-detection Cycles:10 Average:113.90 Median:114.50 stddev:1.85 (1.6%) stddev-sans-first:1.80 04:29:20 INFO - PID 16390 | Values: 116.0 116.0 112.0 111.0 114.0 113.0 114.0 115.0 112.0 116.0 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | [#2] audio-dft Cycles:10 Average:163.00 Median:162.50 stddev:2.40 (1.5%) stddev-sans-first:2.52 04:29:20 INFO - PID 16390 | Values: 162.0 162.0 164.0 162.0 164.0 163.0 160.0 162.0 162.0 169.0 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | [#3] audio-fft Cycles:10 Average:73.40 Median:73.00 stddev:0.97 (1.3%) stddev-sans-first:1.01 04:29:20 INFO - PID 16390 | Values: 73.0 73.0 74.0 72.0 73.0 73.0 73.0 75.0 73.0 75.0 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:77.50 stddev:0.84 (1.1%) stddev-sans-first:0.73 04:29:20 INFO - PID 16390 | Values: 76.0 78.0 77.0 77.0 77.0 77.0 79.0 77.0 78.0 78.0 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:115.00 stddev:2.17 (1.9%) stddev-sans-first:2.22 04:29:20 INFO - PID 16390 | Values: 113.0 114.0 115.0 117.0 117.0 117.0 114.0 115.0 114.0 110.0 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:150.00 stddev:0.67 (0.4%) stddev-sans-first:0.67 04:29:20 INFO - PID 16390 | Values: 149.0 149.0 149.0 150.0 150.0 150.0 149.0 151.0 150.0 150.0 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.00 stddev:1.58 (1.7%) stddev-sans-first:1.56 04:29:20 INFO - PID 16390 | Values: 96.0 94.0 93.0 94.0 93.0 94.0 94.0 93.0 98.0 95.0 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | [#8] json-parse-financial Cycles:10 Average:46.40 Median:46.00 stddev:3.10 (6.7%) stddev-sans-first:1.67 04:29:20 INFO - PID 16390 | Values: 54.0 48.0 44.0 45.0 44.0 47.0 45.0 45.0 48.0 44.0 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | [#9] json-stringify-tinderbox Cycles:10 Average:37.50 Median:37.00 stddev:2.68 (7.2%) stddev-sans-first:2.83 04:29:20 INFO - PID 16390 | Values: 37.0 37.0 36.0 36.0 45.0 37.0 37.0 37.0 36.0 37.0 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | [#10] stanford-crypto-aes Cycles:10 Average:72.70 Median:73.00 stddev:1.95 (2.7%) stddev-sans-first:1.66 04:29:20 INFO - PID 16390 | Values: 76.0 74.0 75.0 73.0 73.0 70.0 71.0 73.0 71.0 71.0 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | [#11] stanford-crypto-ccm Cycles:10 Average:110.40 Median:110.00 stddev:4.25 (3.9%) stddev-sans-first:4.42 04:29:20 INFO - PID 16390 | Values: 108.0 108.0 105.0 110.0 110.0 111.0 115.0 120.0 109.0 108.0 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.70 Median:160.50 stddev:5.46 (3.4%) stddev-sans-first:5.56 04:29:20 INFO - PID 16390 | Values: 163.0 155.0 153.0 156.0 166.0 152.0 154.0 161.0 160.0 167.0 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.50 Median:59.00 stddev:2.84 (4.8%) stddev-sans-first:2.71 04:29:20 INFO - PID 16390 | Values: 63.0 65.0 59.0 57.0 59.0 57.0 58.0 57.0 58.0 62.0 04:29:20 INFO - PID 16390 | -------- Summary: end -------- 04:29:20 INFO - PID 16390 | 04:29:20 INFO - PID 16390 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:29:20 INFO - TEST-INFO | 16390: exit 0 04:29:20 INFO - TEST-OK | kraken | took 59961ms 04:29:20 INFO - SUITE-END | took 1168s 04:29:21 INFO - Completed test suite (00:19:29) 04:29:21 INFO - javascript benchmark 04:29:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9044.188424217438, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3873.205992771482, 3875.2443273561657, 3891.0272431892026, 3919.3924941634054, 3921.27456420562, 6278.811635650926, 6279.730769989932, 6354.968225158874, 6407.391297826711, 6414.871702565946, 6380.04299355097, 6398.776042838502, 6404.839879003027, 6412.03819427085, 6428.646424446665, 5666.51834594058, 5745.36800951897, 5789.247397838275, 5797.826065218029, 5807.912881306784, 712.9513341242957, 5786.56600754943, 5817.854553636151, 5854.443827836349, 5869.794557190499, 5708.343540492834, 5722.54219662426, 5724.0, 5821.15593238978, 5836.270466191726, 5712.428757124296, 5718.914216286759, 5772.6536407815665, 5779.219805326269, 5791.18923350727, 5711.257536520226, 5739.426057394269, 5810.157527158581, 5811.476967072984, 5823.272090988627, 6357.300696923335, 6378.840528986766, 6380.776672816475, 6433.7426502939825, 6456.192975878015, 6795.2185498667795, 6849.280825513349, 6869.519133660646, 6923.792286231421, 7020.754273600451, 6959.756408525677, 6964.547304425196, 6974.860502789967, 6988.0, 6989.650517474106, 6374.681265936731, 6421.4220720134945, 6452.548321617489, 6464.353564643498, 6480.449161821298, 7129.358357747724, 7197.1363436387965, 7215.675294611755, 7239.855202895913, 7243.782686519413, 6802.353776391334, 6819.147606549182, 6878.759243426456, 6913.032175495435, 6974.930250697529, 6835.213950395669, 6905.965470172617, 6937.445004399636, 6999.615021173782, 7074.045003924507, 6877.243503214642, 6897.241303456616, 6899.793006209822, 6901.344372284625, 6907.896381554281, 6901.965490172617, 6920.619365934821, 6920.9653951729915, 6926.307369263033, 6927.618980956096, 4961.280614310905, 4962.255661650781, 5016.473270306566, 5021.84934451967, 5023.547880690755, 4485.865424037284, 4501.437320334958, 4524.728516289033, 4542.1824071667415, 4591.4949355571525, 6338.080978258126, 6346.650934198571, 6349.904751428732, 6350.650714210763, 6363.204599425072, 6899.862002759916, 6936.93063069373, 6950.79147625572, 6966.12923384577, 6974.197967233732, 6335.778247761306, 6409.903851442232, 6419.903701444482, 6422.775202867877, 6434.742610295536, 5539.723013849291, 5550.639208451437, 5620.325560932714, 5621.269234999424, 5643.097104463266, 7090.893636595455, 7174.210836807947, 7215.711371545184, 7250.0, 7273.3453989141235, 6891.862162756817, 6910.861782764317, 6942.06282151903, 6990.335918087773, 6995.685194166173, 7198.71205151788, 7208.279172082959, 7210.567365958059, 7294.781156565312, 7301.342879140902, 6746.089277947414, 6827.044213809972, 6849.657517124224, 6941.757038503628, 6944.51388402807, 6965.930340696528, 7026.437884969292, 7031.964840175971, 7043.859122817617, 7052.788416347517, 6525.608463492205, 6649.501287403367, 6680.0647909291765, 6688.6990085445295, 6702.798916032527, 6637.867242655217, 6639.203295604559, 6670.699818508082, 6713.597184168965, 6739.42714869222, 6643.70103345341, 6671.0, 6677.532572720057, 6679.365460281266, 6698.83252918681, 6704.664766761545, 6789.253182149861, 6805.251422343439, 6816.250212476524, 6832.487563432663, 6505.089287499848, 6550.639714815731, 6558.606483610999, 6568.868622627616, 6649.0], "value": 6289.452595162754, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [932.6083045121051, 947.9146876781089, 966.4153187321672, 997.2670087485701, 1001.3291094966372, 43421.56578434215, 45556.544434555726, 45582.40461583841, 45651.08697826041, 45712.08575828481, 42449.575504244945, 42556.0, 42656.36015459771, 42661.57338426615, 42685.35971960415, 7518.548887066766, 7642.694292228304, 7643.0, 7644.5031072980355, 7647.50291231071, 7428.962855185743, 7521.548707077593, 7526.021617189757, 7527.0967483902, 7531.3221810037085, 7518.135414427356, 7543.245675432468, 8303.95848020759, 8470.534120623362, 8471.661133554651, 8386.245237928586, 8444.493330400164, 8452.408331416804, 8454.788630284262, 8459.11179326168, 8293.0, 8387.0, 8394.538300393477, 8395.328373730148, 8402.747917562483, 42704.359434608195, 42826.35760463595, 43499.56500435018, 44074.11851762947, 44207.33688994667, 3685.834137463794, 3707.1102935295603, 3709.7959612221316, 3711.573169085536, 3715.925681486382, 35051.29897402038, 35138.824305878305, 35177.648223517695, 35179.82410087959, 35185.1203719909, 5526.613137080406, 5585.525230355424, 5596.3284405871555, 5596.552275817925, 5600.131979543177, 219647.0, 221112.0, 221383.0, 221671.0, 221934.89032554894, 52266.47733522692, 52288.73855630698, 52348.73825630924, 52403.73798131061, 52411.73794131005, 54557.72721136448, 54616.453835461136, 54642.726786366606, 54751.178732319044, 54789.0, 15686.0, 15957.361705531881, 16002.599935001484, 16052.839471605135, 16054.839451605334, 12885.61343159688, 12988.285644289655, 13009.284489352987, 13012.609621711175, 13029.348532573333, 14697.706045879237, 14911.701765964837, 14955.102693838404, 15067.321970511353, 15110.773338399933, 5714.7428365723645, 5880.235569375954, 5882.264716910387, 5913.556483263773, 6025.939740602538, 1083.853679753239, 1091.1161958813386, 1112.9499172537255, 1122.180808010157, 1123.8201887698092, 26115.608265876028, 26124.0, 26143.477130457286, 26235.737642623328, 26359.73640263611, 1211.4548453196096, 1224.9510019599115, 1229.33001514175, 1237.696764292754, 1257.7673130470891, 13666.590002299947, 13885.722285554233, 13964.441422343196, 13971.441142354395, 14033.368498417596, 282104.0, 287381.0, 287863.0, 287911.0, 288330.0, 61459.69270153621, 63018.68490657609, 68084.6595767018, 68129.65935170293, 68237.65881170661, 1535.4684226279512, 1541.083055581927, 1542.8071491063617, 1545.6831349573536, 1558.5012795905427, 31513.527297090102, 31591.526127107652, 31788.0, 32242.193945150622, 32498.83750581232, 44717.0, 44732.55267447284, 44913.550864490935, 44969.0, 44986.0, 252229.0, 253085.0, 253375.0, 253645.0, 254011.0, 39382.0, 40562.59437405588, 40661.0, 40729.0, 40766.388504173054, 49971.0, 50515.0, 50546.74726626343, 50838.491615083374, 50935.0, 36189.81905090563, 36798.286041702144, 36916.0, 36941.630583694896, 37077.81461092785, 36720.81639591785, 36841.447378289886, 37092.0, 37531.624683752816, 37591.81204093962, 43734.12531749411, 43838.12323753571, 43944.56055439533, 44212.11575768531, 44263.0, 34766.30467390588, 34895.12762180966, 34937.47593786146, 35538.0, 35586.46620300749, 26423.735762642897, 26449.206523804318, 26652.466950661266, 26880.865595671898, 26945.0], "value": 19113.663506378034, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.3374386942754, 591.9852003699909, 601.7262145723696, 617.1452538234547, 618.6411881108957, 23486.177983770573, 24149.63775543364, 24184.75815241847, 24201.0, 24206.757932420693, 24611.0, 25803.48393032138, 25978.74021259787, 25982.610260846104, 25989.870050649766, 6579.7039133238995, 6721.932780672179, 6735.528513004092, 6739.326067393246, 6739.831504212385, 6250.312465628777, 6426.646534440604, 6457.45111665509, 6494.285628580852, 6554.508411869105, 6413.262474815409, 6440.742370305182, 6442.194725659293, 6444.871102577946, 6459.935400645981, 6231.127642130105, 6428.582142160792, 6434.871302573945, 6447.7098530566, 6458.128152699373, 5998.070299103646, 6014.368491308437, 6026.548008899306, 6030.396960303979, 6037.607555508878, 30158.396832063456, 30756.384872302653, 30785.230369240726, 30804.2298942526, 30821.69178308233, 2121.077331360863, 2142.2930432957087, 2143.2820005298245, 2146.4848436375314, 2148.4414052346347, 19998.0, 21186.5762684747, 21259.68110478344, 21342.466438339165, 21357.893210533846, 2500.1499490173246, 2503.3616427810975, 2576.497582971321, 2586.4697736963876, 2593.5072336256053, 137929.0, 146097.0, 150600.0, 153017.0, 158368.4163158365, 15445.0, 15731.764023539656, 15979.360825567079, 16017.839821601869, 16343.591410214602, 25047.62428563573, 25182.748172518408, 25195.496090078097, 25233.747662523507, 25281.494370112494, 10613.893861061291, 10818.837717434244, 10837.891621083845, 10865.728356791144, 10911.781764364827, 9832.0, 10038.0, 10051.798964020825, 10102.444365559964, 10145.898541014643, 9498.762530936643, 9616.95191524038, 9677.70966870981, 9737.853932191023, 9778.559964801601, 2709.282040259333, 2734.767544758678, 2794.7345002224756, 2860.427914417125, 2900.4344152889985, 1028.547439126782, 1046.3721766939775, 1053.0943388685723, 1056.75166335911, 1061.368485750977, 20275.618092059463, 20563.588728225353, 20611.71674377255, 21216.575668486545, 21217.78782212158, 1093.5297821936645, 1111.8776934537195, 1118.8433619293305, 1131.9207655464204, 1134.0813940708053, 11318.830217546743, 11325.490352934137, 11590.884091159149, 11653.41732913351, 11677.124215683858, 573793.0, 596987.0, 612031.0, 613365.0, 619208.0, 124243.37878310731, 127718.0, 129020.70979290275, 130165.0, 131088.0, 1110.2672236323988, 1133.3483247132767, 1216.8053111502118, 1265.5001274496522, 1269.6444995401487, 21699.457513562287, 22294.21970231034, 22388.21641242549, 22409.103635854382, 22444.5511089774, 59687.0, 61469.692651536454, 61799.382006181164, 62419.37580624317, 63997.680011599645, 82136.58931705303, 82164.58917705613, 82233.0, 82410.58794705987, 83737.0, 15023.849761502683, 15110.697786044437, 15253.694926101638, 15360.923195383952, 15362.38550458014, 24482.387940301636, 24490.265292041266, 24736.0, 24831.627525587493, 24842.25473235806, 19354.225830966603, 20172.19311227593, 20245.088970996047, 20327.79672203259, 20329.186832526535, 16981.15094245258, 17522.91238543799, 17639.823601764332, 17679.558011049314, 17839.375621853174, 20330.491737706674, 20617.896910515352, 20744.0, 20797.792022079586, 20863.47841303919, 14940.327685254408, 14992.100473971597, 15090.54728358151, 15153.393864245307, 15166.24168791578, 18534.25862965466, 19423.90288048607, 19659.0, 19661.803381965998, 19698.40904772859], "value": 12724.94687168822, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.4973559782882, 1421.3959067396354, 1450.6010847017076, 1473.3517252408933, 1474.0787008119926, 16325.346986120556, 17030.23363948622, 17039.659206815857, 17041.233144508496, 17046.659066818655, 16823.747643785355, 16939.83060169398, 16959.0, 16965.745513817303, 16968.74546881798, 2797.4684809886157, 2836.475252078367, 2882.0777351247607, 2887.56686497025, 2891.176014835772, 2809.3117186289387, 2825.420788738314, 2839.205022593677, 2844.0, 2846.985765071172, 2685.677718673762, 2719.02115238514, 2925.0, 2925.078600240928, 2925.400292939942, 2899.043315705812, 2903.433830403072, 2915.9125226243245, 2929.3555417808047, 2935.0461100142434, 2767.349672826884, 2783.707710690389, 2784.7076057013924, 2785.749282564579, 2795.73440523152, 16054.83945160545, 16371.754423683535, 16429.0, 16430.589235269093, 16449.25978330966, 7997.1602981686665, 8239.670413183465, 8243.587820609006, 8296.83406331876, 8302.750917472486, 5254.921176182322, 5405.459454054602, 5437.8368648940595, 5535.501804837584, 5546.417626149277, 2023.2412845183057, 2035.369035598969, 2054.8047935446107, 2061.165228082629, 2081.08432289792, 29773.10680679583, 30058.098257052323, 30063.849680751457, 30069.248268793235, 30079.398412031638, 6687.866242675119, 6717.798466046026, 6724.865502690017, 6874.931250687529, 6891.207511136183, 18980.43058708241, 19042.143103560375, 19058.61882762337, 19107.90446047761, 19112.2355105797, 7861.2531809478005, 7987.121416644164, 7990.081140668781, 7990.520568765893, 7993.560354180576, 7301.050863387725, 7321.63391830417, 7349.375303099189, 7375.44684148691, 7418.851622967511, 5411.675299482044, 5642.6614403135945, 5642.774289028475, 5677.914831277534, 5724.227229324071, 1463.2244910197612, 1486.2388070136922, 1623.7320842061165, 1650.3233674193523, 1668.0742188085728, 968.4915419404728, 1006.582268358623, 1007.4408703169664, 1019.1337363241186, 1022.8261195596617, 9756.268279879037, 9872.570215903294, 10051.698449046395, 10080.546375413123, 10185.847212291821, 777.859985202669, 789.8380831929555, 799.5842162075688, 807.6163822184416, 866.5234121233296, 5792.565941267073, 5958.195643588147, 6297.434360088991, 6341.143945567382, 6343.492520598341, 23614.763852361255, 25210.36974075663, 25257.368565785633, 25265.0, 25273.494530109663, 23789.7621023791, 24407.0, 24733.87633061823, 24767.380815479395, 24908.501829963297, 12217.266963982189, 12316.261024338568, 12547.058970577244, 12552.748945021049, 12735.55425560101, 9686.564104615169, 9691.2731545133, 9903.653372131941, 9912.653057142965, 9947.303688741718, 20554.28060017892, 20627.0, 20734.688979665014, 21354.57290854143, 21534.892325538898, 21537.461563461038, 21608.783912161307, 21698.674519881897, 21703.891480542497, 21806.45483862916, 11425.60010399632, 11749.941250293694, 11795.11536634773, 11905.702357441134, 11911.0, 15174.772378414553, 15282.235888205323, 15433.768493472382, 15434.45979390716, 15500.992435491657, 12225.69435764113, 12572.245665259748, 12676.619701408972, 12741.617751467471, 12846.229226246081, 12261.0, 12343.444544995677, 12548.43532041042, 12663.493460261487, 12712.74574508523, 10324.845127322946, 10548.947255263674, 10581.206409519471, 10582.629607963685, 10590.152787777113, 8230.176982301722, 8369.581520924052, 8405.15948405179, 8415.8737618937, 8490.0, 8563.743087707378, 8689.695860644848, 8695.130486951255, 8725.869111963453, 8782.121787821166], "value": 7232.456719896683, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [533.115029051774, 542.5442628192318, 562.3673367461606, 563.3296377311, 575.6718670357898, 6352.269489008765, 6361.363863613637, 6393.433608765848, 6406.263279722822, 6406.679666016698, 6309.62142271464, 6328.208973878265, 6335.366463353674, 6345.397187267202, 6348.777792777254, 5070.062038522867, 5115.9512299978605, 5119.5136462036235, 5133.666311689747, 5144.588432925367, 5048.545630893219, 5072.807890145813, 5082.009008243394, 5092.747555790919, 5112.97443512782, 4788.97605511972, 4879.560839524442, 4976.054549635575, 4983.177775667029, 5076.746162691869, 4889.535494128052, 4893.437254715717, 4913.14020046494, 4944.936838579704, 4961.429435614915, 4422.314541246112, 4439.311906654474, 4443.822247110112, 4466.82132714691, 4474.955250447487, 5998.460138587505, 6013.218281623361, 6055.788047418319, 6061.757529698807, 6094.421030002142, 5697.316322041382, 5781.566382521286, 5783.8554036149435, 5794.5074668653215, 5866.618669786493, 6527.673616319213, 6567.901481477782, 6588.945768676988, 6647.734090636369, 6693.431058360045, 4792.0, 4901.632377571696, 4998.12532806761, 5003.099442100421, 5015.749212539358, 6767.4924380671155, 6790.891510636044, 6816.7614133505585, 6817.761378351734, 6844.965775171142, 5122.487751224922, 5141.434442211354, 5150.974245128826, 5163.870903227449, 5180.274761533388, 5179.689218646893, 5220.504052115043, 5237.9476205238225, 5240.449752775904, 5242.868928276824, 5733.88532229353, 5770.2498675171955, 5771.422857714279, 5860.472557469848, 5892.734826932712, 6712.563683360566, 6730.27423893324, 6730.697118629673, 6735.797926062226, 6745.156855393076, 1818.1363852170114, 1822.6719190545564, 1823.7629108215847, 1838.512794109562, 1845.0959030075435, 1237.8638349781518, 1278.341654048166, 1281.3342654549049, 1287.1183239480986, 1295.9287239201747, 6237.532185086136, 6277.121203031579, 6281.528885333618, 6288.496920246369, 6305.873882522324, 6627.966860165669, 6693.832654183684, 6806.353396427331, 6864.313568643096, 6893.862122757517, 6140.416660417253, 6166.1983942087245, 6195.75590720358, 6212.192414986023, 6284.780032698834, 3553.218291975761, 3568.5360903082433, 3608.736942070307, 3608.9278214435562, 3643.0163855758797, 7003.26465721092, 7026.543274687231, 7055.823604409931, 7069.187043489962, 7074.05463497532, 6913.170419549686, 6929.099217101744, 6954.191910103409, 6994.965025174842, 7011.29887011305, 7055.541389809646, 7101.715931362688, 7103.112110986127, 7113.57318560888, 7122.7507037253445, 6828.931710682829, 6831.658417079226, 6842.55523390978, 6877.415419689382, 6900.965495172492, 6717.361850624183, 6718.899216511659, 6723.562968407038, 6754.2570317266045, 6755.527113102232, 7036.472264580075, 7056.918209128422, 7090.432765378654, 7094.787156385317, 7116.8932466014085, 5181.590560389437, 5198.532132108128, 5202.505761952609, 5213.504717051874, 5216.243644671577, 6661.233958094881, 6674.766383176566, 6807.965960170365, 6879.174499060145, 6889.758858440131, 6517.576357536745, 6528.543001990005, 6541.149650545399, 6568.211814582281, 6574.342565743387, 6479.177285401635, 6537.738490460519, 6558.376344677086, 6564.5076619254705, 6580.440662543738, 6313.589616674902, 6427.421532062137, 6480.481561474976, 6561.934380656323, 6571.5399922005, 6653.2348779889, 6701.932980670131, 6705.8264803658185, 6711.395974362135, 6722.983105068362, 6467.547271690936, 6467.611943283418, 6479.416852483299, 6506.154199953976, 6546.559756853485], "value": 5189.340406917395, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [942.0626476655729, 949.3164921256692, 960.1550635440811, 964.4936408385597, 988.2143695761869, 16127.596810079755, 16168.83831161688, 16182.352705891764, 19160.712589311137, 19372.418827435165, 20219.898900505512, 20309.49226269344, 20789.896050519725, 21450.785492145074, 21652.242171524, 1047.1988928469727, 1064.3613831700957, 1069.5294070608945, 1070.812607793637, 1074.156786922266, 1050.7373156710823, 1056.392574269798, 1056.7463808685936, 1056.8943105689445, 1057.2493529593999, 1120.63019203663, 1127.2898074213235, 1127.413744852676, 1128.4527004402858, 1128.6331942118802, 1101.1961268274165, 1109.3953795181606, 1112.232559533919, 1113.0427832064418, 1113.092829344082, 815.804206990318, 817.5421763812284, 818.6357071103362, 819.19309480162, 820.3765138494728, 16906.492805215865, 17230.483085507454, 17392.130393480402, 17480.562985925328, 17546.122693865254, 22338.441538961495, 22354.776452235434, 22420.6636900445, 22523.77476225233, 22839.31482055541, 16944.729145314028, 17273.568160795956, 17394.21726022332, 17450.738238926428, 17921.551961200945, 3858.131920317934, 3895.844166233347, 3896.610338966109, 3896.7661940283674, 3897.4543563900793, 143880.0, 145127.27436362856, 146009.80985285336, 146621.0, 147468.0, 14740.926295368454, 14746.557603271918, 14834.0, 14912.0, 14946.775798363033, 25368.873155634104, 25644.0, 25649.102281420062, 25663.615045774328, 25826.225213243633, 8898.243649289754, 9055.36612437123, 9098.681546145854, 9101.089891010846, 9107.453552786721, 4284.485861696617, 4355.0, 4363.956360436418, 4378.912421751547, 4380.605745482858, 5689.77240910359, 5778.0, 5790.10253410722, 5840.620359676607, 5860.501857342088, 958.2142643032646, 970.2529052629436, 988.8071825993862, 989.7921436498272, 995.7709726762885, 645.6642545876213, 699.9545029573061, 701.3442431326725, 717.2110678253879, 726.5096060159372, 13533.226497326557, 13643.0, 13843.16940983524, 13976.720465590632, 14010.509632162826, 707.5931339479716, 711.1856923822195, 711.3562226185311, 711.5303899426353, 724.4602770935687, 6236.6258024518675, 6313.084602732578, 6326.367363263636, 6361.713722882481, 6462.903056454157, 140417.0, 141192.0, 143413.0, 143438.0, 149659.25170374295, 120195.39902300433, 123789.0, 126005.36997314954, 127201.0, 130488.0, 106207.46896265469, 108396.0, 110166.0, 110846.0, 111715.88284117218, 12226.694332641398, 12287.631371058882, 12518.937405312916, 12674.936625316814, 12706.72932706722, 52544.0, 52644.0, 52927.0, 54469.0, 54626.726866367004, 70922.64538677274, 71296.0, 71631.0, 71706.0, 73629.0, 16696.0, 17163.91418042902, 17274.82725172732, 17508.737368939732, 17524.649507009533, 18185.818141818414, 18301.54246143857, 18421.35525256663, 18460.0, 18556.536086597942, 17669.64660706753, 17784.733229001835, 17840.91079544646, 17872.195751191488, 17965.281388744304, 17149.0, 17440.040797755737, 17485.562860928578, 17544.73682894732, 17569.385071522436, 17294.135293235035, 17375.304987800348, 17419.738703919196, 17478.563035924202, 17499.737503937708, 13320.666983325495, 13345.399457024263, 13419.731605368033, 13661.248631325372, 13769.655758606115, 13975.231362275172, 14007.229602371968, 14088.859111408754, 14184.858151418353, 14201.644958876112], "value": 9531.906285732437, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1377.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 106.0, 106.0, 105.0, 106.0, 106.0, 105.0, 108.0, 105.0, 105.0], "value": 105.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 112.0, 111.0, 114.0, 113.0, 114.0, 115.0, 112.0, 116.0], "value": 113.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 164.0, 162.0, 164.0, 163.0, 160.0, 162.0, 162.0, 169.0], "value": 163.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 74.0, 72.0, 73.0, 73.0, 73.0, 75.0, 73.0, 75.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 77.0, 77.0, 77.0, 77.0, 79.0, 77.0, 78.0, 78.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 115.0, 117.0, 117.0, 117.0, 114.0, 115.0, 114.0, 110.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 150.0, 150.0, 150.0, 149.0, 151.0, 150.0, 150.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 93.0, 94.0, 93.0, 94.0, 94.0, 93.0, 98.0, 95.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 48.0, 44.0, 45.0, 44.0, 47.0, 45.0, 45.0, 48.0, 44.0], "value": 46.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 36.0, 36.0, 45.0, 37.0, 37.0, 37.0, 36.0, 37.0], "value": 37.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 75.0, 73.0, 73.0, 70.0, 71.0, 73.0, 71.0, 71.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 108.0, 105.0, 110.0, 110.0, 111.0, 115.0, 120.0, 109.0, 108.0], "value": 110.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 155.0, 153.0, 156.0, 166.0, 152.0, 154.0, 161.0, 160.0, 167.0], "value": 158.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 59.0, 57.0, 59.0, 57.0, 58.0, 57.0, 58.0, 62.0], "value": 59.5, "unit": "score"}]}]} 04:29:21 INFO - Return code: 0 04:29:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:29:21 INFO - # TBPL SUCCESS # 04:29:21 INFO - Running post-action listener: _package_coverage_data 04:29:21 INFO - Running post-action listener: _resource_record_post_action 04:29:21 INFO - [mozharness: 2017-09-09 11:29:21.456112Z] Finished run-tests step (success) 04:29:21 INFO - Running post-run listener: _resource_record_post_run 04:29:21 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 180224; Write bytes: 299372544; Read time: 56; Write time: 152152 04:29:21 INFO - TinderboxPrint: CPU usage
13.0% 04:29:21 INFO - TinderboxPrint: I/O read bytes / time
180,224 / 56 04:29:21 INFO - TinderboxPrint: I/O write bytes / time
299,372,544 / 152,152 04:29:21 INFO - TinderboxPrint: CPU idle
8,221.5 (86.9%) 04:29:21 INFO - TinderboxPrint: CPU user
1,184.9 (12.5%) 04:29:21 INFO - TinderboxPrint: Swap in / out
0 / 0 04:29:21 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 6463488; Read time: 0; Write time: 1308 04:29:21 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:29:21 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 180224; Write bytes: 286044160; Read time: 56; Write time: 150296 04:29:22 INFO - Running post-run listener: _upload_blobber_files 04:29:22 INFO - Blob upload gear active. 04:29:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:29:22 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 04:29:22 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'] 04:29:22 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 04:29:22 INFO - (blobuploader) - INFO - Open directory for files ... 04:29:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:29:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:29:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:23 INFO - (blobuploader) - INFO - Done attempting. 04:29:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:29:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:29:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:30 INFO - (blobuploader) - INFO - Done attempting. 04:29:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:29:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:29:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:31 INFO - (blobuploader) - INFO - Done attempting. 04:29:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:29:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:29:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:32 INFO - (blobuploader) - INFO - Done attempting. 04:29:32 INFO - (blobuploader) - INFO - Iteration through files over. 04:29:32 INFO - Return code: 0 04:29:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:29:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:29:32 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f0c2ecee371cf0577e0db398dd7dbff7d8cb79b63d4ce262772dd9affe6d250b75cb020de7522d539bdff7f9117fdc9b445bf32bafcdfe7ee5d00b44fd4562f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34dafeb250dd5f10a8b7f12bff27c7ba4616627e1f7fc1df0934dd05deb042c105c633aed3547882897a8ec1e9bf2bdcfba540780cbbdd5ca87321e4943bec48", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7dc4a3b94c21016f8ed6b128a3b19d1325acd93876d226b20db8fcc1f10e31bd7f9b669947563bbcb65922008919631b5bd153ff08ebacc2662f388fdab446b4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00fe882a958c79825643e91fd316b89a46f129257f28c5aec184a6b56bbf5ce0e2ae7ca2bf85926b83f43866c32c50ca4de6b88efa620ad8298528ee8f536644"} 04:29:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:29:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:29:32 INFO - Contents: 04:29:32 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f0c2ecee371cf0577e0db398dd7dbff7d8cb79b63d4ce262772dd9affe6d250b75cb020de7522d539bdff7f9117fdc9b445bf32bafcdfe7ee5d00b44fd4562f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34dafeb250dd5f10a8b7f12bff27c7ba4616627e1f7fc1df0934dd05deb042c105c633aed3547882897a8ec1e9bf2bdcfba540780cbbdd5ca87321e4943bec48", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7dc4a3b94c21016f8ed6b128a3b19d1325acd93876d226b20db8fcc1f10e31bd7f9b669947563bbcb65922008919631b5bd153ff08ebacc2662f388fdab446b4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00fe882a958c79825643e91fd316b89a46f129257f28c5aec184a6b56bbf5ce0e2ae7ca2bf85926b83f43866c32c50ca4de6b88efa620ad8298528ee8f536644"} 04:29:32 INFO - Running post-run listener: copy_logs_to_upload_dir 04:29:32 INFO - Copying logs to upload dir... 04:29:32 INFO - mkdir: /builds/slave/test/build/upload/logs 04:29:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.162581 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-09 04:29:32.456398) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-09 04:29:32.461399) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387 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/0f0c2ecee371cf0577e0db398dd7dbff7d8cb79b63d4ce262772dd9affe6d250b75cb020de7522d539bdff7f9117fdc9b445bf32bafcdfe7ee5d00b44fd4562f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34dafeb250dd5f10a8b7f12bff27c7ba4616627e1f7fc1df0934dd05deb042c105c633aed3547882897a8ec1e9bf2bdcfba540780cbbdd5ca87321e4943bec48", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7dc4a3b94c21016f8ed6b128a3b19d1325acd93876d226b20db8fcc1f10e31bd7f9b669947563bbcb65922008919631b5bd153ff08ebacc2662f388fdab446b4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00fe882a958c79825643e91fd316b89a46f129257f28c5aec184a6b56bbf5ce0e2ae7ca2bf85926b83f43866c32c50ca4de6b88efa620ad8298528ee8f536644"} build_url:https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011464 build_url: 'https://queue.taskcluster.net/v1/task/XvLPM4gBSoCLFs5hXh0goQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f0c2ecee371cf0577e0db398dd7dbff7d8cb79b63d4ce262772dd9affe6d250b75cb020de7522d539bdff7f9117fdc9b445bf32bafcdfe7ee5d00b44fd4562f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/34dafeb250dd5f10a8b7f12bff27c7ba4616627e1f7fc1df0934dd05deb042c105c633aed3547882897a8ec1e9bf2bdcfba540780cbbdd5ca87321e4943bec48", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7dc4a3b94c21016f8ed6b128a3b19d1325acd93876d226b20db8fcc1f10e31bd7f9b669947563bbcb65922008919631b5bd153ff08ebacc2662f388fdab446b4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/00fe882a958c79825643e91fd316b89a46f129257f28c5aec184a6b56bbf5ce0e2ae7ca2bf85926b83f43866c32c50ca4de6b88efa620ad8298528ee8f536644"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-09 04:29:32.496616) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-09 04:29:32.496969) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1504954888.231752-875516387 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004513 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-09 04:29:32.579899) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-09 04:29:32.580227) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-09 04:29:32.580558) ========= ========= Total master_lag: 0.49 =========