builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-048 starttime: 1506943464.94 results: success (0) revision: b4352de175f8a950bc89a2b1b51f32489ca1e4e4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 04:24:24.941255) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 04:24:24.941660) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 04:24:24.941946) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 04:24:25.045187) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 04:24:25.045483) ========= 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=fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-02 04:24:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 106M=0s 2017-10-02 04:24:25 (106 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144166 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 04:24:25.233074) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 04:24:25.233399) ========= 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=fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018762 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 04:24:25.286386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 04:24:25.286684) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b4352de175f8a950bc89a2b1b51f32489ca1e4e4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b4352de175f8a950bc89a2b1b51f32489ca1e4e4 --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=fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-02 04:24:25,476 truncating revision to first 12 chars 2017-10-02 04:24:25,476 Setting DEBUG logging. 2017-10-02 04:24:25,476 attempt 1/10 2017-10-02 04:24:25,476 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b4352de175f8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-02 04:24:25,915 unpacking tar archive at: mozilla-beta-b4352de175f8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.749311 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 04:24:26.054627) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 04:24:26.054946) ========= script_repo_revision: b4352de175f8a950bc89a2b1b51f32489ca1e4e4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 04:24:26.055333) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 04:24:26.055601) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 04:24:26.074338) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-10-02 04:24:26.074710) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 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', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] 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=fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:24:26 INFO - MultiFileLogger online at 20171002 04:24:26 in /builds/slave/test 04:24:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 04:24:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:24:26 INFO - {'append_to_log': False, 04:24:26 INFO - 'base_work_dir': '/builds/slave/test', 04:24:26 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 04:24:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:24:26 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 04:24:26 INFO - 'buildbot_json_path': 'buildprops.json', 04:24:26 INFO - 'code_coverage': False, 04:24:26 INFO - 'config_files': ('talos/linux_config.py',), 04:24:26 INFO - 'default_actions': ('clobber', 04:24:26 INFO - 'read-buildbot-config', 04:24:26 INFO - 'download-and-extract', 04:24:26 INFO - 'populate-webroot', 04:24:26 INFO - 'create-virtualenv', 04:24:26 INFO - 'install', 04:24:26 INFO - 'setup-mitmproxy', 04:24:26 INFO - 'run-tests'), 04:24:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:24:26 INFO - 'disable_ccov_upload': False, 04:24:26 INFO - 'disable_stylo': False, 04:24:26 INFO - 'download_minidump_stackwalk': True, 04:24:26 INFO - 'download_symbols': 'ondemand', 04:24:26 INFO - 'enable_stylo': False, 04:24:26 INFO - 'enable_webrender': False, 04:24:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:24:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:24:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:24:26 INFO - 'gecko_profile': False, 04:24:26 INFO - 'gecko_profile_interval': 0, 04:24:26 INFO - 'installer_path': 'installer.exe', 04:24:26 INFO - 'log_level': 'info', 04:24:26 INFO - 'log_name': 'talos', 04:24:26 INFO - 'log_to_console': True, 04:24:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:24:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:24:26 INFO - 'opt_config_files': (), 04:24:26 INFO - 'pip_index': False, 04:24:26 INFO - 'suite': 'dromaeojs-e10s', 04:24:26 INFO - 'system_bits': '32', 04:24:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:24:26 INFO - 'title': 'talos-linux64-ix-048', 04:24:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:24:26 INFO - 'use_talos_json': True, 04:24:26 INFO - 'verify': 'False', 04:24:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:24:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:24:26 INFO - 'work_dir': 'build'} 04:24:26 INFO - [mozharness: 2017-10-02 11:24:26.413383Z] Running clobber step. 04:24:26 INFO - Running pre-action listener: _resource_record_pre_action 04:24:26 INFO - Running main action method: clobber 04:24:26 INFO - rmtree: /builds/slave/test/build 04:24:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:24:27 INFO - Running post-action listener: _resource_record_post_action 04:24:27 INFO - [mozharness: 2017-10-02 11:24:27.924712Z] Finished clobber step (success) 04:24:27 INFO - [mozharness: 2017-10-02 11:24:27.924809Z] Running read-buildbot-config step. 04:24:27 INFO - Running pre-action listener: _resource_record_pre_action 04:24:27 INFO - Running main action method: read_buildbot_config 04:24:27 INFO - Using buildbot properties: 04:24:27 INFO - { 04:24:27 INFO - "project": "", 04:24:27 INFO - "product": "firefox", 04:24:27 INFO - "who": "ffxbld@noreply.mozilla.org", 04:24:27 INFO - "installer_path": "public/build/target.tar.bz2", 04:24:27 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 04:24:27 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 04:24:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 04:24:27 INFO - "stage_platform": "linux64", 04:24:27 INFO - "basedir": "/builds/slave/test", 04:24:27 INFO - "buildnumber": 141, 04:24:27 INFO - "platform": "ubuntu64_hw", 04:24:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:24:27 INFO - "slavebuilddir": "test", 04:24:27 INFO - "taskId": "KI7gz_J1QJyDCMoWsAjeqg", 04:24:27 INFO - "branch": "mozilla-beta", 04:24:27 INFO - "script_repo_revision": "production", 04:24:27 INFO - "revision": "b4352de175f8a950bc89a2b1b51f32489ca1e4e4", 04:24:27 INFO - "slavename": "talos-linux64-ix-048", 04:24:27 INFO - "repo_path": "releases/mozilla-beta" 04:24:27 INFO - } 04:24:27 INFO - Finding installer, test and symbols from parent task. 04:24:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KI7gz_J1QJyDCMoWsAjeqg'}, attempt #1 04:24:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KI7gz_J1QJyDCMoWsAjeqg'}, attempt #1 04:24:28 INFO - Task dependencies: NCFkVlATSo-sckLy5nLCzw 04:24:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw'}, attempt #1 04:24:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.tar.bz2 04:24:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.test_packages.json 04:24:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.crashreporter-symbols.zip 04:24:29 INFO - Running post-action listener: _resource_record_post_action 04:24:29 INFO - [mozharness: 2017-10-02 11:24:29.309436Z] Finished read-buildbot-config step (success) 04:24:29 INFO - [mozharness: 2017-10-02 11:24:29.309654Z] Running download-and-extract step. 04:24:29 INFO - Running pre-action listener: _resource_record_pre_action 04:24:29 INFO - Running main action method: download_and_extract 04:24:29 INFO - mkdir: /builds/slave/test/build/tests 04:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:29 INFO - trying https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.test_packages.json 04:24:29 INFO - Downloading https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:24:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:24:29 INFO - Downloaded 989 bytes. 04:24:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:24:29 INFO - Using the following test package requirements: 04:24:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:24:29 INFO - u'common': [u'target.common.tests.zip'], 04:24:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:24:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:24:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:24:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:24:29 INFO - u'mozbase': [u'target.common.tests.zip'], 04:24:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:24:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:24:29 INFO - u'web-platform': [u'target.common.tests.zip', 04:24:29 INFO - u'target.web-platform.tests.tar.gz'], 04:24:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:24:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 04:24:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.common.tests.zip 04:24:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:24:29 INFO - Fetch https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.common.tests.zip into memory 04:24:31 INFO - Content-Length response header: 55184811 04:24:31 INFO - Bytes received: 55184811 04:24:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.talos.tests.zip 04:24:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:24:42 INFO - Fetch https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.talos.tests.zip into memory 04:24:43 INFO - Content-Length response header: 14509184 04:24:43 INFO - Bytes received: 14509184 04:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:44 INFO - trying https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.tar.bz2 04:24:44 INFO - Downloading https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:24:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:24:46 INFO - Downloaded 53531422 bytes. 04:24:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.tar.bz2 04:24:46 INFO - mkdir: /builds/slave/test/properties 04:24:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:24:46 INFO - Writing to file /builds/slave/test/properties/build_url 04:24:46 INFO - Contents: 04:24:46 INFO - build_url:https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.tar.bz2 04:24:46 INFO - Running post-action listener: _resource_record_post_action 04:24:46 INFO - Running post-action listener: find_tests_for_verification 04:24:46 INFO - Running post-action listener: set_extra_try_arguments 04:24:46 INFO - [mozharness: 2017-10-02 11:24:46.191618Z] Finished download-and-extract step (success) 04:24:46 INFO - [mozharness: 2017-10-02 11:24:46.191715Z] Running populate-webroot step. 04:24:46 INFO - Running pre-action listener: _resource_record_pre_action 04:24:46 INFO - Running main action method: populate_webroot 04:24:46 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:24:46 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:24:46 INFO - 'tests': ['tresize', 04:24:46 INFO - 'tcanvasmark']}, 04:24:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:24:46 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:24:46 INFO - 'tests': ['dromaeo_css', 04:24:46 INFO - 'kraken']}, 04:24:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:24:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:24:46 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:24:46 INFO - 'talos_options': ['--disable-stylo'], 04:24:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:24:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:24:46 INFO - 'tests': ['damp', 'tps']}, 04:24:46 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:24:46 INFO - 'talos_options': ['--disable-stylo'], 04:24:46 INFO - 'tests': ['damp', 'tps']}, 04:24:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:24:46 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:24:46 INFO - 'tests': ['dromaeo_dom']}, 04:24:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:24:46 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:24:46 INFO - 'tests': ['basic_compositor_video', 04:24:46 INFO - 'glvideo']}, 04:24:46 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:24:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:24:46 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:24:46 INFO - 'talos_options': ['--disable-stylo'], 04:24:46 INFO - 'tests': ['ts_paint_webext', 04:24:46 INFO - 'tp5o_webext']}, 04:24:46 INFO - 'other-e10s': {'tests': ['a11yr', 04:24:46 INFO - 'ts_paint', 04:24:46 INFO - 'tpaint', 04:24:46 INFO - 'sessionrestore', 04:24:46 INFO - 'sessionrestore_many_windows', 04:24:46 INFO - 'sessionrestore_no_auto_restore', 04:24:46 INFO - 'tabpaint']}, 04:24:46 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:24:46 INFO - 'tests': ['a11yr', 04:24:46 INFO - 'ts_paint', 04:24:46 INFO - 'tpaint', 04:24:46 INFO - 'sessionrestore', 04:24:46 INFO - 'sessionrestore_many_windows', 04:24:46 INFO - 'sessionrestore_no_auto_restore', 04:24:46 INFO - 'tabpaint']}, 04:24:46 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 04:24:46 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 04:24:46 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:24:46 INFO - 'tests': ['perf_reftest_singletons']}, 04:24:46 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:24:46 INFO - 'tests': ['bloom_basic']}, 04:24:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:24:46 INFO - 'tsvgr_opacity', 04:24:46 INFO - 'tart', 04:24:46 INFO - 'tscrollx', 04:24:46 INFO - 'tsvg_static']}, 04:24:46 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:24:46 INFO - 'tests': ['tsvgx', 04:24:46 INFO - 'tsvgr_opacity', 04:24:46 INFO - 'tart', 04:24:46 INFO - 'tscrollx', 04:24:46 INFO - 'tsvg_static']}, 04:24:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:24:46 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:24:46 INFO - 'talos_options': ['--disable-stylo'], 04:24:46 INFO - 'tests': ['tp5o']}, 04:24:46 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:24:46 INFO - 'talos_options': ['--mitmproxy', 04:24:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:24:46 INFO - '--firstNonBlankPaint'], 04:24:46 INFO - 'tests': ['tp6_google', 04:24:46 INFO - 'tp6_youtube', 04:24:46 INFO - 'tp6_amazon', 04:24:46 INFO - 'tp6_facebook']}, 04:24:46 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:24:46 INFO - 'talos_options': ['--disable-stylo', 04:24:46 INFO - '--mitmproxy', 04:24:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:24:46 INFO - '--firstNonBlankPaint'], 04:24:46 INFO - 'tests': ['tp6_google', 04:24:46 INFO - 'tp6_youtube', 04:24:46 INFO - 'tp6_amazon', 04:24:46 INFO - 'tp6_facebook']}, 04:24:46 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:24:46 INFO - 'talos_options': ['--stylo-threads=1', 04:24:46 INFO - '--mitmproxy', 04:24:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:24:46 INFO - '--firstNonBlankPaint'], 04:24:46 INFO - 'tests': ['tp6_google', 04:24:46 INFO - 'tp6_youtube', 04:24:46 INFO - 'tp6_amazon', 04:24:46 INFO - 'tp6_facebook']}, 04:24:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:24:46 INFO - 'talos_options': ['--xperf_path', 04:24:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:24:46 INFO - 'tests': ['tp5n']}, 04:24:46 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:24:46 INFO - 'talos_options': ['--disable-stylo', 04:24:46 INFO - '--xperf_path', 04:24:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:24:46 INFO - 'tests': ['tp5n']}}} 04:24:46 INFO - Running post-action listener: _resource_record_post_action 04:24:46 INFO - [mozharness: 2017-10-02 11:24:46.206793Z] Finished populate-webroot step (success) 04:24:46 INFO - [mozharness: 2017-10-02 11:24:46.206883Z] Running create-virtualenv step. 04:24:46 INFO - Running pre-action listener: _resource_record_pre_action 04:24:46 INFO - Running main action method: create_virtualenv 04:24:46 INFO - Creating virtualenv /builds/slave/test/build/venv 04:24:46 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:24:46 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:24:46 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:24:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:24:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:24:47 INFO - Installing distribute.............................................................................................................................................................................................done. 04:24:47 INFO - Installing pip...............done. 04:24:47 INFO - Return code: 0 04:24:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:24:47 INFO - Reading from file tmpfile_stdout 04:24:47 INFO - Output received: 04:24:47 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 04:24:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:47 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 0x14871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f6090>, '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 0x17f6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f0f90>, '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': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:24:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:24:47 INFO - Using env: {'DISPLAY': ':0', 04:24:47 INFO - 'HOME': '/home/cltbld', 04:24:47 INFO - 'LANG': 'en_US.UTF-8', 04:24:47 INFO - 'LANGUAGE': 'en_US:en', 04:24:47 INFO - 'LOGNAME': 'cltbld', 04:24:47 INFO - 'MAIL': '/var/mail/cltbld', 04:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:47 INFO - 'MOZ_NO_REMOTE': '1', 04:24:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:24:47 INFO - 'NO_EM_RESTART': '1', 04:24:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:47 INFO - 'PWD': '/builds/slave/test', 04:24:47 INFO - 'SHELL': '/bin/bash', 04:24:47 INFO - 'SHLVL': '1', 04:24:47 INFO - 'TERM': 'linux', 04:24:47 INFO - 'TMOUT': '86400', 04:24:47 INFO - 'USER': 'cltbld', 04:24:47 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 04:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:47 INFO - '_': '/tools/buildbot/bin/python'} 04:24:48 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:24:48 INFO - Downloading/unpacking pip>=1.5 04:24:48 INFO - Running setup.py egg_info for package pip 04:24:48 INFO - warning: no files found matching 'pip/cacert.pem' 04:24:48 INFO - warning: no files found matching '*.html' under directory 'docs' 04:24:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:24:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:24:48 INFO - Installing collected packages: pip 04:24:48 INFO - Found existing installation: pip 1.1 04:24:48 INFO - Uninstalling pip: 04:24:48 INFO - Successfully uninstalled pip 04:24:48 INFO - Running setup.py install for pip 04:24:48 INFO - warning: no files found matching 'pip/cacert.pem' 04:24:48 INFO - warning: no files found matching '*.html' under directory 'docs' 04:24:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:24:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:24:48 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:24:48 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:24:48 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:24:48 INFO - Successfully installed pip 04:24:48 INFO - Cleaning up... 04:24:48 INFO - Return code: 0 04:24:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:24:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:48 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 0x14871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f6090>, '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 0x17f6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f0f90>, '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': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:24:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:24:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:24:48 INFO - Using env: {'DISPLAY': ':0', 04:24:48 INFO - 'HOME': '/home/cltbld', 04:24:48 INFO - 'LANG': 'en_US.UTF-8', 04:24:48 INFO - 'LANGUAGE': 'en_US:en', 04:24:48 INFO - 'LOGNAME': 'cltbld', 04:24:48 INFO - 'MAIL': '/var/mail/cltbld', 04:24:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:48 INFO - 'MOZ_NO_REMOTE': '1', 04:24:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:24:48 INFO - 'NO_EM_RESTART': '1', 04:24:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:24:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:48 INFO - 'PWD': '/builds/slave/test', 04:24:48 INFO - 'SHELL': '/bin/bash', 04:24:48 INFO - 'SHLVL': '1', 04:24:48 INFO - 'TERM': 'linux', 04:24:48 INFO - 'TMOUT': '86400', 04:24:48 INFO - 'USER': 'cltbld', 04:24:48 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 04:24:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:48 INFO - '_': '/tools/buildbot/bin/python'} 04:24:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:48 INFO - Downloading/unpacking psutil>=3.1.1 04:24:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:24:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:24:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:24:50 INFO - Installing collected packages: psutil 04:24:50 INFO - Running setup.py install for psutil 04:24:50 INFO - building 'psutil._psutil_linux' extension 04:24:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:24:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:24:50 INFO - building 'psutil._psutil_posix' extension 04:24:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:24:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:24:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:24:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:24:50 INFO - Successfully installed psutil 04:24:50 INFO - Cleaning up... 04:24:50 INFO - Return code: 0 04:24:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:24: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')]} 04:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24: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')]} 04:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24: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 0x14871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f6090>, '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 0x17f6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f0f90>, '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': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:24: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 04:24: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 04:24:50 INFO - Using env: {'DISPLAY': ':0', 04:24:50 INFO - 'HOME': '/home/cltbld', 04:24:50 INFO - 'LANG': 'en_US.UTF-8', 04:24:50 INFO - 'LANGUAGE': 'en_US:en', 04:24:50 INFO - 'LOGNAME': 'cltbld', 04:24:50 INFO - 'MAIL': '/var/mail/cltbld', 04:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:50 INFO - 'MOZ_NO_REMOTE': '1', 04:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:24:50 INFO - 'NO_EM_RESTART': '1', 04:24:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:50 INFO - 'PWD': '/builds/slave/test', 04:24:50 INFO - 'SHELL': '/bin/bash', 04:24:50 INFO - 'SHLVL': '1', 04:24:50 INFO - 'TERM': 'linux', 04:24:50 INFO - 'TMOUT': '86400', 04:24:50 INFO - 'USER': 'cltbld', 04:24:50 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 04:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:50 INFO - '_': '/tools/buildbot/bin/python'} 04:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:24: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 04:24: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 04:24: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 04:24: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 04:24:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:24:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:24:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:24:52 INFO - Installing collected packages: mozsystemmonitor 04:24:52 INFO - Running setup.py install for mozsystemmonitor 04:24:52 INFO - Successfully installed mozsystemmonitor 04:24:52 INFO - Cleaning up... 04:24:53 INFO - Return code: 0 04:24:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:24: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')]} 04:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24: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')]} 04:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24: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', '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 0x14871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f6090>, '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 0x17f6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f0f90>, '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': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:24: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:24: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 jsonschema==2.5.1 04:24:53 INFO - Using env: {'DISPLAY': ':0', 04:24:53 INFO - 'HOME': '/home/cltbld', 04:24:53 INFO - 'LANG': 'en_US.UTF-8', 04:24:53 INFO - 'LANGUAGE': 'en_US:en', 04:24:53 INFO - 'LOGNAME': 'cltbld', 04:24:53 INFO - 'MAIL': '/var/mail/cltbld', 04:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:53 INFO - 'MOZ_NO_REMOTE': '1', 04:24:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:24:53 INFO - 'NO_EM_RESTART': '1', 04:24:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:53 INFO - 'PWD': '/builds/slave/test', 04:24:53 INFO - 'SHELL': '/bin/bash', 04:24:53 INFO - 'SHLVL': '1', 04:24:53 INFO - 'TERM': 'linux', 04:24:53 INFO - 'TMOUT': '86400', 04:24:53 INFO - 'USER': 'cltbld', 04:24:53 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 04:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:53 INFO - '_': '/tools/buildbot/bin/python'} 04:24:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:53 INFO - Downloading/unpacking jsonschema==2.5.1 04:24: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 04:24: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 04:24: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 04:24: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 04:24:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:24:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:24: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 04:24: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 04:24: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 04:24: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 04:24:54 INFO - Downloading functools32-3.2.3-2.tar.gz 04:24:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:24:54 INFO - warning: no files found matching '*.txt' 04:24:54 INFO - no previously-included directories found matching 'build' 04:24:54 INFO - no previously-included directories found matching 'dist' 04:24:54 INFO - no previously-included directories found matching '.git*' 04:24:54 INFO - Installing collected packages: jsonschema, functools32 04:24:56 INFO - Running setup.py install for functools32 04:24:56 INFO - warning: no files found matching '*.txt' 04:24:56 INFO - no previously-included directories found matching 'build' 04:24:56 INFO - no previously-included directories found matching 'dist' 04:24:56 INFO - no previously-included directories found matching '.git*' 04:24:56 INFO - Successfully installed jsonschema functools32 04:24:56 INFO - Cleaning up... 04:24:56 INFO - Return code: 0 04:24:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:24: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')]} 04:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24: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')]} 04:24:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:56 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 0x14871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f6090>, '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 0x17f6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f0f90>, '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': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:24:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:24:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:24:56 INFO - Using env: {'DISPLAY': ':0', 04:24:56 INFO - 'HOME': '/home/cltbld', 04:24:56 INFO - 'LANG': 'en_US.UTF-8', 04:24:56 INFO - 'LANGUAGE': 'en_US:en', 04:24:56 INFO - 'LOGNAME': 'cltbld', 04:24:56 INFO - 'MAIL': '/var/mail/cltbld', 04:24:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:56 INFO - 'MOZ_NO_REMOTE': '1', 04:24:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:24:56 INFO - 'NO_EM_RESTART': '1', 04:24:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:24:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:56 INFO - 'PWD': '/builds/slave/test', 04:24:56 INFO - 'SHELL': '/bin/bash', 04:24:56 INFO - 'SHLVL': '1', 04:24:56 INFO - 'TERM': 'linux', 04:24:56 INFO - 'TMOUT': '86400', 04:24:56 INFO - 'USER': 'cltbld', 04:24:56 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 04:24:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:56 INFO - '_': '/tools/buildbot/bin/python'} 04:24:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:24:56 INFO - Cleaning up... 04:24:56 INFO - Return code: 0 04:24:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:24: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')]} 04:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24: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')]} 04:24:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:56 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 0x14871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f6090>, '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 0x17f6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f0f90>, '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': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:24:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:24:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:24:56 INFO - Using env: {'DISPLAY': ':0', 04:24:56 INFO - 'HOME': '/home/cltbld', 04:24:56 INFO - 'LANG': 'en_US.UTF-8', 04:24:56 INFO - 'LANGUAGE': 'en_US:en', 04:24:56 INFO - 'LOGNAME': 'cltbld', 04:24:56 INFO - 'MAIL': '/var/mail/cltbld', 04:24:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:56 INFO - 'MOZ_NO_REMOTE': '1', 04:24:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:24:56 INFO - 'NO_EM_RESTART': '1', 04:24:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:24:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:56 INFO - 'PWD': '/builds/slave/test', 04:24:56 INFO - 'SHELL': '/bin/bash', 04:24:56 INFO - 'SHLVL': '1', 04:24:56 INFO - 'TERM': 'linux', 04:24:56 INFO - 'TMOUT': '86400', 04:24:56 INFO - 'USER': 'cltbld', 04:24:56 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 04:24:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:56 INFO - '_': '/tools/buildbot/bin/python'} 04:24:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:56 INFO - Downloading/unpacking blobuploader==1.2.4 04:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:57 INFO - Downloading blobuploader-1.2.4.tar.gz 04:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:24:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:24:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:24:58 INFO - Downloading docopt-0.6.1.tar.gz 04:24:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:24:58 INFO - Installing collected packages: blobuploader, requests, docopt 04:24:58 INFO - Running setup.py install for blobuploader 04:24:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:24:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:24:58 INFO - Running setup.py install for requests 04:24:58 INFO - Running setup.py install for docopt 04:24:58 INFO - Successfully installed blobuploader requests docopt 04:24:58 INFO - Cleaning up... 04:24:58 INFO - Return code: 0 04:24:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:24:58 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 0x14871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f6090>, '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 0x17f6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f0f90>, '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': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:24:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:24:58 INFO - Using env: {'DISPLAY': ':0', 04:24:58 INFO - 'HOME': '/home/cltbld', 04:24:58 INFO - 'LANG': 'en_US.UTF-8', 04:24:58 INFO - 'LANGUAGE': 'en_US:en', 04:24:58 INFO - 'LOGNAME': 'cltbld', 04:24:58 INFO - 'MAIL': '/var/mail/cltbld', 04:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:58 INFO - 'MOZ_NO_REMOTE': '1', 04:24:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:24:58 INFO - 'NO_EM_RESTART': '1', 04:24:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:58 INFO - 'PWD': '/builds/slave/test', 04:24:58 INFO - 'SHELL': '/bin/bash', 04:24:58 INFO - 'SHLVL': '1', 04:24:58 INFO - 'TERM': 'linux', 04:24:58 INFO - 'TMOUT': '86400', 04:24:58 INFO - 'USER': 'cltbld', 04:24:58 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 04:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:58 INFO - '_': '/tools/buildbot/bin/python'} 04:24:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:24:59 INFO - Running setup.py (path:/tmp/pip-1LFVrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:24:59 INFO - Running setup.py (path:/tmp/pip-cf285r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:24:59 INFO - Running setup.py (path:/tmp/pip-IXdGZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:24:59 INFO - Running setup.py (path:/tmp/pip-hZ1sc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:24:59 INFO - Running setup.py (path:/tmp/pip-0Q3dsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:24:59 INFO - Running setup.py (path:/tmp/pip-4f6DEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:24:59 INFO - Running setup.py (path:/tmp/pip-8HpAy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:24:59 INFO - Running setup.py (path:/tmp/pip-n5UPZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:24:59 INFO - Running setup.py (path:/tmp/pip-d2v6DT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:24:59 INFO - Running setup.py (path:/tmp/pip-9T0p4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:24:59 INFO - Running setup.py (path:/tmp/pip-jQkM2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:24:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:24:59 INFO - Running setup.py (path:/tmp/pip-AnBpgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:00 INFO - Running setup.py (path:/tmp/pip-zpDD5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:00 INFO - Running setup.py (path:/tmp/pip-yWP8Af-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:00 INFO - Running setup.py (path:/tmp/pip-jwRWSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:25:00 INFO - Running setup.py (path:/tmp/pip-kUszrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:25:00 INFO - Running setup.py (path:/tmp/pip-LIxDLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:25:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:25:00 INFO - Running setup.py install for manifestparser 04:25:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:25:00 INFO - Running setup.py install for mozcrash 04:25:00 INFO - Running setup.py install for mozdebug 04:25:00 INFO - Running setup.py install for mozdevice 04:25:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:25:00 INFO - Running setup.py install for mozfile 04:25:00 INFO - Running setup.py install for mozhttpd 04:25:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:25:00 INFO - Running setup.py install for mozinfo 04:25:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:25:01 INFO - Running setup.py install for mozInstall 04:25:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:25:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:25:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:25:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:25:01 INFO - Running setup.py install for mozleak 04:25:01 INFO - Running setup.py install for mozlog 04:25:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:25:01 INFO - Running setup.py install for moznetwork 04:25:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:25:01 INFO - Running setup.py install for mozprocess 04:25:01 INFO - Running setup.py install for mozprofile 04:25:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:25:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:25:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:25:01 INFO - Running setup.py install for mozrunner 04:25:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:25:01 INFO - Running setup.py install for mozscreenshot 04:25:01 INFO - Running setup.py install for moztest 04:25:02 INFO - Running setup.py install for mozversion 04:25:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:25:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:25:02 INFO - Cleaning up... 04:25:02 INFO - Return code: 0 04:25:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25: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')]} 04:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25: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')]} 04:25:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f6090>, '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 0x17f6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f0f90>, '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': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:25:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:25:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:25:02 INFO - Using env: {'DISPLAY': ':0', 04:25:02 INFO - 'HOME': '/home/cltbld', 04:25:02 INFO - 'LANG': 'en_US.UTF-8', 04:25:02 INFO - 'LANGUAGE': 'en_US:en', 04:25:02 INFO - 'LOGNAME': 'cltbld', 04:25:02 INFO - 'MAIL': '/var/mail/cltbld', 04:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:02 INFO - 'MOZ_NO_REMOTE': '1', 04:25:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:02 INFO - 'NO_EM_RESTART': '1', 04:25:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:02 INFO - 'PWD': '/builds/slave/test', 04:25:02 INFO - 'SHELL': '/bin/bash', 04:25:02 INFO - 'SHLVL': '1', 04:25:02 INFO - 'TERM': 'linux', 04:25:02 INFO - 'TMOUT': '86400', 04:25:02 INFO - 'USER': 'cltbld', 04:25:02 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 04:25:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:02 INFO - '_': '/tools/buildbot/bin/python'} 04:25:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:25:02 INFO - Running setup.py (path:/tmp/pip-7bJhpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:25:02 INFO - Running setup.py (path:/tmp/pip-NB5ppN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:25:02 INFO - Running setup.py (path:/tmp/pip-mBA7QY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:25:02 INFO - Running setup.py (path:/tmp/pip-nK3Wyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:25:02 INFO - Running setup.py (path:/tmp/pip-NDA3ol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:25:02 INFO - Running setup.py (path:/tmp/pip-1Ow7EE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:25:02 INFO - Running setup.py (path:/tmp/pip-OUxjwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:25:02 INFO - Running setup.py (path:/tmp/pip-pbItoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:25:02 INFO - Running setup.py (path:/tmp/pip-mHOlgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:25:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:25:02 INFO - Running setup.py (path:/tmp/pip-cnN28P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:25:03 INFO - Running setup.py (path:/tmp/pip-UcRsvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:25:03 INFO - Running setup.py (path:/tmp/pip-Xs4GpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:03 INFO - Running setup.py (path:/tmp/pip-dq8SYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:03 INFO - Running setup.py (path:/tmp/pip-BYEyLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:03 INFO - Running setup.py (path:/tmp/pip-NzJYLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:25:03 INFO - Running setup.py (path:/tmp/pip-LVp0uo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:25:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:25:03 INFO - Running setup.py (path:/tmp/pip-v6h4f5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:25:03 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:25:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:04 INFO - Downloading blessings-1.6.tar.gz 04:25:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:25:04 INFO - Installing collected packages: blessings 04:25:04 INFO - Running setup.py install for blessings 04:25:04 INFO - Successfully installed blessings 04:25:04 INFO - Cleaning up... 04:25:04 INFO - Return code: 0 04:25:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:25:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:25:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:25:05 INFO - Reading from file tmpfile_stdout 04:25:05 INFO - Current package versions: 04:25:05 INFO - argparse == 1.2.1 04:25:05 INFO - blessings == 1.6 04:25:05 INFO - blobuploader == 1.2.4 04:25:05 INFO - docopt == 0.6.1 04:25:05 INFO - functools32 == 3.2.3-2 04:25:05 INFO - jsonschema == 2.5.1 04:25:05 INFO - manifestparser == 1.1 04:25:05 INFO - mozInstall == 1.13 04:25:05 INFO - mozcrash == 1.0 04:25:05 INFO - mozdebug == 0.1 04:25:05 INFO - mozdevice == 0.51 04:25:05 INFO - mozfile == 1.2 04:25:05 INFO - mozhttpd == 0.7 04:25:05 INFO - mozinfo == 0.10 04:25:05 INFO - mozleak == 0.1 04:25:05 INFO - mozlog == 3.5 04:25:05 INFO - moznetwork == 0.27 04:25:05 INFO - mozprocess == 0.25 04:25:05 INFO - mozprofile == 0.28 04:25:05 INFO - mozrunner == 6.13 04:25:05 INFO - mozscreenshot == 0.1 04:25:05 INFO - mozsystemmonitor == 0.3 04:25:05 INFO - moztest == 0.8 04:25:05 INFO - mozversion == 1.4 04:25:05 INFO - psutil == 3.1.1 04:25:05 INFO - requests == 1.2.3 04:25:05 INFO - wsgiref == 0.1.2 04:25:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f6090>, '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 0x17f6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f0f90>, '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': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:25:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:25:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:25:05 INFO - Using env: {'DISPLAY': ':0', 04:25:05 INFO - 'HOME': '/home/cltbld', 04:25:05 INFO - 'LANG': 'en_US.UTF-8', 04:25:05 INFO - 'LANGUAGE': 'en_US:en', 04:25:05 INFO - 'LOGNAME': 'cltbld', 04:25:05 INFO - 'MAIL': '/var/mail/cltbld', 04:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:05 INFO - 'MOZ_NO_REMOTE': '1', 04:25:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:05 INFO - 'NO_EM_RESTART': '1', 04:25:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:05 INFO - 'PWD': '/builds/slave/test', 04:25:05 INFO - 'SHELL': '/bin/bash', 04:25:05 INFO - 'SHLVL': '1', 04:25:05 INFO - 'TERM': 'linux', 04:25:05 INFO - 'TMOUT': '86400', 04:25:05 INFO - 'USER': 'cltbld', 04:25:05 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 04:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:05 INFO - '_': '/tools/buildbot/bin/python'} 04:25:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:25:05 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:25:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:25:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:25:06 INFO - Installing collected packages: simplejson 04:25:06 INFO - Running setup.py install for simplejson 04:25:06 INFO - building 'simplejson._speedups' extension 04:25:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 04:25:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 04:25:07 INFO - Successfully installed simplejson 04:25:07 INFO - Cleaning up... 04:25:07 INFO - Return code: 0 04:25:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:25:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:07 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 0x14871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x164fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17f6090>, '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 0x17f6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f0f90>, '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': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:25:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:25:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:25:07 INFO - Using env: {'DISPLAY': ':0', 04:25:07 INFO - 'HOME': '/home/cltbld', 04:25:07 INFO - 'LANG': 'en_US.UTF-8', 04:25:07 INFO - 'LANGUAGE': 'en_US:en', 04:25:07 INFO - 'LOGNAME': 'cltbld', 04:25:07 INFO - 'MAIL': '/var/mail/cltbld', 04:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:07 INFO - 'MOZ_NO_REMOTE': '1', 04:25:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:07 INFO - 'NO_EM_RESTART': '1', 04:25:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:07 INFO - 'PWD': '/builds/slave/test', 04:25:07 INFO - 'SHELL': '/bin/bash', 04:25:07 INFO - 'SHLVL': '1', 04:25:07 INFO - 'TERM': 'linux', 04:25:07 INFO - 'TMOUT': '86400', 04:25:07 INFO - 'USER': 'cltbld', 04:25:07 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 04:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:07 INFO - '_': '/tools/buildbot/bin/python'} 04:25:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:25:07 INFO - Cleaning up... 04:25:07 INFO - Return code: 0 04:25:07 INFO - Running post-action listener: _resource_record_post_action 04:25:07 INFO - Running post-action listener: _start_resource_monitoring 04:25:07 INFO - Starting resource monitoring. 04:25:07 INFO - [mozharness: 2017-10-02 11:25:07.859712Z] Finished create-virtualenv step (success) 04:25:07 INFO - [mozharness: 2017-10-02 11:25:07.860239Z] Running install step. 04:25:07 INFO - Running pre-action listener: _resource_record_pre_action 04:25:07 INFO - Running main action method: install 04:25:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:25:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:25:08 INFO - Reading from file tmpfile_stdout 04:25:08 INFO - Detecting whether we're running mozinstall >=1.0... 04:25:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:25:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:25:08 INFO - Reading from file tmpfile_stdout 04:25:08 INFO - Output received: 04:25:08 INFO - Usage: mozinstall [options] installer 04:25:08 INFO - Options: 04:25:08 INFO - -h, --help show this help message and exit 04:25:08 INFO - -d DEST, --destination=DEST 04:25:08 INFO - Directory to install application into. [default: 04:25:08 INFO - "/builds/slave/test"] 04:25:08 INFO - --app=APP Application being installed. [default: firefox] 04:25:08 INFO - mkdir: /builds/slave/test/build/application 04:25:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:25:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:25:22 INFO - Reading from file tmpfile_stdout 04:25:22 INFO - Output received: 04:25:22 INFO - /builds/slave/test/build/application/firefox/firefox 04:25:22 INFO - Running post-action listener: _resource_record_post_action 04:25:22 INFO - [mozharness: 2017-10-02 11:25:22.832449Z] Finished install step (success) 04:25:22 INFO - [mozharness: 2017-10-02 11:25:22.832700Z] Running setup-mitmproxy step. 04:25:22 INFO - Running pre-action listener: _resource_record_pre_action 04:25:22 INFO - Running main action method: setup_mitmproxy 04:25:22 INFO - Skipping: mitmproxy is not required 04:25:22 INFO - Running post-action listener: _resource_record_post_action 04:25:22 INFO - [mozharness: 2017-10-02 11:25:22.833828Z] Finished setup-mitmproxy step (success) 04:25:22 INFO - [mozharness: 2017-10-02 11:25:22.834048Z] Running run-tests step. 04:25:22 INFO - Running pre-action listener: _resource_record_pre_action 04:25:22 INFO - Running pre-action listener: _set_gcov_prefix 04:25:22 INFO - Running main action method: run_tests 04:25:22 WARNING - Try message not found. 04:25:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:25:22 INFO - Python 2.7.3 04:25:22 INFO - Return code: 0 04:25:22 INFO - grabbing minidump binary from tooltool 04:25: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')]} 04:25: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 0x17f6090>, '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 0x17f6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17f0f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:25: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 04:25: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 04:25: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 04:25:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:25:22 INFO - Return code: 0 04:25:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:25:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:25:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:25:22 INFO - ENV: RUST_BACKTRACE is now full 04:25:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:25:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:25:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-048 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:25:22 INFO - Using env: {'DISPLAY': ':0', 04:25:22 INFO - 'HOME': '/home/cltbld', 04:25:22 INFO - 'LANG': 'en_US.UTF-8', 04:25:22 INFO - 'LANGUAGE': 'en_US:en', 04:25:22 INFO - 'LOGNAME': 'cltbld', 04:25:22 INFO - 'MAIL': '/var/mail/cltbld', 04:25:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:25:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:25:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:22 INFO - 'MOZ_NO_REMOTE': '1', 04:25:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:25:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:22 INFO - 'NO_EM_RESTART': '1', 04:25:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:22 INFO - 'PWD': '/builds/slave/test', 04:25:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:25:22 INFO - 'RUST_BACKTRACE': 'full', 04:25:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:25:22 INFO - 'SHELL': '/bin/bash', 04:25:22 INFO - 'SHLVL': '1', 04:25:22 INFO - 'TERM': 'linux', 04:25:22 INFO - 'TMOUT': '86400', 04:25:22 INFO - 'USER': 'cltbld', 04:25:22 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 04:25:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:22 INFO - '_': '/tools/buildbot/bin/python'} 04:25:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:25:23 INFO - mozversion application_buildid: 20171002100446 04:25:23 INFO - mozversion application_changeset: b4352de175f8a950bc89a2b1b51f32489ca1e4e4 04:25:23 INFO - mozversion application_display_name: Firefox 04:25:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:25:23 INFO - mozversion application_name: Firefox 04:25:23 INFO - mozversion application_remotingname: firefox 04:25:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:25:23 INFO - mozversion application_vendor: Mozilla 04:25:23 INFO - mozversion application_version: 57.0 04:25:23 INFO - mozversion platform_buildid: 20171002100446 04:25:23 INFO - mozversion platform_changeset: b4352de175f8a950bc89a2b1b51f32489ca1e4e4 04:25:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:25:23 INFO - mozversion platform_version: 57.0 04:25:23 INFO - using testdate: 1506943523 04:25:23 INFO - actual date: 1506943523 04:25:23 INFO - starting webserver on 'localhost:59980' 04:25:23 INFO - SUITE-START | Running 2 tests 04:25:23 INFO - TEST-START | dromaeo_css 04:25:23 INFO - Initialising browser for dromaeo_css test... 04:25:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59980/getInfo.html -profile /tmp/tmp2mPHvc/profile 04:25:23 INFO - TEST-INFO | started process 16050 (/builds/slave/test/build/application/firefox/firefox http://localhost:59980/getInfo.html) 04:25:28 INFO - TEST-INFO | 16050: exit 0 04:25:28 INFO - Browser initialized. 04:25:28 INFO - Running cycle 1/1 for dromaeo_css test... 04:25:28 INFO - Using env: {'DISPLAY': ':0', 04:25:28 INFO - 'HOME': '/home/cltbld', 04:25:28 INFO - 'JSGC_DISABLE_POISONING': '1', 04:25:28 INFO - 'LANG': 'en_US.UTF-8', 04:25:28 INFO - 'LANGUAGE': 'en_US:en', 04:25:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:25:28 INFO - 'LOGNAME': 'cltbld', 04:25:28 INFO - 'MAIL': '/var/mail/cltbld', 04:25:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:25:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:25:28 INFO - 'MOZ_CRASHREPORTER': '1', 04:25:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:25:28 INFO - 'MOZ_NO_REMOTE': '1', 04:25:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:25:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:25:28 INFO - 'NO_EM_RESTART': '1', 04:25:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:25:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:28 INFO - 'PWD': '/builds/slave/test', 04:25:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:25:28 INFO - 'RUST_BACKTRACE': 'full', 04:25:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:25:28 INFO - 'SHELL': '/bin/bash', 04:25:28 INFO - 'SHLVL': '1', 04:25:28 INFO - 'TERM': 'linux', 04:25:28 INFO - 'TMOUT': '86400', 04:25:28 INFO - 'USER': 'cltbld', 04:25:28 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 04:25:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:28 INFO - '_': '/tools/buildbot/bin/python'} 04:25:28 INFO - TEST-INFO | started process 16240 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2mPHvc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:25:29 INFO - PID 16240 | 04:25:29 INFO - PID 16240 | (/builds/slave/test/build/application/firefox/firefox:16290): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:25:29 INFO - PID 16240 | 04:25:39 INFO - PID 16240 | RSS: Main: 143986688 04:25:39 INFO - PID 16240 | 04:28:37 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:59980/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59980/tests/dromaeo/cssquery-ext.html) 04:28:38 INFO - PID 16240 | RSS: Main: 146354176 04:28:38 INFO - PID 16240 | 04:31:39 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:59980/tests/dromaeo/cssquery-ext.html (next: http://localhost:59980/tests/dromaeo/cssquery-jquery.html) 04:31:40 INFO - PID 16240 | RSS: Main: 144601088 04:31:40 INFO - PID 16240 | 04:34:42 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:59980/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59980/tests/dromaeo/cssquery-mootools.html) 04:34:42 INFO - PID 16240 | RSS: Main: 146333696 04:34:42 INFO - PID 16240 | 04:37:44 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:59980/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59980/tests/dromaeo/cssquery-prototype.html) 04:37:44 INFO - PID 16240 | RSS: Main: 145735680 04:37:44 INFO - PID 16240 | 04:40:47 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:59980/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59980/tests/dromaeo/cssquery-yui.html) 04:40:48 INFO - PID 16240 | RSS: Main: 146944000 04:40:48 INFO - PID 16240 | 04:43:49 INFO - PID 16240 | Cycle 1(1): loaded http://localhost:59980/tests/dromaeo/cssquery-yui.html (next: http://localhost:59980/tests/dromaeo/cssquery-dojo.html) 04:43:49 INFO - PID 16240 | RSS: Main: 149725184 04:43:49 INFO - PID 16240 | 04:43:49 INFO - PID 16240 | __start_tp_report 04:43:49 INFO - PID 16240 | _x_x_mozilla_page_load 04:43:49 INFO - PID 16240 | _x_x_mozilla_page_load_details 04:43:49 INFO - PID 16240 | |i|pagename|runs| 04:43:49 INFO - PID 16240 | |0;dojo.html;3963.484746982892;3993.580674029225;4008.4388185654007;4010.13782036862;4021.7989100544983;6472.38512341328;6476.481881449485;6503.674816259192;6587.044878492616;6589.011648252758;6418.19772528434;6455.289918109004;6550.639714815683;6556.704948277327;6577.736890524373;5774.711264436783;5818.476337129647;5902.970485147568;5924.289085309769;5961.731722072495;5876.764929402819;5880.441358070998;5890.705464726747;5896.351401345849;6002.399760024006;5872.941270587304;5878.970605146989;5885.764569417218;5953.85115372117;5962.731677074542;5870.73581688825;5955.910661340084;5979.103134529855;5998.430149135826;6018.6689732064715;5863.8534036649;5922.7038648067855;5928.911066334009;5957.493613042897;5973.342932277446;6345.968270158666;6362.777302794426;6422.710978006001;6451.096846441502;6491.350864913471;7027.227005029443;7067.469939754487;7069.505134640577;7077;7132.4294056475355;7073.151221853359;7129.037579926696;7170.390516806077;7203.387712044482;7204.459665525106;6447.387498187664;6541.803745887631;6600.834979125512;6640.701168447383;6682.966585167043;7208.06295181623;7351.081114860643;7367.631618419057;7369.520981136209;7373.705051797868;6896.896546551806;6995.580265184105;7068.151821781419;7068.399186069241;7072.5402848814665;7046.224915259318;7064.964675176694;7164.104486939133;7169.354758071799;7174;7029.297070293032;7051.048108505389;7117.67970441321;7125.608091554911;7129.679164437613;7030.472714546328;7051.048108505389;7117.92882071183;7130.215676275606;7146.571205727673;5181.818636347785;5222.895542089137;5231;5241.816536421207;5249.658772179796;4758.500357462486;4835.903281934412;4877.097736918681;4907.533784290486;4911.238757992517;6485.6108633482145;6575.506836987245;6650.767223147167;6658.933410665928;6666.699998500079;6980.965095174492;6997.685104170325;7018.368346848808;7070.646467676698;7091.858162836715;6558;6613.966930165318;6626.9337306627285;6632.635205063768;6633.2040155181685;5980.760769569256;5990.850228744316;6019.969900150471;6050.213472248667;6062.333143354227;7265.09186351706;7384.076990376203;7473.1032276126125;7490.700371985168;7495.512791668524;7094.290570942864;7106.751263705745;7181.1741649708865;7193.352598266181;7202.099737532703;7182.138143422822;7221.783346499614;7315.231900650568;7381.8154546136775;7392.4825262231125;6920.688569014507;6982.79051628452;7052.682629281797;7055.576665400093;7081.46888983318;7096.0420343253;7098.396636285974;7166.749163779451;7207.351338379571;7223.783286501203;6821.147356580427;6828.453723702191;6894.551854129466;6897.55165914214;6907.481938854707;6798.252192258947;6807;6888.552244104118;6889.621070841152;6893.034975103389;6678.532502724763;6740.629265390255;6802.727890884507;6816.625085620339;6819.045333653392;6830.59016459014;6920.9653951729915;6935.479839011993;6955.374016338554;6973.8256543584785;6630.701618427086;6666.400023997863;6672.733090676318;6697.799066028027;6771.39057484809 04:43:49 INFO - PID 16240 | |1;ext.html;977.2426369563588;981.6711401680436;985.3299756165807;1035.1304903880741;1037.305005646217;44770.776146119264;45159.77420112899;45454.772726136405;45611.54388456114;45613.087738245216;43039.78480107603;43127;43182.13635727292;43220.56779432205;43429;8159.306458950996;8229.05365882925;8257.133001034896;8264.710735124272;8265.545395003272;8160.30637395822;8194.672213111467;8263.214994575506;8269.627866745981;8277.420580559334;8844.778880527974;9029.23251523618;9029.774255643628;9031.412958157698;9035.051319611444;8946.955265223698;8970.058143894928;8991.14584114508;9009.279257659371;9032.322575806807;8814.382993190478;8850.73447796567;8882.42264252828;8890.688825891128;8894.155055269805;41487.792561037306;41821;41832.790836045926;42107.157856843;42168;3780.1872597391693;3812.8284227209842;3819.102510909934;3821.178446633959;3824.464574959508;34422.655773442195;34454.6554534454;34656.65343346585;34661.826690866386;34835;5432.592555558349;5645.15322701594;5698.40166782486;5731.971340143315;5744.655320680772;225073;225340;227045;227345;228774;53308.73345633325;53666.7316663422;53836.73081634567;53876.73061634667;53919.19121213185;55831;55886;55951.440485595434;56437.435625643215;56459.71770141123;16503.422380216634;16723;16896.23966921467;16993.660126797397;17152.742708859376;13368.598942031755;13411.262380568967;13420.798688019688;13441.260730659907;13453.93273033648;15404;15550.300236489385;15602.843971560365;15646.139462329682;15668.216589170495;6030.18592490017;6093.969530152321;6152.969235153795;6274.780382686584;6340.651264180422;1122.9045531129782;1126.4030064066058;1128.8588926384202;1132.3432409202642;1140.5722853929776;26222.213333600022;26389.076382326526;26535.73464265371;26648.733512664625;26664.60003099955;1214.9939250303692;1227.6010296653624;1240.1195151442578;1243.583399561139;1263.6209137258786;13861.4455421782;14104;14111.647208819862;14188.077774944595;14253.78619320711;289444;291491;291869;292955;293838;53312.20031699527;54128.72935635375;58435.415645843845;61088.69455652782;62385.68807155935;1510.765831296151;1540.614846288428;1541.175471122944;1549.093780138609;1555.61887337603;31070.533941990438;31275.374492509567;31582.210444739067;31753.68246317507;32221.838890805397;38468;38650.80674596722;38681.613183868925;38865.6113438862;38888.22223555457;242959;243566;245531;247236;247368;37075.44386834253;37831.62168378391;38583.614163858;38684.22631547297;38691.80654096712;47083.529164707914;47790.761046195934;48380.516194837604;48512.5148748508;49181;35817.82091089632;36019.279614408086;36147.819260903525;36334.27331453409;36336.636633634385;35434.645653543135;35570;36117.63882361143;36346.8182659085;36592.63407365892;42655.360169598105;42709.78645106879;42980.785096074316;43174.78412608043;43228;34611;34807.60769569189;35342.64657353394;35393.64606353903;35826.28347433089;24922.37694057663;25773.484530309663;25818.483630327664;26403.867980659972;26489.60265596056 04:43:49 INFO - PID 16240 | |2;jquery.html;633.5573571607382;635.8146556729763;645.8198378579982;647.0296129130592;647.3735552336751;23910.163144289952;24638.137665181723;24677.75322246777;24704.50590988184;24727.876360618193;26284.080057198003;26424.339391515223;26425.3393665158;26540;26548.734512654868;6693.364130407604;6891.586504809702;7032.261612530688;7071.045408869788;7077.823054423629;6911.89632155518;6997.335253150943;7004.089468369105;7015.543989640682;7019.157701075878;6665.433438157763;6922.688479018456;6951.582905025714;6964.825879353031;6969.895451568231;6973.3375329343635;7016.193137789118;7018.789436316919;7026.262242464519;7031.015657807937;6568.047633093222;6581.14445122131;6599.142111525471;6603.174603174603;6604.669766511655;29377;29698.85150574255;30751.846240768653;31202.53196202059;31404;2242.080746893782;2250.459889626478;2252.087904398721;2253.808426283768;2336.3224664847176;20339.288124915704;20721.792782072138;20767.792322076737;20792.27227047046;20834.479138021517;2634.433596776683;2638.4986852498155;2677.678678558566;2680.437108207279;2691.838489690625;144642.27678861644;151260;152412.2379388096;158035.2098239513;158172;16248.918755406148;16270.186490675418;17063.658726825393;17146.22841972114;17328.82671173272;27259.45481090367;27266.863665681543;27453.450930981668;27468;27476.03833865805;10598.0991615712;10698.304610200312;10916.399598022026;10960.451977401099;11118.277311974696;9582.28132890036;9783.266255030901;9799.510024498746;9835.164011058998;9960.651377201762;10043.598256069676;10103.19174466041;10130.594776208869;10145.391276523285;10580.52387642558;3097.287623846483;3142.827144507074;3148.9055328340187;3149.88975385865;3220.7745457817728;1048.8584041154347;1071.2554774431694;1099.1701265544623;1104.5692180049787;1114.6990312615549;21555.67666485004;21664.891675541836;21854.89072554627;21876.23433179863;22084.558308834054;1150.4937827356105;1152.7118220444888;1157.5948418053783;1159.8724140344555;1162.5291756838494;11866.703332416586;11933.403329833647;11990.580329688419;12048.457819398149;12067.094967877445;588358;604096;608565;617110;621420;130907;131024;131043;133604;134042.32978835044;1163;1228.5270170984236;1265.531753251303;1281.0968267371481;1324.38416136497;21884.23405180811;22235.39668700959;22616.886915565316;22717.43206419853;22836.31491055271;63242.683786580776;63298.36701632925;63401;63570.68214659082;63952.040719388315;82070.58964705138;82106.17893820985;82245;83232;83583.58208209163;15503.84496155024;15643.530694078741;15827.366905323657;16055.277512511731;16126.113063781135;21757.673634895807;21857.234996775038;21872.234471794047;21953;24007.159749408685;18561.90719046396;18753.90623046876;18785.812141878952;18991.145398457378;19022.524436889187;15393.769093463383;15919.920400397923;16662.58343541421;16890.493285201464;17541.912290438468;18305;19253.90373048126;19319.4204173875;19395.51511212231;19601.60796784028;15815.367385304458;15827.446039388566;15930.522084337488;15945.282462288993;15965.760513592537;16720.498385048468;16735.414260500824;16913.74629380585;16926.238319275908;16940.322387104377 04:43:49 INFO - PID 16240 | |3;mootools.html;1410.351238430322;1454.0621299261984;1461.2547600723628;1461.4592600737726;1474.0197768483958;17456.38902638408;18145.909270453645;18213.544661383472;18215.27138914442;18223.36218232362;17748.29006839728;17848.37530686426;17947.282108715637;17954;17962.640747185047;3067.662557118715;3075.3541756231107;3097.860596273173;3109.9222519436967;3116.703913128249;3037.6202974628172;3079.461094308498;3091.7526597872115;3092.056922638594;3092.6134233220846;3076.938461230774;3190.6490286068515;3198.1524895902603;3200.1839530919583;3200.3439294944596;3097.194729370369;3113.455145349566;3117.2518595537135;3137.231378312328;3138.5919830422126;3010.7741919356135;3049.435854366927;3050.542418637222;3051.282948507121;3053.832039237841;17015.489535313962;17315.65368692632;17330.913345433317;17343.392981245597;17352.132393380405;8772.824543509094;9096.63613455461;9099.63601455941;9108.908910910872;9178.954105229497;5561.610687251894;5712.2574065371655;5860.062390017577;5869.559783016291;5904.586678932476;1975.8814471131634;1994.8104930031623;2022.9898850505654;2045.734054572896;2048.682454219598;32879;32985.505217421756;33004.99999999976;33066.17334566631;33256.6674333256;7384.630768461556;7637.236276372319;7759.844803103906;7902.802429939183;8061.395395345373;19836.50408739793;19885.60228795416;19900.70148947767;19956.50108747293;19986;8429.704960326482;8660.566971651393;8698.695545655999;8713;8729.476231426135;7868.331191848757;7884.960575197088;7969.920300797034;7976.042874854938;7987.3610111190965;5857.414258574108;5981.773736384031;5995.220621319288;6043.305019922677;6243.594166379262;1550.387596899241;1603.8396160383868;1670.7159782836948;1674.4976507047836;1714.9571260718333;984.8571957066185;1024.1653052762124;1026.0867827633413;1029.577873072052;1043.4052590023766;9669.70990870275;9793.902060979299;9800.068993445753;9942.403455792532;9957.402555846673;831.3806214370333;833.2584000239791;834.9415540912199;845.226617644848;907.773056735816;5970.194023806731;6014.827248566229;6098.115773212948;6136.938630613637;6173.9073913891325;25506.872465637553;25642.358941026625;26584.202473925812;26584.867075664497;26898.193054208405;24980.625290620654;25386.111486098267;25432.49135017289;25477.36306592313;25551.488970220864;11744.412779360997;11818;11897.464614092387;11927.343996080126;12047.39763011846;9497.620095196115;9732.464714440774;9770.853437198306;9784.217262618831;9951.104400603981;22174.445638859157;22235.888820555792;22316;22487;22660.433489162904;23087.88456057709;23125.190618328277;23154.72649004321;23220.651690224324;23246.883765581064;11822.46798894069;12026.518939242682;12128.454219560317;12145;12148.149629525848;15593.532194034196;15824;15831.762523562385;15832.366705331659;15928.92035539815;11393.544258229578;11596.826047609462;11615.709607259549;11638.476268568103;11816.054715622558;11295.209335346446;11343.319400835977;11562.537498500302;11580.942095289469;11651.708707282385;10114.140298074746;10215.336003159771;10258.230632702427;10387.37675739458;10404.791904162026;8420.4947703138;8430.40987130895;8489.74530764078;8514.318854491501;8628.22345988864;8000.359971202406;8105.351571874118;8175.7138500152205;8209.58952052383;8215.137410571804 04:43:49 INFO - PID 16240 | |4;prototype.html;564.7063526965978;588.9764409423623;603.8399824046626;611.5138464920387;614.9354317796631;6435.581687190329;6501.38236867498;6522.15212022437;6524.934750652498;6526.445252153567;6414.262359828621;6431.324710905346;6444.420002199801;6448;6452.741890324393;5329.520343169114;5330.600204984622;5336.786528538853;5341.4658534146665;5342.946570534284;5044.47033061527;5180.585553155738;5314.654547454403;5323.920141197885;5340.893182136355;5165.741712914358;5168.147255702805;5176.275321454999;5184.53339199472;5185.507376799198;5128.871778205537;5137.229415587654;5139.563137133348;5146.819861304855;5155.432902380735;4664.673472856902;4670.392848929651;4699.7415142167165;4708.364370809931;4740.099381117577;6396.904046439307;6399.520035997319;6411.070394792776;6428.935710642927;6438.581492202992;6051.425114614105;6070.573415247413;6073.939260607382;6103.633781973052;6143.262808462969;6778.491613128986;6821.965890170567;6826.317368263183;6835.111435513351;6858.245592984767;4966.1557535218735;5258.106121959276;5276.498732620395;5285.814996475144;5287.973560132214;6799.864002719967;6907.13660792401;6967.307327116636;7056.259092795285;7126.6080365579355;5350.785968561214;5364.7049412282695;5376.91934620981;5393.487618676298;5399.2980912481125;5410.431904650034;5453.127499600045;5472.179173124063;5486.3690675572025;5517.531009864207;6038.939610603926;6048.818535443944;6071.696415179312;6107.603005804608;6111.480524155497;6686.699098540577;6778;6842.4868134890085;6882.931170688329;6902.965485172542;1821.6265665538533;1857.2014033965254;1861.6649003179286;1873.9318588404751;1890.6596812573594;1283.2172374851334;1315.5592876386327;1332.140769203858;1340.095435580979;1357.1449986508437;6340.682965851689;6420.1974753155855;6423.389777971085;6516.57642253252;6565.21217453899;6881.552699074544;6920.930790692129;6950.582965022115;7025.473089518307;7031.92968070323;6407.263164736022;6424.550281480251;6435.420812126931;6442.291347951722;6526.4452521536205;3683.9447408288897;3694.5012423323046;3709.998300458915;3734.925301494009;3735.7571757835653;7124.928750712426;7182.820429489305;7252.238514955854;7252.709891604382;7355.227701091415;7172.318629730167;7196.733029095818;7233.0597022386755;7233.31283528064;7237.299234679846;7246.818829529198;7286.96356518214;7327.706891724271;7337.19290878003;7359.889601656087;6910.136232970879;6928.792136235921;6947.305269473214;7000.334968178015;7026.683799228945;6932.549384290005;6941.305869413019;7016.929830701628;7018.754343598154;7019.6490175492045;7283.924081636708;7326.4138868889295;7332.193458719643;7341.155767086853;7351.044364232615;5418.349798024262;5446.128619420873;5450.754716037709;5458.099413596712;5469.316335458067;6827.658617069226;7010;7047.295270472911;7109;7111.324424179695;6615.801525954229;6854.931450685629;6855.108835851307;6874;6879.690413931486;6560.343965603402;6720.563163394364;6774.899781067885;6839.760608378683;6856.280090590616;6390.201224846895;6719.496037797283;6742.629155396304;6783.389494945479;6813.386795188457;6790.490713196629;6847.417969472651;6852;6903.206131294965;6939.722411103701;6485.935140648533;6575.50683698715;6593.604383736991;6617.735290588323;6668.599304250563 04:43:49 INFO - PID 16240 | |5;yui.html;985.6303886042734;1002.3234316836134;1021.2187676427529;1026.34314039015;1035.5236591168064;17686.469405917825;18095;18101;18119.63760724785;18135.183916723745;16355.59111022225;17038.74441883373;17291.74062389062;17334.561110972852;17492.475225743216;1064.8552805733827;1085.1915323084274;1088.9183311251647;1089.5260561655666;1089.8964598363143;1083.7344850511636;1100.8458815765798;1108.6175269531998;1110.256128393974;1110.3726394587072;1169.8011338072547;1176.97646047079;1177.2230327983532;1177.8351030855688;1178.658189125157;1160.5241850841157;1164.441068287227;1164.7612239490884;1169.6315660566936;1174.3834486894364;855.4696088425217;857.2541888556996;860.3977215948861;864.3042350907517;865.8657908024203;17934.730979035325;18499.537511562183;18537.351192708324;18674.813251867577;18704.158312875952;19730.30943916956;20237.898810506;20289.289874854458;20319.89840050805;20325.695114573293;16954;17402.39091631787;17483.912580437143;17507.47477575675;17515.47453576382;3644.890653280406;3657.4513822926506;3672.2288319452946;3676.8529258829612;3678.9448158277646;157509;157740;158629;159165.40834591622;159781;15855.762163567555;15965.920170399073;16042;16049.518514444586;16193.67612647764;31403.214919626735;31962.360752784814;32379.838100809346;32433.837830811168;34322.48516272258;9391.248700104112;9501.667441639509;9521.238300936047;9541.61833526665;9614.471204083842;4763.571278584875;4820.421549414023;4825.541573550507;4830.275458681155;4861.829835955796;6063.757449701962;6203.193584833943;6225.937740622627;6231.719572619152;6249.187605611241;991.4447909170743;1021.7394564386034;1022.703416009349;1026.5585798106886;1026.697124348319;667.8764428580631;687.1856849633101;693.552658535252;698.4621841182362;701.4493622506308;11921.761564768656;12094.939525302494;12568.120231583702;12623.495060197673;12663.49346026167;729.6169511006764;734.9412047036225;736.3446532586003;744.571871174077;747.5439981611213;5787.392323806024;5814.476697097281;5900.321463031721;5930.584859059911;5950.166976623276;146868;150055;151833.24083379513;152324;159467.20266398593;128923;129300;131599.3420032913;133983;136721;109817.45091274653;115349.1953183972;117351.41324293324;119488;122279.99999999822;13737.313134343443;13809.585712428643;13914.512992045635;14105.929470352583;14125.293735312987;55358.72320638371;55455;56092.71953640206;56467.15299270597;56534.586635334446;71824.12703492011;72680.63659681668;72886.63556682183;73102.26897730955;73199.63400182965;20613;21153.788462115182;21215.68176477385;21316.893415532824;21453.89273053625;21092.68360974556;21332.466688332916;21451.249206277706;21720.565588687823;21828.890855545622;21442.8927855366;21524.677129842752;21548;21549.46126346854;21731.348059558237;18945.90527047356;19363.612727745647;19366.515837104183;19558.60882782365;19580.510487237363;18842.057897104816;18854.434366969013;18854.811451885853;18924.14841332116;18995.620087597894;14562;15007.77488337654;15099.47151849724;15332.770008450105;15674.451394201604;15580.143092129589;15640.217989100727;15835.366585336918;15861.920690396935;15928.522144335688 04:43:49 INFO - PID 16240 | __end_tp_report 04:43:49 INFO - PID 16240 | __start_cc_report 04:43:49 INFO - PID 16240 | _x_x_mozilla_cycle_collect,250 04:43:49 INFO - PID 16240 | __end_cc_report 04:43:49 INFO - PID 16240 | __startTimestamp1506944629943__endTimestamp 04:43:49 INFO - PID 16240 | 04:43:49 INFO - PID 16240 | ------- Summary: start ------- 04:43:49 INFO - PID 16240 | Number of tests: 6 04:43:49 INFO - PID 16240 | 04:43:49 INFO - PID 16240 | [#0] dojo.html Cycles:165 Average:6546.58 Median:6798.25 stddev:757.80 (11.1%) stddev-sans-first:732.52 04:43:49 INFO - PID 16240 | Values: 3963.5 3993.6 4008.4 4010.1 4021.8 6472.4 6476.5 6503.7 6587.0 6589.0 6418.2 6455.3 6550.6 6556.7 6577.7 5774.7 5818.5 5903.0 5924.3 5961.7 5876.8 5880.4 5890.7 5896.4 6002.4 5872.9 5879.0 5885.8 5953.9 5962.7 5870.7 5955.9 5979.1 5998.4 6018.7 5863.9 5922.7 5928.9 5957.5 5973.3 6346.0 6362.8 6422.7 6451.1 6491.4 7027.2 7067.5 7069.5 7077.0 7132.4 7073.2 7129.0 7170.4 7203.4 7204.5 6447.4 6541.8 6600.8 6640.7 6683.0 7208.1 7351.1 7367.6 7369.5 7373.7 6896.9 6995.6 7068.2 7068.4 7072.5 7046.2 7065.0 7164.1 7169.4 7174.0 7029.3 7051.0 7117.7 7125.6 7129.7 7030.5 7051.0 7117.9 7130.2 7146.6 5181.8 5222.9 5231.0 5241.8 5249.7 4758.5 4835.9 4877.1 4907.5 4911.2 6485.6 6575.5 6650.8 6658.9 6666.7 6981.0 6997.7 7018.4 7070.6 7091.9 6558.0 6614.0 6626.9 6632.6 6633.2 5980.8 5990.9 6020.0 6050.2 6062.3 7265.1 7384.1 7473.1 7490.7 7495.5 7094.3 7106.8 7181.2 7193.4 7202.1 7182.1 7221.8 7315.2 7381.8 7392.5 6920.7 6982.8 7052.7 7055.6 7081.5 7096.0 7098.4 7166.7 7207.4 7223.8 6821.1 6828.5 6894.6 6897.6 6907.5 6798.3 6807.0 6888.6 6889.6 6893.0 6678.5 6740.6 6802.7 6816.6 6819.0 6830.6 6921.0 6935.5 6955.4 6973.8 6630.7 6666.4 6672.7 6697.8 6771.4 04:43:49 INFO - PID 16240 | 04:43:49 INFO - PID 16240 | [#1] ext.html Cycles:180 Average:43883.94 Median:3815.97 stddev:66530.04 (1743.5%) stddev-sans-first:66638.67 04:43:49 INFO - PID 16240 | Values: 977.2 981.7 985.3 1035.1 1037.3 44770.8 45159.8 45454.8 45611.5 45613.1 43039.8 43127.0 43182.1 43220.6 43429.0 8159.3 8229.1 8257.1 8264.7 8265.5 8160.3 8194.7 8263.2 8269.6 8277.4 8844.8 9029.2 9029.8 9031.4 9035.1 8947.0 8970.1 8991.1 9009.3 9032.3 8814.4 8850.7 8882.4 8890.7 8894.2 41487.8 41821.0 41832.8 42107.2 42168.0 3780.2 3812.8 3819.1 3821.2 3824.5 34422.7 34454.7 34656.7 34661.8 34835.0 5432.6 5645.2 5698.4 5732.0 5744.7 225073.0 225340.0 227045.0 227345.0 228774.0 53308.7 53666.7 53836.7 53876.7 53919.2 55831.0 55886.0 55951.4 56437.4 56459.7 16503.4 16723.0 16896.2 16993.7 17152.7 13368.6 13411.3 13420.8 13441.3 13453.9 15404.0 15550.3 15602.8 15646.1 15668.2 6030.2 6094.0 6153.0 6274.8 6340.7 1122.9 1126.4 1128.9 1132.3 1140.6 26222.2 26389.1 26535.7 26648.7 26664.6 1215.0 1227.6 1240.1 1243.6 1263.6 13861.4 14104.0 14111.6 14188.1 14253.8 289444.0 291491.0 291869.0 292955.0 293838.0 53312.2 54128.7 58435.4 61088.7 62385.7 1510.8 1540.6 1541.2 1549.1 1555.6 31070.5 31275.4 31582.2 31753.7 32221.8 38468.0 38650.8 38681.6 38865.6 38888.2 242959.0 243566.0 245531.0 247236.0 247368.0 37075.4 37831.6 38583.6 38684.2 38691.8 47083.5 47790.8 48380.5 48512.5 49181.0 35817.8 36019.3 36147.8 36334.3 36336.6 35434.6 35570.0 36117.6 36346.8 36592.6 42655.4 42709.8 42980.8 43174.8 43228.0 34611.0 34807.6 35342.6 35393.6 35826.3 24922.4 25773.5 25818.5 26403.9 26489.6 04:43:49 INFO - PID 16240 | 04:43:49 INFO - PID 16240 | [#2] jquery.html Cycles:180 Average:40125.99 Median:12238.74 stddev:101816.85 (831.9%) stddev-sans-first:102059.30 04:43:49 INFO - PID 16240 | Values: 633.6 635.8 645.8 647.0 647.4 23910.2 24638.1 24677.8 24704.5 24727.9 26284.1 26424.3 26425.3 26540.0 26548.7 6693.4 6891.6 7032.3 7071.0 7077.8 6911.9 6997.3 7004.1 7015.5 7019.2 6665.4 6922.7 6951.6 6964.8 6969.9 6973.3 7016.2 7018.8 7026.3 7031.0 6568.0 6581.1 6599.1 6603.2 6604.7 29377.0 29698.9 30751.8 31202.5 31404.0 2242.1 2250.5 2252.1 2253.8 2336.3 20339.3 20721.8 20767.8 20792.3 20834.5 2634.4 2638.5 2677.7 2680.4 2691.8 144642.3 151260.0 152412.2 158035.2 158172.0 16248.9 16270.2 17063.7 17146.2 17328.8 27259.5 27266.9 27453.5 27468.0 27476.0 10598.1 10698.3 10916.4 10960.5 11118.3 9582.3 9783.3 9799.5 9835.2 9960.7 10043.6 10103.2 10130.6 10145.4 10580.5 3097.3 3142.8 3148.9 3149.9 3220.8 1048.9 1071.3 1099.2 1104.6 1114.7 21555.7 21664.9 21854.9 21876.2 22084.6 1150.5 1152.7 1157.6 1159.9 1162.5 11866.7 11933.4 11990.6 12048.5 12067.1 588358.0 604096.0 608565.0 617110.0 621420.0 130907.0 131024.0 131043.0 133604.0 134042.3 1163.0 1228.5 1265.5 1281.1 1324.4 21884.2 22235.4 22616.9 22717.4 22836.3 63242.7 63298.4 63401.0 63570.7 63952.0 82070.6 82106.2 82245.0 83232.0 83583.6 15503.8 15643.5 15827.4 16055.3 16126.1 21757.7 21857.2 21872.2 21953.0 24007.2 18561.9 18753.9 18785.8 18991.1 19022.5 15393.8 15919.9 16662.6 16890.5 17541.9 18305.0 19253.9 19319.4 19395.5 19601.6 15815.4 15827.4 15930.5 15945.3 15965.8 16720.5 16735.4 16913.7 16926.2 16940.3 04:43:49 INFO - PID 16240 | 04:43:49 INFO - PID 16240 | [#3] mootools.html Cycles:180 Average:10733.77 Median:3043.53 stddev:8082.88 (265.6%) stddev-sans-first:8075.21 04:43:49 INFO - PID 16240 | Values: 1410.4 1454.1 1461.3 1461.5 1474.0 17456.4 18145.9 18213.5 18215.3 18223.4 17748.3 17848.4 17947.3 17954.0 17962.6 3067.7 3075.4 3097.9 3109.9 3116.7 3037.6 3079.5 3091.8 3092.1 3092.6 3076.9 3190.6 3198.2 3200.2 3200.3 3097.2 3113.5 3117.3 3137.2 3138.6 3010.8 3049.4 3050.5 3051.3 3053.8 17015.5 17315.7 17330.9 17343.4 17352.1 8772.8 9096.6 9099.6 9108.9 9179.0 5561.6 5712.3 5860.1 5869.6 5904.6 1975.9 1994.8 2023.0 2045.7 2048.7 32879.0 32985.5 33005.0 33066.2 33256.7 7384.6 7637.2 7759.8 7902.8 8061.4 19836.5 19885.6 19900.7 19956.5 19986.0 8429.7 8660.6 8698.7 8713.0 8729.5 7868.3 7885.0 7969.9 7976.0 7987.4 5857.4 5981.8 5995.2 6043.3 6243.6 1550.4 1603.8 1670.7 1674.5 1715.0 984.9 1024.2 1026.1 1029.6 1043.4 9669.7 9793.9 9800.1 9942.4 9957.4 831.4 833.3 834.9 845.2 907.8 5970.2 6014.8 6098.1 6136.9 6173.9 25506.9 25642.4 26584.2 26584.9 26898.2 24980.6 25386.1 25432.5 25477.4 25551.5 11744.4 11818.0 11897.5 11927.3 12047.4 9497.6 9732.5 9770.9 9784.2 9951.1 22174.4 22235.9 22316.0 22487.0 22660.4 23087.9 23125.2 23154.7 23220.7 23246.9 11822.5 12026.5 12128.5 12145.0 12148.1 15593.5 15824.0 15831.8 15832.4 15928.9 11393.5 11596.8 11615.7 11638.5 11816.1 11295.2 11343.3 11562.5 11580.9 11651.7 10114.1 10215.3 10258.2 10387.4 10404.8 8420.5 8430.4 8489.7 8514.3 8628.2 8000.4 8105.4 8175.7 8209.6 8215.1 04:43:49 INFO - PID 16240 | 04:43:49 INFO - PID 16240 | [#4] prototype.html Cycles:180 Average:5843.29 Median:6443.36 stddev:1627.86 (25.3%) stddev-sans-first:1583.48 04:43:49 INFO - PID 16240 | Values: 564.7 589.0 603.8 611.5 614.9 6435.6 6501.4 6522.2 6524.9 6526.4 6414.3 6431.3 6444.4 6448.0 6452.7 5329.5 5330.6 5336.8 5341.5 5342.9 5044.5 5180.6 5314.7 5323.9 5340.9 5165.7 5168.1 5176.3 5184.5 5185.5 5128.9 5137.2 5139.6 5146.8 5155.4 4664.7 4670.4 4699.7 4708.4 4740.1 6396.9 6399.5 6411.1 6428.9 6438.6 6051.4 6070.6 6073.9 6103.6 6143.3 6778.5 6822.0 6826.3 6835.1 6858.2 4966.2 5258.1 5276.5 5285.8 5288.0 6799.9 6907.1 6967.3 7056.3 7126.6 5350.8 5364.7 5376.9 5393.5 5399.3 5410.4 5453.1 5472.2 5486.4 5517.5 6038.9 6048.8 6071.7 6107.6 6111.5 6686.7 6778.0 6842.5 6882.9 6903.0 1821.6 1857.2 1861.7 1873.9 1890.7 1283.2 1315.6 1332.1 1340.1 1357.1 6340.7 6420.2 6423.4 6516.6 6565.2 6881.6 6920.9 6950.6 7025.5 7031.9 6407.3 6424.6 6435.4 6442.3 6526.4 3683.9 3694.5 3710.0 3734.9 3735.8 7124.9 7182.8 7252.2 7252.7 7355.2 7172.3 7196.7 7233.1 7233.3 7237.3 7246.8 7287.0 7327.7 7337.2 7359.9 6910.1 6928.8 6947.3 7000.3 7026.7 6932.5 6941.3 7016.9 7018.8 7019.6 7283.9 7326.4 7332.2 7341.2 7351.0 5418.3 5446.1 5450.8 5458.1 5469.3 6827.7 7010.0 7047.3 7109.0 7111.3 6615.8 6854.9 6855.1 6874.0 6879.7 6560.3 6720.6 6774.9 6839.8 6856.3 6390.2 6719.5 6742.6 6783.4 6813.4 6790.5 6847.4 6852.0 6903.2 6939.7 6485.9 6575.5 6593.6 6617.7 6668.6 04:43:49 INFO - PID 16240 | 04:43:49 INFO - PID 16240 | [#5] yui.html Cycles:180 Average:28517.34 Median:18606.08 stddev:42512.86 (228.5%) stddev-sans-first:42581.86 04:43:49 INFO - PID 16240 | Values: 985.6 1002.3 1021.2 1026.3 1035.5 17686.5 18095.0 18101.0 18119.6 18135.2 16355.6 17038.7 17291.7 17334.6 17492.5 1064.9 1085.2 1088.9 1089.5 1089.9 1083.7 1100.8 1108.6 1110.3 1110.4 1169.8 1177.0 1177.2 1177.8 1178.7 1160.5 1164.4 1164.8 1169.6 1174.4 855.5 857.3 860.4 864.3 865.9 17934.7 18499.5 18537.4 18674.8 18704.2 19730.3 20237.9 20289.3 20319.9 20325.7 16954.0 17402.4 17483.9 17507.5 17515.5 3644.9 3657.5 3672.2 3676.9 3678.9 157509.0 157740.0 158629.0 159165.4 159781.0 15855.8 15965.9 16042.0 16049.5 16193.7 31403.2 31962.4 32379.8 32433.8 34322.5 9391.2 9501.7 9521.2 9541.6 9614.5 4763.6 4820.4 4825.5 4830.3 4861.8 6063.8 6203.2 6225.9 6231.7 6249.2 991.4 1021.7 1022.7 1026.6 1026.7 667.9 687.2 693.6 698.5 701.4 11921.8 12094.9 12568.1 12623.5 12663.5 729.6 734.9 736.3 744.6 747.5 5787.4 5814.5 5900.3 5930.6 5950.2 146868.0 150055.0 151833.2 152324.0 159467.2 128923.0 129300.0 131599.3 133983.0 136721.0 109817.5 115349.2 117351.4 119488.0 122280.0 13737.3 13809.6 13914.5 14105.9 14125.3 55358.7 55455.0 56092.7 56467.2 56534.6 71824.1 72680.6 72886.6 73102.3 73199.6 20613.0 21153.8 21215.7 21316.9 21453.9 21092.7 21332.5 21451.2 21720.6 21828.9 21442.9 21524.7 21548.0 21549.5 21731.3 18945.9 19363.6 19366.5 19558.6 19580.5 18842.1 18854.4 18854.8 18924.1 18995.6 14562.0 15007.8 15099.5 15332.8 15674.5 15580.1 15640.2 15835.4 15861.9 15928.5 04:43:49 INFO - PID 16240 | -------- Summary: end -------- 04:43:49 INFO - PID 16240 | 04:43:50 INFO - TEST-INFO | 16240: exit 0 04:43:50 INFO - TEST-OK | dromaeo_css | took 1107463ms 04:43:50 INFO - TEST-START | kraken 04:43:50 INFO - Initialising browser for kraken test... 04:43:50 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59980/getInfo.html -profile /tmp/tmpq0nikL/profile 04:43:50 INFO - TEST-INFO | started process 16598 (/builds/slave/test/build/application/firefox/firefox http://localhost:59980/getInfo.html) 04:43:55 INFO - TEST-INFO | 16598: exit 0 04:43:55 INFO - Browser initialized. 04:43:55 INFO - Running cycle 1/1 for kraken test... 04:43:55 INFO - Using env: {'DISPLAY': ':0', 04:43:55 INFO - 'HOME': '/home/cltbld', 04:43:55 INFO - 'JSGC_DISABLE_POISONING': '1', 04:43:55 INFO - 'LANG': 'en_US.UTF-8', 04:43:55 INFO - 'LANGUAGE': 'en_US:en', 04:43:55 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:43:55 INFO - 'LOGNAME': 'cltbld', 04:43:55 INFO - 'MAIL': '/var/mail/cltbld', 04:43:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:43:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:43:55 INFO - 'MOZ_CRASHREPORTER': '1', 04:43:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:55 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:43:55 INFO - 'MOZ_NO_REMOTE': '1', 04:43:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:43:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:55 INFO - 'NO_EM_RESTART': '1', 04:43:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:43:55 INFO - 'PWD': '/builds/slave/test', 04:43:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:43:55 INFO - 'RUST_BACKTRACE': 'full', 04:43:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:43:55 INFO - 'SHELL': '/bin/bash', 04:43:55 INFO - 'SHLVL': '1', 04:43:55 INFO - 'TERM': 'linux', 04:43:55 INFO - 'TMOUT': '86400', 04:43:55 INFO - 'USER': 'cltbld', 04:43:55 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982', 04:43:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:55 INFO - '_': '/tools/buildbot/bin/python'} 04:43:55 INFO - TEST-INFO | started process 16783 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpq0nikL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:43:56 INFO - PID 16783 | 04:43:56 INFO - PID 16783 | (/builds/slave/test/build/application/firefox/firefox:16833): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:43:56 INFO - PID 16783 | 04:44:07 INFO - PID 16783 | RSS: Main: 143794176 04:44:07 INFO - PID 16783 | 04:44:09 INFO - PID 16783 | Cycle 1(1): loaded http://localhost:59980/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59980/tests/kraken/driver.html?testName=audio-beat-detection) 04:44:10 INFO - PID 16783 | RSS: Main: 149274624 04:44:10 INFO - PID 16783 | 04:44:12 INFO - PID 16783 | Cycle 1(1): loaded http://localhost:59980/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59980/tests/kraken/driver.html?testName=audio-dft) 04:44:13 INFO - PID 16783 | RSS: Main: 149704704 04:44:13 INFO - PID 16783 | 04:44:16 INFO - PID 16783 | Cycle 1(1): loaded http://localhost:59980/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59980/tests/kraken/driver.html?testName=audio-fft) 04:44:17 INFO - PID 16783 | RSS: Main: 151187456 04:44:17 INFO - PID 16783 | 04:44:19 INFO - PID 16783 | Cycle 1(1): loaded http://localhost:59980/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59980/tests/kraken/driver.html?testName=audio-oscillator) 04:44:19 INFO - PID 16783 | RSS: Main: 151257088 04:44:19 INFO - PID 16783 | 04:44:21 INFO - PID 16783 | Cycle 1(1): loaded http://localhost:59980/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59980/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:44:22 INFO - PID 16783 | RSS: Main: 151347200 04:44:22 INFO - PID 16783 | 04:44:25 INFO - PID 16783 | Cycle 1(1): loaded http://localhost:59980/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59980/tests/kraken/driver.html?testName=imaging-darkroom) 04:44:25 INFO - PID 16783 | RSS: Main: 151162880 04:44:25 INFO - PID 16783 | 04:44:29 INFO - PID 16783 | Cycle 1(1): loaded http://localhost:59980/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59980/tests/kraken/driver.html?testName=imaging-desaturate) 04:44:30 INFO - PID 16783 | RSS: Main: 152096768 04:44:30 INFO - PID 16783 | 04:44:32 INFO - PID 16783 | Cycle 1(1): loaded http://localhost:59980/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59980/tests/kraken/driver.html?testName=json-parse-financial) 04:44:33 INFO - PID 16783 | RSS: Main: 153075712 04:44:33 INFO - PID 16783 | 04:44:35 INFO - PID 16783 | Cycle 1(1): loaded http://localhost:59980/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59980/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:44:35 INFO - PID 16783 | RSS: Main: 153346048 04:44:35 INFO - PID 16783 | 04:44:37 INFO - PID 16783 | Cycle 1(1): loaded http://localhost:59980/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59980/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:44:37 INFO - PID 16783 | RSS: Main: 153493504 04:44:37 INFO - PID 16783 | 04:44:39 INFO - PID 16783 | Cycle 1(1): loaded http://localhost:59980/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59980/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:44:40 INFO - PID 16783 | RSS: Main: 150237184 04:44:40 INFO - PID 16783 | 04:44:42 INFO - PID 16783 | Cycle 1(1): loaded http://localhost:59980/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59980/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:44:43 INFO - PID 16783 | RSS: Main: 150323200 04:44:43 INFO - PID 16783 | 04:44:46 INFO - PID 16783 | Cycle 1(1): loaded http://localhost:59980/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59980/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:44:46 INFO - PID 16783 | RSS: Main: 150769664 04:44:46 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | Cycle 1(1): loaded http://localhost:59980/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59980/tests/kraken/driver.html?testName=ai-astar) 04:44:48 INFO - PID 16783 | RSS: Main: 152076288 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | __start_tp_report 04:44:48 INFO - PID 16783 | _x_x_mozilla_page_load 04:44:48 INFO - PID 16783 | _x_x_mozilla_page_load_details 04:44:48 INFO - PID 16783 | |i|pagename|runs| 04:44:48 INFO - PID 16783 | |0;ai-astar;107;105;104;106;104;106;93;105;105;105 04:44:48 INFO - PID 16783 | |1;audio-beat-detection;114;111;114;116;141;113;118;115;112;113 04:44:48 INFO - PID 16783 | |2;audio-dft;160;162;162;162;164;162;162;162;163;162 04:44:48 INFO - PID 16783 | |3;audio-fft;74;75;74;73;73;73;74;74;72;72 04:44:48 INFO - PID 16783 | |4;audio-oscillator;78;81;81;81;83;77;78;77;77;77 04:44:48 INFO - PID 16783 | |5;imaging-gaussian-blur;113;114;115;113;113;115;114;114;114;109 04:44:48 INFO - PID 16783 | |6;imaging-darkroom;149;150;150;149;149;148;151;150;149;149 04:44:48 INFO - PID 16783 | |7;imaging-desaturate;94;101;94;100;99;99;99;94;94;95 04:44:48 INFO - PID 16783 | |8;json-parse-financial;45;44;44;42;50;43;43;45;40;42 04:44:48 INFO - PID 16783 | |9;json-stringify-tinderbox;38;39;38;39;39;39;39;39;38;38 04:44:48 INFO - PID 16783 | |10;stanford-crypto-aes;70;69;71;70;70;71;70;69;70;85 04:44:48 INFO - PID 16783 | |11;stanford-crypto-ccm;103;107;101;112;102;102;105;108;102;108 04:44:48 INFO - PID 16783 | |12;stanford-crypto-pbkdf2;158;148;156;154;150;150;155;153;149;152 04:44:48 INFO - PID 16783 | |13;stanford-crypto-sha256-iterative;58;57;59;58;58;58;61;59;66;66 04:44:48 INFO - PID 16783 | __end_tp_report 04:44:48 INFO - PID 16783 | __start_cc_report 04:44:48 INFO - PID 16783 | _x_x_mozilla_cycle_collect,652 04:44:48 INFO - PID 16783 | __end_cc_report 04:44:48 INFO - PID 16783 | __startTimestamp1506944688660__endTimestamp 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | ------- Summary: start ------- 04:44:48 INFO - PID 16783 | Number of tests: 14 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | [#0] ai-astar Cycles:10 Average:104.00 Median:105.50 stddev:3.97 (3.8%) stddev-sans-first:4.06 04:44:48 INFO - PID 16783 | Values: 107.0 105.0 104.0 106.0 104.0 106.0 93.0 105.0 105.0 105.0 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | [#1] audio-beat-detection Cycles:10 Average:116.70 Median:114.50 stddev:8.77 (7.7%) stddev-sans-first:9.25 04:44:48 INFO - PID 16783 | Values: 114.0 111.0 114.0 116.0 141.0 113.0 118.0 115.0 112.0 113.0 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:0.99 (0.6%) stddev-sans-first:0.71 04:44:48 INFO - PID 16783 | Values: 160.0 162.0 162.0 162.0 164.0 162.0 162.0 162.0 163.0 162.0 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | [#3] audio-fft Cycles:10 Average:73.40 Median:74.00 stddev:0.97 (1.3%) stddev-sans-first:1.00 04:44:48 INFO - PID 16783 | Values: 74.0 75.0 74.0 73.0 73.0 73.0 74.0 74.0 72.0 72.0 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:79.50 stddev:2.26 (2.8%) stddev-sans-first:2.37 04:44:48 INFO - PID 16783 | Values: 78.0 81.0 81.0 81.0 83.0 77.0 78.0 77.0 77.0 77.0 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:114.00 stddev:1.71 (1.5%) stddev-sans-first:1.81 04:44:48 INFO - PID 16783 | Values: 113.0 114.0 115.0 113.0 113.0 115.0 114.0 114.0 114.0 109.0 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | [#6] imaging-darkroom Cycles:10 Average:149.40 Median:149.50 stddev:0.84 (0.6%) stddev-sans-first:0.88 04:44:48 INFO - PID 16783 | Values: 149.0 150.0 150.0 149.0 149.0 148.0 151.0 150.0 149.0 149.0 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | [#7] imaging-desaturate Cycles:10 Average:96.90 Median:94.50 stddev:2.92 (3.1%) stddev-sans-first:2.91 04:44:48 INFO - PID 16783 | Values: 94.0 101.0 94.0 100.0 99.0 99.0 99.0 94.0 94.0 95.0 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | [#8] json-parse-financial Cycles:10 Average:43.80 Median:44.00 stddev:2.66 (6.0%) stddev-sans-first:2.78 04:44:48 INFO - PID 16783 | Values: 45.0 44.0 44.0 42.0 50.0 43.0 43.0 45.0 40.0 42.0 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | [#9] json-stringify-tinderbox Cycles:10 Average:38.60 Median:39.00 stddev:0.52 (1.3%) stddev-sans-first:0.50 04:44:48 INFO - PID 16783 | Values: 38.0 39.0 38.0 39.0 39.0 39.0 39.0 39.0 38.0 38.0 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | [#10] stanford-crypto-aes Cycles:10 Average:71.50 Median:70.00 stddev:4.79 (6.8%) stddev-sans-first:5.05 04:44:48 INFO - PID 16783 | Values: 70.0 69.0 71.0 70.0 70.0 71.0 70.0 69.0 70.0 85.0 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | [#11] stanford-crypto-ccm Cycles:10 Average:105.00 Median:106.00 stddev:3.62 (3.4%) stddev-sans-first:3.77 04:44:48 INFO - PID 16783 | Values: 103.0 107.0 101.0 112.0 102.0 102.0 105.0 108.0 102.0 108.0 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.50 Median:153.50 stddev:3.27 (2.1%) stddev-sans-first:2.80 04:44:48 INFO - PID 16783 | Values: 158.0 148.0 156.0 154.0 150.0 150.0 155.0 153.0 149.0 152.0 04:44:48 INFO - PID 16783 | 04:44:48 INFO - PID 16783 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.00 Median:59.00 stddev:3.33 (5.6%) stddev-sans-first:3.46 04:44:48 INFO - PID 16783 | Values: 58.0 57.0 59.0 58.0 58.0 58.0 61.0 59.0 66.0 66.0 04:44:48 INFO - PID 16783 | -------- Summary: end -------- 04:44:48 INFO - PID 16783 | 04:44:49 INFO - PID 16783 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:44:49 INFO - TEST-INFO | 16783: exit 0 04:44:49 INFO - TEST-OK | kraken | took 58713ms 04:44:49 INFO - SUITE-END | took 1166s 04:44:49 INFO - Completed test suite (00:19:26) 04:44:49 INFO - javascript benchmark 04:44:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9323.665284763501, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3963.484746982892, 3993.580674029225, 4008.4388185654007, 4010.13782036862, 4021.7989100544983, 6472.38512341328, 6476.481881449485, 6503.674816259192, 6587.044878492616, 6589.011648252758, 6418.19772528434, 6455.289918109004, 6550.639714815683, 6556.704948277327, 6577.736890524373, 5774.711264436783, 5818.476337129647, 5902.970485147568, 5924.289085309769, 5961.731722072495, 5876.764929402819, 5880.441358070998, 5890.705464726747, 5896.351401345849, 6002.399760024006, 5872.941270587304, 5878.970605146989, 5885.764569417218, 5953.85115372117, 5962.731677074542, 5870.73581688825, 5955.910661340084, 5979.103134529855, 5998.430149135826, 6018.6689732064715, 5863.8534036649, 5922.7038648067855, 5928.911066334009, 5957.493613042897, 5973.342932277446, 6345.968270158666, 6362.777302794426, 6422.710978006001, 6451.096846441502, 6491.350864913471, 7027.227005029443, 7067.469939754487, 7069.505134640577, 7077.0, 7132.4294056475355, 7073.151221853359, 7129.037579926696, 7170.390516806077, 7203.387712044482, 7204.459665525106, 6447.387498187664, 6541.803745887631, 6600.834979125512, 6640.701168447383, 6682.966585167043, 7208.06295181623, 7351.081114860643, 7367.631618419057, 7369.520981136209, 7373.705051797868, 6896.896546551806, 6995.580265184105, 7068.151821781419, 7068.399186069241, 7072.5402848814665, 7046.224915259318, 7064.964675176694, 7164.104486939133, 7169.354758071799, 7174.0, 7029.297070293032, 7051.048108505389, 7117.67970441321, 7125.608091554911, 7129.679164437613, 7030.472714546328, 7051.048108505389, 7117.92882071183, 7130.215676275606, 7146.571205727673, 5181.818636347785, 5222.895542089137, 5231.0, 5241.816536421207, 5249.658772179796, 4758.500357462486, 4835.903281934412, 4877.097736918681, 4907.533784290486, 4911.238757992517, 6485.6108633482145, 6575.506836987245, 6650.767223147167, 6658.933410665928, 6666.699998500079, 6980.965095174492, 6997.685104170325, 7018.368346848808, 7070.646467676698, 7091.858162836715, 6558.0, 6613.966930165318, 6626.9337306627285, 6632.635205063768, 6633.2040155181685, 5980.760769569256, 5990.850228744316, 6019.969900150471, 6050.213472248667, 6062.333143354227, 7265.09186351706, 7384.076990376203, 7473.1032276126125, 7490.700371985168, 7495.512791668524, 7094.290570942864, 7106.751263705745, 7181.1741649708865, 7193.352598266181, 7202.099737532703, 7182.138143422822, 7221.783346499614, 7315.231900650568, 7381.8154546136775, 7392.4825262231125, 6920.688569014507, 6982.79051628452, 7052.682629281797, 7055.576665400093, 7081.46888983318, 7096.0420343253, 7098.396636285974, 7166.749163779451, 7207.351338379571, 7223.783286501203, 6821.147356580427, 6828.453723702191, 6894.551854129466, 6897.55165914214, 6907.481938854707, 6798.252192258947, 6807.0, 6888.552244104118, 6889.621070841152, 6893.034975103389, 6678.532502724763, 6740.629265390255, 6802.727890884507, 6816.625085620339, 6819.045333653392, 6830.59016459014, 6920.9653951729915, 6935.479839011993, 6955.374016338554, 6973.8256543584785, 6630.701618427086, 6666.400023997863, 6672.733090676318, 6697.799066028027, 6771.39057484809], "value": 6496.184083917628, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [977.2426369563588, 981.6711401680436, 985.3299756165807, 1035.1304903880741, 1037.305005646217, 44770.776146119264, 45159.77420112899, 45454.772726136405, 45611.54388456114, 45613.087738245216, 43039.78480107603, 43127.0, 43182.13635727292, 43220.56779432205, 43429.0, 8159.306458950996, 8229.05365882925, 8257.133001034896, 8264.710735124272, 8265.545395003272, 8160.30637395822, 8194.672213111467, 8263.214994575506, 8269.627866745981, 8277.420580559334, 8844.778880527974, 9029.23251523618, 9029.774255643628, 9031.412958157698, 9035.051319611444, 8946.955265223698, 8970.058143894928, 8991.14584114508, 9009.279257659371, 9032.322575806807, 8814.382993190478, 8850.73447796567, 8882.42264252828, 8890.688825891128, 8894.155055269805, 41487.792561037306, 41821.0, 41832.790836045926, 42107.157856843, 42168.0, 3780.1872597391693, 3812.8284227209842, 3819.102510909934, 3821.178446633959, 3824.464574959508, 34422.655773442195, 34454.6554534454, 34656.65343346585, 34661.826690866386, 34835.0, 5432.592555558349, 5645.15322701594, 5698.40166782486, 5731.971340143315, 5744.655320680772, 225073.0, 225340.0, 227045.0, 227345.0, 228774.0, 53308.73345633325, 53666.7316663422, 53836.73081634567, 53876.73061634667, 53919.19121213185, 55831.0, 55886.0, 55951.440485595434, 56437.435625643215, 56459.71770141123, 16503.422380216634, 16723.0, 16896.23966921467, 16993.660126797397, 17152.742708859376, 13368.598942031755, 13411.262380568967, 13420.798688019688, 13441.260730659907, 13453.93273033648, 15404.0, 15550.300236489385, 15602.843971560365, 15646.139462329682, 15668.216589170495, 6030.18592490017, 6093.969530152321, 6152.969235153795, 6274.780382686584, 6340.651264180422, 1122.9045531129782, 1126.4030064066058, 1128.8588926384202, 1132.3432409202642, 1140.5722853929776, 26222.213333600022, 26389.076382326526, 26535.73464265371, 26648.733512664625, 26664.60003099955, 1214.9939250303692, 1227.6010296653624, 1240.1195151442578, 1243.583399561139, 1263.6209137258786, 13861.4455421782, 14104.0, 14111.647208819862, 14188.077774944595, 14253.78619320711, 289444.0, 291491.0, 291869.0, 292955.0, 293838.0, 53312.20031699527, 54128.72935635375, 58435.415645843845, 61088.69455652782, 62385.68807155935, 1510.765831296151, 1540.614846288428, 1541.175471122944, 1549.093780138609, 1555.61887337603, 31070.533941990438, 31275.374492509567, 31582.210444739067, 31753.68246317507, 32221.838890805397, 38468.0, 38650.80674596722, 38681.613183868925, 38865.6113438862, 38888.22223555457, 242959.0, 243566.0, 245531.0, 247236.0, 247368.0, 37075.44386834253, 37831.62168378391, 38583.614163858, 38684.22631547297, 38691.80654096712, 47083.529164707914, 47790.761046195934, 48380.516194837604, 48512.5148748508, 49181.0, 35817.82091089632, 36019.279614408086, 36147.819260903525, 36334.27331453409, 36336.636633634385, 35434.645653543135, 35570.0, 36117.63882361143, 36346.8182659085, 36592.63407365892, 42655.360169598105, 42709.78645106879, 42980.785096074316, 43174.78412608043, 43228.0, 34611.0, 34807.60769569189, 35342.64657353394, 35393.64606353903, 35826.28347433089, 24922.37694057663, 25773.484530309663, 25818.483630327664, 26403.867980659972, 26489.60265596056], "value": 19238.43255186029, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [633.5573571607382, 635.8146556729763, 645.8198378579982, 647.0296129130592, 647.3735552336751, 23910.163144289952, 24638.137665181723, 24677.75322246777, 24704.50590988184, 24727.876360618193, 26284.080057198003, 26424.339391515223, 26425.3393665158, 26540.0, 26548.734512654868, 6693.364130407604, 6891.586504809702, 7032.261612530688, 7071.045408869788, 7077.823054423629, 6911.89632155518, 6997.335253150943, 7004.089468369105, 7015.543989640682, 7019.157701075878, 6665.433438157763, 6922.688479018456, 6951.582905025714, 6964.825879353031, 6969.895451568231, 6973.3375329343635, 7016.193137789118, 7018.789436316919, 7026.262242464519, 7031.015657807937, 6568.047633093222, 6581.14445122131, 6599.142111525471, 6603.174603174603, 6604.669766511655, 29377.0, 29698.85150574255, 30751.846240768653, 31202.53196202059, 31404.0, 2242.080746893782, 2250.459889626478, 2252.087904398721, 2253.808426283768, 2336.3224664847176, 20339.288124915704, 20721.792782072138, 20767.792322076737, 20792.27227047046, 20834.479138021517, 2634.433596776683, 2638.4986852498155, 2677.678678558566, 2680.437108207279, 2691.838489690625, 144642.27678861644, 151260.0, 152412.2379388096, 158035.2098239513, 158172.0, 16248.918755406148, 16270.186490675418, 17063.658726825393, 17146.22841972114, 17328.82671173272, 27259.45481090367, 27266.863665681543, 27453.450930981668, 27468.0, 27476.03833865805, 10598.0991615712, 10698.304610200312, 10916.399598022026, 10960.451977401099, 11118.277311974696, 9582.28132890036, 9783.266255030901, 9799.510024498746, 9835.164011058998, 9960.651377201762, 10043.598256069676, 10103.19174466041, 10130.594776208869, 10145.391276523285, 10580.52387642558, 3097.287623846483, 3142.827144507074, 3148.9055328340187, 3149.88975385865, 3220.7745457817728, 1048.8584041154347, 1071.2554774431694, 1099.1701265544623, 1104.5692180049787, 1114.6990312615549, 21555.67666485004, 21664.891675541836, 21854.89072554627, 21876.23433179863, 22084.558308834054, 1150.4937827356105, 1152.7118220444888, 1157.5948418053783, 1159.8724140344555, 1162.5291756838494, 11866.703332416586, 11933.403329833647, 11990.580329688419, 12048.457819398149, 12067.094967877445, 588358.0, 604096.0, 608565.0, 617110.0, 621420.0, 130907.0, 131024.0, 131043.0, 133604.0, 134042.32978835044, 1163.0, 1228.5270170984236, 1265.531753251303, 1281.0968267371481, 1324.38416136497, 21884.23405180811, 22235.39668700959, 22616.886915565316, 22717.43206419853, 22836.31491055271, 63242.683786580776, 63298.36701632925, 63401.0, 63570.68214659082, 63952.040719388315, 82070.58964705138, 82106.17893820985, 82245.0, 83232.0, 83583.58208209163, 15503.84496155024, 15643.530694078741, 15827.366905323657, 16055.277512511731, 16126.113063781135, 21757.673634895807, 21857.234996775038, 21872.234471794047, 21953.0, 24007.159749408685, 18561.90719046396, 18753.90623046876, 18785.812141878952, 18991.145398457378, 19022.524436889187, 15393.769093463383, 15919.920400397923, 16662.58343541421, 16890.493285201464, 17541.912290438468, 18305.0, 19253.90373048126, 19319.4204173875, 19395.51511212231, 19601.60796784028, 15815.367385304458, 15827.446039388566, 15930.522084337488, 15945.282462288993, 15965.760513592537, 16720.498385048468, 16735.414260500824, 16913.74629380585, 16926.238319275908, 16940.322387104377], "value": 13010.408714013653, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1410.351238430322, 1454.0621299261984, 1461.2547600723628, 1461.4592600737726, 1474.0197768483958, 17456.38902638408, 18145.909270453645, 18213.544661383472, 18215.27138914442, 18223.36218232362, 17748.29006839728, 17848.37530686426, 17947.282108715637, 17954.0, 17962.640747185047, 3067.662557118715, 3075.3541756231107, 3097.860596273173, 3109.9222519436967, 3116.703913128249, 3037.6202974628172, 3079.461094308498, 3091.7526597872115, 3092.056922638594, 3092.6134233220846, 3076.938461230774, 3190.6490286068515, 3198.1524895902603, 3200.1839530919583, 3200.3439294944596, 3097.194729370369, 3113.455145349566, 3117.2518595537135, 3137.231378312328, 3138.5919830422126, 3010.7741919356135, 3049.435854366927, 3050.542418637222, 3051.282948507121, 3053.832039237841, 17015.489535313962, 17315.65368692632, 17330.913345433317, 17343.392981245597, 17352.132393380405, 8772.824543509094, 9096.63613455461, 9099.63601455941, 9108.908910910872, 9178.954105229497, 5561.610687251894, 5712.2574065371655, 5860.062390017577, 5869.559783016291, 5904.586678932476, 1975.8814471131634, 1994.8104930031623, 2022.9898850505654, 2045.734054572896, 2048.682454219598, 32879.0, 32985.505217421756, 33004.99999999976, 33066.17334566631, 33256.6674333256, 7384.630768461556, 7637.236276372319, 7759.844803103906, 7902.802429939183, 8061.395395345373, 19836.50408739793, 19885.60228795416, 19900.70148947767, 19956.50108747293, 19986.0, 8429.704960326482, 8660.566971651393, 8698.695545655999, 8713.0, 8729.476231426135, 7868.331191848757, 7884.960575197088, 7969.920300797034, 7976.042874854938, 7987.3610111190965, 5857.414258574108, 5981.773736384031, 5995.220621319288, 6043.305019922677, 6243.594166379262, 1550.387596899241, 1603.8396160383868, 1670.7159782836948, 1674.4976507047836, 1714.9571260718333, 984.8571957066185, 1024.1653052762124, 1026.0867827633413, 1029.577873072052, 1043.4052590023766, 9669.70990870275, 9793.902060979299, 9800.068993445753, 9942.403455792532, 9957.402555846673, 831.3806214370333, 833.2584000239791, 834.9415540912199, 845.226617644848, 907.773056735816, 5970.194023806731, 6014.827248566229, 6098.115773212948, 6136.938630613637, 6173.9073913891325, 25506.872465637553, 25642.358941026625, 26584.202473925812, 26584.867075664497, 26898.193054208405, 24980.625290620654, 25386.111486098267, 25432.49135017289, 25477.36306592313, 25551.488970220864, 11744.412779360997, 11818.0, 11897.464614092387, 11927.343996080126, 12047.39763011846, 9497.620095196115, 9732.464714440774, 9770.853437198306, 9784.217262618831, 9951.104400603981, 22174.445638859157, 22235.888820555792, 22316.0, 22487.0, 22660.433489162904, 23087.88456057709, 23125.190618328277, 23154.72649004321, 23220.651690224324, 23246.883765581064, 11822.46798894069, 12026.518939242682, 12128.454219560317, 12145.0, 12148.149629525848, 15593.532194034196, 15824.0, 15831.762523562385, 15832.366705331659, 15928.92035539815, 11393.544258229578, 11596.826047609462, 11615.709607259549, 11638.476268568103, 11816.054715622558, 11295.209335346446, 11343.319400835977, 11562.537498500302, 11580.942095289469, 11651.708707282385, 10114.140298074746, 10215.336003159771, 10258.230632702427, 10387.37675739458, 10404.791904162026, 8420.4947703138, 8430.40987130895, 8489.74530764078, 8514.318854491501, 8628.22345988864, 8000.359971202406, 8105.351571874118, 8175.7138500152205, 8209.58952052383, 8215.137410571804], "value": 7501.263259775111, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.7063526965978, 588.9764409423623, 603.8399824046626, 611.5138464920387, 614.9354317796631, 6435.581687190329, 6501.38236867498, 6522.15212022437, 6524.934750652498, 6526.445252153567, 6414.262359828621, 6431.324710905346, 6444.420002199801, 6448.0, 6452.741890324393, 5329.520343169114, 5330.600204984622, 5336.786528538853, 5341.4658534146665, 5342.946570534284, 5044.47033061527, 5180.585553155738, 5314.654547454403, 5323.920141197885, 5340.893182136355, 5165.741712914358, 5168.147255702805, 5176.275321454999, 5184.53339199472, 5185.507376799198, 5128.871778205537, 5137.229415587654, 5139.563137133348, 5146.819861304855, 5155.432902380735, 4664.673472856902, 4670.392848929651, 4699.7415142167165, 4708.364370809931, 4740.099381117577, 6396.904046439307, 6399.520035997319, 6411.070394792776, 6428.935710642927, 6438.581492202992, 6051.425114614105, 6070.573415247413, 6073.939260607382, 6103.633781973052, 6143.262808462969, 6778.491613128986, 6821.965890170567, 6826.317368263183, 6835.111435513351, 6858.245592984767, 4966.1557535218735, 5258.106121959276, 5276.498732620395, 5285.814996475144, 5287.973560132214, 6799.864002719967, 6907.13660792401, 6967.307327116636, 7056.259092795285, 7126.6080365579355, 5350.785968561214, 5364.7049412282695, 5376.91934620981, 5393.487618676298, 5399.2980912481125, 5410.431904650034, 5453.127499600045, 5472.179173124063, 5486.3690675572025, 5517.531009864207, 6038.939610603926, 6048.818535443944, 6071.696415179312, 6107.603005804608, 6111.480524155497, 6686.699098540577, 6778.0, 6842.4868134890085, 6882.931170688329, 6902.965485172542, 1821.6265665538533, 1857.2014033965254, 1861.6649003179286, 1873.9318588404751, 1890.6596812573594, 1283.2172374851334, 1315.5592876386327, 1332.140769203858, 1340.095435580979, 1357.1449986508437, 6340.682965851689, 6420.1974753155855, 6423.389777971085, 6516.57642253252, 6565.21217453899, 6881.552699074544, 6920.930790692129, 6950.582965022115, 7025.473089518307, 7031.92968070323, 6407.263164736022, 6424.550281480251, 6435.420812126931, 6442.291347951722, 6526.4452521536205, 3683.9447408288897, 3694.5012423323046, 3709.998300458915, 3734.925301494009, 3735.7571757835653, 7124.928750712426, 7182.820429489305, 7252.238514955854, 7252.709891604382, 7355.227701091415, 7172.318629730167, 7196.733029095818, 7233.0597022386755, 7233.31283528064, 7237.299234679846, 7246.818829529198, 7286.96356518214, 7327.706891724271, 7337.19290878003, 7359.889601656087, 6910.136232970879, 6928.792136235921, 6947.305269473214, 7000.334968178015, 7026.683799228945, 6932.549384290005, 6941.305869413019, 7016.929830701628, 7018.754343598154, 7019.6490175492045, 7283.924081636708, 7326.4138868889295, 7332.193458719643, 7341.155767086853, 7351.044364232615, 5418.349798024262, 5446.128619420873, 5450.754716037709, 5458.099413596712, 5469.316335458067, 6827.658617069226, 7010.0, 7047.295270472911, 7109.0, 7111.324424179695, 6615.801525954229, 6854.931450685629, 6855.108835851307, 6874.0, 6879.690413931486, 6560.343965603402, 6720.563163394364, 6774.899781067885, 6839.760608378683, 6856.280090590616, 6390.201224846895, 6719.496037797283, 6742.629155396304, 6783.389494945479, 6813.386795188457, 6790.490713196629, 6847.417969472651, 6852.0, 6903.206131294965, 6939.722411103701, 6485.935140648533, 6575.50683698715, 6593.604383736991, 6617.735290588323, 6668.599304250563], "value": 5376.3237426104, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [985.6303886042734, 1002.3234316836134, 1021.2187676427529, 1026.34314039015, 1035.5236591168064, 17686.469405917825, 18095.0, 18101.0, 18119.63760724785, 18135.183916723745, 16355.59111022225, 17038.74441883373, 17291.74062389062, 17334.561110972852, 17492.475225743216, 1064.8552805733827, 1085.1915323084274, 1088.9183311251647, 1089.5260561655666, 1089.8964598363143, 1083.7344850511636, 1100.8458815765798, 1108.6175269531998, 1110.256128393974, 1110.3726394587072, 1169.8011338072547, 1176.97646047079, 1177.2230327983532, 1177.8351030855688, 1178.658189125157, 1160.5241850841157, 1164.441068287227, 1164.7612239490884, 1169.6315660566936, 1174.3834486894364, 855.4696088425217, 857.2541888556996, 860.3977215948861, 864.3042350907517, 865.8657908024203, 17934.730979035325, 18499.537511562183, 18537.351192708324, 18674.813251867577, 18704.158312875952, 19730.30943916956, 20237.898810506, 20289.289874854458, 20319.89840050805, 20325.695114573293, 16954.0, 17402.39091631787, 17483.912580437143, 17507.47477575675, 17515.47453576382, 3644.890653280406, 3657.4513822926506, 3672.2288319452946, 3676.8529258829612, 3678.9448158277646, 157509.0, 157740.0, 158629.0, 159165.40834591622, 159781.0, 15855.762163567555, 15965.920170399073, 16042.0, 16049.518514444586, 16193.67612647764, 31403.214919626735, 31962.360752784814, 32379.838100809346, 32433.837830811168, 34322.48516272258, 9391.248700104112, 9501.667441639509, 9521.238300936047, 9541.61833526665, 9614.471204083842, 4763.571278584875, 4820.421549414023, 4825.541573550507, 4830.275458681155, 4861.829835955796, 6063.757449701962, 6203.193584833943, 6225.937740622627, 6231.719572619152, 6249.187605611241, 991.4447909170743, 1021.7394564386034, 1022.703416009349, 1026.5585798106886, 1026.697124348319, 667.8764428580631, 687.1856849633101, 693.552658535252, 698.4621841182362, 701.4493622506308, 11921.761564768656, 12094.939525302494, 12568.120231583702, 12623.495060197673, 12663.49346026167, 729.6169511006764, 734.9412047036225, 736.3446532586003, 744.571871174077, 747.5439981611213, 5787.392323806024, 5814.476697097281, 5900.321463031721, 5930.584859059911, 5950.166976623276, 146868.0, 150055.0, 151833.24083379513, 152324.0, 159467.20266398593, 128923.0, 129300.0, 131599.3420032913, 133983.0, 136721.0, 109817.45091274653, 115349.1953183972, 117351.41324293324, 119488.0, 122279.99999999822, 13737.313134343443, 13809.585712428643, 13914.512992045635, 14105.929470352583, 14125.293735312987, 55358.72320638371, 55455.0, 56092.71953640206, 56467.15299270597, 56534.586635334446, 71824.12703492011, 72680.63659681668, 72886.63556682183, 73102.26897730955, 73199.63400182965, 20613.0, 21153.788462115182, 21215.68176477385, 21316.893415532824, 21453.89273053625, 21092.68360974556, 21332.466688332916, 21451.249206277706, 21720.565588687823, 21828.890855545622, 21442.8927855366, 21524.677129842752, 21548.0, 21549.46126346854, 21731.348059558237, 18945.90527047356, 19363.612727745647, 19366.515837104183, 19558.60882782365, 19580.510487237363, 18842.057897104816, 18854.434366969013, 18854.811451885853, 18924.14841332116, 18995.620087597894, 14562.0, 15007.77488337654, 15099.47151849724, 15332.770008450105, 15674.451394201604, 15580.143092129589, 15640.217989100727, 15835.366585336918, 15861.920690396935, 15928.522144335688], "value": 10017.420939174182, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1366.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 105.0, 104.0, 106.0, 104.0, 106.0, 93.0, 105.0, 105.0, 105.0], "value": 104.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 111.0, 114.0, 116.0, 141.0, 113.0, 118.0, 115.0, 112.0, 113.0], "value": 116.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 162.0, 162.0, 164.0, 162.0, 162.0, 162.0, 163.0, 162.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 73.0, 73.0, 73.0, 74.0, 74.0, 72.0, 72.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 81.0, 81.0, 81.0, 83.0, 77.0, 78.0, 77.0, 77.0, 77.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 115.0, 113.0, 113.0, 115.0, 114.0, 114.0, 114.0, 109.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 150.0, 149.0, 149.0, 148.0, 151.0, 150.0, 149.0, 149.0], "value": 149.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 101.0, 94.0, 100.0, 99.0, 99.0, 99.0, 94.0, 94.0, 95.0], "value": 96.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 44.0, 44.0, 42.0, 50.0, 43.0, 43.0, 45.0, 40.0, 42.0], "value": 43.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 38.0, 39.0, 39.0, 39.0, 39.0, 39.0, 38.0, 38.0], "value": 38.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 71.0, 70.0, 70.0, 71.0, 70.0, 69.0, 70.0, 85.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 107.0, 101.0, 112.0, 102.0, 102.0, 105.0, 108.0, 102.0, 108.0], "value": 105.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 148.0, 156.0, 154.0, 150.0, 150.0, 155.0, 153.0, 149.0, 152.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 59.0, 58.0, 58.0, 58.0, 61.0, 59.0, 66.0, 66.0], "value": 60.0, "unit": "score"}]}]} 04:44:49 INFO - Return code: 0 04:44:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:44:49 INFO - # TBPL SUCCESS # 04:44:49 INFO - Running post-action listener: _package_coverage_data 04:44:49 INFO - Running post-action listener: _resource_record_post_action 04:44:49 INFO - [mozharness: 2017-10-02 11:44:49.962727Z] Finished run-tests step (success) 04:44:49 INFO - Running post-run listener: _resource_record_post_run 04:44:50 INFO - Total resource usage - Wall time: 1182s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284254208; Read time: 12; Write time: 289656 04:44:50 INFO - TinderboxPrint: CPU usage
13.1% 04:44:50 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 04:44:50 INFO - TinderboxPrint: I/O write bytes / time
284,254,208 / 289,656 04:44:50 INFO - TinderboxPrint: CPU idle
8,193.7 (86.9%) 04:44:50 INFO - TinderboxPrint: CPU user
1,180.9 (12.5%) 04:44:50 INFO - TinderboxPrint: Swap in / out
0 / 0 04:44:50 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 9289728; Read time: 0; Write time: 1336 04:44:50 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:44:50 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 4096; Write bytes: 274964480; Read time: 12; Write time: 288320 04:44:50 INFO - Running post-run listener: _upload_blobber_files 04:44:50 INFO - Blob upload gear active. 04:44:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:44:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:44:50 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:44:50 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:44:51 INFO - (blobuploader) - INFO - Open directory for files ... 04:44:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:44:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:44:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:44:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:44:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:44:51 INFO - (blobuploader) - INFO - Done attempting. 04:44:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:44:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:44:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:44:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:44:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:44:52 INFO - (blobuploader) - INFO - Done attempting. 04:44:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:44:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:44:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:45:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:00 INFO - (blobuploader) - INFO - Done attempting. 04:45:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:45:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:45:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:01 INFO - (blobuploader) - INFO - Done attempting. 04:45:01 INFO - (blobuploader) - INFO - Iteration through files over. 04:45:01 INFO - Return code: 0 04:45:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:45:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:45:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fb8d7e503e299d832633916bc097cce205330fa70a88057fe06d2e3d4d969a2fd2f27d8dc39cad436ff30d2d6d38a7ec8ef9170d57fa1d1e66c17cf8df3a3616", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7a0e29462c7392baeca2f083a63bc1debb6315657273cc99f09e4fd57cb67846a2a6fb5d2388013c122df5b6748540e8244d9dc29d52fbbd3195d94dd4220cc9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0e50530eab7e800aecd7fb9e5b67ba3c8849e709c6ca5da8cf3a12994743752007a55764f926a9820cf9ce90eac040ac375ee4e0991c59d613295947c1011718", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/01210665233f057d05cfa409c5fecc53c0bc77a57327d65c6495d02eb4013da4058116eaa29040c70f61c552bf5c521d8af852a7fac079f7f7994f3bb5e2eb7e"} 04:45:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:45:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:45:01 INFO - Contents: 04:45:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fb8d7e503e299d832633916bc097cce205330fa70a88057fe06d2e3d4d969a2fd2f27d8dc39cad436ff30d2d6d38a7ec8ef9170d57fa1d1e66c17cf8df3a3616", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7a0e29462c7392baeca2f083a63bc1debb6315657273cc99f09e4fd57cb67846a2a6fb5d2388013c122df5b6748540e8244d9dc29d52fbbd3195d94dd4220cc9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0e50530eab7e800aecd7fb9e5b67ba3c8849e709c6ca5da8cf3a12994743752007a55764f926a9820cf9ce90eac040ac375ee4e0991c59d613295947c1011718", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/01210665233f057d05cfa409c5fecc53c0bc77a57327d65c6495d02eb4013da4058116eaa29040c70f61c552bf5c521d8af852a7fac079f7f7994f3bb5e2eb7e"} 04:45:01 INFO - Running post-run listener: copy_logs_to_upload_dir 04:45:01 INFO - Copying logs to upload dir... 04:45:01 INFO - mkdir: /builds/slave/test/build/upload/logs 04:45:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.293412 ========= master_lag: -0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-10-02 04:45:01.286546) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 04:45:01.294261) ========= 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=fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982 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/Mozilla-Beta-Non-PGO/sha512/fb8d7e503e299d832633916bc097cce205330fa70a88057fe06d2e3d4d969a2fd2f27d8dc39cad436ff30d2d6d38a7ec8ef9170d57fa1d1e66c17cf8df3a3616", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7a0e29462c7392baeca2f083a63bc1debb6315657273cc99f09e4fd57cb67846a2a6fb5d2388013c122df5b6748540e8244d9dc29d52fbbd3195d94dd4220cc9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0e50530eab7e800aecd7fb9e5b67ba3c8849e709c6ca5da8cf3a12994743752007a55764f926a9820cf9ce90eac040ac375ee4e0991c59d613295947c1011718", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/01210665233f057d05cfa409c5fecc53c0bc77a57327d65c6495d02eb4013da4058116eaa29040c70f61c552bf5c521d8af852a7fac079f7f7994f3bb5e2eb7e"} build_url:https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011358 build_url: 'https://queue.taskcluster.net/v1/task/NCFkVlATSo-sckLy5nLCzw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fb8d7e503e299d832633916bc097cce205330fa70a88057fe06d2e3d4d969a2fd2f27d8dc39cad436ff30d2d6d38a7ec8ef9170d57fa1d1e66c17cf8df3a3616", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7a0e29462c7392baeca2f083a63bc1debb6315657273cc99f09e4fd57cb67846a2a6fb5d2388013c122df5b6748540e8244d9dc29d52fbbd3195d94dd4220cc9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0e50530eab7e800aecd7fb9e5b67ba3c8849e709c6ca5da8cf3a12994743752007a55764f926a9820cf9ce90eac040ac375ee4e0991c59d613295947c1011718", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/01210665233f057d05cfa409c5fecc53c0bc77a57327d65c6495d02eb4013da4058116eaa29040c70f61c552bf5c521d8af852a7fac079f7f7994f3bb5e2eb7e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 04:45:01.330508) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 04:45:01.330911) ========= 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=fa44defc139c4ba8574fe040000001db-1506943268.951118-1007910982 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004507 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 04:45:01.411693) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 04:45:01.415018) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 04:45:01.415326) ========= ========= Total master_lag: 0.12 =========