builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-011 starttime: 1506225021.18 results: success (0) revision: ff40c5dcaa41261b39a4e9795e02a9d51dd30ced ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 20:50:21.178489) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 20:50:21.178910) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 20:50:21.179182) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 20:50:21.299774) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:50:21.300031) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-23 20:50:21-- 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% 36.6M=0s 2017-09-23 20:50:21 (36.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121272 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:50:21.469835) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:50:21.470108) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022024 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:50:21.559414) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:50:21.561764) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ff40c5dcaa41261b39a4e9795e02a9d51dd30ced --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 ff40c5dcaa41261b39a4e9795e02a9d51dd30ced --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=5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-23 20:50:21,698 truncating revision to first 12 chars 2017-09-23 20:50:21,698 Setting DEBUG logging. 2017-09-23 20:50:21,698 attempt 1/10 2017-09-23 20:50:21,698 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ff40c5dcaa41?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-23 20:50:22,050 unpacking tar archive at: mozilla-central-ff40c5dcaa41/testing/mozharness/ program finished with exit code 0 elapsedTime=0.745593 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:50:22.328634) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 20:50:22.328922) ========= script_repo_revision: ff40c5dcaa41261b39a4e9795e02a9d51dd30ced ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 20:50:22.329259) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 20:50:22.329541) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 20:50:22.363104) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-23 20:50:22.363354) ========= /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=5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:50:22 INFO - MultiFileLogger online at 20170923 20:50:22 in /builds/slave/test 20:50:22 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 20:50:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:50:22 INFO - {'append_to_log': False, 20:50:22 INFO - 'base_work_dir': '/builds/slave/test', 20:50:22 INFO - 'blob_upload_branch': 'Firefox', 20:50:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:50:22 INFO - 'branch': 'Firefox', 20:50:22 INFO - 'buildbot_json_path': 'buildprops.json', 20:50:22 INFO - 'code_coverage': False, 20:50:22 INFO - 'config_files': ('talos/linux_config.py',), 20:50:22 INFO - 'default_actions': ('clobber', 20:50:22 INFO - 'read-buildbot-config', 20:50:22 INFO - 'download-and-extract', 20:50:22 INFO - 'populate-webroot', 20:50:22 INFO - 'create-virtualenv', 20:50:22 INFO - 'install', 20:50:22 INFO - 'setup-mitmproxy', 20:50:22 INFO - 'run-tests'), 20:50:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:50:22 INFO - 'disable_ccov_upload': False, 20:50:22 INFO - 'disable_stylo': False, 20:50:22 INFO - 'download_minidump_stackwalk': True, 20:50:22 INFO - 'download_symbols': 'ondemand', 20:50:22 INFO - 'enable_stylo': False, 20:50:22 INFO - 'enable_webrender': False, 20:50:22 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 20:50:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:50:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:50:22 INFO - 'gecko_profile': False, 20:50:22 INFO - 'gecko_profile_interval': 0, 20:50:22 INFO - 'installer_path': 'installer.exe', 20:50:22 INFO - 'log_level': 'info', 20:50:22 INFO - 'log_name': 'talos', 20:50:22 INFO - 'log_to_console': True, 20:50:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:50:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:50:22 INFO - 'opt_config_files': (), 20:50:22 INFO - 'pip_index': False, 20:50:22 INFO - 'suite': 'dromaeojs-e10s', 20:50:22 INFO - 'system_bits': '32', 20:50:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:50:22 INFO - 'title': 'talos-linux64-ix-011', 20:50:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:50:22 INFO - 'use_talos_json': True, 20:50:22 INFO - 'verify': 'False', 20:50:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:50:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:50:22 INFO - 'work_dir': 'build'} 20:50:22 INFO - [mozharness: 2017-09-24 03:50:22.634915Z] Running clobber step. 20:50:22 INFO - Running pre-action listener: _resource_record_pre_action 20:50:22 INFO - Running main action method: clobber 20:50:22 INFO - rmtree: /builds/slave/test/build 20:50:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:50:23 INFO - Running post-action listener: _resource_record_post_action 20:50:23 INFO - [mozharness: 2017-09-24 03:50:23.614770Z] Finished clobber step (success) 20:50:23 INFO - [mozharness: 2017-09-24 03:50:23.614867Z] Running read-buildbot-config step. 20:50:23 INFO - Running pre-action listener: _resource_record_pre_action 20:50:23 INFO - Running main action method: read_buildbot_config 20:50:23 INFO - Using buildbot properties: 20:50:23 INFO - { 20:50:23 INFO - "project": "", 20:50:23 INFO - "product": "firefox", 20:50:23 INFO - "who": "philringnalda@gmail.com", 20:50:23 INFO - "installer_path": "public/build/target.tar.bz2", 20:50:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 20:50:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 20:50:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 20:50:23 INFO - "stage_platform": "linux64-pgo", 20:50:23 INFO - "basedir": "/builds/slave/test", 20:50:23 INFO - "buildnumber": 203, 20:50:23 INFO - "platform": "ubuntu64_hw", 20:50:23 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:50:23 INFO - "slavebuilddir": "test", 20:50:23 INFO - "taskId": "ZMYgjLesQxi7Ixa__deblw", 20:50:23 INFO - "branch": "mozilla-central", 20:50:23 INFO - "script_repo_revision": "production", 20:50:23 INFO - "revision": "ff40c5dcaa41261b39a4e9795e02a9d51dd30ced", 20:50:23 INFO - "slavename": "talos-linux64-ix-011", 20:50:23 INFO - "repo_path": "mozilla-central" 20:50:23 INFO - } 20:50:23 INFO - Finding installer, test and symbols from parent task. 20:50:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZMYgjLesQxi7Ixa__deblw'}, attempt #1 20:50:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZMYgjLesQxi7Ixa__deblw'}, attempt #1 20:50:24 INFO - Task dependencies: Q3qoSt1vRnKturi1wRqswg 20:50:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg'}, attempt #1 20:50:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.tar.bz2 20:50:25 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.test_packages.json 20:50:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.crashreporter-symbols.zip 20:50:25 INFO - Running post-action listener: _resource_record_post_action 20:50:25 INFO - [mozharness: 2017-09-24 03:50:25.031497Z] Finished read-buildbot-config step (success) 20:50:25 INFO - [mozharness: 2017-09-24 03:50:25.031713Z] Running download-and-extract step. 20:50:25 INFO - Running pre-action listener: _resource_record_pre_action 20:50:25 INFO - Running main action method: download_and_extract 20:50:25 INFO - mkdir: /builds/slave/test/build/tests 20:50:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:25 INFO - trying https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.test_packages.json 20:50:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:50:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:50:25 INFO - Downloaded 989 bytes. 20:50:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:50:25 INFO - Using the following test package requirements: 20:50:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 20:50:25 INFO - u'common': [u'target.common.tests.zip'], 20:50:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:50:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:50:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:50:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:50:25 INFO - u'mozbase': [u'target.common.tests.zip'], 20:50:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:50:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:50:25 INFO - u'web-platform': [u'target.common.tests.zip', 20:50:25 INFO - u'target.web-platform.tests.tar.gz'], 20:50:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:50:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 20:50:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.common.tests.zip 20:50:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:50:25 INFO - Fetch https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.common.tests.zip into memory 20:50:27 INFO - Content-Length response header: 55344974 20:50:27 INFO - Bytes received: 55344974 20:50:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.talos.tests.zip 20:50:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:50:38 INFO - Fetch https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.talos.tests.zip into memory 20:50:40 INFO - Content-Length response header: 14513823 20:50:40 INFO - Bytes received: 14513823 20:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:40 INFO - trying https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.tar.bz2 20:50:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:50:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:50:44 INFO - Downloaded 62324801 bytes. 20:50:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.tar.bz2 20:50:44 INFO - mkdir: /builds/slave/test/properties 20:50:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:50:44 INFO - Writing to file /builds/slave/test/properties/build_url 20:50:44 INFO - Contents: 20:50:44 INFO - build_url:https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.tar.bz2 20:50:44 INFO - Running post-action listener: _resource_record_post_action 20:50:44 INFO - Running post-action listener: find_tests_for_verification 20:50:44 INFO - Running post-action listener: set_extra_try_arguments 20:50:44 INFO - [mozharness: 2017-09-24 03:50:44.396951Z] Finished download-and-extract step (success) 20:50:44 INFO - [mozharness: 2017-09-24 03:50:44.397180Z] Running populate-webroot step. 20:50:44 INFO - Running pre-action listener: _resource_record_pre_action 20:50:44 INFO - Running main action method: populate_webroot 20:50:44 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:50:44 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:50:44 INFO - 'tests': ['tresize', 20:50:44 INFO - 'tcanvasmark']}, 20:50:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:50:44 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:50:44 INFO - 'tests': ['dromaeo_css', 20:50:44 INFO - 'kraken']}, 20:50:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:50:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:50:44 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:50:44 INFO - 'talos_options': ['--disable-stylo'], 20:50:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:50:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:50:44 INFO - 'tests': ['damp', 'tps']}, 20:50:44 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:50:44 INFO - 'talos_options': ['--disable-stylo'], 20:50:44 INFO - 'tests': ['damp', 'tps']}, 20:50:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:50:44 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:50:44 INFO - 'tests': ['dromaeo_dom']}, 20:50:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 20:50:44 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:50:44 INFO - 'tests': ['basic_compositor_video', 20:50:44 INFO - 'glvideo']}, 20:50:44 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 20:50:44 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 20:50:44 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:50:44 INFO - 'talos_options': ['--disable-stylo'], 20:50:44 INFO - 'tests': ['ts_paint_webext', 20:50:44 INFO - 'tp5o_webext']}, 20:50:44 INFO - 'other-e10s': {'tests': ['a11yr', 20:50:44 INFO - 'ts_paint', 20:50:44 INFO - 'tpaint', 20:50:44 INFO - 'sessionrestore', 20:50:44 INFO - 'sessionrestore_many_windows', 20:50:44 INFO - 'sessionrestore_no_auto_restore', 20:50:44 INFO - 'tabpaint']}, 20:50:44 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:50:44 INFO - 'tests': ['a11yr', 20:50:44 INFO - 'ts_paint', 20:50:44 INFO - 'tpaint', 20:50:44 INFO - 'sessionrestore', 20:50:44 INFO - 'sessionrestore_many_windows', 20:50:44 INFO - 'sessionrestore_no_auto_restore', 20:50:44 INFO - 'tabpaint']}, 20:50:44 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 20:50:44 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 20:50:44 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:50:44 INFO - 'tests': ['perf_reftest_singletons']}, 20:50:44 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:50:44 INFO - 'tests': ['perf_reftest']}, 20:50:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:50:44 INFO - 'tsvgr_opacity', 20:50:44 INFO - 'tart', 20:50:44 INFO - 'tscrollx', 20:50:44 INFO - 'tsvg_static']}, 20:50:44 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:50:44 INFO - 'tests': ['tsvgx', 20:50:44 INFO - 'tsvgr_opacity', 20:50:44 INFO - 'tart', 20:50:44 INFO - 'tscrollx', 20:50:44 INFO - 'tsvg_static']}, 20:50:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:50:44 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:50:44 INFO - 'talos_options': ['--disable-stylo'], 20:50:44 INFO - 'tests': ['tp5o']}, 20:50:44 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:50:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 20:50:44 INFO - 'talos_options': ['--mitmproxy', 20:50:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:50:44 INFO - '--firstNonBlankPaint'], 20:50:44 INFO - 'tests': ['tp6_google', 20:50:44 INFO - 'tp6_youtube', 20:50:44 INFO - 'tp6_amazon', 20:50:44 INFO - 'tp6_facebook']}, 20:50:44 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:50:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 20:50:44 INFO - 'talos_options': ['--disable-stylo', 20:50:44 INFO - '--mitmproxy', 20:50:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:50:44 INFO - '--firstNonBlankPaint'], 20:50:44 INFO - 'tests': ['tp6_google', 20:50:44 INFO - 'tp6_youtube', 20:50:44 INFO - 'tp6_amazon', 20:50:44 INFO - 'tp6_facebook']}, 20:50:44 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:50:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 20:50:44 INFO - 'talos_options': ['--stylo-threads=1', 20:50:44 INFO - '--mitmproxy', 20:50:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:50:44 INFO - '--firstNonBlankPaint'], 20:50:44 INFO - 'tests': ['tp6_google', 20:50:44 INFO - 'tp6_youtube', 20:50:44 INFO - 'tp6_amazon', 20:50:44 INFO - 'tp6_facebook']}, 20:50:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:50:44 INFO - 'talos_options': ['--xperf_path', 20:50:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:50:44 INFO - 'tests': ['tp5n']}, 20:50:44 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:50:44 INFO - 'talos_options': ['--disable-stylo', 20:50:44 INFO - '--xperf_path', 20:50:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:50:44 INFO - 'tests': ['tp5n']}}} 20:50:44 INFO - Running post-action listener: _resource_record_post_action 20:50:44 INFO - [mozharness: 2017-09-24 03:50:44.417635Z] Finished populate-webroot step (success) 20:50:44 INFO - [mozharness: 2017-09-24 03:50:44.417724Z] Running create-virtualenv step. 20:50:44 INFO - Running pre-action listener: _resource_record_pre_action 20:50:44 INFO - Running main action method: create_virtualenv 20:50:44 INFO - Creating virtualenv /builds/slave/test/build/venv 20:50:44 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:50:44 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 20:50:44 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 20:50:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:50:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:50:45 INFO - Installing distribute.............................................................................................................................................................................................done. 20:50:45 INFO - Installing pip...............done. 20:50:45 INFO - Return code: 0 20:50:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:50:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:50:45 INFO - Reading from file tmpfile_stdout 20:50:45 INFO - Output received: 20:50:45 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 20:50:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:50:45 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')]} 20:50:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:45 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')]} 20:50:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c439c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c40d60>, '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 0x1c41a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c41ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', '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 20:50:45 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 20:50:45 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 20:50:45 INFO - Using env: {'DISPLAY': ':0', 20:50:45 INFO - 'HOME': '/home/cltbld', 20:50:45 INFO - 'LANG': 'en_US.UTF-8', 20:50:45 INFO - 'LANGUAGE': 'en_US:en', 20:50:45 INFO - 'LOGNAME': 'cltbld', 20:50:45 INFO - 'MAIL': '/var/mail/cltbld', 20:50:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:45 INFO - 'MOZ_NO_REMOTE': '1', 20:50:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:45 INFO - 'NO_EM_RESTART': '1', 20:50:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:45 INFO - 'PWD': '/builds/slave/test', 20:50:45 INFO - 'SHELL': '/bin/bash', 20:50:45 INFO - 'SHLVL': '1', 20:50:45 INFO - 'TERM': 'linux', 20:50:45 INFO - 'TMOUT': '86400', 20:50:45 INFO - 'USER': 'cltbld', 20:50:45 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', 20:50:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:45 INFO - '_': '/tools/buildbot/bin/python'} 20:50:46 INFO - Ignoring indexes: http://pypi.python.org/simple/ 20:50:46 INFO - Downloading/unpacking pip>=1.5 20:50:46 INFO - Running setup.py egg_info for package pip 20:50:46 INFO - warning: no files found matching 'pip/cacert.pem' 20:50:46 INFO - warning: no files found matching '*.html' under directory 'docs' 20:50:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 20:50:46 INFO - no previously-included directories found matching 'docs/_build/_sources' 20:50:46 INFO - Installing collected packages: pip 20:50:46 INFO - Found existing installation: pip 1.1 20:50:46 INFO - Uninstalling pip: 20:50:46 INFO - Successfully uninstalled pip 20:50:46 INFO - Running setup.py install for pip 20:50:46 INFO - warning: no files found matching 'pip/cacert.pem' 20:50:46 INFO - warning: no files found matching '*.html' under directory 'docs' 20:50:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 20:50:46 INFO - no previously-included directories found matching 'docs/_build/_sources' 20:50:46 INFO - Installing pip script to /builds/slave/test/build/venv/bin 20:50:46 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 20:50:46 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 20:50:46 INFO - Successfully installed pip 20:50:46 INFO - Cleaning up... 20:50:46 INFO - Return code: 0 20:50:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:50:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:46 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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c439c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c40d60>, '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 0x1c41a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c41ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', '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 20:50:46 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 20:50:46 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 20:50:46 INFO - Using env: {'DISPLAY': ':0', 20:50:46 INFO - 'HOME': '/home/cltbld', 20:50:46 INFO - 'LANG': 'en_US.UTF-8', 20:50:46 INFO - 'LANGUAGE': 'en_US:en', 20:50:46 INFO - 'LOGNAME': 'cltbld', 20:50:46 INFO - 'MAIL': '/var/mail/cltbld', 20:50:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:46 INFO - 'MOZ_NO_REMOTE': '1', 20:50:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:46 INFO - 'NO_EM_RESTART': '1', 20:50:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:46 INFO - 'PWD': '/builds/slave/test', 20:50:46 INFO - 'SHELL': '/bin/bash', 20:50:46 INFO - 'SHLVL': '1', 20:50:46 INFO - 'TERM': 'linux', 20:50:46 INFO - 'TMOUT': '86400', 20:50:46 INFO - 'USER': 'cltbld', 20:50:46 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', 20:50:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:46 INFO - '_': '/tools/buildbot/bin/python'} 20:50:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:46 INFO - Downloading/unpacking psutil>=3.1.1 20:50:46 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 20:50:46 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 20:50:46 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 20:50:46 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 20:50:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:50:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:50:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:50:48 INFO - Installing collected packages: psutil 20:50:48 INFO - Running setup.py install for psutil 20:50:49 INFO - building 'psutil._psutil_linux' extension 20:50:49 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 20:50:49 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 20:50:49 INFO - building 'psutil._psutil_posix' extension 20:50:49 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 20:50:49 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 20:50:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:50:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:50:49 INFO - Successfully installed psutil 20:50:49 INFO - Cleaning up... 20:50:50 INFO - Return code: 0 20:50:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:50:50 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')]} 20:50:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:50 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')]} 20:50:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c439c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c40d60>, '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 0x1c41a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c41ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', '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 20:50:50 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 20:50:50 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 20:50:50 INFO - Using env: {'DISPLAY': ':0', 20:50:50 INFO - 'HOME': '/home/cltbld', 20:50:50 INFO - 'LANG': 'en_US.UTF-8', 20:50:50 INFO - 'LANGUAGE': 'en_US:en', 20:50:50 INFO - 'LOGNAME': 'cltbld', 20:50:50 INFO - 'MAIL': '/var/mail/cltbld', 20:50:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:50 INFO - 'MOZ_NO_REMOTE': '1', 20:50:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:50 INFO - 'NO_EM_RESTART': '1', 20:50:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:50 INFO - 'PWD': '/builds/slave/test', 20:50:50 INFO - 'SHELL': '/bin/bash', 20:50:50 INFO - 'SHLVL': '1', 20:50:50 INFO - 'TERM': 'linux', 20:50:50 INFO - 'TMOUT': '86400', 20:50:50 INFO - 'USER': 'cltbld', 20:50:50 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', 20:50:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:50 INFO - '_': '/tools/buildbot/bin/python'} 20:50:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:50:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:50:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:50:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:50:51 INFO - Installing collected packages: mozsystemmonitor 20:50:51 INFO - Running setup.py install for mozsystemmonitor 20:50:51 INFO - Successfully installed mozsystemmonitor 20:50:51 INFO - Cleaning up... 20:50:51 INFO - Return code: 0 20:50:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:50: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')]} 20:50:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50: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')]} 20:50:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c439c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c40d60>, '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 0x1c41a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c41ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', '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 20:50:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:50:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:50:51 INFO - Using env: {'DISPLAY': ':0', 20:50:51 INFO - 'HOME': '/home/cltbld', 20:50:51 INFO - 'LANG': 'en_US.UTF-8', 20:50:51 INFO - 'LANGUAGE': 'en_US:en', 20:50:51 INFO - 'LOGNAME': 'cltbld', 20:50:51 INFO - 'MAIL': '/var/mail/cltbld', 20:50:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:51 INFO - 'MOZ_NO_REMOTE': '1', 20:50:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:51 INFO - 'NO_EM_RESTART': '1', 20:50:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:51 INFO - 'PWD': '/builds/slave/test', 20:50:51 INFO - 'SHELL': '/bin/bash', 20:50:51 INFO - 'SHLVL': '1', 20:50:51 INFO - 'TERM': 'linux', 20:50:51 INFO - 'TMOUT': '86400', 20:50:51 INFO - 'USER': 'cltbld', 20:50:51 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', 20:50:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:51 INFO - '_': '/tools/buildbot/bin/python'} 20:50:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:51 INFO - Downloading/unpacking jsonschema==2.5.1 20:50:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:50:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:53 INFO - Downloading functools32-3.2.3-2.tar.gz 20:50:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:50:53 INFO - warning: no files found matching '*.txt' 20:50:53 INFO - no previously-included directories found matching 'build' 20:50:53 INFO - no previously-included directories found matching 'dist' 20:50:53 INFO - no previously-included directories found matching '.git*' 20:50:53 INFO - Installing collected packages: jsonschema, functools32 20:50:53 INFO - Running setup.py install for functools32 20:50:53 INFO - warning: no files found matching '*.txt' 20:50:53 INFO - no previously-included directories found matching 'build' 20:50:53 INFO - no previously-included directories found matching 'dist' 20:50:53 INFO - no previously-included directories found matching '.git*' 20:50:53 INFO - Successfully installed jsonschema functools32 20:50:53 INFO - Cleaning up... 20:50:53 INFO - Return code: 0 20:50:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c439c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c40d60>, '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 0x1c41a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c41ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', '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 20:50:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:50:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:50:53 INFO - Using env: {'DISPLAY': ':0', 20:50:53 INFO - 'HOME': '/home/cltbld', 20:50:53 INFO - 'LANG': 'en_US.UTF-8', 20:50:53 INFO - 'LANGUAGE': 'en_US:en', 20:50:53 INFO - 'LOGNAME': 'cltbld', 20:50:53 INFO - 'MAIL': '/var/mail/cltbld', 20:50:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:53 INFO - 'MOZ_NO_REMOTE': '1', 20:50:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:53 INFO - 'NO_EM_RESTART': '1', 20:50:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:53 INFO - 'PWD': '/builds/slave/test', 20:50:53 INFO - 'SHELL': '/bin/bash', 20:50:53 INFO - 'SHLVL': '1', 20:50:53 INFO - 'TERM': 'linux', 20:50:53 INFO - 'TMOUT': '86400', 20:50:53 INFO - 'USER': 'cltbld', 20:50:53 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', 20:50:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:53 INFO - '_': '/tools/buildbot/bin/python'} 20:50:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:50:53 INFO - Cleaning up... 20:50:53 INFO - Return code: 0 20:50:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c439c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c40d60>, '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 0x1c41a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c41ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', '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 20:50:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:50:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:50:53 INFO - Using env: {'DISPLAY': ':0', 20:50:53 INFO - 'HOME': '/home/cltbld', 20:50:53 INFO - 'LANG': 'en_US.UTF-8', 20:50:53 INFO - 'LANGUAGE': 'en_US:en', 20:50:53 INFO - 'LOGNAME': 'cltbld', 20:50:53 INFO - 'MAIL': '/var/mail/cltbld', 20:50:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:53 INFO - 'MOZ_NO_REMOTE': '1', 20:50:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:53 INFO - 'NO_EM_RESTART': '1', 20:50:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:53 INFO - 'PWD': '/builds/slave/test', 20:50:53 INFO - 'SHELL': '/bin/bash', 20:50:53 INFO - 'SHLVL': '1', 20:50:53 INFO - 'TERM': 'linux', 20:50:53 INFO - 'TMOUT': '86400', 20:50:53 INFO - 'USER': 'cltbld', 20:50:53 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', 20:50:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:53 INFO - '_': '/tools/buildbot/bin/python'} 20:50:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:53 INFO - Downloading/unpacking blobuploader==1.2.4 20:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:54 INFO - Downloading blobuploader-1.2.4.tar.gz 20:50:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:50:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:50:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:55 INFO - Downloading docopt-0.6.1.tar.gz 20:50:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:50:55 INFO - Installing collected packages: blobuploader, requests, docopt 20:50:55 INFO - Running setup.py install for blobuploader 20:50:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:50:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:50:55 INFO - Running setup.py install for requests 20:50:55 INFO - Running setup.py install for docopt 20:50:56 INFO - Successfully installed blobuploader requests docopt 20:50:56 INFO - Cleaning up... 20:50:56 INFO - Return code: 0 20:50:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:50:56 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')]} 20:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:56 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')]} 20:50:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:56 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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c439c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c40d60>, '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 0x1c41a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c41ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', '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 20:50:56 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 20:50:56 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 20:50:56 INFO - Using env: {'DISPLAY': ':0', 20:50:56 INFO - 'HOME': '/home/cltbld', 20:50:56 INFO - 'LANG': 'en_US.UTF-8', 20:50:56 INFO - 'LANGUAGE': 'en_US:en', 20:50:56 INFO - 'LOGNAME': 'cltbld', 20:50:56 INFO - 'MAIL': '/var/mail/cltbld', 20:50:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:56 INFO - 'MOZ_NO_REMOTE': '1', 20:50:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:56 INFO - 'NO_EM_RESTART': '1', 20:50:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:56 INFO - 'PWD': '/builds/slave/test', 20:50:56 INFO - 'SHELL': '/bin/bash', 20:50:56 INFO - 'SHLVL': '1', 20:50:56 INFO - 'TERM': 'linux', 20:50:56 INFO - 'TMOUT': '86400', 20:50:56 INFO - 'USER': 'cltbld', 20:50:56 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', 20:50:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:56 INFO - '_': '/tools/buildbot/bin/python'} 20:50:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:50:56 INFO - Running setup.py (path:/tmp/pip-j6kUKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:50:56 INFO - Running setup.py (path:/tmp/pip-JBsEtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:50:56 INFO - Running setup.py (path:/tmp/pip-lrAx8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:50:56 INFO - Running setup.py (path:/tmp/pip-QxVIcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:50:56 INFO - Running setup.py (path:/tmp/pip-4i5Ih2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:50:56 INFO - Running setup.py (path:/tmp/pip-CZbgeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:50:56 INFO - Running setup.py (path:/tmp/pip-zTBRfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:50:56 INFO - Running setup.py (path:/tmp/pip-dgxf1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:50:56 INFO - Running setup.py (path:/tmp/pip-b3wblG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:50:56 INFO - Running setup.py (path:/tmp/pip-4RoKgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:50:57 INFO - Running setup.py (path:/tmp/pip-_Ji9Bg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:50:57 INFO - Running setup.py (path:/tmp/pip-tHiANx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:50:57 INFO - Running setup.py (path:/tmp/pip-QUkDKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:50:57 INFO - Running setup.py (path:/tmp/pip-AP8_yJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:50:57 INFO - Running setup.py (path:/tmp/pip-6MC4E4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:50:57 INFO - Running setup.py (path:/tmp/pip-3njuyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:50:57 INFO - Running setup.py (path:/tmp/pip-ihVOIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:50:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:50:57 INFO - Running setup.py install for manifestparser 20:50:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:50:57 INFO - Running setup.py install for mozcrash 20:50:57 INFO - Running setup.py install for mozdebug 20:50:57 INFO - Running setup.py install for mozdevice 20:50:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:50:57 INFO - Running setup.py install for mozfile 20:50:58 INFO - Running setup.py install for mozhttpd 20:50:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:50:58 INFO - Running setup.py install for mozinfo 20:50:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:50:58 INFO - Running setup.py install for mozInstall 20:50:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:50:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:50:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:50:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:50:58 INFO - Running setup.py install for mozleak 20:50:58 INFO - Running setup.py install for mozlog 20:50:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:50:58 INFO - Running setup.py install for moznetwork 20:50:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:50:58 INFO - Running setup.py install for mozprocess 20:50:58 INFO - Running setup.py install for mozprofile 20:50:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:50:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:50:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:50:58 INFO - Running setup.py install for mozrunner 20:50:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:50:58 INFO - Running setup.py install for mozscreenshot 20:50:59 INFO - Running setup.py install for moztest 20:50:59 INFO - Running setup.py install for mozversion 20:50:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:50:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:50:59 INFO - Cleaning up... 20:50:59 INFO - Return code: 0 20:50:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:50: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')]} 20:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50: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')]} 20:50:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:59 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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c439c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c40d60>, '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 0x1c41a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c41ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', '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 20:50:59 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 20:50:59 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 20:50:59 INFO - Using env: {'DISPLAY': ':0', 20:50:59 INFO - 'HOME': '/home/cltbld', 20:50:59 INFO - 'LANG': 'en_US.UTF-8', 20:50:59 INFO - 'LANGUAGE': 'en_US:en', 20:50:59 INFO - 'LOGNAME': 'cltbld', 20:50:59 INFO - 'MAIL': '/var/mail/cltbld', 20:50:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:59 INFO - 'MOZ_NO_REMOTE': '1', 20:50:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:59 INFO - 'NO_EM_RESTART': '1', 20:50:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:59 INFO - 'PWD': '/builds/slave/test', 20:50:59 INFO - 'SHELL': '/bin/bash', 20:50:59 INFO - 'SHLVL': '1', 20:50:59 INFO - 'TERM': 'linux', 20:50:59 INFO - 'TMOUT': '86400', 20:50:59 INFO - 'USER': 'cltbld', 20:50:59 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', 20:50:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:59 INFO - '_': '/tools/buildbot/bin/python'} 20:50:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:50:59 INFO - Running setup.py (path:/tmp/pip-t5uTZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:50:59 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)) 20:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:50:59 INFO - Running setup.py (path:/tmp/pip-Hpv21w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:50:59 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)) 20:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:50:59 INFO - Running setup.py (path:/tmp/pip-hGA15S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:50:59 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)) 20:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:50:59 INFO - Running setup.py (path:/tmp/pip-TJPbdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:50:59 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)) 20:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:50:59 INFO - Running setup.py (path:/tmp/pip-AY_a0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:50:59 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)) 20:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:50:59 INFO - Running setup.py (path:/tmp/pip-7lpfic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:50:59 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)) 20:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:50:59 INFO - Running setup.py (path:/tmp/pip-MAHJTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:50:59 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)) 20:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:50:59 INFO - Running setup.py (path:/tmp/pip-SX4jAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:51:00 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)) 20:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:51:00 INFO - Running setup.py (path:/tmp/pip-pSaN15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:51:00 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)) 20:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:51:00 INFO - Running setup.py (path:/tmp/pip-MlvOLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:51:00 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)) 20:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:51:00 INFO - Running setup.py (path:/tmp/pip-ThKf91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:51:00 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)) 20:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:51:00 INFO - Running setup.py (path:/tmp/pip-7DSrZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:51:00 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)) 20:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:51:00 INFO - Running setup.py (path:/tmp/pip-Dqo8dE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:51:00 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)) 20:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:51:00 INFO - Running setup.py (path:/tmp/pip-m3ErjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:51:00 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)) 20:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:51:00 INFO - Running setup.py (path:/tmp/pip-H7jASR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:51:00 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)) 20:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:51:00 INFO - Running setup.py (path:/tmp/pip-tqR7lF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:51:00 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)) 20:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:51:00 INFO - Running setup.py (path:/tmp/pip-K1IPzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:51:00 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)) 20:51:00 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)) 20:51:00 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)) 20:51:00 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)) 20:51:00 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)) 20:51:00 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)) 20:51:00 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)) 20:51:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:51:00 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 20:51:00 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 20:51:00 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 20:51:00 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 20:51:01 INFO - Downloading blessings-1.6.tar.gz 20:51:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:51:01 INFO - Installing collected packages: blessings 20:51:01 INFO - Running setup.py install for blessings 20:51:01 INFO - Successfully installed blessings 20:51:01 INFO - Cleaning up... 20:51:02 INFO - Return code: 0 20:51:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:51:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:51:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:51:02 INFO - Reading from file tmpfile_stdout 20:51:02 INFO - Current package versions: 20:51:02 INFO - argparse == 1.2.1 20:51:02 INFO - blessings == 1.6 20:51:02 INFO - blobuploader == 1.2.4 20:51:02 INFO - docopt == 0.6.1 20:51:02 INFO - functools32 == 3.2.3-2 20:51:02 INFO - jsonschema == 2.5.1 20:51:02 INFO - manifestparser == 1.1 20:51:02 INFO - mozInstall == 1.13 20:51:02 INFO - mozcrash == 1.0 20:51:02 INFO - mozdebug == 0.1 20:51:02 INFO - mozdevice == 0.51 20:51:02 INFO - mozfile == 1.2 20:51:02 INFO - mozhttpd == 0.7 20:51:02 INFO - mozinfo == 0.10 20:51:02 INFO - mozleak == 0.1 20:51:02 INFO - mozlog == 3.5 20:51:02 INFO - moznetwork == 0.27 20:51:02 INFO - mozprocess == 0.25 20:51:02 INFO - mozprofile == 0.28 20:51:02 INFO - mozrunner == 6.13 20:51:02 INFO - mozscreenshot == 0.1 20:51:02 INFO - mozsystemmonitor == 0.3 20:51:02 INFO - moztest == 0.8 20:51:02 INFO - mozversion == 1.4 20:51:02 INFO - psutil == 3.1.1 20:51:02 INFO - requests == 1.2.3 20:51:02 INFO - wsgiref == 0.1.2 20:51:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:51:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:51:02 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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c439c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c40d60>, '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 0x1c41a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c41ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', '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 20:51:02 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 20:51:02 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 20:51:02 INFO - Using env: {'DISPLAY': ':0', 20:51:02 INFO - 'HOME': '/home/cltbld', 20:51:02 INFO - 'LANG': 'en_US.UTF-8', 20:51:02 INFO - 'LANGUAGE': 'en_US:en', 20:51:02 INFO - 'LOGNAME': 'cltbld', 20:51:02 INFO - 'MAIL': '/var/mail/cltbld', 20:51:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:51:02 INFO - 'MOZ_NO_REMOTE': '1', 20:51:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:51:02 INFO - 'NO_EM_RESTART': '1', 20:51:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:51:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:51:02 INFO - 'PWD': '/builds/slave/test', 20:51:02 INFO - 'SHELL': '/bin/bash', 20:51:02 INFO - 'SHLVL': '1', 20:51:02 INFO - 'TERM': 'linux', 20:51:02 INFO - 'TMOUT': '86400', 20:51:02 INFO - 'USER': 'cltbld', 20:51:02 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', 20:51:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:51:02 INFO - '_': '/tools/buildbot/bin/python'} 20:51:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:51:02 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)) 20:51:02 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)) 20:51:02 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)) 20:51:02 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)) 20:51:02 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)) 20:51:02 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)) 20:51:02 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)) 20:51:02 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)) 20:51:02 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)) 20:51:02 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 20:51:02 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 20:51:02 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 20:51:02 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 20:51:02 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 20:51:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 20:51:03 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)) 20:51:03 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)) 20:51:03 INFO - Installing collected packages: simplejson 20:51:03 INFO - Running setup.py install for simplejson 20:51:03 INFO - building 'simplejson._speedups' extension 20:51:03 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 20:51:04 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 20:51:04 INFO - Successfully installed simplejson 20:51:04 INFO - Cleaning up... 20:51:04 INFO - Return code: 0 20:51:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:51:04 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')]} 20:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:51:04 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')]} 20:51:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:51:04 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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a9eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c439c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c40d60>, '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 0x1c41a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c41ef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', '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 20:51:04 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 20:51:04 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 20:51:04 INFO - Using env: {'DISPLAY': ':0', 20:51:04 INFO - 'HOME': '/home/cltbld', 20:51:04 INFO - 'LANG': 'en_US.UTF-8', 20:51:04 INFO - 'LANGUAGE': 'en_US:en', 20:51:04 INFO - 'LOGNAME': 'cltbld', 20:51:04 INFO - 'MAIL': '/var/mail/cltbld', 20:51:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:51:04 INFO - 'MOZ_NO_REMOTE': '1', 20:51:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:51:04 INFO - 'NO_EM_RESTART': '1', 20:51:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:51:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:51:04 INFO - 'PWD': '/builds/slave/test', 20:51:04 INFO - 'SHELL': '/bin/bash', 20:51:04 INFO - 'SHLVL': '1', 20:51:04 INFO - 'TERM': 'linux', 20:51:04 INFO - 'TMOUT': '86400', 20:51:04 INFO - 'USER': 'cltbld', 20:51:04 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', 20:51:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:51:04 INFO - '_': '/tools/buildbot/bin/python'} 20:51:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:51:04 INFO - Cleaning up... 20:51:04 INFO - Return code: 0 20:51:04 INFO - Running post-action listener: _resource_record_post_action 20:51:04 INFO - Running post-action listener: _start_resource_monitoring 20:51:04 INFO - Starting resource monitoring. 20:51:04 INFO - [mozharness: 2017-09-24 03:51:04.991236Z] Finished create-virtualenv step (success) 20:51:04 INFO - [mozharness: 2017-09-24 03:51:04.991755Z] Running install step. 20:51:04 INFO - Running pre-action listener: _resource_record_pre_action 20:51:04 INFO - Running main action method: install 20:51:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:51:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:51:05 INFO - Reading from file tmpfile_stdout 20:51:05 INFO - Detecting whether we're running mozinstall >=1.0... 20:51:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:51:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:51:05 INFO - Reading from file tmpfile_stdout 20:51:05 INFO - Output received: 20:51:05 INFO - Usage: mozinstall [options] installer 20:51:05 INFO - Options: 20:51:05 INFO - -h, --help show this help message and exit 20:51:05 INFO - -d DEST, --destination=DEST 20:51:05 INFO - Directory to install application into. [default: 20:51:05 INFO - "/builds/slave/test"] 20:51:05 INFO - --app=APP Application being installed. [default: firefox] 20:51:05 INFO - mkdir: /builds/slave/test/build/application 20:51:05 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'] 20:51:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:51:22 INFO - Reading from file tmpfile_stdout 20:51:22 INFO - Output received: 20:51:22 INFO - /builds/slave/test/build/application/firefox/firefox 20:51:22 INFO - Running post-action listener: _resource_record_post_action 20:51:22 INFO - [mozharness: 2017-09-24 03:51:22.936924Z] Finished install step (success) 20:51:22 INFO - [mozharness: 2017-09-24 03:51:22.937176Z] Running setup-mitmproxy step. 20:51:22 INFO - Running pre-action listener: _resource_record_pre_action 20:51:22 INFO - Running main action method: setup_mitmproxy 20:51:22 INFO - Skipping: mitmproxy is not required 20:51:22 INFO - Running post-action listener: _resource_record_post_action 20:51:22 INFO - [mozharness: 2017-09-24 03:51:22.938354Z] Finished setup-mitmproxy step (success) 20:51:22 INFO - [mozharness: 2017-09-24 03:51:22.938575Z] Running run-tests step. 20:51:22 INFO - Running pre-action listener: _resource_record_pre_action 20:51:22 INFO - Running pre-action listener: _set_gcov_prefix 20:51:22 INFO - Running main action method: run_tests 20:51:22 WARNING - Try message not found. 20:51:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:51:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:51:22 INFO - Python 2.7.3 20:51:22 INFO - Return code: 0 20:51:22 INFO - grabbing minidump binary from tooltool 20:51:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:51:22 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 0x1c40d60>, '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 0x1c41a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c41ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:51:22 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 20:51:22 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 20:51:22 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 20:51:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:51:23 INFO - Return code: 0 20:51:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:51:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:51:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:51:23 INFO - ENV: RUST_BACKTRACE is now full 20:51:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:51:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:51:23 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/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 20:51:23 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/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 20:51:23 INFO - Using env: {'DISPLAY': ':0', 20:51:23 INFO - 'HOME': '/home/cltbld', 20:51:23 INFO - 'LANG': 'en_US.UTF-8', 20:51:23 INFO - 'LANGUAGE': 'en_US:en', 20:51:23 INFO - 'LOGNAME': 'cltbld', 20:51:23 INFO - 'MAIL': '/var/mail/cltbld', 20:51:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:51:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:51:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:51:23 INFO - 'MOZ_NO_REMOTE': '1', 20:51:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:51:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:51:23 INFO - 'NO_EM_RESTART': '1', 20:51:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:51:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:51:23 INFO - 'PWD': '/builds/slave/test', 20:51:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:51:23 INFO - 'RUST_BACKTRACE': 'full', 20:51:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:51:23 INFO - 'SHELL': '/bin/bash', 20:51:23 INFO - 'SHLVL': '1', 20:51:23 INFO - 'TERM': 'linux', 20:51:23 INFO - 'TMOUT': '86400', 20:51:23 INFO - 'USER': 'cltbld', 20:51:23 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', 20:51:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:51:23 INFO - '_': '/tools/buildbot/bin/python'} 20:51:23 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/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 20:51:23 INFO - mozversion application_buildid: 20170924022042 20:51:23 INFO - mozversion application_changeset: ff40c5dcaa41261b39a4e9795e02a9d51dd30ced 20:51:23 INFO - mozversion application_display_name: Nightly 20:51:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:51:23 INFO - mozversion application_name: Firefox 20:51:23 INFO - mozversion application_remotingname: firefox 20:51:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:51:23 INFO - mozversion application_vendor: Mozilla 20:51:23 INFO - mozversion application_version: 58.0a1 20:51:23 INFO - mozversion platform_buildid: 20170924022042 20:51:23 INFO - mozversion platform_changeset: ff40c5dcaa41261b39a4e9795e02a9d51dd30ced 20:51:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:51:23 INFO - mozversion platform_version: 58.0a1 20:51:23 INFO - using testdate: 1506225083 20:51:23 INFO - actual date: 1506225083 20:51:23 INFO - starting webserver on 'localhost:54675' 20:51:23 INFO - SUITE-START | Running 2 tests 20:51:23 INFO - TEST-START | dromaeo_css 20:51:23 INFO - Initialising browser for dromaeo_css test... 20:51:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54675/getInfo.html -profile /tmp/tmpuaYpJw/profile 20:51:23 INFO - TEST-INFO | started process 7416 (/builds/slave/test/build/application/firefox/firefox http://localhost:54675/getInfo.html) 20:51:29 INFO - TEST-INFO | 7416: exit 0 20:51:29 INFO - Browser initialized. 20:51:29 INFO - Running cycle 1/1 for dromaeo_css test... 20:51:29 INFO - Using env: {'DISPLAY': ':0', 20:51:29 INFO - 'HOME': '/home/cltbld', 20:51:29 INFO - 'JSGC_DISABLE_POISONING': '1', 20:51:29 INFO - 'LANG': 'en_US.UTF-8', 20:51:29 INFO - 'LANGUAGE': 'en_US:en', 20:51:29 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 20:51:29 INFO - 'LOGNAME': 'cltbld', 20:51:29 INFO - 'MAIL': '/var/mail/cltbld', 20:51:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:51:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:51:29 INFO - 'MOZ_CRASHREPORTER': '1', 20:51:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:51:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 20:51:29 INFO - 'MOZ_NO_REMOTE': '1', 20:51:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:51:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:51:29 INFO - 'NO_EM_RESTART': '1', 20:51:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:51:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:51:29 INFO - 'PWD': '/builds/slave/test', 20:51:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:51:29 INFO - 'RUST_BACKTRACE': 'full', 20:51:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:51:29 INFO - 'SHELL': '/bin/bash', 20:51:29 INFO - 'SHLVL': '1', 20:51:29 INFO - 'TERM': 'linux', 20:51:29 INFO - 'TMOUT': '86400', 20:51:29 INFO - 'USER': 'cltbld', 20:51:29 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', 20:51:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:51:29 INFO - '_': '/tools/buildbot/bin/python'} 20:51:29 INFO - TEST-INFO | started process 7653 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuaYpJw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 20:51:30 INFO - PID 7653 | 20:51:30 INFO - PID 7653 | (/builds/slave/test/build/application/firefox/firefox:7702): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 20:51:30 INFO - PID 7653 | 20:51:40 INFO - PID 7653 | RSS: Main: 141361152 20:51:40 INFO - PID 7653 | 20:54:37 INFO - PID 7653 | Cycle 1(1): loaded http://localhost:54675/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54675/tests/dromaeo/cssquery-ext.html) 20:54:38 INFO - PID 7653 | RSS: Main: 146317312 20:54:38 INFO - PID 7653 | 20:57:40 INFO - PID 7653 | Cycle 1(1): loaded http://localhost:54675/tests/dromaeo/cssquery-ext.html (next: http://localhost:54675/tests/dromaeo/cssquery-jquery.html) 20:57:40 INFO - PID 7653 | RSS: Main: 146255872 20:57:40 INFO - PID 7653 | 21:00:42 INFO - PID 7653 | Cycle 1(1): loaded http://localhost:54675/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54675/tests/dromaeo/cssquery-mootools.html) 21:00:42 INFO - PID 7653 | RSS: Main: 148627456 21:00:42 INFO - PID 7653 | 21:03:44 INFO - PID 7653 | Cycle 1(1): loaded http://localhost:54675/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54675/tests/dromaeo/cssquery-prototype.html) 21:03:45 INFO - PID 7653 | RSS: Main: 146550784 21:03:45 INFO - PID 7653 | 21:06:48 INFO - PID 7653 | Cycle 1(1): loaded http://localhost:54675/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54675/tests/dromaeo/cssquery-yui.html) 21:06:48 INFO - PID 7653 | RSS: Main: 147333120 21:06:48 INFO - PID 7653 | 21:09:50 INFO - PID 7653 | Cycle 1(1): loaded http://localhost:54675/tests/dromaeo/cssquery-yui.html (next: http://localhost:54675/tests/dromaeo/cssquery-dojo.html) 21:09:50 INFO - PID 7653 | RSS: Main: 150360064 21:09:50 INFO - PID 7653 | 21:09:50 INFO - PID 7653 | __start_tp_report 21:09:50 INFO - PID 7653 | _x_x_mozilla_page_load 21:09:50 INFO - PID 7653 | _x_x_mozilla_page_load_details 21:09:50 INFO - PID 7653 | |i|pagename|runs| 21:09:50 INFO - PID 7653 | |0;dojo.html;3840.2703486337596;3875.689944804416;3894.8636797712084;3905.7265991380605;3911.0613452771327;6218.62688238706;6220.004799232123;6309.526785491089;6311.495080393569;6367.426931576158;6314.02132669437;6321.020241862519;6350.587211831228;6428.035794630808;6454.935450645504;5657.32112146543;5765.077587585987;5776.162456443813;5798.565107616924;5811.796587119452;150.05435768467106;5454.263674403965;5670.744816483269;5697.914531282034;5772.971135144318;5670.376258611549;5679.943200568004;5737.971310143444;5744.109663002241;5747.023006088954;5659.5755318351285;5683.886322273573;5745.827625171251;5748.2527271454865;5761.8559536011935;5643.492085712305;5651.802186923438;5731.340895796995;5732.22614946981;5736.627119237278;6258.374162583751;6289.8742025159245;6327.96836015817;6356.0783686365685;6365.522585806083;6705.698243579001;6710.966445167792;6839.076724642159;6844.384005439484;6940.410065144469;6917.688704008332;6944;6952.721891124349;6977.686004129826;6988.301169883021;6254.155688981975;6365.3316401778075;6380.011098279774;6490.448311893542;6502.902456463157;7087.184973728087;7176.425885929218;7177.461690373243;7180.497365184387;7196.712131514681;6792;6818.181818181751;6818.386345228854;6875.106236189264;6891.310868913069;6773.525853190328;6872.347127022925;6888.104546408935;6890.689918953659;6890.689918953659;6713.597184168868;6717;6805.761798337035;6900.861982760317;6911.965440172868;6864.691088901011;6868.519203655696;6923.099997000358;6954.652267386711;6956.7217311307995;5022.698638081727;5033.094043072282;5080.59355251579;5083.847484575469;5112.156494178419;4521.095780843844;4553.54464553542;4579.5878370946775;4584.770761461914;4617.5382461754225;6357.618542887441;6360.109584658151;6368.29948705642;6377.362263773585;6393.552451328363;6706.899396509057;6820.3861652451515;6875.690593923286;6892.862142757117;6906.102206713195;6229.501639868799;6259.123722678829;6310.968445157745;6315.810525684236;6351.904721429182;5519;5530.972345138329;5606.439356064361;5620.718964051781;5630.915536266959;7116.857662846714;7194.388476979515;7212.314830091238;7231.566106033655;7238.638068096574;6814.046033555207;6815.2162501311;6831;6913.274106218976;6928.237893831674;7009.579425234502;7112.110986126734;7129.7861064168155;7139.107611548557;7145.1425828900865;6812.14848143982;6826.692798824164;6870.828229294108;6890.035395044598;6906.516543842084;6806.047153398429;6869.690863911036;6901.033855260368;6994.6852391643515;7005.649717514206;6633.1376920999965;6678.632675202911;6678.966605166943;6680.031395447536;6681.899771503528;6634.104395906491;6676;6689.2307384651385;6689.832754181184;6735.360140786617;6547.70535325921;6550.410463058348;6621.6689165540565;6645.235797883112;6647.302033286435;6597.835054123685;6730.158730158731;6809.727610895707;6811.4550835934;6829.795106146824;6405.743770249138;6469.644169570719;6475.514336424882;6490.675466226764;6585.703643335965 21:09:50 INFO - PID 7653 | |1;ext.html;944.1502647617145;957.2389949989757;979.0503211884472;1000.9049140331671;1004.9547770350333;42979.35530967038;44957.55042449575;44972.10055798882;44989.32516012262;45105.323420148685;41488.377674334835;41890.16219675605;41993;42001;42012.57987420125;7642.503237289586;7807.843843123134;7814.413918956073;7819.530828150329;7820.139784623687;7626.237376262357;7668.769936901902;7684.346830519385;7709.730159444421;7717.8070548236465;8012.719554815582;8188.426810123294;8204.712835050774;8210.46631968923;8211.917880821205;8090.231428014329;8125.471844330129;8141.470804397695;8152.38857085721;8165.510069395856;8302.83394332116;8522.105178956244;8524.914750852475;8527.914720852774;8532.360072994488;40372.596274037176;40632.59367406347;40681.59318406808;40703.59296407057;40748;3707.5736290326686;3761.774293542396;3762.5861155272896;3763.153290509641;3764.1154328732728;31878.521822172454;31998.20004499883;32140.357192856012;32261.83869080663;32270;5425.755840987165;5431.6740995540395;5466.398696143421;5480.7533660985355;5486.3690675572025;200543.99728001413;201092;201403;201565;201626;51191.23213151806;51270.74364628153;51306.74346628243;51360.486395136315;51495;54191.18713219305;54196.187057194176;54207.186892196645;54214;54286.18570721443;16683.416080437128;16955.152242388078;17011;17045.48863534096;17050.06224657656;13992.650183745283;14040.508582199574;14048.789268160986;14073.437062517385;14106;15139.54581362561;15218.46735364279;15339.312675605674;15353.462628807938;15440.227988600525;5893.764249430061;6073.635581865102;6116.082587611894;6122.908156377569;6169.321374648785;1103.3159441146381;1110.6001839337832;1113.554578168739;1120.7646394257133;1130.5025788652965;25708;25761.355966100997;25897.22308330753;25913.74086259151;25974.610380844304;1189.935008167685;1222.1139673736648;1226.466487078124;1227.0919519555644;1230.4647478346947;13523;13524.93237533806;13655.38550765218;13701.383437745326;13919.930400348136;268740;269044;269558;270086;270223;48225.5177448221;48644.513554863996;48959.265611015864;55405.445945540836;55964.72017639967;1508.4946542908028;1521.163360151934;1529.0061460050833;1536.7464368379315;1541.1523661986087;30932;30966.845165774026;31343.21641958969;31866;32331.676683232865;39678;39725;39737.80131099326;39751.006224843455;39757;208833;209112;209387;209889;210502;36725;36763;36876.81561592175;36894.077648059014;36993.26013479769;45900.540994589624;45903;45936;46308;46358.53641463677;32919.83540082284;33180.66819331872;33401;33627.690570073006;33993.490097648064;34684.82657586696;34811;34840.82579587086;34997.47503787496;35232;39144.217115658095;40384.3942340871;40524.59475405208;40568;40616.593834061285;30058.549121762753;32340.191495212806;33237.833810830794;33248.83375583107;33636.1590960228;25312;25556.74443255618;25835.354116147246;25928.740712592633;26681.599776002986 21:09:50 INFO - PID 7653 | |2;jquery.html;610.938906109389;615.8552740106077;618.2210414877253;621.5928566788755;629.4083561452235;22783.54432911343;23172.188973385935;23429.5314093718;23510.88244558779;23544.88227558864;24854.00583976639;25537.361565960815;25676.871615641943;25740.613890791654;25769.742302576968;6780.321967803217;6970.09388779458;7018.4736144789085;7018.4736144789085;7021.789346319619;6611.9338806611795;6846.931530684704;6901.585904845725;6914.239433662293;6992.475564332695;6839;6842.828929276759;6844.965775171142;6852.623105729208;6885.208201056893;6512.902306465407;6832.760853370133;6842.726290948381;6852.6916288767125;6884.5525040872435;6338.619682819046;6360.0459931010255;6362.554621176473;6366.331535188831;6366.968165159191;29015.709842901513;29217.41565168706;29377.11868643944;29469.557956630666;29492.70507294921;2314.166899916029;2344.2615576093644;2345.2260753951155;2351;2352.588297048027;20160.79839201604;20464.795352046436;21003.789962100338;21149.471263218387;21217.257395991066;2512.459821138464;2538.517681640501;2581.0966161843394;2633.8288011279396;2649.5628221343454;139147;144739.2763036178;145265;151024;151424.2428787849;15582.454614088452;16252.106134162734;16318.265678044516;16614.418495352846;16792.412265570645;25858;25907.22278331653;25936.22191334263;25947.091851785473;25982;10589;10646.73383165427;10687.091597214168;10707.1434285257;10783.78432431347;9696;9756.512174391251;9831.852522212173;9917.752056198653;9951.751206219758;9817;9859.704208873745;10029.648962286285;10046.944149902583;10173;2800.341919648881;2808.1528306220607;2910.490664133768;2925.400292939942;3017.411604737099;1043.4886905416292;1048.5491238767252;1052.7841792432687;1054.878688950765;1057.2704833664748;20901.686474702892;21055.8947205263;21084.683729744065;21235.893820530797;21497.57004859894;1107.545906178463;1111.8332250162541;1115.2639258089785;1124;1128.4696192789377;11634.243774154822;11778.941105294418;11787.823182652268;11941.462634181482;12025.639230822915;602640;616780;626158;626425;626938;126032;128803.35598322137;130639;131357.3432132833;135679;1023.3501726403787;1070.7216123807866;1201.3512703140207;1203.6088271311858;1263.7977923532007;22262;22417.43956401103;22442.214522491635;23065.65401518945;23206.83965801737;64061.35938640741;65253.67373163104;67447;68449;68773.65613171902;80369.59815200887;81217;82115;83786.58106709427;84306.57846710728;21310.36068917935;21910.452238693524;21916.780832191474;22111.778882210972;22155.44611384664;24292.392690182885;24314.51370972606;24405;24523.632145517473;24623.38441538976;19724.01379930969;19860.801391985897;19895.701564476254;20006.199752009758;20080.49798755043;17334.47996560105;17564;17633.294668213643;18168.545786355447;18182.36361727333;19976.20095196176;20219.59560808746;20385.79614203839;20474.181032758523;20582.27962021322;21419.892900535397;21515.46211344666;21630.6755398666;21794.673079903496;21809.89095054578;19351.90324048371;19407.902960485106;19597.216111355385;19789.505262368555;19805.900970495633 21:09:50 INFO - PID 7653 | |3;mootools.html;1418.4538952503287;1482.9555113346603;1494.813148356455;1507.2388443835855;1514.2428785607196;16640.251188696508;17318.047507387088;17361.565960850985;17442.040687762168;17482.38811641593;17047.232874520647;17072.231749571267;17107.657846843085;17114;17215.311387544516;2883.9423211535864;2892.8698208580613;2946.6758656547763;2959.156640357488;2965.5255159174535;2803.7756979441597;2816.605675205473;2832.7450529452344;2838.787090968175;2840.3893162969957;2780.7219278072134;2812.339100311425;2878.553824157259;2967.9703202968017;2991.8504074796283;2825.095969289828;2838.886444542216;2858.056841242385;2872.5691146328113;2877.323828900207;2703.5809449535354;2814.3526988792487;2816.2959260184953;2822.4637318909545;2822.520171570817;16719.414820481226;16733.07968061756;16751.83248167515;16780.160991950477;16796.496105116865;8659.177378149066;8941.195292423648;8944.955275223581;8947.821043579157;8949.597268122885;5420.989108180334;5488.396276409591;5640.717964101819;5673.23411339468;5682.147677848356;2106.147010460766;2149.795769401904;2169.0998235732154;2225.098834971839;2230.02993697742;30955.690443095507;31076.844615776776;31084.689153108404;31229;31230.53154202689;6915.132914113161;7128.203860698444;7136.31484089547;7234.421246300389;7272.854542909112;20104.89947550253;20178.697319539926;20193.192272308945;20207.59584808325;20232.393028208884;8095;8815;9013.369064165576;9044.366894317467;9044.412113212751;7941.960290198512;7950.761477155695;8013.559254240956;8070.596470176468;8099.14958929304;5502.367227768777;5710.543156547466;5748.540116790647;5781.855453613609;5796.1595568642315;1602.1028224194488;1609.3964763213794;1622.6998005368807;1658.941937032198;1659.8174200837898;900.4552245891141;908.4413085952092;918.9908100919039;920.0845159066771;920.9907900921039;7824.843503129906;7848.686052557948;7875.251851074138;7921.683132674743;7957.323627491728;736.495500582103;746.9253074692488;757.2730179028194;767.9040119985002;771.3597713897564;4803.519648035169;5261.815836445706;5272.314599102092;5317.946820531745;5473.014857325719;24879.253622391356;25781.74218257831;25955.610665840028;26044.479110417684;26149.477010459683;24468.632970505456;24989;25177.74822251791;25236;25684.743152568608;12590.496380144876;12662.430190641444;13036.674083147996;13042.087053906136;13052.412641431158;8648.654053838027;8800.823983520422;8986.86519702192;9075.364724469224;9083;20849.79150208539;21204.363869083336;21608.243711470022;21654.13383464707;21979;21819.345419637437;22230.555388892455;22490.212842551085;22532.66201007019;22557.774422255567;13281.867181328063;13529.661758455724;13534.526291579747;13550.661233468847;13554.728905422033;14979.251037448303;15021.849781502046;15041.097534147986;15207.31567079462;15213.543593692208;11683.18217724751;11931.940340298243;12094.455749491119;12222.572209972608;12412.81380779271;11843.755151793808;11886.16796824214;12106;12201.145919785884;12209.267443953391;10003.849942251018;10156.644517441853;10159.339642923187;10163.23775716839;10165.593376264867;7666.19504951972;7671.309582137411;7696.461247712607;7737.845243095219;7783.104942931635;7401.222871598405;7438.702451901863;7484.476086673881;7588.27911348421;7590 21:09:50 INFO - PID 7653 | |4;prototype.html;538.3943064052943;554.9972250138749;578.7627072900111;587.5886879184571;591.1930215256174;6267.279262884769;6347.714352854121;6415.294317625064;6432.678366081695;6465.482761379096;6304.464120549759;6334.398232167948;6339.144215530902;6344.669048749409;6395.296517383084;5160.767765450545;5170.224466330043;5179.477954990605;5206.255904272073;5218.895622087565;4923;5042.235217673803;5057.544820966113;5183.118869792144;5194.091034069042;5039.09296326661;5051.747412629372;5081.237814327844;5108.182690769478;5114.465660301913;4986.850394488171;4997.50024997501;5033.647644664857;5038.874028149289;5058.367704036977;4507.819687212507;4555.157295900269;4567.611753000999;4589.05924285524;4590.586847183736;6053.69731513427;6124.265088189446;6128.662923539204;6153.753849846001;6233.563650544464;5869.85325366865;5912.113183022538;5967.910481342783;5978.103284507315;6027.186329845503;6707;6767.35710107539;6772.593644381303;6774.61418172554;6840.589564626089;5064.468230835746;5106.055379754757;5115.590752739772;5155.097857874857;5177.119889618736;6836.897446538306;6879.415249703831;6984.266652001568;7020.754273600451;7022.016917631536;5688.715564221855;5738.282714660668;5758.683272420057;5769.249997500298;5816.331121921034;5813.331466881363;5835.445632664975;5839.934034631852;5855.238818953594;5864.325602555676;6211.689415529206;6231;6259.2488901332135;6275.717592708339;6280.874382512324;6824.556403833735;6832.180138383425;6887.690053947585;6888.311168883171;6904.240533541307;2007.9397618071482;2030.4822270320974;2032.1972820735728;2035.592881423721;2053.866498677611;1432.7062952094795;1542.7685847122798;1556.8053993250844;1568.6627375114176;1575.6376033512356;6199.690015499207;6267.7806276781;6272;6299.622022678654;6343.809685709436;6743.865122697617;6753.189617245962;6763.830904227434;6777.152855893013;6807.128039789098;6096.817095487142;6136.140940268366;6165.290991535941;6193.535484838655;6224.253089629183;3515.191505953645;3561.4479755637917;3568.0732404501277;3596.8741094062107;3616.2044350242904;7006.75476358325;7015.438764898796;7018.964905175441;7047.365737083687;7108.253633368422;7024.085004722982;7026.648667566601;7041.225465198824;7046.788596342118;7088.149422069385;6990.790276291719;7080.575165490087;7086.610236436943;7140.143182817991;7180.066591343092;6786.524943254122;6838.076859623887;6838.760643377458;6852.211995620368;6854.177498700188;6740.460763139035;6759.695813688494;6770.255271919987;6775.457963363017;6777.525573209828;6781.898271525833;6897.655117244218;6964.303569643198;6968.163820341591;7046.379332753598;5599.188117722989;5630.7466164023535;5649.152627105967;5697.6296540724725;5745.2818397700285;6558.508111891531;6606.8348291293105;6613.371729685865;6731.932680673131;6884.621345826027;6516.022596610357;6621.33786621334;6650.268470468148;6674.332566743286;6701.262861085374;6514.902276465951;6536.1894385412825;6618.933810661832;6661.16735408074;6687.164104486939;6584.539082264387;6595.207755540488;6604.768833090819;6668.866622667617;6677.732890684319;6681.732730690718;6772.255051944184;6782.118324617965;6822.283660215606;6833.89749153773;6452.645104519296;6527.5430719849155;6533.477321814148;6560.343965603402;6583.473322134314 21:09:50 INFO - PID 7653 | |5;yui.html;962.6245764151986;971.6502059258665;998.6754304850925;1006.290565151568;1006.3760468509525;20754.377368678925;21184.788152118475;21222.257220997268;21247.362579122633;21272.57454850902;19239.807601923978;19808.90095549524;20189.19243230269;20407.795922040776;20581.588368232664;1034.6585626743176;1050.070687441612;1050.1179009631908;1055.688571871296;1055.7360659835042;1049.150188347441;1055.3667799320424;1058.6718117383637;1064.542246833861;1066.2536224642743;1108.1799468393372;1112.2881355932209;1115.0299239661506;1115.1134847796022;1115.1469126118525;1101.686019484443;1104.9613263535819;1106.9446527673624;1107.9113670906308;1109.206917054302;769.2576663519731;802.5786462107382;807.4630370803409;813.3452570680599;817.0114161864152;19309.903450482656;19373.709394359095;19402.4179274622;19494.220231190735;19509.9024504878;22602.54794904109;22627.773722262733;22734.54530909389;22746;22829.77170228293;17711.202995865217;17888.91055544727;18085.909570452062;18151.364702235358;18194.363197288163;3942.960570394288;3969.186316805048;3972.563018068039;3973.106051138471;3975.52293724752;159703;161308;161861.19069404577;163617.3638263614;165555;17788;17852.642947140983;18130.818691812914;18183.181756820974;18340.266389344542;34092.829535852165;34152;34250.82874585661;34294.65705342965;34473.827630861684;8994.685186018589;9128.954355228314;9153.35926485153;9164;9171.954140229256;4766.094442055998;4789.808407663724;4818.060478206716;4848.684835485682;4858.805647774049;6101.206843110456;6128.203333566607;6162.476189523941;6218.471429928416;6230.59501132425;1101.8677758669012;1117.5976648406568;1130.3726431830248;1139.32779660001;1139.4302848575712;745.5526683989672;755.467395486181;766.769969009295;776.6427443375998;780.4458834227648;13283.203007819562;13638;13669.316534173251;13771.449142034206;13876.930615346859;740.2449501508539;742.5396254322354;742.6027075514681;751.3575892611739;760.8782594784808;6200;6264.404881536247;6290.528210384148;6299.086632438362;6320.841978950563;153960;156468;156629.21685391729;157048.7798798565;158823.20588397214;111189.44405277922;132038;136104;137893;138001;111164;111527;111975.4401228005;116532.41733791276;116761;14907.478238261609;14931.10413375201;15047.462441139272;15245.847541524887;15322.233888305764;53132.468675314296;53361.7331913338;53578.732106339216;54185.72907135439;54717.45282547283;69518.30481695119;70231;70328;71258;71470.64264678852;20311.4922126948;21129.6830547545;21131.366059018255;21158.153673852874;21329.466763331042;22861.65707514422;22886.313410597708;22950;22956.081756729545;23059.654105188773;21420.250291239732;21441.14235430565;21609.35171944844;21697.783022169577;21939.451513711647;20885;21102.894485527475;21282.893585531972;21367.35897923065;21377;20634.896825515778;20770.792292076887;20848.68726969127;20898.791012089685;20997.055132518766;15710.293036813142;15784.60538486547;15811.446599368966;15820;15851.048937063813;14244.21656808844;14542.272886355428;14543.636409089857;14592;14664.266786660837 21:09:50 INFO - PID 7653 | __end_tp_report 21:09:50 INFO - PID 7653 | __start_cc_report 21:09:50 INFO - PID 7653 | _x_x_mozilla_cycle_collect,274 21:09:50 INFO - PID 7653 | __end_cc_report 21:09:50 INFO - PID 7653 | __startTimestamp1506226190397__endTimestamp 21:09:50 INFO - PID 7653 | 21:09:50 INFO - PID 7653 | ------- Summary: start ------- 21:09:50 INFO - PID 7653 | Number of tests: 6 21:09:50 INFO - PID 7653 | 21:09:50 INFO - PID 7653 | [#0] dojo.html Cycles:165 Average:6300.87 Median:6597.84 stddev:894.83 (13.6%) stddev-sans-first:876.50 21:09:50 INFO - PID 7653 | Values: 3840.3 3875.7 3894.9 3905.7 3911.1 6218.6 6220.0 6309.5 6311.5 6367.4 6314.0 6321.0 6350.6 6428.0 6454.9 5657.3 5765.1 5776.2 5798.6 5811.8 150.1 5454.3 5670.7 5697.9 5773.0 5670.4 5679.9 5738.0 5744.1 5747.0 5659.6 5683.9 5745.8 5748.3 5761.9 5643.5 5651.8 5731.3 5732.2 5736.6 6258.4 6289.9 6328.0 6356.1 6365.5 6705.7 6711.0 6839.1 6844.4 6940.4 6917.7 6944.0 6952.7 6977.7 6988.3 6254.2 6365.3 6380.0 6490.4 6502.9 7087.2 7176.4 7177.5 7180.5 7196.7 6792.0 6818.2 6818.4 6875.1 6891.3 6773.5 6872.3 6888.1 6890.7 6890.7 6713.6 6717.0 6805.8 6900.9 6912.0 6864.7 6868.5 6923.1 6954.7 6956.7 5022.7 5033.1 5080.6 5083.8 5112.2 4521.1 4553.5 4579.6 4584.8 4617.5 6357.6 6360.1 6368.3 6377.4 6393.6 6706.9 6820.4 6875.7 6892.9 6906.1 6229.5 6259.1 6311.0 6315.8 6351.9 5519.0 5531.0 5606.4 5620.7 5630.9 7116.9 7194.4 7212.3 7231.6 7238.6 6814.0 6815.2 6831.0 6913.3 6928.2 7009.6 7112.1 7129.8 7139.1 7145.1 6812.1 6826.7 6870.8 6890.0 6906.5 6806.0 6869.7 6901.0 6994.7 7005.6 6633.1 6678.6 6679.0 6680.0 6681.9 6634.1 6676.0 6689.2 6689.8 6735.4 6547.7 6550.4 6621.7 6645.2 6647.3 6597.8 6730.2 6809.7 6811.5 6829.8 6405.7 6469.6 6475.5 6490.7 6585.7 21:09:50 INFO - PID 7653 | 21:09:50 INFO - PID 7653 | [#1] ext.html Cycles:180 Average:40661.30 Median:36943.67 stddev:59305.55 (160.5%) stddev-sans-first:59396.94 21:09:50 INFO - PID 7653 | Values: 944.2 957.2 979.1 1000.9 1005.0 42979.4 44957.6 44972.1 44989.3 45105.3 41488.4 41890.2 41993.0 42001.0 42012.6 7642.5 7807.8 7814.4 7819.5 7820.1 7626.2 7668.8 7684.3 7709.7 7717.8 8012.7 8188.4 8204.7 8210.5 8211.9 8090.2 8125.5 8141.5 8152.4 8165.5 8302.8 8522.1 8524.9 8527.9 8532.4 40372.6 40632.6 40681.6 40703.6 40748.0 3707.6 3761.8 3762.6 3763.2 3764.1 31878.5 31998.2 32140.4 32261.8 32270.0 5425.8 5431.7 5466.4 5480.8 5486.4 200544.0 201092.0 201403.0 201565.0 201626.0 51191.2 51270.7 51306.7 51360.5 51495.0 54191.2 54196.2 54207.2 54214.0 54286.2 16683.4 16955.2 17011.0 17045.5 17050.1 13992.7 14040.5 14048.8 14073.4 14106.0 15139.5 15218.5 15339.3 15353.5 15440.2 5893.8 6073.6 6116.1 6122.9 6169.3 1103.3 1110.6 1113.6 1120.8 1130.5 25708.0 25761.4 25897.2 25913.7 25974.6 1189.9 1222.1 1226.5 1227.1 1230.5 13523.0 13524.9 13655.4 13701.4 13919.9 268740.0 269044.0 269558.0 270086.0 270223.0 48225.5 48644.5 48959.3 55405.4 55964.7 1508.5 1521.2 1529.0 1536.7 1541.2 30932.0 30966.8 31343.2 31866.0 32331.7 39678.0 39725.0 39737.8 39751.0 39757.0 208833.0 209112.0 209387.0 209889.0 210502.0 36725.0 36763.0 36876.8 36894.1 36993.3 45900.5 45903.0 45936.0 46308.0 46358.5 32919.8 33180.7 33401.0 33627.7 33993.5 34684.8 34811.0 34840.8 34997.5 35232.0 39144.2 40384.4 40524.6 40568.0 40616.6 30058.5 32340.2 33237.8 33248.8 33636.2 25312.0 25556.7 25835.4 25928.7 26681.6 21:09:50 INFO - PID 7653 | 21:09:50 INFO - PID 7653 | [#2] jquery.html Cycles:180 Average:40672.58 Median:23318.19 stddev:103370.20 (443.3%) stddev-sans-first:103616.42 21:09:50 INFO - PID 7653 | Values: 610.9 615.9 618.2 621.6 629.4 22783.5 23172.2 23429.5 23510.9 23544.9 24854.0 25537.4 25676.9 25740.6 25769.7 6780.3 6970.1 7018.5 7018.5 7021.8 6611.9 6846.9 6901.6 6914.2 6992.5 6839.0 6842.8 6845.0 6852.6 6885.2 6512.9 6832.8 6842.7 6852.7 6884.6 6338.6 6360.0 6362.6 6366.3 6367.0 29015.7 29217.4 29377.1 29469.6 29492.7 2314.2 2344.3 2345.2 2351.0 2352.6 20160.8 20464.8 21003.8 21149.5 21217.3 2512.5 2538.5 2581.1 2633.8 2649.6 139147.0 144739.3 145265.0 151024.0 151424.2 15582.5 16252.1 16318.3 16614.4 16792.4 25858.0 25907.2 25936.2 25947.1 25982.0 10589.0 10646.7 10687.1 10707.1 10783.8 9696.0 9756.5 9831.9 9917.8 9951.8 9817.0 9859.7 10029.6 10046.9 10173.0 2800.3 2808.2 2910.5 2925.4 3017.4 1043.5 1048.5 1052.8 1054.9 1057.3 20901.7 21055.9 21084.7 21235.9 21497.6 1107.5 1111.8 1115.3 1124.0 1128.5 11634.2 11778.9 11787.8 11941.5 12025.6 602640.0 616780.0 626158.0 626425.0 626938.0 126032.0 128803.4 130639.0 131357.3 135679.0 1023.4 1070.7 1201.4 1203.6 1263.8 22262.0 22417.4 22442.2 23065.7 23206.8 64061.4 65253.7 67447.0 68449.0 68773.7 80369.6 81217.0 82115.0 83786.6 84306.6 21310.4 21910.5 21916.8 22111.8 22155.4 24292.4 24314.5 24405.0 24523.6 24623.4 19724.0 19860.8 19895.7 20006.2 20080.5 17334.5 17564.0 17633.3 18168.5 18182.4 19976.2 20219.6 20385.8 20474.2 20582.3 21419.9 21515.5 21630.7 21794.7 21809.9 19351.9 19407.9 19597.2 19789.5 19805.9 21:09:50 INFO - PID 7653 | 21:09:50 INFO - PID 7653 | [#3] mootools.html Cycles:180 Average:10428.00 Median:2816.45 stddev:7887.65 (280.1%) stddev-sans-first:7880.73 21:09:50 INFO - PID 7653 | Values: 1418.5 1483.0 1494.8 1507.2 1514.2 16640.3 17318.0 17361.6 17442.0 17482.4 17047.2 17072.2 17107.7 17114.0 17215.3 2883.9 2892.9 2946.7 2959.2 2965.5 2803.8 2816.6 2832.7 2838.8 2840.4 2780.7 2812.3 2878.6 2968.0 2991.9 2825.1 2838.9 2858.1 2872.6 2877.3 2703.6 2814.4 2816.3 2822.5 2822.5 16719.4 16733.1 16751.8 16780.2 16796.5 8659.2 8941.2 8945.0 8947.8 8949.6 5421.0 5488.4 5640.7 5673.2 5682.1 2106.1 2149.8 2169.1 2225.1 2230.0 30955.7 31076.8 31084.7 31229.0 31230.5 6915.1 7128.2 7136.3 7234.4 7272.9 20104.9 20178.7 20193.2 20207.6 20232.4 8095.0 8815.0 9013.4 9044.4 9044.4 7942.0 7950.8 8013.6 8070.6 8099.1 5502.4 5710.5 5748.5 5781.9 5796.2 1602.1 1609.4 1622.7 1658.9 1659.8 900.5 908.4 919.0 920.1 921.0 7824.8 7848.7 7875.3 7921.7 7957.3 736.5 746.9 757.3 767.9 771.4 4803.5 5261.8 5272.3 5317.9 5473.0 24879.3 25781.7 25955.6 26044.5 26149.5 24468.6 24989.0 25177.7 25236.0 25684.7 12590.5 12662.4 13036.7 13042.1 13052.4 8648.7 8800.8 8986.9 9075.4 9083.0 20849.8 21204.4 21608.2 21654.1 21979.0 21819.3 22230.6 22490.2 22532.7 22557.8 13281.9 13529.7 13534.5 13550.7 13554.7 14979.3 15021.8 15041.1 15207.3 15213.5 11683.2 11931.9 12094.5 12222.6 12412.8 11843.8 11886.2 12106.0 12201.1 12209.3 10003.8 10156.6 10159.3 10163.2 10165.6 7666.2 7671.3 7696.5 7737.8 7783.1 7401.2 7438.7 7484.5 7588.3 7590.0 21:09:50 INFO - PID 7653 | 21:09:50 INFO - PID 7653 | [#4] prototype.html Cycles:180 Average:5762.63 Median:6319.43 stddev:1557.02 (24.6%) stddev-sans-first:1511.21 21:09:50 INFO - PID 7653 | Values: 538.4 555.0 578.8 587.6 591.2 6267.3 6347.7 6415.3 6432.7 6465.5 6304.5 6334.4 6339.1 6344.7 6395.3 5160.8 5170.2 5179.5 5206.3 5218.9 4923.0 5042.2 5057.5 5183.1 5194.1 5039.1 5051.7 5081.2 5108.2 5114.5 4986.9 4997.5 5033.6 5038.9 5058.4 4507.8 4555.2 4567.6 4589.1 4590.6 6053.7 6124.3 6128.7 6153.8 6233.6 5869.9 5912.1 5967.9 5978.1 6027.2 6707.0 6767.4 6772.6 6774.6 6840.6 5064.5 5106.1 5115.6 5155.1 5177.1 6836.9 6879.4 6984.3 7020.8 7022.0 5688.7 5738.3 5758.7 5769.2 5816.3 5813.3 5835.4 5839.9 5855.2 5864.3 6211.7 6231.0 6259.2 6275.7 6280.9 6824.6 6832.2 6887.7 6888.3 6904.2 2007.9 2030.5 2032.2 2035.6 2053.9 1432.7 1542.8 1556.8 1568.7 1575.6 6199.7 6267.8 6272.0 6299.6 6343.8 6743.9 6753.2 6763.8 6777.2 6807.1 6096.8 6136.1 6165.3 6193.5 6224.3 3515.2 3561.4 3568.1 3596.9 3616.2 7006.8 7015.4 7019.0 7047.4 7108.3 7024.1 7026.6 7041.2 7046.8 7088.1 6990.8 7080.6 7086.6 7140.1 7180.1 6786.5 6838.1 6838.8 6852.2 6854.2 6740.5 6759.7 6770.3 6775.5 6777.5 6781.9 6897.7 6964.3 6968.2 7046.4 5599.2 5630.7 5649.2 5697.6 5745.3 6558.5 6606.8 6613.4 6731.9 6884.6 6516.0 6621.3 6650.3 6674.3 6701.3 6514.9 6536.2 6618.9 6661.2 6687.2 6584.5 6595.2 6604.8 6668.9 6677.7 6681.7 6772.3 6782.1 6822.3 6833.9 6452.6 6527.5 6533.5 6560.3 6583.5 21:09:50 INFO - PID 7653 | 21:09:50 INFO - PID 7653 | [#5] yui.html Cycles:180 Average:29093.90 Median:20809.74 stddev:42759.54 (205.5%) stddev-sans-first:42827.32 21:09:50 INFO - PID 7653 | Values: 962.6 971.7 998.7 1006.3 1006.4 20754.4 21184.8 21222.3 21247.4 21272.6 19239.8 19808.9 20189.2 20407.8 20581.6 1034.7 1050.1 1050.1 1055.7 1055.7 1049.2 1055.4 1058.7 1064.5 1066.3 1108.2 1112.3 1115.0 1115.1 1115.1 1101.7 1105.0 1106.9 1107.9 1109.2 769.3 802.6 807.5 813.3 817.0 19309.9 19373.7 19402.4 19494.2 19509.9 22602.5 22627.8 22734.5 22746.0 22829.8 17711.2 17888.9 18085.9 18151.4 18194.4 3943.0 3969.2 3972.6 3973.1 3975.5 159703.0 161308.0 161861.2 163617.4 165555.0 17788.0 17852.6 18130.8 18183.2 18340.3 34092.8 34152.0 34250.8 34294.7 34473.8 8994.7 9129.0 9153.4 9164.0 9172.0 4766.1 4789.8 4818.1 4848.7 4858.8 6101.2 6128.2 6162.5 6218.5 6230.6 1101.9 1117.6 1130.4 1139.3 1139.4 745.6 755.5 766.8 776.6 780.4 13283.2 13638.0 13669.3 13771.4 13876.9 740.2 742.5 742.6 751.4 760.9 6200.0 6264.4 6290.5 6299.1 6320.8 153960.0 156468.0 156629.2 157048.8 158823.2 111189.4 132038.0 136104.0 137893.0 138001.0 111164.0 111527.0 111975.4 116532.4 116761.0 14907.5 14931.1 15047.5 15245.8 15322.2 53132.5 53361.7 53578.7 54185.7 54717.5 69518.3 70231.0 70328.0 71258.0 71470.6 20311.5 21129.7 21131.4 21158.2 21329.5 22861.7 22886.3 22950.0 22956.1 23059.7 21420.3 21441.1 21609.4 21697.8 21939.5 20885.0 21102.9 21282.9 21367.4 21377.0 20634.9 20770.8 20848.7 20898.8 20997.1 15710.3 15784.6 15811.4 15820.0 15851.0 14244.2 14542.3 14543.6 14592.0 14664.3 21:09:50 INFO - PID 7653 | -------- Summary: end -------- 21:09:50 INFO - PID 7653 | 21:09:51 INFO - TEST-INFO | 7653: exit 0 21:09:51 INFO - TEST-OK | dromaeo_css | took 1107745ms 21:09:51 INFO - TEST-START | kraken 21:09:51 INFO - Initialising browser for kraken test... 21:09:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54675/getInfo.html -profile /tmp/tmp4qj0p9/profile 21:09:51 INFO - TEST-INFO | started process 8024 (/builds/slave/test/build/application/firefox/firefox http://localhost:54675/getInfo.html) 21:09:56 INFO - TEST-INFO | 8024: exit 0 21:09:56 INFO - Browser initialized. 21:09:56 INFO - Running cycle 1/1 for kraken test... 21:09:56 INFO - Using env: {'DISPLAY': ':0', 21:09:56 INFO - 'HOME': '/home/cltbld', 21:09:56 INFO - 'JSGC_DISABLE_POISONING': '1', 21:09:56 INFO - 'LANG': 'en_US.UTF-8', 21:09:56 INFO - 'LANGUAGE': 'en_US:en', 21:09:56 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:09:56 INFO - 'LOGNAME': 'cltbld', 21:09:56 INFO - 'MAIL': '/var/mail/cltbld', 21:09:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:09:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:09:56 INFO - 'MOZ_CRASHREPORTER': '1', 21:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:56 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:09:56 INFO - 'MOZ_NO_REMOTE': '1', 21:09:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:09:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:56 INFO - 'NO_EM_RESTART': '1', 21:09:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:56 INFO - 'PWD': '/builds/slave/test', 21:09:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:09:56 INFO - 'RUST_BACKTRACE': 'full', 21:09:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:09:56 INFO - 'SHELL': '/bin/bash', 21:09:56 INFO - 'SHLVL': '1', 21:09:56 INFO - 'TERM': 'linux', 21:09:56 INFO - 'TMOUT': '86400', 21:09:56 INFO - 'USER': 'cltbld', 21:09:56 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195', 21:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:56 INFO - '_': '/tools/buildbot/bin/python'} 21:09:56 INFO - TEST-INFO | started process 8259 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4qj0p9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:09:57 INFO - PID 8259 | 21:09:57 INFO - PID 8259 | (/builds/slave/test/build/application/firefox/firefox:8308): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:09:57 INFO - PID 8259 | 21:10:07 INFO - PID 8259 | RSS: Main: 143851520 21:10:07 INFO - PID 8259 | 21:10:10 INFO - PID 8259 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54675/tests/kraken/driver.html?testName=audio-beat-detection) 21:10:11 INFO - PID 8259 | RSS: Main: 150048768 21:10:11 INFO - PID 8259 | 21:10:13 INFO - PID 8259 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54675/tests/kraken/driver.html?testName=audio-dft) 21:10:14 INFO - PID 8259 | RSS: Main: 150650880 21:10:14 INFO - PID 8259 | 21:10:17 INFO - PID 8259 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54675/tests/kraken/driver.html?testName=audio-fft) 21:10:17 INFO - PID 8259 | RSS: Main: 147656704 21:10:17 INFO - PID 8259 | 21:10:19 INFO - PID 8259 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54675/tests/kraken/driver.html?testName=audio-oscillator) 21:10:20 INFO - PID 8259 | RSS: Main: 148664320 21:10:20 INFO - PID 8259 | 21:10:22 INFO - PID 8259 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54675/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:10:22 INFO - PID 8259 | RSS: Main: 148545536 21:10:22 INFO - PID 8259 | 21:10:26 INFO - PID 8259 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54675/tests/kraken/driver.html?testName=imaging-darkroom) 21:10:26 INFO - PID 8259 | RSS: Main: 148463616 21:10:26 INFO - PID 8259 | 21:10:30 INFO - PID 8259 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54675/tests/kraken/driver.html?testName=imaging-desaturate) 21:10:30 INFO - PID 8259 | RSS: Main: 149876736 21:10:30 INFO - PID 8259 | 21:10:33 INFO - PID 8259 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54675/tests/kraken/driver.html?testName=json-parse-financial) 21:10:34 INFO - PID 8259 | RSS: Main: 151097344 21:10:34 INFO - PID 8259 | 21:10:35 INFO - PID 8259 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54675/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:10:36 INFO - PID 8259 | RSS: Main: 150933504 21:10:36 INFO - PID 8259 | 21:10:38 INFO - PID 8259 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:10:38 INFO - PID 8259 | RSS: Main: 150474752 21:10:38 INFO - PID 8259 | 21:10:40 INFO - PID 8259 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:10:41 INFO - PID 8259 | RSS: Main: 147750912 21:10:41 INFO - PID 8259 | 21:10:43 INFO - PID 8259 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:10:44 INFO - PID 8259 | RSS: Main: 148082688 21:10:44 INFO - PID 8259 | 21:10:47 INFO - PID 8259 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:10:47 INFO - PID 8259 | RSS: Main: 148684800 21:10:47 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | Cycle 1(1): loaded http://localhost:54675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54675/tests/kraken/driver.html?testName=ai-astar) 21:10:49 INFO - PID 8259 | RSS: Main: 150200320 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | __start_tp_report 21:10:49 INFO - PID 8259 | _x_x_mozilla_page_load 21:10:49 INFO - PID 8259 | _x_x_mozilla_page_load_details 21:10:49 INFO - PID 8259 | |i|pagename|runs| 21:10:49 INFO - PID 8259 | |0;ai-astar;106;105;104;105;106;107;108;107;108;106 21:10:49 INFO - PID 8259 | |1;audio-beat-detection;117;116;116;117;114;115;119;113;117;116 21:10:49 INFO - PID 8259 | |2;audio-dft;162;162;162;161;162;161;163;162;164;168 21:10:49 INFO - PID 8259 | |3;audio-fft;72;73;74;74;74;74;72;73;73;73 21:10:49 INFO - PID 8259 | |4;audio-oscillator;79;78;77;78;77;78;78;77;76;78 21:10:49 INFO - PID 8259 | |5;imaging-gaussian-blur;114;113;113;114;114;115;116;114;114;115 21:10:49 INFO - PID 8259 | |6;imaging-darkroom;152;151;155;151;151;150;151;151;152;151 21:10:49 INFO - PID 8259 | |7;imaging-desaturate;94;94;94;94;95;94;94;94;94;96 21:10:49 INFO - PID 8259 | |8;json-parse-financial;45;44;44;45;44;44;45;45;44;45 21:10:49 INFO - PID 8259 | |9;json-stringify-tinderbox;36;37;36;37;37;37;37;37;36;36 21:10:49 INFO - PID 8259 | |10;stanford-crypto-aes;73;71;70;69;74;70;71;72;70;71 21:10:49 INFO - PID 8259 | |11;stanford-crypto-ccm;110;106;104;102;111;108;108;107;103;111 21:10:49 INFO - PID 8259 | |12;stanford-crypto-pbkdf2;156;153;155;154;151;156;151;153;154;152 21:10:49 INFO - PID 8259 | |13;stanford-crypto-sha256-iterative;59;60;61;61;62;60;60;59;60;60 21:10:49 INFO - PID 8259 | __end_tp_report 21:10:49 INFO - PID 8259 | __start_cc_report 21:10:49 INFO - PID 8259 | _x_x_mozilla_cycle_collect,688 21:10:49 INFO - PID 8259 | __end_cc_report 21:10:49 INFO - PID 8259 | __startTimestamp1506226249552__endTimestamp 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | ------- Summary: start ------- 21:10:49 INFO - PID 8259 | Number of tests: 14 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | [#0] ai-astar Cycles:10 Average:106.20 Median:106.50 stddev:1.32 (1.2%) stddev-sans-first:1.39 21:10:49 INFO - PID 8259 | Values: 106.0 105.0 104.0 105.0 106.0 107.0 108.0 107.0 108.0 106.0 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | [#1] audio-beat-detection Cycles:10 Average:116.00 Median:116.50 stddev:1.70 (1.5%) stddev-sans-first:1.76 21:10:49 INFO - PID 8259 | Values: 117.0 116.0 116.0 117.0 114.0 115.0 119.0 113.0 117.0 116.0 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | [#2] audio-dft Cycles:10 Average:162.70 Median:162.00 stddev:2.06 (1.3%) stddev-sans-first:2.17 21:10:49 INFO - PID 8259 | Values: 162.0 162.0 162.0 161.0 162.0 161.0 163.0 162.0 164.0 168.0 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | [#3] audio-fft Cycles:10 Average:73.20 Median:73.50 stddev:0.79 (1.1%) stddev-sans-first:0.71 21:10:49 INFO - PID 8259 | Values: 72.0 73.0 74.0 74.0 74.0 74.0 72.0 73.0 73.0 73.0 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:78.00 stddev:0.84 (1.1%) stddev-sans-first:0.73 21:10:49 INFO - PID 8259 | Values: 79.0 78.0 77.0 78.0 77.0 78.0 78.0 77.0 76.0 78.0 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 21:10:49 INFO - PID 8259 | Values: 114.0 113.0 113.0 114.0 114.0 115.0 116.0 114.0 114.0 115.0 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | [#6] imaging-darkroom Cycles:10 Average:151.50 Median:151.00 stddev:1.35 (0.9%) stddev-sans-first:1.42 21:10:49 INFO - PID 8259 | Values: 152.0 151.0 155.0 151.0 151.0 150.0 151.0 151.0 152.0 151.0 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 21:10:49 INFO - PID 8259 | Values: 94.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 96.0 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | [#8] json-parse-financial Cycles:10 Average:44.50 Median:45.00 stddev:0.53 (1.2%) stddev-sans-first:0.53 21:10:49 INFO - PID 8259 | Values: 45.0 44.0 44.0 45.0 44.0 44.0 45.0 45.0 44.0 45.0 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | [#9] json-stringify-tinderbox Cycles:10 Average:36.60 Median:37.00 stddev:0.52 (1.4%) stddev-sans-first:0.50 21:10:49 INFO - PID 8259 | Values: 36.0 37.0 36.0 37.0 37.0 37.0 37.0 37.0 36.0 36.0 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | [#10] stanford-crypto-aes Cycles:10 Average:71.10 Median:71.00 stddev:1.52 (2.1%) stddev-sans-first:1.45 21:10:49 INFO - PID 8259 | Values: 73.0 71.0 70.0 69.0 74.0 70.0 71.0 72.0 70.0 71.0 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | [#11] stanford-crypto-ccm Cycles:10 Average:107.00 Median:108.00 stddev:3.23 (3.0%) stddev-sans-first:3.24 21:10:49 INFO - PID 8259 | Values: 110.0 106.0 104.0 102.0 111.0 108.0 108.0 107.0 103.0 111.0 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.50 Median:154.00 stddev:1.84 (1.2%) stddev-sans-first:1.72 21:10:49 INFO - PID 8259 | Values: 156.0 153.0 155.0 154.0 151.0 156.0 151.0 153.0 154.0 152.0 21:10:49 INFO - PID 8259 | 21:10:49 INFO - PID 8259 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.20 Median:60.00 stddev:0.92 (1.5%) stddev-sans-first:0.87 21:10:49 INFO - PID 8259 | Values: 59.0 60.0 61.0 61.0 62.0 60.0 60.0 59.0 60.0 60.0 21:10:49 INFO - PID 8259 | -------- Summary: end -------- 21:10:49 INFO - PID 8259 | 21:10:50 INFO - PID 8259 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 21:10:50 INFO - TEST-INFO | 8259: exit 0 21:10:50 INFO - TEST-OK | kraken | took 59322ms 21:10:50 INFO - SUITE-END | took 1167s 21:10:50 INFO - Completed test suite (00:19:27) 21:10:50 INFO - javascript benchmark 21:10:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9186.538039444491, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3840.2703486337596, 3875.689944804416, 3894.8636797712084, 3905.7265991380605, 3911.0613452771327, 6218.62688238706, 6220.004799232123, 6309.526785491089, 6311.495080393569, 6367.426931576158, 6314.02132669437, 6321.020241862519, 6350.587211831228, 6428.035794630808, 6454.935450645504, 5657.32112146543, 5765.077587585987, 5776.162456443813, 5798.565107616924, 5811.796587119452, 150.05435768467106, 5454.263674403965, 5670.744816483269, 5697.914531282034, 5772.971135144318, 5670.376258611549, 5679.943200568004, 5737.971310143444, 5744.109663002241, 5747.023006088954, 5659.5755318351285, 5683.886322273573, 5745.827625171251, 5748.2527271454865, 5761.8559536011935, 5643.492085712305, 5651.802186923438, 5731.340895796995, 5732.22614946981, 5736.627119237278, 6258.374162583751, 6289.8742025159245, 6327.96836015817, 6356.0783686365685, 6365.522585806083, 6705.698243579001, 6710.966445167792, 6839.076724642159, 6844.384005439484, 6940.410065144469, 6917.688704008332, 6944.0, 6952.721891124349, 6977.686004129826, 6988.301169883021, 6254.155688981975, 6365.3316401778075, 6380.011098279774, 6490.448311893542, 6502.902456463157, 7087.184973728087, 7176.425885929218, 7177.461690373243, 7180.497365184387, 7196.712131514681, 6792.0, 6818.181818181751, 6818.386345228854, 6875.106236189264, 6891.310868913069, 6773.525853190328, 6872.347127022925, 6888.104546408935, 6890.689918953659, 6890.689918953659, 6713.597184168868, 6717.0, 6805.761798337035, 6900.861982760317, 6911.965440172868, 6864.691088901011, 6868.519203655696, 6923.099997000358, 6954.652267386711, 6956.7217311307995, 5022.698638081727, 5033.094043072282, 5080.59355251579, 5083.847484575469, 5112.156494178419, 4521.095780843844, 4553.54464553542, 4579.5878370946775, 4584.770761461914, 4617.5382461754225, 6357.618542887441, 6360.109584658151, 6368.29948705642, 6377.362263773585, 6393.552451328363, 6706.899396509057, 6820.3861652451515, 6875.690593923286, 6892.862142757117, 6906.102206713195, 6229.501639868799, 6259.123722678829, 6310.968445157745, 6315.810525684236, 6351.904721429182, 5519.0, 5530.972345138329, 5606.439356064361, 5620.718964051781, 5630.915536266959, 7116.857662846714, 7194.388476979515, 7212.314830091238, 7231.566106033655, 7238.638068096574, 6814.046033555207, 6815.2162501311, 6831.0, 6913.274106218976, 6928.237893831674, 7009.579425234502, 7112.110986126734, 7129.7861064168155, 7139.107611548557, 7145.1425828900865, 6812.14848143982, 6826.692798824164, 6870.828229294108, 6890.035395044598, 6906.516543842084, 6806.047153398429, 6869.690863911036, 6901.033855260368, 6994.6852391643515, 7005.649717514206, 6633.1376920999965, 6678.632675202911, 6678.966605166943, 6680.031395447536, 6681.899771503528, 6634.104395906491, 6676.0, 6689.2307384651385, 6689.832754181184, 6735.360140786617, 6547.70535325921, 6550.410463058348, 6621.6689165540565, 6645.235797883112, 6647.302033286435, 6597.835054123685, 6730.158730158731, 6809.727610895707, 6811.4550835934, 6829.795106146824, 6405.743770249138, 6469.644169570719, 6475.514336424882, 6490.675466226764, 6585.703643335965], "value": 6241.450789536042, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [944.1502647617145, 957.2389949989757, 979.0503211884472, 1000.9049140331671, 1004.9547770350333, 42979.35530967038, 44957.55042449575, 44972.10055798882, 44989.32516012262, 45105.323420148685, 41488.377674334835, 41890.16219675605, 41993.0, 42001.0, 42012.57987420125, 7642.503237289586, 7807.843843123134, 7814.413918956073, 7819.530828150329, 7820.139784623687, 7626.237376262357, 7668.769936901902, 7684.346830519385, 7709.730159444421, 7717.8070548236465, 8012.719554815582, 8188.426810123294, 8204.712835050774, 8210.46631968923, 8211.917880821205, 8090.231428014329, 8125.471844330129, 8141.470804397695, 8152.38857085721, 8165.510069395856, 8302.83394332116, 8522.105178956244, 8524.914750852475, 8527.914720852774, 8532.360072994488, 40372.596274037176, 40632.59367406347, 40681.59318406808, 40703.59296407057, 40748.0, 3707.5736290326686, 3761.774293542396, 3762.5861155272896, 3763.153290509641, 3764.1154328732728, 31878.521822172454, 31998.20004499883, 32140.357192856012, 32261.83869080663, 32270.0, 5425.755840987165, 5431.6740995540395, 5466.398696143421, 5480.7533660985355, 5486.3690675572025, 200543.99728001413, 201092.0, 201403.0, 201565.0, 201626.0, 51191.23213151806, 51270.74364628153, 51306.74346628243, 51360.486395136315, 51495.0, 54191.18713219305, 54196.187057194176, 54207.186892196645, 54214.0, 54286.18570721443, 16683.416080437128, 16955.152242388078, 17011.0, 17045.48863534096, 17050.06224657656, 13992.650183745283, 14040.508582199574, 14048.789268160986, 14073.437062517385, 14106.0, 15139.54581362561, 15218.46735364279, 15339.312675605674, 15353.462628807938, 15440.227988600525, 5893.764249430061, 6073.635581865102, 6116.082587611894, 6122.908156377569, 6169.321374648785, 1103.3159441146381, 1110.6001839337832, 1113.554578168739, 1120.7646394257133, 1130.5025788652965, 25708.0, 25761.355966100997, 25897.22308330753, 25913.74086259151, 25974.610380844304, 1189.935008167685, 1222.1139673736648, 1226.466487078124, 1227.0919519555644, 1230.4647478346947, 13523.0, 13524.93237533806, 13655.38550765218, 13701.383437745326, 13919.930400348136, 268740.0, 269044.0, 269558.0, 270086.0, 270223.0, 48225.5177448221, 48644.513554863996, 48959.265611015864, 55405.445945540836, 55964.72017639967, 1508.4946542908028, 1521.163360151934, 1529.0061460050833, 1536.7464368379315, 1541.1523661986087, 30932.0, 30966.845165774026, 31343.21641958969, 31866.0, 32331.676683232865, 39678.0, 39725.0, 39737.80131099326, 39751.006224843455, 39757.0, 208833.0, 209112.0, 209387.0, 209889.0, 210502.0, 36725.0, 36763.0, 36876.81561592175, 36894.077648059014, 36993.26013479769, 45900.540994589624, 45903.0, 45936.0, 46308.0, 46358.53641463677, 32919.83540082284, 33180.66819331872, 33401.0, 33627.690570073006, 33993.490097648064, 34684.82657586696, 34811.0, 34840.82579587086, 34997.47503787496, 35232.0, 39144.217115658095, 40384.3942340871, 40524.59475405208, 40568.0, 40616.593834061285, 30058.549121762753, 32340.191495212806, 33237.833810830794, 33248.83375583107, 33636.1590960228, 25312.0, 25556.74443255618, 25835.354116147246, 25928.740712592633, 26681.599776002986], "value": 18434.414631233612, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.938906109389, 615.8552740106077, 618.2210414877253, 621.5928566788755, 629.4083561452235, 22783.54432911343, 23172.188973385935, 23429.5314093718, 23510.88244558779, 23544.88227558864, 24854.00583976639, 25537.361565960815, 25676.871615641943, 25740.613890791654, 25769.742302576968, 6780.321967803217, 6970.09388779458, 7018.4736144789085, 7018.4736144789085, 7021.789346319619, 6611.9338806611795, 6846.931530684704, 6901.585904845725, 6914.239433662293, 6992.475564332695, 6839.0, 6842.828929276759, 6844.965775171142, 6852.623105729208, 6885.208201056893, 6512.902306465407, 6832.760853370133, 6842.726290948381, 6852.6916288767125, 6884.5525040872435, 6338.619682819046, 6360.0459931010255, 6362.554621176473, 6366.331535188831, 6366.968165159191, 29015.709842901513, 29217.41565168706, 29377.11868643944, 29469.557956630666, 29492.70507294921, 2314.166899916029, 2344.2615576093644, 2345.2260753951155, 2351.0, 2352.588297048027, 20160.79839201604, 20464.795352046436, 21003.789962100338, 21149.471263218387, 21217.257395991066, 2512.459821138464, 2538.517681640501, 2581.0966161843394, 2633.8288011279396, 2649.5628221343454, 139147.0, 144739.2763036178, 145265.0, 151024.0, 151424.2428787849, 15582.454614088452, 16252.106134162734, 16318.265678044516, 16614.418495352846, 16792.412265570645, 25858.0, 25907.22278331653, 25936.22191334263, 25947.091851785473, 25982.0, 10589.0, 10646.73383165427, 10687.091597214168, 10707.1434285257, 10783.78432431347, 9696.0, 9756.512174391251, 9831.852522212173, 9917.752056198653, 9951.751206219758, 9817.0, 9859.704208873745, 10029.648962286285, 10046.944149902583, 10173.0, 2800.341919648881, 2808.1528306220607, 2910.490664133768, 2925.400292939942, 3017.411604737099, 1043.4886905416292, 1048.5491238767252, 1052.7841792432687, 1054.878688950765, 1057.2704833664748, 20901.686474702892, 21055.8947205263, 21084.683729744065, 21235.893820530797, 21497.57004859894, 1107.545906178463, 1111.8332250162541, 1115.2639258089785, 1124.0, 1128.4696192789377, 11634.243774154822, 11778.941105294418, 11787.823182652268, 11941.462634181482, 12025.639230822915, 602640.0, 616780.0, 626158.0, 626425.0, 626938.0, 126032.0, 128803.35598322137, 130639.0, 131357.3432132833, 135679.0, 1023.3501726403787, 1070.7216123807866, 1201.3512703140207, 1203.6088271311858, 1263.7977923532007, 22262.0, 22417.43956401103, 22442.214522491635, 23065.65401518945, 23206.83965801737, 64061.35938640741, 65253.67373163104, 67447.0, 68449.0, 68773.65613171902, 80369.59815200887, 81217.0, 82115.0, 83786.58106709427, 84306.57846710728, 21310.36068917935, 21910.452238693524, 21916.780832191474, 22111.778882210972, 22155.44611384664, 24292.392690182885, 24314.51370972606, 24405.0, 24523.632145517473, 24623.38441538976, 19724.01379930969, 19860.801391985897, 19895.701564476254, 20006.199752009758, 20080.49798755043, 17334.47996560105, 17564.0, 17633.294668213643, 18168.545786355447, 18182.36361727333, 19976.20095196176, 20219.59560808746, 20385.79614203839, 20474.181032758523, 20582.27962021322, 21419.892900535397, 21515.46211344666, 21630.6755398666, 21794.673079903496, 21809.89095054578, 19351.90324048371, 19407.902960485106, 19597.216111355385, 19789.505262368555, 19805.900970495633], "value": 13168.938306223174, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.4538952503287, 1482.9555113346603, 1494.813148356455, 1507.2388443835855, 1514.2428785607196, 16640.251188696508, 17318.047507387088, 17361.565960850985, 17442.040687762168, 17482.38811641593, 17047.232874520647, 17072.231749571267, 17107.657846843085, 17114.0, 17215.311387544516, 2883.9423211535864, 2892.8698208580613, 2946.6758656547763, 2959.156640357488, 2965.5255159174535, 2803.7756979441597, 2816.605675205473, 2832.7450529452344, 2838.787090968175, 2840.3893162969957, 2780.7219278072134, 2812.339100311425, 2878.553824157259, 2967.9703202968017, 2991.8504074796283, 2825.095969289828, 2838.886444542216, 2858.056841242385, 2872.5691146328113, 2877.323828900207, 2703.5809449535354, 2814.3526988792487, 2816.2959260184953, 2822.4637318909545, 2822.520171570817, 16719.414820481226, 16733.07968061756, 16751.83248167515, 16780.160991950477, 16796.496105116865, 8659.177378149066, 8941.195292423648, 8944.955275223581, 8947.821043579157, 8949.597268122885, 5420.989108180334, 5488.396276409591, 5640.717964101819, 5673.23411339468, 5682.147677848356, 2106.147010460766, 2149.795769401904, 2169.0998235732154, 2225.098834971839, 2230.02993697742, 30955.690443095507, 31076.844615776776, 31084.689153108404, 31229.0, 31230.53154202689, 6915.132914113161, 7128.203860698444, 7136.31484089547, 7234.421246300389, 7272.854542909112, 20104.89947550253, 20178.697319539926, 20193.192272308945, 20207.59584808325, 20232.393028208884, 8095.0, 8815.0, 9013.369064165576, 9044.366894317467, 9044.412113212751, 7941.960290198512, 7950.761477155695, 8013.559254240956, 8070.596470176468, 8099.14958929304, 5502.367227768777, 5710.543156547466, 5748.540116790647, 5781.855453613609, 5796.1595568642315, 1602.1028224194488, 1609.3964763213794, 1622.6998005368807, 1658.941937032198, 1659.8174200837898, 900.4552245891141, 908.4413085952092, 918.9908100919039, 920.0845159066771, 920.9907900921039, 7824.843503129906, 7848.686052557948, 7875.251851074138, 7921.683132674743, 7957.323627491728, 736.495500582103, 746.9253074692488, 757.2730179028194, 767.9040119985002, 771.3597713897564, 4803.519648035169, 5261.815836445706, 5272.314599102092, 5317.946820531745, 5473.014857325719, 24879.253622391356, 25781.74218257831, 25955.610665840028, 26044.479110417684, 26149.477010459683, 24468.632970505456, 24989.0, 25177.74822251791, 25236.0, 25684.743152568608, 12590.496380144876, 12662.430190641444, 13036.674083147996, 13042.087053906136, 13052.412641431158, 8648.654053838027, 8800.823983520422, 8986.86519702192, 9075.364724469224, 9083.0, 20849.79150208539, 21204.363869083336, 21608.243711470022, 21654.13383464707, 21979.0, 21819.345419637437, 22230.555388892455, 22490.212842551085, 22532.66201007019, 22557.774422255567, 13281.867181328063, 13529.661758455724, 13534.526291579747, 13550.661233468847, 13554.728905422033, 14979.251037448303, 15021.849781502046, 15041.097534147986, 15207.31567079462, 15213.543593692208, 11683.18217724751, 11931.940340298243, 12094.455749491119, 12222.572209972608, 12412.81380779271, 11843.755151793808, 11886.16796824214, 12106.0, 12201.145919785884, 12209.267443953391, 10003.849942251018, 10156.644517441853, 10159.339642923187, 10163.23775716839, 10165.593376264867, 7666.19504951972, 7671.309582137411, 7696.461247712607, 7737.845243095219, 7783.104942931635, 7401.222871598405, 7438.702451901863, 7484.476086673881, 7588.27911348421, 7590.0], "value": 7214.0371162545, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.3943064052943, 554.9972250138749, 578.7627072900111, 587.5886879184571, 591.1930215256174, 6267.279262884769, 6347.714352854121, 6415.294317625064, 6432.678366081695, 6465.482761379096, 6304.464120549759, 6334.398232167948, 6339.144215530902, 6344.669048749409, 6395.296517383084, 5160.767765450545, 5170.224466330043, 5179.477954990605, 5206.255904272073, 5218.895622087565, 4923.0, 5042.235217673803, 5057.544820966113, 5183.118869792144, 5194.091034069042, 5039.09296326661, 5051.747412629372, 5081.237814327844, 5108.182690769478, 5114.465660301913, 4986.850394488171, 4997.50024997501, 5033.647644664857, 5038.874028149289, 5058.367704036977, 4507.819687212507, 4555.157295900269, 4567.611753000999, 4589.05924285524, 4590.586847183736, 6053.69731513427, 6124.265088189446, 6128.662923539204, 6153.753849846001, 6233.563650544464, 5869.85325366865, 5912.113183022538, 5967.910481342783, 5978.103284507315, 6027.186329845503, 6707.0, 6767.35710107539, 6772.593644381303, 6774.61418172554, 6840.589564626089, 5064.468230835746, 5106.055379754757, 5115.590752739772, 5155.097857874857, 5177.119889618736, 6836.897446538306, 6879.415249703831, 6984.266652001568, 7020.754273600451, 7022.016917631536, 5688.715564221855, 5738.282714660668, 5758.683272420057, 5769.249997500298, 5816.331121921034, 5813.331466881363, 5835.445632664975, 5839.934034631852, 5855.238818953594, 5864.325602555676, 6211.689415529206, 6231.0, 6259.2488901332135, 6275.717592708339, 6280.874382512324, 6824.556403833735, 6832.180138383425, 6887.690053947585, 6888.311168883171, 6904.240533541307, 2007.9397618071482, 2030.4822270320974, 2032.1972820735728, 2035.592881423721, 2053.866498677611, 1432.7062952094795, 1542.7685847122798, 1556.8053993250844, 1568.6627375114176, 1575.6376033512356, 6199.690015499207, 6267.7806276781, 6272.0, 6299.622022678654, 6343.809685709436, 6743.865122697617, 6753.189617245962, 6763.830904227434, 6777.152855893013, 6807.128039789098, 6096.817095487142, 6136.140940268366, 6165.290991535941, 6193.535484838655, 6224.253089629183, 3515.191505953645, 3561.4479755637917, 3568.0732404501277, 3596.8741094062107, 3616.2044350242904, 7006.75476358325, 7015.438764898796, 7018.964905175441, 7047.365737083687, 7108.253633368422, 7024.085004722982, 7026.648667566601, 7041.225465198824, 7046.788596342118, 7088.149422069385, 6990.790276291719, 7080.575165490087, 7086.610236436943, 7140.143182817991, 7180.066591343092, 6786.524943254122, 6838.076859623887, 6838.760643377458, 6852.211995620368, 6854.177498700188, 6740.460763139035, 6759.695813688494, 6770.255271919987, 6775.457963363017, 6777.525573209828, 6781.898271525833, 6897.655117244218, 6964.303569643198, 6968.163820341591, 7046.379332753598, 5599.188117722989, 5630.7466164023535, 5649.152627105967, 5697.6296540724725, 5745.2818397700285, 6558.508111891531, 6606.8348291293105, 6613.371729685865, 6731.932680673131, 6884.621345826027, 6516.022596610357, 6621.33786621334, 6650.268470468148, 6674.332566743286, 6701.262861085374, 6514.902276465951, 6536.1894385412825, 6618.933810661832, 6661.16735408074, 6687.164104486939, 6584.539082264387, 6595.207755540488, 6604.768833090819, 6668.866622667617, 6677.732890684319, 6681.732730690718, 6772.255051944184, 6782.118324617965, 6822.283660215606, 6833.89749153773, 6452.645104519296, 6527.5430719849155, 6533.477321814148, 6560.343965603402, 6583.473322134314], "value": 5328.966858024933, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [962.6245764151986, 971.6502059258665, 998.6754304850925, 1006.290565151568, 1006.3760468509525, 20754.377368678925, 21184.788152118475, 21222.257220997268, 21247.362579122633, 21272.57454850902, 19239.807601923978, 19808.90095549524, 20189.19243230269, 20407.795922040776, 20581.588368232664, 1034.6585626743176, 1050.070687441612, 1050.1179009631908, 1055.688571871296, 1055.7360659835042, 1049.150188347441, 1055.3667799320424, 1058.6718117383637, 1064.542246833861, 1066.2536224642743, 1108.1799468393372, 1112.2881355932209, 1115.0299239661506, 1115.1134847796022, 1115.1469126118525, 1101.686019484443, 1104.9613263535819, 1106.9446527673624, 1107.9113670906308, 1109.206917054302, 769.2576663519731, 802.5786462107382, 807.4630370803409, 813.3452570680599, 817.0114161864152, 19309.903450482656, 19373.709394359095, 19402.4179274622, 19494.220231190735, 19509.9024504878, 22602.54794904109, 22627.773722262733, 22734.54530909389, 22746.0, 22829.77170228293, 17711.202995865217, 17888.91055544727, 18085.909570452062, 18151.364702235358, 18194.363197288163, 3942.960570394288, 3969.186316805048, 3972.563018068039, 3973.106051138471, 3975.52293724752, 159703.0, 161308.0, 161861.19069404577, 163617.3638263614, 165555.0, 17788.0, 17852.642947140983, 18130.818691812914, 18183.181756820974, 18340.266389344542, 34092.829535852165, 34152.0, 34250.82874585661, 34294.65705342965, 34473.827630861684, 8994.685186018589, 9128.954355228314, 9153.35926485153, 9164.0, 9171.954140229256, 4766.094442055998, 4789.808407663724, 4818.060478206716, 4848.684835485682, 4858.805647774049, 6101.206843110456, 6128.203333566607, 6162.476189523941, 6218.471429928416, 6230.59501132425, 1101.8677758669012, 1117.5976648406568, 1130.3726431830248, 1139.32779660001, 1139.4302848575712, 745.5526683989672, 755.467395486181, 766.769969009295, 776.6427443375998, 780.4458834227648, 13283.203007819562, 13638.0, 13669.316534173251, 13771.449142034206, 13876.930615346859, 740.2449501508539, 742.5396254322354, 742.6027075514681, 751.3575892611739, 760.8782594784808, 6200.0, 6264.404881536247, 6290.528210384148, 6299.086632438362, 6320.841978950563, 153960.0, 156468.0, 156629.21685391729, 157048.7798798565, 158823.20588397214, 111189.44405277922, 132038.0, 136104.0, 137893.0, 138001.0, 111164.0, 111527.0, 111975.4401228005, 116532.41733791276, 116761.0, 14907.478238261609, 14931.10413375201, 15047.462441139272, 15245.847541524887, 15322.233888305764, 53132.468675314296, 53361.7331913338, 53578.732106339216, 54185.72907135439, 54717.45282547283, 69518.30481695119, 70231.0, 70328.0, 71258.0, 71470.64264678852, 20311.4922126948, 21129.6830547545, 21131.366059018255, 21158.153673852874, 21329.466763331042, 22861.65707514422, 22886.313410597708, 22950.0, 22956.081756729545, 23059.654105188773, 21420.250291239732, 21441.14235430565, 21609.35171944844, 21697.783022169577, 21939.451513711647, 20885.0, 21102.894485527475, 21282.893585531972, 21367.35897923065, 21377.0, 20634.896825515778, 20770.792292076887, 20848.68726969127, 20898.791012089685, 20997.055132518766, 15710.293036813142, 15784.60538486547, 15811.446599368966, 15820.0, 15851.048937063813, 14244.21656808844, 14542.272886355428, 14543.636409089857, 14592.0, 14664.266786660837], "value": 10318.057808874944, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1368.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 104.0, 105.0, 106.0, 107.0, 108.0, 107.0, 108.0, 106.0], "value": 106.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 114.0, 115.0, 119.0, 113.0, 117.0, 116.0], "value": 116.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 161.0, 162.0, 161.0, 163.0, 162.0, 164.0, 168.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 74.0, 74.0, 74.0, 74.0, 72.0, 73.0, 73.0, 73.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 77.0, 78.0, 77.0, 78.0, 78.0, 77.0, 76.0, 78.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 114.0, 114.0, 115.0, 116.0, 114.0, 114.0, 115.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 155.0, 151.0, 151.0, 150.0, 151.0, 151.0, 152.0, 151.0], "value": 151.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 96.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 44.0, 44.0, 45.0, 44.0, 44.0, 45.0, 45.0, 44.0, 45.0], "value": 44.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 37.0, 36.0, 37.0, 37.0, 37.0, 37.0, 37.0, 36.0, 36.0], "value": 36.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 70.0, 69.0, 74.0, 70.0, 71.0, 72.0, 70.0, 71.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 104.0, 102.0, 111.0, 108.0, 108.0, 107.0, 103.0, 111.0], "value": 107.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 153.0, 155.0, 154.0, 151.0, 156.0, 151.0, 153.0, 154.0, 152.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 61.0, 61.0, 62.0, 60.0, 60.0, 59.0, 60.0, 60.0], "value": 60.2, "unit": "score"}]}]} 21:10:50 INFO - Return code: 0 21:10:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:10:50 INFO - # TBPL SUCCESS # 21:10:50 INFO - Running post-action listener: _package_coverage_data 21:10:50 INFO - Running post-action listener: _resource_record_post_action 21:10:50 INFO - [mozharness: 2017-09-24 04:10:50.867082Z] Finished run-tests step (success) 21:10:50 INFO - Running post-run listener: _resource_record_post_run 21:10:50 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 430080; Write bytes: 387272704; Read time: 600; Write time: 362080 21:10:50 INFO - TinderboxPrint: CPU usage
13.1% 21:10:50 INFO - TinderboxPrint: I/O read bytes / time
430,080 / 600 21:10:50 INFO - TinderboxPrint: I/O write bytes / time
387,272,704 / 362,080 21:10:50 INFO - TinderboxPrint: CPU idle
8,209.8 (86.9%) 21:10:50 INFO - TinderboxPrint: CPU user
1,187.3 (12.6%) 21:10:50 INFO - TinderboxPrint: Swap in / out
0 / 0 21:10:50 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 74788864; Read time: 232; Write time: 53804 21:10:50 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 21:10:50 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 425984; Write bytes: 312483840; Read time: 368; Write time: 308276 21:10:51 INFO - Running post-run listener: _upload_blobber_files 21:10:51 INFO - Blob upload gear active. 21:10:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:10:51 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 21:10:51 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'] 21:10:51 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 21:10:51 INFO - (blobuploader) - INFO - Open directory for files ... 21:10:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:10:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:10:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:10:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:10:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:10:52 INFO - (blobuploader) - INFO - Done attempting. 21:10:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:10:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:10:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:10:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:10:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:10:53 INFO - (blobuploader) - INFO - Done attempting. 21:10:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:10:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:10:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:11:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:11:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:11:00 INFO - (blobuploader) - INFO - Done attempting. 21:11:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:11:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:11:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:11:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:11:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:11:01 INFO - (blobuploader) - INFO - Done attempting. 21:11:01 INFO - (blobuploader) - INFO - Iteration through files over. 21:11:01 INFO - Return code: 0 21:11:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:11:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:11:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07c991b6a8d067a6fb0baefae0614247a31e61161216fa514048c51fa0221629bed3b2443d49039110246791b01a972c2352ecd75fbb812834cd1c43fe019dc5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d4ffd446927c2499e00340848517c22492bbfb9ff5b84107a24addab2c5f5d9288cf958f7fad04496cef8ada1801d8a75df59fd5417445ce59e22521afe1147", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99a9ba3075cd164e7183523899df0b1623de165f423289d9be85d99499c9d27fdadb67fe3a201f9cb2bb02aa378ccc98a3e28019989e2ab4c3782e2eb8d57d73", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/241ea95286ced5ecb11260b05d3fd81f85324575e9c9e5f73bfecda9f8883fe305438b066899a8017e6b7389544ab18ddb6545766c4aa9a0e31c558810431228"} 21:11:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:11:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:11:01 INFO - Contents: 21:11:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07c991b6a8d067a6fb0baefae0614247a31e61161216fa514048c51fa0221629bed3b2443d49039110246791b01a972c2352ecd75fbb812834cd1c43fe019dc5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d4ffd446927c2499e00340848517c22492bbfb9ff5b84107a24addab2c5f5d9288cf958f7fad04496cef8ada1801d8a75df59fd5417445ce59e22521afe1147", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99a9ba3075cd164e7183523899df0b1623de165f423289d9be85d99499c9d27fdadb67fe3a201f9cb2bb02aa378ccc98a3e28019989e2ab4c3782e2eb8d57d73", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/241ea95286ced5ecb11260b05d3fd81f85324575e9c9e5f73bfecda9f8883fe305438b066899a8017e6b7389544ab18ddb6545766c4aa9a0e31c558810431228"} 21:11:01 INFO - Running post-run listener: copy_logs_to_upload_dir 21:11:01 INFO - Copying logs to upload dir... 21:11:01 INFO - mkdir: /builds/slave/test/build/upload/logs 21:11:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.465283 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-23 21:11:01.905348) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 21:11:01.913105) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07c991b6a8d067a6fb0baefae0614247a31e61161216fa514048c51fa0221629bed3b2443d49039110246791b01a972c2352ecd75fbb812834cd1c43fe019dc5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d4ffd446927c2499e00340848517c22492bbfb9ff5b84107a24addab2c5f5d9288cf958f7fad04496cef8ada1801d8a75df59fd5417445ce59e22521afe1147", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99a9ba3075cd164e7183523899df0b1623de165f423289d9be85d99499c9d27fdadb67fe3a201f9cb2bb02aa378ccc98a3e28019989e2ab4c3782e2eb8d57d73", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/241ea95286ced5ecb11260b05d3fd81f85324575e9c9e5f73bfecda9f8883fe305438b066899a8017e6b7389544ab18ddb6545766c4aa9a0e31c558810431228"} build_url:https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011455 build_url: 'https://queue.taskcluster.net/v1/task/Q3qoSt1vRnKturi1wRqswg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07c991b6a8d067a6fb0baefae0614247a31e61161216fa514048c51fa0221629bed3b2443d49039110246791b01a972c2352ecd75fbb812834cd1c43fe019dc5", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d4ffd446927c2499e00340848517c22492bbfb9ff5b84107a24addab2c5f5d9288cf958f7fad04496cef8ada1801d8a75df59fd5417445ce59e22521afe1147", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99a9ba3075cd164e7183523899df0b1623de165f423289d9be85d99499c9d27fdadb67fe3a201f9cb2bb02aa378ccc98a3e28019989e2ab4c3782e2eb8d57d73", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/241ea95286ced5ecb11260b05d3fd81f85324575e9c9e5f73bfecda9f8883fe305438b066899a8017e6b7389544ab18ddb6545766c4aa9a0e31c558810431228"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 21:11:01.943828) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 21:11:01.944132) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506224109.510516-1216610195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004625 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 21:11:02.026523) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 21:11:02.029884) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 21:11:02.030414) ========= ========= Total master_lag: 0.31 =========