builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-032 starttime: 1506292945.03 results: success (0) revision: d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-24 15:42:25.032535) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-24 15:42:25.033122) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-24 15:42:25.033453) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-24 15:42:25.166153) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 15:42:25.166604) ========= 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=745a4373a2a16df560f216e000000264-1506289779.632996-117999451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-24 15:42: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% 38.1M=0s 2017-09-24 15:42:25 (38.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121681 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 15:42:25.353518) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 15:42:25.353873) ========= 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=745a4373a2a16df560f216e000000264-1506289779.632996-117999451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019697 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 15:42:25.450639) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-24 15:42:25.450963) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 --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=745a4373a2a16df560f216e000000264-1506289779.632996-117999451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-24 15:42:25,421 truncating revision to first 12 chars 2017-09-24 15:42:25,421 Setting DEBUG logging. 2017-09-24 15:42:25,421 attempt 1/10 2017-09-24 15:42:25,422 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d9cfaad3b11e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-24 15:42:26,669 unpacking tar archive at: mozilla-central-d9cfaad3b11e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.604264 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-24 15:42:27.085040) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-24 15:42:27.085309) ========= script_repo_revision: d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-24 15:42:27.085662) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-24 15:42:27.085923) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-24 15:42:27.119860) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-09-24 15:42:27.120125) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1506289779.632996-117999451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:42:27 INFO - MultiFileLogger online at 20170924 15:42:27 in /builds/slave/test 15:42:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:42:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:42:27 INFO - {'append_to_log': False, 15:42:27 INFO - 'base_work_dir': '/builds/slave/test', 15:42:27 INFO - 'blob_upload_branch': 'Firefox', 15:42:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:42:27 INFO - 'branch': 'Firefox', 15:42:27 INFO - 'buildbot_json_path': 'buildprops.json', 15:42:27 INFO - 'code_coverage': False, 15:42:27 INFO - 'config_files': ('talos/linux_config.py',), 15:42:27 INFO - 'default_actions': ('clobber', 15:42:27 INFO - 'read-buildbot-config', 15:42:27 INFO - 'download-and-extract', 15:42:27 INFO - 'populate-webroot', 15:42:27 INFO - 'create-virtualenv', 15:42:27 INFO - 'install', 15:42:27 INFO - 'setup-mitmproxy', 15:42:27 INFO - 'run-tests'), 15:42:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:42:27 INFO - 'disable_ccov_upload': False, 15:42:27 INFO - 'disable_stylo': False, 15:42:27 INFO - 'download_minidump_stackwalk': True, 15:42:27 INFO - 'download_symbols': 'ondemand', 15:42:27 INFO - 'enable_stylo': False, 15:42:27 INFO - 'enable_webrender': False, 15:42:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:42:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:42:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:42:27 INFO - 'gecko_profile': False, 15:42:27 INFO - 'gecko_profile_interval': 0, 15:42:27 INFO - 'installer_path': 'installer.exe', 15:42:27 INFO - 'log_level': 'info', 15:42:27 INFO - 'log_name': 'talos', 15:42:27 INFO - 'log_to_console': True, 15:42:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:42:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:42:27 INFO - 'opt_config_files': (), 15:42:27 INFO - 'pip_index': False, 15:42:27 INFO - 'suite': 'dromaeojs-e10s', 15:42:27 INFO - 'system_bits': '32', 15:42:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:42:27 INFO - 'title': 'talos-linux64-ix-032', 15:42:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:42:27 INFO - 'use_talos_json': True, 15:42:27 INFO - 'verify': 'False', 15:42:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:42:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:42:27 INFO - 'work_dir': 'build'} 15:42:27 INFO - [mozharness: 2017-09-24 22:42:27.214114Z] Running clobber step. 15:42:27 INFO - Running pre-action listener: _resource_record_pre_action 15:42:27 INFO - Running main action method: clobber 15:42:27 INFO - rmtree: /builds/slave/test/build 15:42:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:42:28 INFO - Running post-action listener: _resource_record_post_action 15:42:28 INFO - [mozharness: 2017-09-24 22:42:28.722320Z] Finished clobber step (success) 15:42:28 INFO - [mozharness: 2017-09-24 22:42:28.722418Z] Running read-buildbot-config step. 15:42:28 INFO - Running pre-action listener: _resource_record_pre_action 15:42:28 INFO - Running main action method: read_buildbot_config 15:42:28 INFO - Using buildbot properties: 15:42:28 INFO - { 15:42:28 INFO - "project": "", 15:42:28 INFO - "product": "firefox", 15:42:28 INFO - "who": "archaeopteryx@coole-files.de", 15:42:28 INFO - "installer_path": "public/build/target.tar.bz2", 15:42:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 15:42:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:42:28 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 15:42:28 INFO - "stage_platform": "linux64-qr", 15:42:28 INFO - "basedir": "/builds/slave/test", 15:42:28 INFO - "buildnumber": 93, 15:42:28 INFO - "platform": "ubuntu64_hw_qr", 15:42:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:42:28 INFO - "slavebuilddir": "test", 15:42:28 INFO - "taskId": "JfD-5RLKTge6S5eJJcLHoQ", 15:42:28 INFO - "branch": "mozilla-central", 15:42:28 INFO - "script_repo_revision": "production", 15:42:28 INFO - "revision": "d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4", 15:42:28 INFO - "slavename": "talos-linux64-ix-032", 15:42:28 INFO - "repo_path": "mozilla-central" 15:42:28 INFO - } 15:42:28 INFO - Finding installer, test and symbols from parent task. 15:42:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JfD-5RLKTge6S5eJJcLHoQ'}, attempt #1 15:42:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JfD-5RLKTge6S5eJJcLHoQ'}, attempt #1 15:42:29 INFO - Task dependencies: fh1savD2Q1aQeqxvlInuLA 15:42:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA'}, attempt #1 15:42:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2 15:42:30 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.test_packages.json 15:42:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.crashreporter-symbols.zip 15:42:30 INFO - Running post-action listener: _resource_record_post_action 15:42:30 INFO - [mozharness: 2017-09-24 22:42:30.137928Z] Finished read-buildbot-config step (success) 15:42:30 INFO - [mozharness: 2017-09-24 22:42:30.138058Z] Running download-and-extract step. 15:42:30 INFO - Running pre-action listener: _resource_record_pre_action 15:42:30 INFO - Running main action method: download_and_extract 15:42:30 INFO - mkdir: /builds/slave/test/build/tests 15:42:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:30 INFO - trying https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.test_packages.json 15:42:30 INFO - Downloading https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:42:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:42:30 INFO - Downloaded 989 bytes. 15:42:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:42:30 INFO - Using the following test package requirements: 15:42:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:42:30 INFO - u'common': [u'target.common.tests.zip'], 15:42:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:42:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:42:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:42:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:42:30 INFO - u'mozbase': [u'target.common.tests.zip'], 15:42:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:42:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:42:30 INFO - u'web-platform': [u'target.common.tests.zip', 15:42:30 INFO - u'target.web-platform.tests.tar.gz'], 15:42:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:42:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:42:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.common.tests.zip 15:42:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:42:30 INFO - Fetch https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.common.tests.zip into memory 15:42:33 INFO - Content-Length response header: 54944009 15:42:33 INFO - Bytes received: 54944009 15:42:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.talos.tests.zip 15:42:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:42:43 INFO - Fetch https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.talos.tests.zip into memory 15:42:44 INFO - Content-Length response header: 14513823 15:42:44 INFO - Bytes received: 14513823 15:42: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')]} 15:42:44 INFO - trying https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2 15:42:44 INFO - Downloading https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:42:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:42:46 INFO - Downloaded 61624893 bytes. 15:42:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2 15:42:47 INFO - mkdir: /builds/slave/test/properties 15:42:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:42:47 INFO - Writing to file /builds/slave/test/properties/build_url 15:42:47 INFO - Contents: 15:42:47 INFO - build_url:https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2 15:42:47 INFO - Running post-action listener: _resource_record_post_action 15:42:47 INFO - Running post-action listener: find_tests_for_verification 15:42:47 INFO - Running post-action listener: set_extra_try_arguments 15:42:47 INFO - [mozharness: 2017-09-24 22:42:47.645506Z] Finished download-and-extract step (success) 15:42:47 INFO - [mozharness: 2017-09-24 22:42:47.645701Z] Running populate-webroot step. 15:42:47 INFO - Running pre-action listener: _resource_record_pre_action 15:42:47 INFO - Running main action method: populate_webroot 15:42:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:42:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:42:47 INFO - 'tests': ['tresize', 15:42:47 INFO - 'tcanvasmark']}, 15:42:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:42:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:42:47 INFO - 'tests': ['dromaeo_css', 15:42:47 INFO - 'kraken']}, 15:42:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:42:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:42:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:42:47 INFO - 'talos_options': ['--disable-stylo'], 15:42:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:42:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:42:47 INFO - 'tests': ['damp', 'tps']}, 15:42:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:42:47 INFO - 'talos_options': ['--disable-stylo'], 15:42:47 INFO - 'tests': ['damp', 'tps']}, 15:42:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:42:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:42:47 INFO - 'tests': ['dromaeo_dom']}, 15:42:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:42:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:42:47 INFO - 'tests': ['basic_compositor_video', 15:42:47 INFO - 'glvideo']}, 15:42:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:42:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:42:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:42:47 INFO - 'talos_options': ['--disable-stylo'], 15:42:47 INFO - 'tests': ['ts_paint_webext', 15:42:47 INFO - 'tp5o_webext']}, 15:42:47 INFO - 'other-e10s': {'tests': ['a11yr', 15:42:47 INFO - 'ts_paint', 15:42:47 INFO - 'tpaint', 15:42:47 INFO - 'sessionrestore', 15:42:47 INFO - 'sessionrestore_many_windows', 15:42:47 INFO - 'sessionrestore_no_auto_restore', 15:42:47 INFO - 'tabpaint']}, 15:42:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:42:47 INFO - 'tests': ['a11yr', 15:42:47 INFO - 'ts_paint', 15:42:47 INFO - 'tpaint', 15:42:47 INFO - 'sessionrestore', 15:42:47 INFO - 'sessionrestore_many_windows', 15:42:47 INFO - 'sessionrestore_no_auto_restore', 15:42:47 INFO - 'tabpaint']}, 15:42:47 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 15:42:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 15:42:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:42:47 INFO - 'tests': ['perf_reftest_singletons']}, 15:42:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:42:47 INFO - 'tests': ['perf_reftest']}, 15:42:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:42:47 INFO - 'tsvgr_opacity', 15:42:47 INFO - 'tart', 15:42:47 INFO - 'tscrollx', 15:42:47 INFO - 'tsvg_static']}, 15:42:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:42:47 INFO - 'tests': ['tsvgx', 15:42:47 INFO - 'tsvgr_opacity', 15:42:47 INFO - 'tart', 15:42:47 INFO - 'tscrollx', 15:42:47 INFO - 'tsvg_static']}, 15:42:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:42:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:42:47 INFO - 'talos_options': ['--disable-stylo'], 15:42:47 INFO - 'tests': ['tp5o']}, 15:42:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:42:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:42:47 INFO - 'talos_options': ['--mitmproxy', 15:42:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:42:47 INFO - '--firstNonBlankPaint'], 15:42:47 INFO - 'tests': ['tp6_google', 15:42:47 INFO - 'tp6_youtube', 15:42:47 INFO - 'tp6_amazon', 15:42:47 INFO - 'tp6_facebook']}, 15:42:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:42:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:42:47 INFO - 'talos_options': ['--disable-stylo', 15:42:47 INFO - '--mitmproxy', 15:42:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:42:47 INFO - '--firstNonBlankPaint'], 15:42:47 INFO - 'tests': ['tp6_google', 15:42:47 INFO - 'tp6_youtube', 15:42:47 INFO - 'tp6_amazon', 15:42:47 INFO - 'tp6_facebook']}, 15:42:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:42:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:42:47 INFO - 'talos_options': ['--stylo-threads=1', 15:42:47 INFO - '--mitmproxy', 15:42:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:42:47 INFO - '--firstNonBlankPaint'], 15:42:47 INFO - 'tests': ['tp6_google', 15:42:47 INFO - 'tp6_youtube', 15:42:47 INFO - 'tp6_amazon', 15:42:47 INFO - 'tp6_facebook']}, 15:42:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:42:47 INFO - 'talos_options': ['--xperf_path', 15:42:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:42:47 INFO - 'tests': ['tp5n']}, 15:42:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:42:47 INFO - 'talos_options': ['--disable-stylo', 15:42:47 INFO - '--xperf_path', 15:42:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:42:47 INFO - 'tests': ['tp5n']}}} 15:42:47 INFO - Running post-action listener: _resource_record_post_action 15:42:47 INFO - [mozharness: 2017-09-24 22:42:47.665420Z] Finished populate-webroot step (success) 15:42:47 INFO - [mozharness: 2017-09-24 22:42:47.665509Z] Running create-virtualenv step. 15:42:47 INFO - Running pre-action listener: _resource_record_pre_action 15:42:47 INFO - Running main action method: create_virtualenv 15:42:47 INFO - Creating virtualenv /builds/slave/test/build/venv 15:42:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:42:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:42:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:42:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:42:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:42:48 INFO - Installing distribute.............................................................................................................................................................................................done. 15:42:48 INFO - Installing pip...............done. 15:42:48 INFO - Return code: 0 15:42:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:42:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:42:48 INFO - Reading from file tmpfile_stdout 15:42:48 INFO - Output received: 15:42:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:42:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:42: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')]} 15:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42: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')]} 15:42:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ff9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13fcd60>, '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 0x13fda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13fdef0>, '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': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', '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 15:42: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', 'pip>=1.5'] in /builds/slave/test/build 15:42: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 pip>=1.5 15:42:48 INFO - Using env: {'DISPLAY': ':0', 15:42:48 INFO - 'HOME': '/home/cltbld', 15:42:48 INFO - 'LANG': 'en_US.UTF-8', 15:42:48 INFO - 'LANGUAGE': 'en_US:en', 15:42:48 INFO - 'LOGNAME': 'cltbld', 15:42:48 INFO - 'MAIL': '/var/mail/cltbld', 15:42:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:48 INFO - 'MOZ_NO_REMOTE': '1', 15:42:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:48 INFO - 'NO_EM_RESTART': '1', 15:42:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:48 INFO - 'PWD': '/builds/slave/test', 15:42:48 INFO - 'SHELL': '/bin/bash', 15:42:48 INFO - 'SHLVL': '1', 15:42:48 INFO - 'TERM': 'linux', 15:42:48 INFO - 'TMOUT': '86400', 15:42:48 INFO - 'USER': 'cltbld', 15:42:48 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', 15:42:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:48 INFO - '_': '/tools/buildbot/bin/python'} 15:42:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:42:49 INFO - Downloading/unpacking pip>=1.5 15:42:49 INFO - Running setup.py egg_info for package pip 15:42:49 INFO - warning: no files found matching 'pip/cacert.pem' 15:42:49 INFO - warning: no files found matching '*.html' under directory 'docs' 15:42:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:42:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:42:49 INFO - Installing collected packages: pip 15:42:49 INFO - Found existing installation: pip 1.1 15:42:49 INFO - Uninstalling pip: 15:42:49 INFO - Successfully uninstalled pip 15:42:49 INFO - Running setup.py install for pip 15:42:49 INFO - warning: no files found matching 'pip/cacert.pem' 15:42:49 INFO - warning: no files found matching '*.html' under directory 'docs' 15:42:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:42:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:42:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:42:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:42:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:42:49 INFO - Successfully installed pip 15:42:49 INFO - Cleaning up... 15:42:49 INFO - Return code: 0 15:42:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:42:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:49 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 0x10921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ff9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13fcd60>, '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 0x13fda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13fdef0>, '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': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', '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 15:42:49 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 15:42:49 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 15:42:49 INFO - Using env: {'DISPLAY': ':0', 15:42:49 INFO - 'HOME': '/home/cltbld', 15:42:49 INFO - 'LANG': 'en_US.UTF-8', 15:42:49 INFO - 'LANGUAGE': 'en_US:en', 15:42:49 INFO - 'LOGNAME': 'cltbld', 15:42:49 INFO - 'MAIL': '/var/mail/cltbld', 15:42:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:49 INFO - 'MOZ_NO_REMOTE': '1', 15:42:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:49 INFO - 'NO_EM_RESTART': '1', 15:42:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:49 INFO - 'PWD': '/builds/slave/test', 15:42:49 INFO - 'SHELL': '/bin/bash', 15:42:49 INFO - 'SHLVL': '1', 15:42:49 INFO - 'TERM': 'linux', 15:42:49 INFO - 'TMOUT': '86400', 15:42:49 INFO - 'USER': 'cltbld', 15:42:49 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', 15:42:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:49 INFO - '_': '/tools/buildbot/bin/python'} 15:42:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:50 INFO - Downloading/unpacking psutil>=3.1.1 15:42: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 15:42: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 15:42: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 15:42: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 15:42:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:42:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:42:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:42:51 INFO - Installing collected packages: psutil 15:42:51 INFO - Running setup.py install for psutil 15:42:51 INFO - building 'psutil._psutil_linux' extension 15:42:51 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 15:42:51 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 15:42:51 INFO - building 'psutil._psutil_posix' extension 15:42:51 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 15:42:51 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 15:42:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:42:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:42:51 INFO - Successfully installed psutil 15:42:51 INFO - Cleaning up... 15:42:51 INFO - Return code: 0 15:42:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ff9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13fcd60>, '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 0x13fda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13fdef0>, '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': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', '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 15:42:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:42:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:42:51 INFO - Using env: {'DISPLAY': ':0', 15:42:51 INFO - 'HOME': '/home/cltbld', 15:42:51 INFO - 'LANG': 'en_US.UTF-8', 15:42:51 INFO - 'LANGUAGE': 'en_US:en', 15:42:51 INFO - 'LOGNAME': 'cltbld', 15:42:51 INFO - 'MAIL': '/var/mail/cltbld', 15:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:51 INFO - 'MOZ_NO_REMOTE': '1', 15:42:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:51 INFO - 'NO_EM_RESTART': '1', 15:42:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:51 INFO - 'PWD': '/builds/slave/test', 15:42:51 INFO - 'SHELL': '/bin/bash', 15:42:51 INFO - 'SHLVL': '1', 15:42:51 INFO - 'TERM': 'linux', 15:42:51 INFO - 'TMOUT': '86400', 15:42:51 INFO - 'USER': 'cltbld', 15:42:51 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', 15:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:51 INFO - '_': '/tools/buildbot/bin/python'} 15:42:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:42:52 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 15:42:52 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 15:42:52 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 15:42:52 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 15:42:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:42:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:42:53 INFO - Installing collected packages: mozsystemmonitor 15:42:53 INFO - Running setup.py install for mozsystemmonitor 15:42:53 INFO - Successfully installed mozsystemmonitor 15:42:53 INFO - Cleaning up... 15:42:54 INFO - Return code: 0 15:42:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:42:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:54 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 0x10921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ff9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13fcd60>, '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 0x13fda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13fdef0>, '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': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', '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 15:42:54 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 15:42:54 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 15:42:54 INFO - Using env: {'DISPLAY': ':0', 15:42:54 INFO - 'HOME': '/home/cltbld', 15:42:54 INFO - 'LANG': 'en_US.UTF-8', 15:42:54 INFO - 'LANGUAGE': 'en_US:en', 15:42:54 INFO - 'LOGNAME': 'cltbld', 15:42:54 INFO - 'MAIL': '/var/mail/cltbld', 15:42:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:54 INFO - 'MOZ_NO_REMOTE': '1', 15:42:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:54 INFO - 'NO_EM_RESTART': '1', 15:42:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:54 INFO - 'PWD': '/builds/slave/test', 15:42:54 INFO - 'SHELL': '/bin/bash', 15:42:54 INFO - 'SHLVL': '1', 15:42:54 INFO - 'TERM': 'linux', 15:42:54 INFO - 'TMOUT': '86400', 15:42:54 INFO - 'USER': 'cltbld', 15:42:54 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', 15:42:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:54 INFO - '_': '/tools/buildbot/bin/python'} 15:42:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:54 INFO - Downloading/unpacking jsonschema==2.5.1 15:42: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 15:42: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 15:42: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 15:42: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 15:42:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:42:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:55 INFO - Downloading functools32-3.2.3-2.tar.gz 15:42:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:42:55 INFO - warning: no files found matching '*.txt' 15:42:55 INFO - no previously-included directories found matching 'build' 15:42:55 INFO - no previously-included directories found matching 'dist' 15:42:55 INFO - no previously-included directories found matching '.git*' 15:42:55 INFO - Installing collected packages: jsonschema, functools32 15:42:57 INFO - Running setup.py install for functools32 15:42:57 INFO - warning: no files found matching '*.txt' 15:42:57 INFO - no previously-included directories found matching 'build' 15:42:57 INFO - no previously-included directories found matching 'dist' 15:42:57 INFO - no previously-included directories found matching '.git*' 15:42:57 INFO - Successfully installed jsonschema functools32 15:42:57 INFO - Cleaning up... 15:42:57 INFO - Return code: 0 15:42:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:42:57 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')]} 15:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:57 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')]} 15:42:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:57 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 0x10921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ff9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13fcd60>, '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 0x13fda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13fdef0>, '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': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', '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 15:42:57 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 15:42:57 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 15:42:57 INFO - Using env: {'DISPLAY': ':0', 15:42:57 INFO - 'HOME': '/home/cltbld', 15:42:57 INFO - 'LANG': 'en_US.UTF-8', 15:42:57 INFO - 'LANGUAGE': 'en_US:en', 15:42:57 INFO - 'LOGNAME': 'cltbld', 15:42:57 INFO - 'MAIL': '/var/mail/cltbld', 15:42:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:57 INFO - 'MOZ_NO_REMOTE': '1', 15:42:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:57 INFO - 'NO_EM_RESTART': '1', 15:42:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:57 INFO - 'PWD': '/builds/slave/test', 15:42:57 INFO - 'SHELL': '/bin/bash', 15:42:57 INFO - 'SHLVL': '1', 15:42:57 INFO - 'TERM': 'linux', 15:42:57 INFO - 'TMOUT': '86400', 15:42:57 INFO - 'USER': 'cltbld', 15:42:57 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', 15:42:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:57 INFO - '_': '/tools/buildbot/bin/python'} 15:42:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:42:57 INFO - Cleaning up... 15:42:57 INFO - Return code: 0 15:42:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:42:57 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')]} 15:42:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:57 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')]} 15:42:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:57 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 0x10921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ff9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13fcd60>, '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 0x13fda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13fdef0>, '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': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', '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 15:42:57 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 15:42:57 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 15:42:57 INFO - Using env: {'DISPLAY': ':0', 15:42:57 INFO - 'HOME': '/home/cltbld', 15:42:57 INFO - 'LANG': 'en_US.UTF-8', 15:42:57 INFO - 'LANGUAGE': 'en_US:en', 15:42:57 INFO - 'LOGNAME': 'cltbld', 15:42:57 INFO - 'MAIL': '/var/mail/cltbld', 15:42:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:57 INFO - 'MOZ_NO_REMOTE': '1', 15:42:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:57 INFO - 'NO_EM_RESTART': '1', 15:42:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:57 INFO - 'PWD': '/builds/slave/test', 15:42:57 INFO - 'SHELL': '/bin/bash', 15:42:57 INFO - 'SHLVL': '1', 15:42:57 INFO - 'TERM': 'linux', 15:42:57 INFO - 'TMOUT': '86400', 15:42:57 INFO - 'USER': 'cltbld', 15:42:57 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', 15:42:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:57 INFO - '_': '/tools/buildbot/bin/python'} 15:42:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:57 INFO - Downloading/unpacking blobuploader==1.2.4 15:42: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 15:42: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 15:42: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 15:42: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 15:42:58 INFO - Downloading blobuploader-1.2.4.tar.gz 15:42:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:42:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:42: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 15:42: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 15:42: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 15:42: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 15:42:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:42:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:42:59 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 15:42:59 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 15:42:59 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 15:42:59 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 15:42:59 INFO - Downloading docopt-0.6.1.tar.gz 15:42:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:42:59 INFO - Installing collected packages: blobuploader, requests, docopt 15:42:59 INFO - Running setup.py install for blobuploader 15:42:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:42:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:42:59 INFO - Running setup.py install for requests 15:42:59 INFO - Running setup.py install for docopt 15:42:59 INFO - Successfully installed blobuploader requests docopt 15:42:59 INFO - Cleaning up... 15:42:59 INFO - Return code: 0 15:42:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:42:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:59 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 0x10921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ff9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13fcd60>, '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 0x13fda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13fdef0>, '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': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', '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 15:42:59 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 15:42:59 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 15:42:59 INFO - Using env: {'DISPLAY': ':0', 15:42:59 INFO - 'HOME': '/home/cltbld', 15:42:59 INFO - 'LANG': 'en_US.UTF-8', 15:42:59 INFO - 'LANGUAGE': 'en_US:en', 15:42:59 INFO - 'LOGNAME': 'cltbld', 15:42:59 INFO - 'MAIL': '/var/mail/cltbld', 15:42:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:59 INFO - 'MOZ_NO_REMOTE': '1', 15:42:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:59 INFO - 'NO_EM_RESTART': '1', 15:42:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:59 INFO - 'PWD': '/builds/slave/test', 15:42:59 INFO - 'SHELL': '/bin/bash', 15:42:59 INFO - 'SHLVL': '1', 15:42:59 INFO - 'TERM': 'linux', 15:42:59 INFO - 'TMOUT': '86400', 15:42:59 INFO - 'USER': 'cltbld', 15:42:59 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', 15:42:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:59 INFO - '_': '/tools/buildbot/bin/python'} 15:43:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:43:00 INFO - Running setup.py (path:/tmp/pip-ujV1Rt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:43:00 INFO - Running setup.py (path:/tmp/pip-AHCfhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:43:00 INFO - Running setup.py (path:/tmp/pip-rojkwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:43:00 INFO - Running setup.py (path:/tmp/pip-5UiIrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:43:00 INFO - Running setup.py (path:/tmp/pip-IolQCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:43:00 INFO - Running setup.py (path:/tmp/pip-MgD_Hk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:43:00 INFO - Running setup.py (path:/tmp/pip-XvTXb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:43:00 INFO - Running setup.py (path:/tmp/pip-utCVW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:43:00 INFO - Running setup.py (path:/tmp/pip-3Kqrmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:43:00 INFO - Running setup.py (path:/tmp/pip-NO_SFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:43:00 INFO - Running setup.py (path:/tmp/pip-ARcoJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:43:00 INFO - Running setup.py (path:/tmp/pip-AkPuKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:43:00 INFO - Running setup.py (path:/tmp/pip-XiSiMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:43:01 INFO - Running setup.py (path:/tmp/pip-03XUh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:43:01 INFO - Running setup.py (path:/tmp/pip-RgvQkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:43:01 INFO - Running setup.py (path:/tmp/pip-HuuaSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:43:01 INFO - Running setup.py (path:/tmp/pip-gElYKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:43:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:43:01 INFO - Running setup.py install for manifestparser 15:43:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:43:01 INFO - Running setup.py install for mozcrash 15:43:01 INFO - Running setup.py install for mozdebug 15:43:01 INFO - Running setup.py install for mozdevice 15:43:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:43:01 INFO - Running setup.py install for mozfile 15:43:01 INFO - Running setup.py install for mozhttpd 15:43:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:43:01 INFO - Running setup.py install for mozinfo 15:43:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:43:02 INFO - Running setup.py install for mozInstall 15:43:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:43:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:43:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:43:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:43:02 INFO - Running setup.py install for mozleak 15:43:02 INFO - Running setup.py install for mozlog 15:43:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:43:02 INFO - Running setup.py install for moznetwork 15:43:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:43:02 INFO - Running setup.py install for mozprocess 15:43:02 INFO - Running setup.py install for mozprofile 15:43:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:43:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:43:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:43:02 INFO - Running setup.py install for mozrunner 15:43:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:43:02 INFO - Running setup.py install for mozscreenshot 15:43:02 INFO - Running setup.py install for moztest 15:43:02 INFO - Running setup.py install for mozversion 15:43:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:43:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:43:03 INFO - Cleaning up... 15:43:03 INFO - Return code: 0 15:43:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:43:03 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')]} 15:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:03 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')]} 15:43:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:03 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 0x10921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ff9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13fcd60>, '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 0x13fda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13fdef0>, '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': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', '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 15:43:03 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 15:43:03 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 15:43:03 INFO - Using env: {'DISPLAY': ':0', 15:43:03 INFO - 'HOME': '/home/cltbld', 15:43:03 INFO - 'LANG': 'en_US.UTF-8', 15:43:03 INFO - 'LANGUAGE': 'en_US:en', 15:43:03 INFO - 'LOGNAME': 'cltbld', 15:43:03 INFO - 'MAIL': '/var/mail/cltbld', 15:43:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:03 INFO - 'MOZ_NO_REMOTE': '1', 15:43:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:43:03 INFO - 'NO_EM_RESTART': '1', 15:43:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:43:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:03 INFO - 'PWD': '/builds/slave/test', 15:43:03 INFO - 'SHELL': '/bin/bash', 15:43:03 INFO - 'SHLVL': '1', 15:43:03 INFO - 'TERM': 'linux', 15:43:03 INFO - 'TMOUT': '86400', 15:43:03 INFO - 'USER': 'cltbld', 15:43:03 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', 15:43:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:03 INFO - '_': '/tools/buildbot/bin/python'} 15:43:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:43:03 INFO - Running setup.py (path:/tmp/pip-IDg2pF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:43:03 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)) 15:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:43:03 INFO - Running setup.py (path:/tmp/pip-39Bpzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:43:03 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)) 15:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:43:03 INFO - Running setup.py (path:/tmp/pip-lYBUM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:43:03 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)) 15:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:43:03 INFO - Running setup.py (path:/tmp/pip-6HcqjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:43:03 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)) 15:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:43:03 INFO - Running setup.py (path:/tmp/pip-8v50IO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:43:03 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)) 15:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:43:03 INFO - Running setup.py (path:/tmp/pip-CY6qwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:43:03 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)) 15:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:43:03 INFO - Running setup.py (path:/tmp/pip-DHQhye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:43:03 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)) 15:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:43:03 INFO - Running setup.py (path:/tmp/pip-gTCESV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:43:03 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)) 15:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:43:03 INFO - Running setup.py (path:/tmp/pip-nIo4HO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:43:03 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)) 15:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:43:03 INFO - Running setup.py (path:/tmp/pip-iFphna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:43:04 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)) 15:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:43:04 INFO - Running setup.py (path:/tmp/pip-cI11Da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:43:04 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)) 15:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:43:04 INFO - Running setup.py (path:/tmp/pip-piIIfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:43:04 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)) 15:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:43:04 INFO - Running setup.py (path:/tmp/pip-ivBDji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:43:04 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)) 15:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:43:04 INFO - Running setup.py (path:/tmp/pip-RhICSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:43:04 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)) 15:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:43:04 INFO - Running setup.py (path:/tmp/pip-cgfZJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:43:04 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)) 15:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:43:04 INFO - Running setup.py (path:/tmp/pip-4XPKpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:43:04 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)) 15:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:43:04 INFO - Running setup.py (path:/tmp/pip-w8h6xp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:43:04 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)) 15:43:04 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)) 15:43:04 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)) 15:43:04 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)) 15:43:04 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)) 15:43:04 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)) 15:43:04 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)) 15:43:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:43:04 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 15:43:04 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 15:43:04 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 15:43:04 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 15:43:05 INFO - Downloading blessings-1.6.tar.gz 15:43:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:43:05 INFO - Installing collected packages: blessings 15:43:05 INFO - Running setup.py install for blessings 15:43:05 INFO - Successfully installed blessings 15:43:05 INFO - Cleaning up... 15:43:05 INFO - Return code: 0 15:43:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:43:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:43:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:43:06 INFO - Reading from file tmpfile_stdout 15:43:06 INFO - Current package versions: 15:43:06 INFO - argparse == 1.2.1 15:43:06 INFO - blessings == 1.6 15:43:06 INFO - blobuploader == 1.2.4 15:43:06 INFO - docopt == 0.6.1 15:43:06 INFO - functools32 == 3.2.3-2 15:43:06 INFO - jsonschema == 2.5.1 15:43:06 INFO - manifestparser == 1.1 15:43:06 INFO - mozInstall == 1.13 15:43:06 INFO - mozcrash == 1.0 15:43:06 INFO - mozdebug == 0.1 15:43:06 INFO - mozdevice == 0.51 15:43:06 INFO - mozfile == 1.2 15:43:06 INFO - mozhttpd == 0.7 15:43:06 INFO - mozinfo == 0.10 15:43:06 INFO - mozleak == 0.1 15:43:06 INFO - mozlog == 3.5 15:43:06 INFO - moznetwork == 0.27 15:43:06 INFO - mozprocess == 0.25 15:43:06 INFO - mozprofile == 0.28 15:43:06 INFO - mozrunner == 6.13 15:43:06 INFO - mozscreenshot == 0.1 15:43:06 INFO - mozsystemmonitor == 0.3 15:43:06 INFO - moztest == 0.8 15:43:06 INFO - mozversion == 1.4 15:43:06 INFO - psutil == 3.1.1 15:43:06 INFO - requests == 1.2.3 15:43:06 INFO - wsgiref == 0.1.2 15:43:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:43:06 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')]} 15:43:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:06 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')]} 15:43:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:06 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 0x10921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ff9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13fcd60>, '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 0x13fda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13fdef0>, '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': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', '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 15:43:06 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 15:43:06 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 15:43:06 INFO - Using env: {'DISPLAY': ':0', 15:43:06 INFO - 'HOME': '/home/cltbld', 15:43:06 INFO - 'LANG': 'en_US.UTF-8', 15:43:06 INFO - 'LANGUAGE': 'en_US:en', 15:43:06 INFO - 'LOGNAME': 'cltbld', 15:43:06 INFO - 'MAIL': '/var/mail/cltbld', 15:43:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:06 INFO - 'MOZ_NO_REMOTE': '1', 15:43:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:43:06 INFO - 'NO_EM_RESTART': '1', 15:43:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:43:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:06 INFO - 'PWD': '/builds/slave/test', 15:43:06 INFO - 'SHELL': '/bin/bash', 15:43:06 INFO - 'SHLVL': '1', 15:43:06 INFO - 'TERM': 'linux', 15:43:06 INFO - 'TMOUT': '86400', 15:43:06 INFO - 'USER': 'cltbld', 15:43:06 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', 15:43:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:06 INFO - '_': '/tools/buildbot/bin/python'} 15:43:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:06 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)) 15:43:06 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)) 15:43:06 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)) 15:43:06 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)) 15:43:06 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)) 15:43:06 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)) 15:43:06 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)) 15:43:06 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)) 15:43:06 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)) 15:43:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:43:06 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 15:43:06 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 15:43:06 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 15:43:06 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 15:43:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:43:07 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)) 15:43:07 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)) 15:43:07 INFO - Installing collected packages: simplejson 15:43:07 INFO - Running setup.py install for simplejson 15:43:07 INFO - building 'simplejson._speedups' extension 15:43:07 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 15:43:08 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 15:43:08 INFO - Successfully installed simplejson 15:43:08 INFO - Cleaning up... 15:43:08 INFO - Return code: 0 15:43:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:43:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:08 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 0x10921f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13ff9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13fcd60>, '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 0x13fda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13fdef0>, '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': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', '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 15:43:08 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 15:43:08 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 15:43:08 INFO - Using env: {'DISPLAY': ':0', 15:43:08 INFO - 'HOME': '/home/cltbld', 15:43:08 INFO - 'LANG': 'en_US.UTF-8', 15:43:08 INFO - 'LANGUAGE': 'en_US:en', 15:43:08 INFO - 'LOGNAME': 'cltbld', 15:43:08 INFO - 'MAIL': '/var/mail/cltbld', 15:43:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:08 INFO - 'MOZ_NO_REMOTE': '1', 15:43:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:43:08 INFO - 'NO_EM_RESTART': '1', 15:43:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:43:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:08 INFO - 'PWD': '/builds/slave/test', 15:43:08 INFO - 'SHELL': '/bin/bash', 15:43:08 INFO - 'SHLVL': '1', 15:43:08 INFO - 'TERM': 'linux', 15:43:08 INFO - 'TMOUT': '86400', 15:43:08 INFO - 'USER': 'cltbld', 15:43:08 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', 15:43:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:08 INFO - '_': '/tools/buildbot/bin/python'} 15:43:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:43:08 INFO - Cleaning up... 15:43:08 INFO - Return code: 0 15:43:08 INFO - Running post-action listener: _resource_record_post_action 15:43:08 INFO - Running post-action listener: _start_resource_monitoring 15:43:08 INFO - Starting resource monitoring. 15:43:08 INFO - [mozharness: 2017-09-24 22:43:08.828367Z] Finished create-virtualenv step (success) 15:43:08 INFO - [mozharness: 2017-09-24 22:43:08.828843Z] Running install step. 15:43:08 INFO - Running pre-action listener: _resource_record_pre_action 15:43:08 INFO - Running main action method: install 15:43:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:43:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:43:09 INFO - Reading from file tmpfile_stdout 15:43:09 INFO - Detecting whether we're running mozinstall >=1.0... 15:43:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:43:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:43:09 INFO - Reading from file tmpfile_stdout 15:43:09 INFO - Output received: 15:43:09 INFO - Usage: mozinstall [options] installer 15:43:09 INFO - Options: 15:43:09 INFO - -h, --help show this help message and exit 15:43:09 INFO - -d DEST, --destination=DEST 15:43:09 INFO - Directory to install application into. [default: 15:43:09 INFO - "/builds/slave/test"] 15:43:09 INFO - --app=APP Application being installed. [default: firefox] 15:43:09 INFO - mkdir: /builds/slave/test/build/application 15:43:09 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'] 15:43:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:43:26 INFO - Reading from file tmpfile_stdout 15:43:26 INFO - Output received: 15:43:26 INFO - /builds/slave/test/build/application/firefox/firefox 15:43:26 INFO - Running post-action listener: _resource_record_post_action 15:43:26 INFO - [mozharness: 2017-09-24 22:43:26.969657Z] Finished install step (success) 15:43:26 INFO - [mozharness: 2017-09-24 22:43:26.969912Z] Running setup-mitmproxy step. 15:43:26 INFO - Running pre-action listener: _resource_record_pre_action 15:43:26 INFO - Running main action method: setup_mitmproxy 15:43:26 INFO - Skipping: mitmproxy is not required 15:43:26 INFO - Running post-action listener: _resource_record_post_action 15:43:26 INFO - [mozharness: 2017-09-24 22:43:26.971038Z] Finished setup-mitmproxy step (success) 15:43:26 INFO - [mozharness: 2017-09-24 22:43:26.971260Z] Running run-tests step. 15:43:26 INFO - Running pre-action listener: _resource_record_pre_action 15:43:26 INFO - Running pre-action listener: _set_gcov_prefix 15:43:26 INFO - Running main action method: run_tests 15:43:26 WARNING - Try message not found. 15:43:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:43:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:43:26 INFO - Python 2.7.3 15:43:26 INFO - Return code: 0 15:43:26 INFO - grabbing minidump binary from tooltool 15:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:26 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 0x13fcd60>, '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 0x13fda60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13fdef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:43:26 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 15:43:26 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 15:43:26 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 15:43:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:43:27 INFO - Return code: 0 15:43:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:43:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:43:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:43:27 INFO - ENV: RUST_BACKTRACE is now full 15:43:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:43:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:43:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 15:43:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 15:43:27 INFO - Using env: {'DISPLAY': ':0', 15:43:27 INFO - 'HOME': '/home/cltbld', 15:43:27 INFO - 'LANG': 'en_US.UTF-8', 15:43:27 INFO - 'LANGUAGE': 'en_US:en', 15:43:27 INFO - 'LOGNAME': 'cltbld', 15:43:27 INFO - 'MAIL': '/var/mail/cltbld', 15:43:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:43:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:43:27 INFO - 'MOZ_ACCELERATED': '1', 15:43:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:27 INFO - 'MOZ_NO_REMOTE': '1', 15:43:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:43:27 INFO - 'MOZ_WEBRENDER': '1', 15:43:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:43:27 INFO - 'NO_EM_RESTART': '1', 15:43:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:43:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:27 INFO - 'PWD': '/builds/slave/test', 15:43:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:43:27 INFO - 'RUST_BACKTRACE': 'full', 15:43:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:43:27 INFO - 'SHELL': '/bin/bash', 15:43:27 INFO - 'SHLVL': '1', 15:43:27 INFO - 'TERM': 'linux', 15:43:27 INFO - 'TMOUT': '86400', 15:43:27 INFO - 'USER': 'cltbld', 15:43:27 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', 15:43:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:27 INFO - '_': '/tools/buildbot/bin/python'} 15:43:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 15:43:27 INFO - mozversion application_buildid: 20170924215115 15:43:27 INFO - mozversion application_changeset: d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 15:43:27 INFO - mozversion application_display_name: Nightly 15:43:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:43:27 INFO - mozversion application_name: Firefox 15:43:27 INFO - mozversion application_remotingname: firefox 15:43:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:43:27 INFO - mozversion application_vendor: Mozilla 15:43:27 INFO - mozversion application_version: 58.0a1 15:43:27 INFO - mozversion platform_buildid: 20170924215115 15:43:27 INFO - mozversion platform_changeset: d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 15:43:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:43:27 INFO - mozversion platform_version: 58.0a1 15:43:27 INFO - using testdate: 1506293007 15:43:27 INFO - actual date: 1506293007 15:43:27 INFO - starting webserver on 'localhost:48011' 15:43:27 INFO - SUITE-START | Running 2 tests 15:43:27 INFO - TEST-START | dromaeo_css 15:43:27 INFO - Initialising browser for dromaeo_css test... 15:43:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48011/getInfo.html -profile /tmp/tmpplpVt3/profile 15:43:27 INFO - TEST-INFO | started process 19165 (/builds/slave/test/build/application/firefox/firefox http://localhost:48011/getInfo.html) 15:43:35 INFO - TEST-INFO | 19165: exit 0 15:43:35 INFO - Browser initialized. 15:43:35 INFO - Running cycle 1/1 for dromaeo_css test... 15:43:35 INFO - Using env: {'DISPLAY': ':0', 15:43:35 INFO - 'HOME': '/home/cltbld', 15:43:35 INFO - 'JSGC_DISABLE_POISONING': '1', 15:43:35 INFO - 'LANG': 'en_US.UTF-8', 15:43:35 INFO - 'LANGUAGE': 'en_US:en', 15:43:35 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:43:35 INFO - 'LOGNAME': 'cltbld', 15:43:35 INFO - 'MAIL': '/var/mail/cltbld', 15:43:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:43:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:43:35 INFO - 'MOZ_ACCELERATED': '1', 15:43:35 INFO - 'MOZ_CRASHREPORTER': '1', 15:43:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:35 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:43:35 INFO - 'MOZ_NO_REMOTE': '1', 15:43:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:43:35 INFO - 'MOZ_WEBRENDER': '1', 15:43:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:43:35 INFO - 'NO_EM_RESTART': '1', 15:43:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:43:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:35 INFO - 'PWD': '/builds/slave/test', 15:43:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:43:35 INFO - 'RUST_BACKTRACE': 'full', 15:43:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:43:35 INFO - 'SHELL': '/bin/bash', 15:43:35 INFO - 'SHLVL': '1', 15:43:35 INFO - 'TERM': 'linux', 15:43:35 INFO - 'TMOUT': '86400', 15:43:35 INFO - 'USER': 'cltbld', 15:43:35 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', 15:43:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:35 INFO - '_': '/tools/buildbot/bin/python'} 15:43:35 INFO - TEST-INFO | started process 19408 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpplpVt3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:43:36 INFO - PID 19408 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:43:36 INFO - PID 19408 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:43:36 INFO - PID 19408 | 15:43:36 INFO - PID 19408 | (/builds/slave/test/build/application/firefox/firefox:19469): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:43:36 INFO - PID 19408 | 15:43:47 INFO - PID 19408 | RSS: Main: 203345920 15:43:47 INFO - PID 19408 | 15:46:45 INFO - PID 19408 | Cycle 1(1): loaded http://localhost:48011/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48011/tests/dromaeo/cssquery-ext.html) 15:46:46 INFO - PID 19408 | RSS: Main: 208986112 15:46:46 INFO - PID 19408 | 15:49:48 INFO - PID 19408 | Cycle 1(1): loaded http://localhost:48011/tests/dromaeo/cssquery-ext.html (next: http://localhost:48011/tests/dromaeo/cssquery-jquery.html) 15:49:49 INFO - PID 19408 | RSS: Main: 206966784 15:49:49 INFO - PID 19408 | 15:52:51 INFO - PID 19408 | Cycle 1(1): loaded http://localhost:48011/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48011/tests/dromaeo/cssquery-mootools.html) 15:52:51 INFO - PID 19408 | RSS: Main: 208351232 15:52:51 INFO - PID 19408 | 15:55:53 INFO - PID 19408 | Cycle 1(1): loaded http://localhost:48011/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48011/tests/dromaeo/cssquery-prototype.html) 15:55:54 INFO - PID 19408 | RSS: Main: 207122432 15:55:54 INFO - PID 19408 | 15:58:58 INFO - PID 19408 | Cycle 1(1): loaded http://localhost:48011/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48011/tests/dromaeo/cssquery-yui.html) 15:58:58 INFO - PID 19408 | RSS: Main: 211181568 15:58:58 INFO - PID 19408 | 16:02:00 INFO - PID 19408 | Cycle 1(1): loaded http://localhost:48011/tests/dromaeo/cssquery-yui.html (next: http://localhost:48011/tests/dromaeo/cssquery-dojo.html) 16:02:01 INFO - PID 19408 | RSS: Main: 211329024 16:02:01 INFO - PID 19408 | 16:02:01 INFO - PID 19408 | __start_tp_report 16:02:01 INFO - PID 19408 | _x_x_mozilla_page_load 16:02:01 INFO - PID 19408 | _x_x_mozilla_page_load_details 16:02:01 INFO - PID 19408 | |i|pagename|runs| 16:02:01 INFO - PID 19408 | |0;dojo.html;3145.905622831315;3160.1941504916244;3178.872845086197;3184.6656101109397;3186.3627274545097;6208.6585237811905;6352.68236588171;6359.872802543958;6368.5223608229335;6490.058941453486;6288.622682639045;6317.6841157942035;6318.304986451487;6350.745970161188;6367.64977926215;5805.7387417566315;5816.767329306823;5823.883522329562;5837.532997360222;5891.204687367215;5902.704864756766;5929.466348028676;5932.911006334887;5991.940080599182;5995.370486098962;5885;5925.348211696732;5956.851078723023;5959.16571679963;5995.970020149894;840.336134453782;5772.797952071699;5789.681567513785;5799.9130013049835;5918.615290006136;5780.479756821865;5811;5812.360640329561;5875.177475153439;5880.882382352329;6253;6310.905336419957;6340.587861788969;6359.268684101341;6379.744810207586;6827.453803695693;6830.931690683129;6846.281140480228;6860.279670634562;6939.6877140528795;6870.828229294308;6882.414994725455;6888.483363747689;6893.551919125291;7002.054722612484;6144.109104179914;6293.2762732285455;6362.172917520693;6362.331955144689;6413.29453760086;6926.757563485254;6975.720971161097;6985.615791131435;6995.335443132894;6999.335063168991;6642.7342906284175;6713.395794378432;6736.6631668416385;6761.932380676229;6791.898121528181;6782.796516104525;6850.143732033496;6903.689333979983;6906.689198986058;6920.411764999931;6753.763618273337;6780.559263647847;6798.694058767367;6903.378695917391;6905.998630198593;6623.701933413008;6751.392374686204;6758.729650814011;6832.111825462658;6905.792826215222;5279.128943724242;5281.577473802086;5286.048511267932;5288.788448462018;5294.047071527085;4633.235516139866;4644.117617652635;4707.693999889996;4740.976295118502;4767.332573439721;5790.652560846278;5860.794872179454;5870.530357571384;5874.9118763218585;5921.5558833086825;6831.829204269834;6834.350736680008;6869.587824730432;6923.723051077901;6951.582905025714;5678.886422271531;5719.8570035749435;5817.38917413674;5914.201582786355;5927.8221653350465;5741.885162296814;5848.473637372657;5915.674637894957;5919.496842768327;5920.8815823684145;6618.8345291368105;6634.303398143125;6641.369069938445;6666.933330666729;6702.262751097375;6841.828954276183;6853.280405557444;6856.588604683735;6954.3045695431065;6960.756373526902;6412.935870641234;6471.191101112361;6521.576097553644;6535;6660.800175994728;6617.43751781104;6668.76659316941;6671.899921501278;6681.832954176184;6686.331366863469;6702.19573651165;6773.458123350219;6778.762743303961;6829;6940;6671.232808227116;6723.058771772054;6724.058631791455;6737.764178253738;6762.459003279628;6578.901316480161;6648.235452922976;6682.799516014527;6686.665666716742;6721.798346049626;6391.360863913572;6471.352864713491;6471.482281417381;6483.448906842971;6616.470682345304;6599.373059559334;6613.272540020497;6642.535022548375;6667.133272674521;6746.224184218878;6255.311915689362;6398;6399.8080057598345;6490.350964903472;6504.642244676588 16:02:01 INFO - PID 19408 | |1;ext.html;810.6190090657389;811.2495941254339;818.2921772666641;827.6068867288035;852.0967774159392;41370;41703.79148104263;41798;41821;42432.36351454731;38668.226635467276;38763;38798;38957;38963.80518097412;6966.989786480957;6979.651017449132;7048.68280927357;7049.295070492935;7059.576425414466;7064.152301723775;7108.964455177716;7113.217546069928;7114.466415018868;7130.680824047542;7005.894911576331;7122.323379278961;7145.285471452865;7158.677859496335;7926.564038977883;7997.7600671979935;7999.96000020002;8074.0714817796115;8074.0714817796115;8078.23256790604;7922.168172341879;8003.359731221488;8009.479383840032;8011.479253848481;8049.436539442241;36994.8150259247;37362.81318593417;38476.230475390614;38669.8399048029;39117.41323880144;3508.719302455823;3554.2180720241763;3560.0565850049757;3560.287942411528;3576.910577235564;32329.191770205696;32357;32388.514172287436;32435.18912027218;32549.186270343194;4877.585405240558;4924.679895806797;4943.629227807893;4950.282209079699;4973.950260497385;186710;187510;187775;188101;188335.99999999863;42481.787591061846;43341.56658433438;48577.514224857296;49056.54717264194;49414;50268.49731502638;50297.24554131691;50520.747396262785;50663;50739.492605074214;15289.082655040733;15478.5356439307;15537.766933496006;15596.922015390077;15638.843611563967;12627.936860315824;12800.679983000313;12807.551735689207;12826.679333016562;12827.10210285271;13375;13424.127431716906;13610.93194534021;13618.18290902549;13643.863561364458;5449.945500545023;5471.23402723619;5522.116461366162;5540.972295138498;5617.949448451286;1017.2980643356209;1018.6791160784329;1025.8153532364097;1026.0919086608255;1034.6533911139702;23421.648675269884;23534;23647.881760591088;23687.407814804425;23736.88131559366;1108.9500972456096;1131.4964840645835;1133.9659810205708;1142.6572028391615;1144.9255798373079;12388.11707209933;12478.064145188966;12553.811692824615;12589.307588082744;12678.936605316914;211647;212952;227039;228524;229210;51782;52217;52661;52709;53162.734186328824;1420.2756594136858;1446.8987170898147;1467.8532146785235;1467.9706405871823;1470.2869108482378;22485.32544023682;23014.539709205386;23020.309390718307;23167.07331706713;23282.185123520594;37695.81152094222;37766.811165944;37874.053148671504;37883.431748523246;38242.61757382502;189712.05143974192;190534.04732976246;190552;190838;192887;30327.848360758937;34010;34476.13809654678;34786.130346741535;35282.64717352896;44262;44269.335959961274;44475.77762111169;44788.77605611951;44859.55140448554;31841;32091.67908320887;32181.230032348445;32446.513302300013;32511.67488325181;31505.337136177994;32073;32309;32396.676033239364;32568.674313256564;35193.82403088071;35789.28421431505;36147.63852361443;36496.27007459889;36926.261474770894;31360.843195784786;31720.841395792875;31931.840340798146;32063.67936320607;32327.838360808044;23282.417939551648;23413.414664632837;23742.169024084073;23945.042198311872;23993.640095398932 16:02:01 INFO - PID 19408 | |2;jquery.html;579.8463407197096;580.2659635561016;583.5652438932996;603.8248907816734;622.6015350175887;21671;22659.999999999978;22663.09347626093;22666.546669066607;22681.20615778448;22364.440888977784;23780.5243895122;23975.880120599373;24173.63739543904;24255.75744242557;6293.5909165904295;6306.747730090791;6411.134496842937;6419.518536109787;6424.421802037816;6360.55476116672;6447;6458.354164583551;6459.740350631629;6465.191851018622;6296.1500197473215;6434.06706027628;6518.478521718251;6582.8683426331445;6604.57070290432;6550.0829883816305;6558.770443034518;6572.342765723413;6618.205815302146;6622.106015687893;5954.672493012882;6090.33006369299;6111.327753947062;6124.785632502841;6185.443310102112;28483.85758071217;28835.71164288372;29061.709382906323;29063.128106156848;29095.12714618565;2045.3863840847835;2056.4344805178694;2056.8354531637433;2065.6281869263676;2073.7200477935435;19157.904210479;19610.117544710523;19692.40922772319;20039.09824057921;20181.091850866746;2317.6755254264417;2355.9057637694473;2371.5019845832394;2373.8575685458754;2406.193925035115;126027;131429;138695;139066;142238.99999999895;14448.783268250985;14603.758680512276;14692.926535367254;14850.53037608487;14965;22673.88663056707;22971.081156753877;23075.38472306956;23123.07507699673;23142.652860207112;9747.171490423252;9855.507224638739;9982;10052.195824334036;10141.594336226468;9157.908420915839;9170.862437063315;9227.169554740105;9276.953615232016;9333.160015598629;8870.51212183336;9041.81916361669;9088.318376121817;9188.081191880758;9268;2635.59148332005;2699.1497678231685;2705.485957668037;2705.6888457827604;2745.9450810983667;930.4882314726873;957.8563215517728;966.3428868369576;968.8934217236099;970.1075010989765;18035.368762093265;18442.723359149622;18475.445736627924;18699.906500467412;18731.812681873278;1011.8735158105237;1023.2172388123092;1029.6344797596896;1033.3231733214757;1033.540074666766;9889.554970026365;10199.6163529553;10262.43556604379;10279.537420815932;10348.5343159558;450427;450844;459517;460719;463079;111525.44237278761;114381.85618143878;117270;118322.67547960363;120810.39594802145;1130.9943450282697;1135.4776802670865;1161.2800063960399;1174.723939874129;1177.8822117788325;16114.597135071717;16291;16645.667086657955;16729.163541822618;16782.412615558398;55086.72456637691;58175.418245817;58198.709006454694;59264.70367648134;59558.10662839974;68460;69878.30121698718;71470.28529714844;71831.28168718246;71849.28150718634;13649.11280766747;13753.656158596115;13856.30718464093;14090.859091408955;14172.220527871066;21117.26089586857;21271.57456850885;21315.147394104682;21482.24812131568;21779.23772667949;17403.47789566315;17512.018149654574;17770.5557361067;17891.552711182325;17956.461306160836;15568.766468503209;15963;16237.18814059316;16240.837591624406;16354.345826167297;16071.598210044842;16090.67818643644;16132;16163.75754363662;16232.513024609281;13921.164730116225;13922.791158132839;14010.43958241659;14038;14056.859431405555;15197;15503.612409689847;15580.220988950734;17174.31302747926;17303.653926921645 16:02:01 INFO - PID 19408 | |3;mootools.html;1297.6431481342631;1379.1724965020983;1383.937722802474;1384.3009280313443;1394.6443656867498;12641.936790316047;13200.93399533001;13227.537036203736;13241.073124881263;15303.540893773205;15466.767998480032;15794;15797.368105275804;15800.604984875385;15815.920920395381;2799.7760179185716;2843.516602177635;2862.656481222246;2865.083173384518;2869.067553045258;2638.0634874619427;2689.610006549049;2708.1604702542277;2724.6049322848176;2896.0298300069503;2871.2821794551364;2872.9425411491757;2883.1206482022976;2885.956710649342;2892.2913886097936;2778.7776977841722;2796.0353677981066;2800.3139230888564;2816.7605753510975;2827.7455029047383;2648.589468632365;2689.690685571165;2708.5124677558033;2718.687350954646;2724.3189202699327;14771.261436928218;14964.326605302787;15085.54743357701;15094.39622415113;15119.244037798066;8139.4709343892455;8385.454945428588;8424.199701028392;8471.49171049739;8504.404691671585;5464.836054918319;5614.831555053355;5630.774769009235;5645.5483561314995;5693.430656934315;1954.7067939809;1962.617289628523;1976.2589029114083;1981.8612697111207;1991.1239054815835;25600.87199564009;25787.226383208534;25898;25949.61075583868;25976.87011564949;6858.348456896587;6905.309469053155;7035.577865328097;7091.361777440055;7147.499675022803;16948.74576881348;16996.32014719422;17009.31962721502;17053.48839534791;17120.828791712174;8382.958085209535;8604.440711353742;8616.439931404439;8722.476651400937;8729.65081396737;7701.075870895528;7724.961375193088;7730.845383092306;7757.340626046736;7791.220877912277;5278.287431196816;5438.374586922476;5471.398146203915;5475.863103422446;5500.4048825840855;1467.1417220925664;1477.2392218007735;1485.8959872809016;1598.9360425582847;1610.9919450402908;866.9046404895456;892.067789160329;899.1143723432414;900.594732370436;913.5204017890661;7729.49274891402;8121.4721043132;8478.872816907751;8510.617022234015;8651.610677519528;938.9718308450757;969.5636963366513;981.6024510073431;983.7786498037884;1004.9397036177853;5264.736763161826;5293.867653308621;5336.813211537577;5414.296141501658;5430.945690543123;20803.1678732852;22194.667079993815;22433.887830560743;22478.775212247994;22540.774592253867;20940.267090651752;21070.683939740917;21717;21831.345059648236;22058.88970555169;11552.191346605658;11905.702357441134;11908.106891983136;11913.285202887855;11942.522299107939;8245.79385515343;8263.504189748634;8263.586820658822;8281.254687078192;8390.28682561989;17947.20527947247;18721.812781872006;18876.811231888056;19185.712214317075;19230.519237019187;18837.529061773566;19158.808411915703;19658.60682786365;19868.10593523266;20284.898575507028;10581.10060644823;10674;10699.14406847466;10709.518071686949;10711.250212485364;14100.237470316211;14173.36219870129;14179;14500.709985800437;14646.194459304841;12201.572944946884;12261.570845020382;12455.190412623151;12640.87359126397;12776.169548979287;12157.1489995703;12224.266544007389;12408.193467424588;12442.502299907903;12528.436220369922;9587.808243835223;10157.644482443078;10259.076683098558;10272.537735802056;10299.279050466395;7565.2812982766545;7570.9242907570215;7596.80934007707;7600.923990760021;7647.388208943383;7357.301056399633;7405.259474052552;7455.962720186364;7471.663775130023;7517.924820751722 16:02:01 INFO - PID 19408 | |4;prototype.html;503.384404857904;510.26012282190834;526.8761840967373;532.5582205587383;533.21373399113;6285.5914365566205;6296.370362963701;6299.905501417476;6385.744570217185;6399.8080057598345;6113.205283313163;6146.723397447115;6211.844703882394;6232.407921247485;6339.587926784756;4880.5846304571805;5091.643584949055;5118.543099312333;5143;5164.122099243128;4958.876028099308;5008.974955125219;5012.949870501285;5019.447860735335;5042.974785126069;4954.504549545053;4961.454240033593;4969.180085285923;4998.825041123561;5017.272495488152;4918.483559226302;4919.90160196794;4925.285833554115;4973.950260497385;5022.899542009158;4482.372467854503;4483.596476317147;4488.618467430272;4502.729836209838;4518.683692141552;6064.181335519693;6171.722272497704;6177.969110154466;6198.907016394758;6207.137858642389;5699.914501282484;5725.627834190765;5727;5750.396208398141;5769.451902069297;6381.691753190585;6424.137000269916;6435.517336199804;6439.903401448982;6442.774502892423;4846.854594362175;4860.125177468054;4866.3673722416215;4871.14754917888;4889.242167464014;6440.903386449207;6559.672016399209;6730.528862979642;6731.865362692719;6790.49071319653;4994.051130285234;5003.749812509433;5030.622703297267;5036.571891389273;5042.899142017139;5032.572231360376;5040.429381015709;5042.369703787027;5077.365329333833;5098.515641014098;5551.250581171571;5593.216949627055;5615.157726341;5678;5682.488576028178;6484.805455836333;6527.1815987646805;6540.542162048611;6561.704723287464;6572.732407319467;1732.3629170434072;1750.9474715758292;1754.921028553718;1757.8729737018095;1780.9821901780817;1179.2217136689915;1220.9328486933305;1242.9248699874543;1245.4582256718356;1250.831137796404;5553.722313884289;5574.665520068809;5640.407757185519;5648.74580643872;5695.316562012585;6618.238902526175;6650.70071846768;6675.232348279913;6707.685848382301;6721.395074443323;5504.587155963319;5522.337319521683;5572.832815015556;5641.210230567724;5642.379338272786;3472.4791281308007;3504.491848681927;3517.3668739627115;3531.52324436203;3549.5208146900354;6414.594527052355;6467.773627923;6482.286948435668;6489.1888513935755;6556.246376521777;6377.330380310001;6788.660566971632;6810.795676129724;6883.139607549057;6956.165260168812;6215.720292586935;6337.809865704036;6380.393862583047;6403.918726413841;6412.871742565123;6156.138140660221;6527.804165875032;6542.182227221598;6564.277929427663;6613.503987200883;6473.805785826433;6552.901706474311;6569.80290591283;6577;6639.269680335063;6417.326180750954;6430.64631445275;6436.485081193587;6487.448566871869;6508.316626754312;4970.502949705001;5038.622103342337;5050.419201791841;5050.621203409685;5057.420715435081;6411.198600174978;6450.451711604566;6545.476361891133;6745.696443659949;6765.898511522233;6359.141515895295;6419.775307864203;6465.706858628388;6469.7412103515335;6552.6723663817575;6341.936580634319;6430.935690643034;6458.54790164684;6501.577397469149;6517.4134327910715;6412.807615771534;6416.582922110048;6472.805815825533;6476.319986401549;6479.384458476437;6431.871362572816;6543.280239173782;6611.471082313499;6617.569857959217;6626.6686665667485;6244.375562443901;6291.276503202007;6350.587211831216;6359.173307470184;6414.550981431255 16:02:01 INFO - PID 19408 | |5;yui.html;914.2320450821311;922.7600823785818;930.279033748845;938.9718308450747;941.0918463682549;19497.610047799037;20045.699314510293;20150.69773953392;20231.898840505793;20255.291064812754;18001.189946452407;18120.093995300216;18196.545086372815;18252.269909203616;18323.450296491126;954.9999999999983;968.3995922528043;973.3867663372066;974.59554284972;974.7952929884733;977.2524019125375;978.3640633588153;979.7011911367031;982.2682101834108;985.526947065409;940.1867384712243;1018.9235807314444;1020.647876482616;1026.2508368890715;1032.4889179855945;1011.0496133634433;1020.1634659579148;1022.7494263905354;1023.089450389158;1026.6971243483154;744.2483092076988;744.437949348244;746.7834328044861;757.6704133701802;757.9848403031964;20421.69367459489;20492.897535512227;20744;20940.371788846358;21072.789272107388;21861.344159675235;22163.667544986838;22239.44401389978;22349.553008939893;22616.886915565483;16794.496165115066;17453.7381939271;17601.823981760146;17663.11684415787;17787.822121778747;3789.2989796887387;3820.102275965146;3824.177801772604;3836.156045669948;3841.692664586826;100428.49785751024;104640.47679761553;141097.29451352678;142690.28654856764;144213;17442.56393590145;17481.7377739334;17610.911945440446;17671.38150164738;17690.469285921445;31615;31727.84136079305;31739.04782856472;31743.84128079391;31944;7978.042634883851;8389.370797190235;8389.664413423394;8438.915610843937;8460.280876125598;4396.230659634552;4444.599986001211;4484.349769283435;4496.460424749051;4512.909741805145;5339.279197308391;5387.272718183073;5404.216388623628;5436.21174929633;5474.753636086386;1080.7460246841986;1097.6048622495896;1101.8347247912716;1103.2553026706942;1107.684309971654;677.3429773120066;684.7397988764237;704.8872180451103;708.0865683268526;711.4557363616838;12224.877751222552;12355.69110772238;12369.62891113268;12951.676208094685;13212.207267563977;682.5120039172016;683.9589624622539;695.3394275438354;699.2308460693197;702.0522294901943;5526.336839579196;5618.381977982419;5639.238702775073;5659.745311460994;5692.6299790513485;122192;127390;127851.36074319569;128736.35631821968;132930;112172.43913780378;112851;112926;113017;114144;93611;95963.52018239864;96890.5155474218;97147;97489.51255243679;10297.124744396511;10451.477426128511;10555.841662375224;10558.841617375592;10611.151107911193;45305;46923.76538117424;47398.52601474079;47441.81395465141;47573.52426475829;60448;60862.391376085674;62161.37838621556;62532.37467625266;64508;15271.236438178268;15402.306896189475;15595.688086238439;15610.21948902573;15952.282147303167;15330.846691533388;15862.444814431901;16007.759883601988;16212.675746484769;16277.837221627633;15777.763333549776;16003.599910002344;16053.759193611872;16114.758278626065;16198.109103999393;16126.516204513884;16195.109268990318;16481.17594120265;16517.421890233785;16746.916265418597;15370.69258614799;15376.923115384352;15670.764938525703;15675.764863526829;15788.921055394649;11709;11819;11877.46551405206;11908.821367679666;12063.638090857288;11427.428628568705;11445.885541144482;11452.427378631202;11645.47595358194;11851.585194518151 16:02:01 INFO - PID 19408 | __end_tp_report 16:02:01 INFO - PID 19408 | __start_cc_report 16:02:01 INFO - PID 19408 | _x_x_mozilla_cycle_collect,294 16:02:01 INFO - PID 19408 | __end_cc_report 16:02:01 INFO - PID 19408 | __startTimestamp1506294121034__endTimestamp 16:02:01 INFO - PID 19408 | 16:02:01 INFO - PID 19408 | ------- Summary: start ------- 16:02:01 INFO - PID 19408 | Number of tests: 6 16:02:01 INFO - PID 19408 | 16:02:01 INFO - PID 19408 | [#0] dojo.html Cycles:165 Average:6233.97 Median:6490.06 stddev:867.60 (13.4%) stddev-sans-first:835.75 16:02:01 INFO - PID 19408 | Values: 3145.9 3160.2 3178.9 3184.7 3186.4 6208.7 6352.7 6359.9 6368.5 6490.1 6288.6 6317.7 6318.3 6350.7 6367.6 5805.7 5816.8 5823.9 5837.5 5891.2 5902.7 5929.5 5932.9 5991.9 5995.4 5885.0 5925.3 5956.9 5959.2 5996.0 840.3 5772.8 5789.7 5799.9 5918.6 5780.5 5811.0 5812.4 5875.2 5880.9 6253.0 6310.9 6340.6 6359.3 6379.7 6827.5 6830.9 6846.3 6860.3 6939.7 6870.8 6882.4 6888.5 6893.6 7002.1 6144.1 6293.3 6362.2 6362.3 6413.3 6926.8 6975.7 6985.6 6995.3 6999.3 6642.7 6713.4 6736.7 6761.9 6791.9 6782.8 6850.1 6903.7 6906.7 6920.4 6753.8 6780.6 6798.7 6903.4 6906.0 6623.7 6751.4 6758.7 6832.1 6905.8 5279.1 5281.6 5286.0 5288.8 5294.0 4633.2 4644.1 4707.7 4741.0 4767.3 5790.7 5860.8 5870.5 5874.9 5921.6 6831.8 6834.4 6869.6 6923.7 6951.6 5678.9 5719.9 5817.4 5914.2 5927.8 5741.9 5848.5 5915.7 5919.5 5920.9 6618.8 6634.3 6641.4 6666.9 6702.3 6841.8 6853.3 6856.6 6954.3 6960.8 6412.9 6471.2 6521.6 6535.0 6660.8 6617.4 6668.8 6671.9 6681.8 6686.3 6702.2 6773.5 6778.8 6829.0 6940.0 6671.2 6723.1 6724.1 6737.8 6762.5 6578.9 6648.2 6682.8 6686.7 6721.8 6391.4 6471.4 6471.5 6483.4 6616.5 6599.4 6613.3 6642.5 6667.1 6746.2 6255.3 6398.0 6399.8 6490.4 6504.6 16:02:01 INFO - PID 19408 | 16:02:01 INFO - PID 19408 | [#1] ext.html Cycles:180 Average:36953.60 Median:35238.24 stddev:51985.89 (147.5%) stddev-sans-first:52060.89 16:02:01 INFO - PID 19408 | Values: 810.6 811.2 818.3 827.6 852.1 41370.0 41703.8 41798.0 41821.0 42432.4 38668.2 38763.0 38798.0 38957.0 38963.8 6967.0 6979.7 7048.7 7049.3 7059.6 7064.2 7109.0 7113.2 7114.5 7130.7 7005.9 7122.3 7145.3 7158.7 7926.6 7997.8 8000.0 8074.1 8074.1 8078.2 7922.2 8003.4 8009.5 8011.5 8049.4 36994.8 37362.8 38476.2 38669.8 39117.4 3508.7 3554.2 3560.1 3560.3 3576.9 32329.2 32357.0 32388.5 32435.2 32549.2 4877.6 4924.7 4943.6 4950.3 4974.0 186710.0 187510.0 187775.0 188101.0 188336.0 42481.8 43341.6 48577.5 49056.5 49414.0 50268.5 50297.2 50520.7 50663.0 50739.5 15289.1 15478.5 15537.8 15596.9 15638.8 12627.9 12800.7 12807.6 12826.7 12827.1 13375.0 13424.1 13610.9 13618.2 13643.9 5449.9 5471.2 5522.1 5541.0 5617.9 1017.3 1018.7 1025.8 1026.1 1034.7 23421.6 23534.0 23647.9 23687.4 23736.9 1109.0 1131.5 1134.0 1142.7 1144.9 12388.1 12478.1 12553.8 12589.3 12678.9 211647.0 212952.0 227039.0 228524.0 229210.0 51782.0 52217.0 52661.0 52709.0 53162.7 1420.3 1446.9 1467.9 1468.0 1470.3 22485.3 23014.5 23020.3 23167.1 23282.2 37695.8 37766.8 37874.1 37883.4 38242.6 189712.1 190534.0 190552.0 190838.0 192887.0 30327.8 34010.0 34476.1 34786.1 35282.6 44262.0 44269.3 44475.8 44788.8 44859.6 31841.0 32091.7 32181.2 32446.5 32511.7 31505.3 32073.0 32309.0 32396.7 32568.7 35193.8 35789.3 36147.6 36496.3 36926.3 31360.8 31720.8 31931.8 32063.7 32327.8 23282.4 23413.4 23742.2 23945.0 23993.6 16:02:01 INFO - PID 19408 | 16:02:01 INFO - PID 19408 | [#2] jquery.html Cycles:180 Average:33383.66 Median:22670.22 stddev:77533.50 (342.0%) stddev-sans-first:77711.88 16:02:01 INFO - PID 19408 | Values: 579.8 580.3 583.6 603.8 622.6 21671.0 22660.0 22663.1 22666.5 22681.2 22364.4 23780.5 23975.9 24173.6 24255.8 6293.6 6306.7 6411.1 6419.5 6424.4 6360.6 6447.0 6458.4 6459.7 6465.2 6296.2 6434.1 6518.5 6582.9 6604.6 6550.1 6558.8 6572.3 6618.2 6622.1 5954.7 6090.3 6111.3 6124.8 6185.4 28483.9 28835.7 29061.7 29063.1 29095.1 2045.4 2056.4 2056.8 2065.6 2073.7 19157.9 19610.1 19692.4 20039.1 20181.1 2317.7 2355.9 2371.5 2373.9 2406.2 126027.0 131429.0 138695.0 139066.0 142239.0 14448.8 14603.8 14692.9 14850.5 14965.0 22673.9 22971.1 23075.4 23123.1 23142.7 9747.2 9855.5 9982.0 10052.2 10141.6 9157.9 9170.9 9227.2 9277.0 9333.2 8870.5 9041.8 9088.3 9188.1 9268.0 2635.6 2699.1 2705.5 2705.7 2745.9 930.5 957.9 966.3 968.9 970.1 18035.4 18442.7 18475.4 18699.9 18731.8 1011.9 1023.2 1029.6 1033.3 1033.5 9889.6 10199.6 10262.4 10279.5 10348.5 450427.0 450844.0 459517.0 460719.0 463079.0 111525.4 114381.9 117270.0 118322.7 120810.4 1131.0 1135.5 1161.3 1174.7 1177.9 16114.6 16291.0 16645.7 16729.2 16782.4 55086.7 58175.4 58198.7 59264.7 59558.1 68460.0 69878.3 71470.3 71831.3 71849.3 13649.1 13753.7 13856.3 14090.9 14172.2 21117.3 21271.6 21315.1 21482.2 21779.2 17403.5 17512.0 17770.6 17891.6 17956.5 15568.8 15963.0 16237.2 16240.8 16354.3 16071.6 16090.7 16132.0 16163.8 16232.5 13921.2 13922.8 14010.4 14038.0 14056.9 15197.0 15503.6 15580.2 17174.3 17303.7 16:02:01 INFO - PID 19408 | 16:02:01 INFO - PID 19408 | [#3] mootools.html Cycles:180 Average:9481.60 Median:2643.33 stddev:6640.48 (251.2%) stddev-sans-first:6630.64 16:02:01 INFO - PID 19408 | Values: 1297.6 1379.2 1383.9 1384.3 1394.6 12641.9 13200.9 13227.5 13241.1 15303.5 15466.8 15794.0 15797.4 15800.6 15815.9 2799.8 2843.5 2862.7 2865.1 2869.1 2638.1 2689.6 2708.2 2724.6 2896.0 2871.3 2872.9 2883.1 2886.0 2892.3 2778.8 2796.0 2800.3 2816.8 2827.7 2648.6 2689.7 2708.5 2718.7 2724.3 14771.3 14964.3 15085.5 15094.4 15119.2 8139.5 8385.5 8424.2 8471.5 8504.4 5464.8 5614.8 5630.8 5645.5 5693.4 1954.7 1962.6 1976.3 1981.9 1991.1 25600.9 25787.2 25898.0 25949.6 25976.9 6858.3 6905.3 7035.6 7091.4 7147.5 16948.7 16996.3 17009.3 17053.5 17120.8 8383.0 8604.4 8616.4 8722.5 8729.7 7701.1 7725.0 7730.8 7757.3 7791.2 5278.3 5438.4 5471.4 5475.9 5500.4 1467.1 1477.2 1485.9 1598.9 1611.0 866.9 892.1 899.1 900.6 913.5 7729.5 8121.5 8478.9 8510.6 8651.6 939.0 969.6 981.6 983.8 1004.9 5264.7 5293.9 5336.8 5414.3 5430.9 20803.2 22194.7 22433.9 22478.8 22540.8 20940.3 21070.7 21717.0 21831.3 22058.9 11552.2 11905.7 11908.1 11913.3 11942.5 8245.8 8263.5 8263.6 8281.3 8390.3 17947.2 18721.8 18876.8 19185.7 19230.5 18837.5 19158.8 19658.6 19868.1 20284.9 10581.1 10674.0 10699.1 10709.5 10711.3 14100.2 14173.4 14179.0 14500.7 14646.2 12201.6 12261.6 12455.2 12640.9 12776.2 12157.1 12224.3 12408.2 12442.5 12528.4 9587.8 10157.6 10259.1 10272.5 10299.3 7565.3 7570.9 7596.8 7600.9 7647.4 7357.3 7405.3 7456.0 7471.7 7517.9 16:02:01 INFO - PID 19408 | 16:02:01 INFO - PID 19408 | [#4] prototype.html Cycles:180 Average:5472.19 Median:6188.44 stddev:1518.63 (24.5%) stddev-sans-first:1476.38 16:02:01 INFO - PID 19408 | Values: 503.4 510.3 526.9 532.6 533.2 6285.6 6296.4 6299.9 6385.7 6399.8 6113.2 6146.7 6211.8 6232.4 6339.6 4880.6 5091.6 5118.5 5143.0 5164.1 4958.9 5009.0 5012.9 5019.4 5043.0 4954.5 4961.5 4969.2 4998.8 5017.3 4918.5 4919.9 4925.3 4974.0 5022.9 4482.4 4483.6 4488.6 4502.7 4518.7 6064.2 6171.7 6178.0 6198.9 6207.1 5699.9 5725.6 5727.0 5750.4 5769.5 6381.7 6424.1 6435.5 6439.9 6442.8 4846.9 4860.1 4866.4 4871.1 4889.2 6440.9 6559.7 6730.5 6731.9 6790.5 4994.1 5003.7 5030.6 5036.6 5042.9 5032.6 5040.4 5042.4 5077.4 5098.5 5551.3 5593.2 5615.2 5678.0 5682.5 6484.8 6527.2 6540.5 6561.7 6572.7 1732.4 1750.9 1754.9 1757.9 1781.0 1179.2 1220.9 1242.9 1245.5 1250.8 5553.7 5574.7 5640.4 5648.7 5695.3 6618.2 6650.7 6675.2 6707.7 6721.4 5504.6 5522.3 5572.8 5641.2 5642.4 3472.5 3504.5 3517.4 3531.5 3549.5 6414.6 6467.8 6482.3 6489.2 6556.2 6377.3 6788.7 6810.8 6883.1 6956.2 6215.7 6337.8 6380.4 6403.9 6412.9 6156.1 6527.8 6542.2 6564.3 6613.5 6473.8 6552.9 6569.8 6577.0 6639.3 6417.3 6430.6 6436.5 6487.4 6508.3 4970.5 5038.6 5050.4 5050.6 5057.4 6411.2 6450.5 6545.5 6745.7 6765.9 6359.1 6419.8 6465.7 6469.7 6552.7 6341.9 6430.9 6458.5 6501.6 6517.4 6412.8 6416.6 6472.8 6476.3 6479.4 6431.9 6543.3 6611.5 6617.6 6626.7 6244.4 6291.3 6350.6 6359.2 6414.6 16:02:01 INFO - PID 19408 | 16:02:01 INFO - PID 19408 | [#5] yui.html Cycles:180 Average:24516.13 Median:20098.20 stddev:35252.83 (175.4%) stddev-sans-first:35307.18 16:02:01 INFO - PID 19408 | Values: 914.2 922.8 930.3 939.0 941.1 19497.6 20045.7 20150.7 20231.9 20255.3 18001.2 18120.1 18196.5 18252.3 18323.5 955.0 968.4 973.4 974.6 974.8 977.3 978.4 979.7 982.3 985.5 940.2 1018.9 1020.6 1026.3 1032.5 1011.0 1020.2 1022.7 1023.1 1026.7 744.2 744.4 746.8 757.7 758.0 20421.7 20492.9 20744.0 20940.4 21072.8 21861.3 22163.7 22239.4 22349.6 22616.9 16794.5 17453.7 17601.8 17663.1 17787.8 3789.3 3820.1 3824.2 3836.2 3841.7 100428.5 104640.5 141097.3 142690.3 144213.0 17442.6 17481.7 17610.9 17671.4 17690.5 31615.0 31727.8 31739.0 31743.8 31944.0 7978.0 8389.4 8389.7 8438.9 8460.3 4396.2 4444.6 4484.3 4496.5 4512.9 5339.3 5387.3 5404.2 5436.2 5474.8 1080.7 1097.6 1101.8 1103.3 1107.7 677.3 684.7 704.9 708.1 711.5 12224.9 12355.7 12369.6 12951.7 13212.2 682.5 684.0 695.3 699.2 702.1 5526.3 5618.4 5639.2 5659.7 5692.6 122192.0 127390.0 127851.4 128736.4 132930.0 112172.4 112851.0 112926.0 113017.0 114144.0 93611.0 95963.5 96890.5 97147.0 97489.5 10297.1 10451.5 10555.8 10558.8 10611.2 45305.0 46923.8 47398.5 47441.8 47573.5 60448.0 60862.4 62161.4 62532.4 64508.0 15271.2 15402.3 15595.7 15610.2 15952.3 15330.8 15862.4 16007.8 16212.7 16277.8 15777.8 16003.6 16053.8 16114.8 16198.1 16126.5 16195.1 16481.2 16517.4 16746.9 15370.7 15376.9 15670.8 15675.8 15788.9 11709.0 11819.0 11877.5 11908.8 12063.6 11427.4 11445.9 11452.4 11645.5 11851.6 16:02:01 INFO - PID 19408 | -------- Summary: end -------- 16:02:01 INFO - PID 19408 | 16:02:01 INFO - TEST-INFO | 19408: exit 0 16:02:01 INFO - TEST-OK | dromaeo_css | took 1114585ms 16:02:01 INFO - TEST-START | kraken 16:02:01 INFO - Initialising browser for kraken test... 16:02:01 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48011/getInfo.html -profile /tmp/tmp4LJQQ9/profile 16:02:01 INFO - TEST-INFO | started process 19789 (/builds/slave/test/build/application/firefox/firefox http://localhost:48011/getInfo.html) 16:02:07 INFO - TEST-INFO | 19789: exit 0 16:02:07 INFO - Browser initialized. 16:02:07 INFO - Running cycle 1/1 for kraken test... 16:02:07 INFO - Using env: {'DISPLAY': ':0', 16:02:07 INFO - 'HOME': '/home/cltbld', 16:02:07 INFO - 'JSGC_DISABLE_POISONING': '1', 16:02:07 INFO - 'LANG': 'en_US.UTF-8', 16:02:07 INFO - 'LANGUAGE': 'en_US:en', 16:02:07 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:02:07 INFO - 'LOGNAME': 'cltbld', 16:02:07 INFO - 'MAIL': '/var/mail/cltbld', 16:02:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:02:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:02:07 INFO - 'MOZ_ACCELERATED': '1', 16:02:07 INFO - 'MOZ_CRASHREPORTER': '1', 16:02:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:07 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:02:07 INFO - 'MOZ_NO_REMOTE': '1', 16:02:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:02:07 INFO - 'MOZ_WEBRENDER': '1', 16:02:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:07 INFO - 'NO_EM_RESTART': '1', 16:02:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:07 INFO - 'PWD': '/builds/slave/test', 16:02:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:02:07 INFO - 'RUST_BACKTRACE': 'full', 16:02:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:02:07 INFO - 'SHELL': '/bin/bash', 16:02:07 INFO - 'SHLVL': '1', 16:02:07 INFO - 'TERM': 'linux', 16:02:07 INFO - 'TMOUT': '86400', 16:02:07 INFO - 'USER': 'cltbld', 16:02:07 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1506289779.632996-117999451', 16:02:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:07 INFO - '_': '/tools/buildbot/bin/python'} 16:02:07 INFO - TEST-INFO | started process 20038 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4LJQQ9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:02:08 INFO - PID 20038 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:02:08 INFO - PID 20038 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:02:09 INFO - PID 20038 | 16:02:09 INFO - PID 20038 | (/builds/slave/test/build/application/firefox/firefox:20098): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:02:09 INFO - PID 20038 | 16:02:19 INFO - PID 20038 | RSS: Main: 202960896 16:02:19 INFO - PID 20038 | 16:02:22 INFO - PID 20038 | Cycle 1(1): loaded http://localhost:48011/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48011/tests/kraken/driver.html?testName=audio-beat-detection) 16:02:23 INFO - PID 20038 | RSS: Main: 202866688 16:02:23 INFO - PID 20038 | 16:02:25 INFO - PID 20038 | Cycle 1(1): loaded http://localhost:48011/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48011/tests/kraken/driver.html?testName=audio-dft) 16:02:26 INFO - PID 20038 | RSS: Main: 203001856 16:02:26 INFO - PID 20038 | 16:02:29 INFO - PID 20038 | Cycle 1(1): loaded http://localhost:48011/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48011/tests/kraken/driver.html?testName=audio-fft) 16:02:30 INFO - PID 20038 | RSS: Main: 198963200 16:02:30 INFO - PID 20038 | 16:02:32 INFO - PID 20038 | Cycle 1(1): loaded http://localhost:48011/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48011/tests/kraken/driver.html?testName=audio-oscillator) 16:02:32 INFO - PID 20038 | RSS: Main: 199270400 16:02:32 INFO - PID 20038 | 16:02:34 INFO - PID 20038 | Cycle 1(1): loaded http://localhost:48011/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48011/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:02:35 INFO - PID 20038 | RSS: Main: 199823360 16:02:35 INFO - PID 20038 | 16:02:38 INFO - PID 20038 | Cycle 1(1): loaded http://localhost:48011/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48011/tests/kraken/driver.html?testName=imaging-darkroom) 16:02:39 INFO - PID 20038 | RSS: Main: 202309632 16:02:39 INFO - PID 20038 | 16:02:43 INFO - PID 20038 | Cycle 1(1): loaded http://localhost:48011/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48011/tests/kraken/driver.html?testName=imaging-desaturate) 16:02:43 INFO - PID 20038 | RSS: Main: 202428416 16:02:43 INFO - PID 20038 | 16:02:46 INFO - PID 20038 | Cycle 1(1): loaded http://localhost:48011/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48011/tests/kraken/driver.html?testName=json-parse-financial) 16:02:47 INFO - PID 20038 | RSS: Main: 203010048 16:02:47 INFO - PID 20038 | 16:02:49 INFO - PID 20038 | Cycle 1(1): loaded http://localhost:48011/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48011/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:02:49 INFO - PID 20038 | RSS: Main: 203182080 16:02:49 INFO - PID 20038 | 16:02:51 INFO - PID 20038 | Cycle 1(1): loaded http://localhost:48011/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48011/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:02:51 INFO - PID 20038 | RSS: Main: 203431936 16:02:51 INFO - PID 20038 | 16:02:54 INFO - PID 20038 | Cycle 1(1): loaded http://localhost:48011/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48011/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:02:54 INFO - PID 20038 | RSS: Main: 203382784 16:02:54 INFO - PID 20038 | 16:02:57 INFO - PID 20038 | Cycle 1(1): loaded http://localhost:48011/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48011/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:02:58 INFO - PID 20038 | RSS: Main: 203317248 16:02:58 INFO - PID 20038 | 16:03:01 INFO - PID 20038 | Cycle 1(1): loaded http://localhost:48011/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48011/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:03:01 INFO - PID 20038 | RSS: Main: 203919360 16:03:01 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | Cycle 1(1): loaded http://localhost:48011/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48011/tests/kraken/driver.html?testName=ai-astar) 16:03:03 INFO - PID 20038 | RSS: Main: 205766656 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | __start_tp_report 16:03:03 INFO - PID 20038 | _x_x_mozilla_page_load 16:03:03 INFO - PID 20038 | _x_x_mozilla_page_load_details 16:03:03 INFO - PID 20038 | |i|pagename|runs| 16:03:03 INFO - PID 20038 | |0;ai-astar;110;115;108;115;109;115;115;113;114;114 16:03:03 INFO - PID 20038 | |1;audio-beat-detection;128;118;120;119;119;117;150;120;119;146 16:03:03 INFO - PID 20038 | |2;audio-dft;171;169;167;167;177;169;164;164;166;169 16:03:03 INFO - PID 20038 | |3;audio-fft;83;83;75;79;76;75;77;84;76;75 16:03:03 INFO - PID 20038 | |4;audio-oscillator;83;79;88;87;79;87;80;79;86;80 16:03:03 INFO - PID 20038 | |5;imaging-gaussian-blur;117;117;121;115;115;122;114;119;115;117 16:03:03 INFO - PID 20038 | |6;imaging-darkroom;149;149;150;149;149;159;158;149;149;149 16:03:03 INFO - PID 20038 | |7;imaging-desaturate;99;97;94;96;94;96;96;101;95;95 16:03:03 INFO - PID 20038 | |8;json-parse-financial;60;54;56;53;54;54;54;54;54;55 16:03:03 INFO - PID 20038 | |9;json-stringify-tinderbox;41;44;43;46;46;47;40;45;41;41 16:03:03 INFO - PID 20038 | |10;stanford-crypto-aes;83;76;80;75;81;82;75;78;76;83 16:03:03 INFO - PID 20038 | |11;stanford-crypto-ccm;122;109;116;121;119;124;117;117;120;121 16:03:03 INFO - PID 20038 | |12;stanford-crypto-pbkdf2;183;167;175;171;168;169;170;170;169;169 16:03:03 INFO - PID 20038 | |13;stanford-crypto-sha256-iterative;66;65;66;74;68;66;67;66;66;65 16:03:03 INFO - PID 20038 | __end_tp_report 16:03:03 INFO - PID 20038 | __start_cc_report 16:03:03 INFO - PID 20038 | _x_x_mozilla_cycle_collect,757 16:03:03 INFO - PID 20038 | __end_cc_report 16:03:03 INFO - PID 20038 | __startTimestamp1506294183838__endTimestamp 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | ------- Summary: start ------- 16:03:03 INFO - PID 20038 | Number of tests: 14 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.50 stddev:2.74 (2.4%) stddev-sans-first:2.71 16:03:03 INFO - PID 20038 | Values: 110.0 115.0 108.0 115.0 109.0 115.0 115.0 113.0 114.0 114.0 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | [#1] audio-beat-detection Cycles:10 Average:125.60 Median:120.00 stddev:12.21 (10.2%) stddev-sans-first:12.92 16:03:03 INFO - PID 20038 | Values: 128.0 118.0 120.0 119.0 119.0 117.0 150.0 120.0 119.0 146.0 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | [#2] audio-dft Cycles:10 Average:168.30 Median:169.00 stddev:3.80 (2.2%) stddev-sans-first:3.91 16:03:03 INFO - PID 20038 | Values: 171.0 169.0 167.0 167.0 177.0 169.0 164.0 164.0 166.0 169.0 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | [#3] audio-fft Cycles:10 Average:78.30 Median:78.00 stddev:3.68 (4.7%) stddev-sans-first:3.49 16:03:03 INFO - PID 20038 | Values: 83.0 83.0 75.0 79.0 76.0 75.0 77.0 84.0 76.0 75.0 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | [#4] audio-oscillator Cycles:10 Average:82.80 Median:84.50 stddev:3.82 (4.5%) stddev-sans-first:4.06 16:03:03 INFO - PID 20038 | Values: 83.0 79.0 88.0 87.0 79.0 87.0 80.0 79.0 86.0 80.0 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:2.70 (2.3%) stddev-sans-first:2.86 16:03:03 INFO - PID 20038 | Values: 117.0 117.0 121.0 115.0 115.0 122.0 114.0 119.0 115.0 117.0 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | [#6] imaging-darkroom Cycles:10 Average:151.00 Median:149.00 stddev:3.97 (2.7%) stddev-sans-first:4.15 16:03:03 INFO - PID 20038 | Values: 149.0 149.0 150.0 149.0 149.0 159.0 158.0 149.0 149.0 149.0 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.00 stddev:2.21 (2.3%) stddev-sans-first:2.12 16:03:03 INFO - PID 20038 | Values: 99.0 97.0 94.0 96.0 94.0 96.0 96.0 101.0 95.0 95.0 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:54.00 stddev:1.99 (3.7%) stddev-sans-first:0.83 16:03:03 INFO - PID 20038 | Values: 60.0 54.0 56.0 53.0 54.0 54.0 54.0 54.0 54.0 55.0 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | [#9] json-stringify-tinderbox Cycles:10 Average:43.40 Median:44.50 stddev:2.55 (5.7%) stddev-sans-first:2.55 16:03:03 INFO - PID 20038 | Values: 41.0 44.0 43.0 46.0 46.0 47.0 40.0 45.0 41.0 41.0 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | [#10] stanford-crypto-aes Cycles:10 Average:78.90 Median:80.50 stddev:3.28 (4.1%) stddev-sans-first:3.13 16:03:03 INFO - PID 20038 | Values: 83.0 76.0 80.0 75.0 81.0 82.0 75.0 78.0 76.0 83.0 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | [#11] stanford-crypto-ccm Cycles:10 Average:118.60 Median:120.50 stddev:4.20 (3.5%) stddev-sans-first:4.27 16:03:03 INFO - PID 20038 | Values: 122.0 109.0 116.0 121.0 119.0 124.0 117.0 117.0 120.0 121.0 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.10 Median:170.00 stddev:4.70 (2.8%) stddev-sans-first:2.28 16:03:03 INFO - PID 20038 | Values: 183.0 167.0 175.0 171.0 168.0 169.0 170.0 170.0 169.0 169.0 16:03:03 INFO - PID 20038 | 16:03:03 INFO - PID 20038 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:66.00 stddev:2.64 (4.0%) stddev-sans-first:2.78 16:03:03 INFO - PID 20038 | Values: 66.0 65.0 66.0 74.0 68.0 66.0 67.0 66.0 66.0 65.0 16:03:03 INFO - PID 20038 | -------- Summary: end -------- 16:03:03 INFO - PID 20038 | 16:03:04 INFO - PID 20038 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:03:04 INFO - TEST-INFO | 20038: exit 0 16:03:04 INFO - TEST-OK | kraken | took 62855ms 16:03:04 INFO - SUITE-END | took 1177s 16:03:04 INFO - Completed test suite (00:19:37) 16:03:05 INFO - javascript benchmark 16:03:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8499.005506517875, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3145.905622831315, 3160.1941504916244, 3178.872845086197, 3184.6656101109397, 3186.3627274545097, 6208.6585237811905, 6352.68236588171, 6359.872802543958, 6368.5223608229335, 6490.058941453486, 6288.622682639045, 6317.6841157942035, 6318.304986451487, 6350.745970161188, 6367.64977926215, 5805.7387417566315, 5816.767329306823, 5823.883522329562, 5837.532997360222, 5891.204687367215, 5902.704864756766, 5929.466348028676, 5932.911006334887, 5991.940080599182, 5995.370486098962, 5885.0, 5925.348211696732, 5956.851078723023, 5959.16571679963, 5995.970020149894, 840.336134453782, 5772.797952071699, 5789.681567513785, 5799.9130013049835, 5918.615290006136, 5780.479756821865, 5811.0, 5812.360640329561, 5875.177475153439, 5880.882382352329, 6253.0, 6310.905336419957, 6340.587861788969, 6359.268684101341, 6379.744810207586, 6827.453803695693, 6830.931690683129, 6846.281140480228, 6860.279670634562, 6939.6877140528795, 6870.828229294308, 6882.414994725455, 6888.483363747689, 6893.551919125291, 7002.054722612484, 6144.109104179914, 6293.2762732285455, 6362.172917520693, 6362.331955144689, 6413.29453760086, 6926.757563485254, 6975.720971161097, 6985.615791131435, 6995.335443132894, 6999.335063168991, 6642.7342906284175, 6713.395794378432, 6736.6631668416385, 6761.932380676229, 6791.898121528181, 6782.796516104525, 6850.143732033496, 6903.689333979983, 6906.689198986058, 6920.411764999931, 6753.763618273337, 6780.559263647847, 6798.694058767367, 6903.378695917391, 6905.998630198593, 6623.701933413008, 6751.392374686204, 6758.729650814011, 6832.111825462658, 6905.792826215222, 5279.128943724242, 5281.577473802086, 5286.048511267932, 5288.788448462018, 5294.047071527085, 4633.235516139866, 4644.117617652635, 4707.693999889996, 4740.976295118502, 4767.332573439721, 5790.652560846278, 5860.794872179454, 5870.530357571384, 5874.9118763218585, 5921.5558833086825, 6831.829204269834, 6834.350736680008, 6869.587824730432, 6923.723051077901, 6951.582905025714, 5678.886422271531, 5719.8570035749435, 5817.38917413674, 5914.201582786355, 5927.8221653350465, 5741.885162296814, 5848.473637372657, 5915.674637894957, 5919.496842768327, 5920.8815823684145, 6618.8345291368105, 6634.303398143125, 6641.369069938445, 6666.933330666729, 6702.262751097375, 6841.828954276183, 6853.280405557444, 6856.588604683735, 6954.3045695431065, 6960.756373526902, 6412.935870641234, 6471.191101112361, 6521.576097553644, 6535.0, 6660.800175994728, 6617.43751781104, 6668.76659316941, 6671.899921501278, 6681.832954176184, 6686.331366863469, 6702.19573651165, 6773.458123350219, 6778.762743303961, 6829.0, 6940.0, 6671.232808227116, 6723.058771772054, 6724.058631791455, 6737.764178253738, 6762.459003279628, 6578.901316480161, 6648.235452922976, 6682.799516014527, 6686.665666716742, 6721.798346049626, 6391.360863913572, 6471.352864713491, 6471.482281417381, 6483.448906842971, 6616.470682345304, 6599.373059559334, 6613.272540020497, 6642.535022548375, 6667.133272674521, 6746.224184218878, 6255.311915689362, 6398.0, 6399.8080057598345, 6490.350964903472, 6504.642244676588], "value": 6170.459561757544, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.6190090657389, 811.2495941254339, 818.2921772666641, 827.6068867288035, 852.0967774159392, 41370.0, 41703.79148104263, 41798.0, 41821.0, 42432.36351454731, 38668.226635467276, 38763.0, 38798.0, 38957.0, 38963.80518097412, 6966.989786480957, 6979.651017449132, 7048.68280927357, 7049.295070492935, 7059.576425414466, 7064.152301723775, 7108.964455177716, 7113.217546069928, 7114.466415018868, 7130.680824047542, 7005.894911576331, 7122.323379278961, 7145.285471452865, 7158.677859496335, 7926.564038977883, 7997.7600671979935, 7999.96000020002, 8074.0714817796115, 8074.0714817796115, 8078.23256790604, 7922.168172341879, 8003.359731221488, 8009.479383840032, 8011.479253848481, 8049.436539442241, 36994.8150259247, 37362.81318593417, 38476.230475390614, 38669.8399048029, 39117.41323880144, 3508.719302455823, 3554.2180720241763, 3560.0565850049757, 3560.287942411528, 3576.910577235564, 32329.191770205696, 32357.0, 32388.514172287436, 32435.18912027218, 32549.186270343194, 4877.585405240558, 4924.679895806797, 4943.629227807893, 4950.282209079699, 4973.950260497385, 186710.0, 187510.0, 187775.0, 188101.0, 188335.99999999863, 42481.787591061846, 43341.56658433438, 48577.514224857296, 49056.54717264194, 49414.0, 50268.49731502638, 50297.24554131691, 50520.747396262785, 50663.0, 50739.492605074214, 15289.082655040733, 15478.5356439307, 15537.766933496006, 15596.922015390077, 15638.843611563967, 12627.936860315824, 12800.679983000313, 12807.551735689207, 12826.679333016562, 12827.10210285271, 13375.0, 13424.127431716906, 13610.93194534021, 13618.18290902549, 13643.863561364458, 5449.945500545023, 5471.23402723619, 5522.116461366162, 5540.972295138498, 5617.949448451286, 1017.2980643356209, 1018.6791160784329, 1025.8153532364097, 1026.0919086608255, 1034.6533911139702, 23421.648675269884, 23534.0, 23647.881760591088, 23687.407814804425, 23736.88131559366, 1108.9500972456096, 1131.4964840645835, 1133.9659810205708, 1142.6572028391615, 1144.9255798373079, 12388.11707209933, 12478.064145188966, 12553.811692824615, 12589.307588082744, 12678.936605316914, 211647.0, 212952.0, 227039.0, 228524.0, 229210.0, 51782.0, 52217.0, 52661.0, 52709.0, 53162.734186328824, 1420.2756594136858, 1446.8987170898147, 1467.8532146785235, 1467.9706405871823, 1470.2869108482378, 22485.32544023682, 23014.539709205386, 23020.309390718307, 23167.07331706713, 23282.185123520594, 37695.81152094222, 37766.811165944, 37874.053148671504, 37883.431748523246, 38242.61757382502, 189712.05143974192, 190534.04732976246, 190552.0, 190838.0, 192887.0, 30327.848360758937, 34010.0, 34476.13809654678, 34786.130346741535, 35282.64717352896, 44262.0, 44269.335959961274, 44475.77762111169, 44788.77605611951, 44859.55140448554, 31841.0, 32091.67908320887, 32181.230032348445, 32446.513302300013, 32511.67488325181, 31505.337136177994, 32073.0, 32309.0, 32396.676033239364, 32568.674313256564, 35193.82403088071, 35789.28421431505, 36147.63852361443, 36496.27007459889, 36926.261474770894, 31360.843195784786, 31720.841395792875, 31931.840340798146, 32063.67936320607, 32327.838360808044, 23282.417939551648, 23413.414664632837, 23742.169024084073, 23945.042198311872, 23993.640095398932], "value": 16962.16028945639, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.8463407197096, 580.2659635561016, 583.5652438932996, 603.8248907816734, 622.6015350175887, 21671.0, 22659.999999999978, 22663.09347626093, 22666.546669066607, 22681.20615778448, 22364.440888977784, 23780.5243895122, 23975.880120599373, 24173.63739543904, 24255.75744242557, 6293.5909165904295, 6306.747730090791, 6411.134496842937, 6419.518536109787, 6424.421802037816, 6360.55476116672, 6447.0, 6458.354164583551, 6459.740350631629, 6465.191851018622, 6296.1500197473215, 6434.06706027628, 6518.478521718251, 6582.8683426331445, 6604.57070290432, 6550.0829883816305, 6558.770443034518, 6572.342765723413, 6618.205815302146, 6622.106015687893, 5954.672493012882, 6090.33006369299, 6111.327753947062, 6124.785632502841, 6185.443310102112, 28483.85758071217, 28835.71164288372, 29061.709382906323, 29063.128106156848, 29095.12714618565, 2045.3863840847835, 2056.4344805178694, 2056.8354531637433, 2065.6281869263676, 2073.7200477935435, 19157.904210479, 19610.117544710523, 19692.40922772319, 20039.09824057921, 20181.091850866746, 2317.6755254264417, 2355.9057637694473, 2371.5019845832394, 2373.8575685458754, 2406.193925035115, 126027.0, 131429.0, 138695.0, 139066.0, 142238.99999999895, 14448.783268250985, 14603.758680512276, 14692.926535367254, 14850.53037608487, 14965.0, 22673.88663056707, 22971.081156753877, 23075.38472306956, 23123.07507699673, 23142.652860207112, 9747.171490423252, 9855.507224638739, 9982.0, 10052.195824334036, 10141.594336226468, 9157.908420915839, 9170.862437063315, 9227.169554740105, 9276.953615232016, 9333.160015598629, 8870.51212183336, 9041.81916361669, 9088.318376121817, 9188.081191880758, 9268.0, 2635.59148332005, 2699.1497678231685, 2705.485957668037, 2705.6888457827604, 2745.9450810983667, 930.4882314726873, 957.8563215517728, 966.3428868369576, 968.8934217236099, 970.1075010989765, 18035.368762093265, 18442.723359149622, 18475.445736627924, 18699.906500467412, 18731.812681873278, 1011.8735158105237, 1023.2172388123092, 1029.6344797596896, 1033.3231733214757, 1033.540074666766, 9889.554970026365, 10199.6163529553, 10262.43556604379, 10279.537420815932, 10348.5343159558, 450427.0, 450844.0, 459517.0, 460719.0, 463079.0, 111525.44237278761, 114381.85618143878, 117270.0, 118322.67547960363, 120810.39594802145, 1130.9943450282697, 1135.4776802670865, 1161.2800063960399, 1174.723939874129, 1177.8822117788325, 16114.597135071717, 16291.0, 16645.667086657955, 16729.163541822618, 16782.412615558398, 55086.72456637691, 58175.418245817, 58198.709006454694, 59264.70367648134, 59558.10662839974, 68460.0, 69878.30121698718, 71470.28529714844, 71831.28168718246, 71849.28150718634, 13649.11280766747, 13753.656158596115, 13856.30718464093, 14090.859091408955, 14172.220527871066, 21117.26089586857, 21271.57456850885, 21315.147394104682, 21482.24812131568, 21779.23772667949, 17403.47789566315, 17512.018149654574, 17770.5557361067, 17891.552711182325, 17956.461306160836, 15568.766468503209, 15963.0, 16237.18814059316, 16240.837591624406, 16354.345826167297, 16071.598210044842, 16090.67818643644, 16132.0, 16163.75754363662, 16232.513024609281, 13921.164730116225, 13922.791158132839, 14010.43958241659, 14038.0, 14056.859431405555, 15197.0, 15503.612409689847, 15580.220988950734, 17174.31302747926, 17303.653926921645], "value": 11634.398965490864, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1297.6431481342631, 1379.1724965020983, 1383.937722802474, 1384.3009280313443, 1394.6443656867498, 12641.936790316047, 13200.93399533001, 13227.537036203736, 13241.073124881263, 15303.540893773205, 15466.767998480032, 15794.0, 15797.368105275804, 15800.604984875385, 15815.920920395381, 2799.7760179185716, 2843.516602177635, 2862.656481222246, 2865.083173384518, 2869.067553045258, 2638.0634874619427, 2689.610006549049, 2708.1604702542277, 2724.6049322848176, 2896.0298300069503, 2871.2821794551364, 2872.9425411491757, 2883.1206482022976, 2885.956710649342, 2892.2913886097936, 2778.7776977841722, 2796.0353677981066, 2800.3139230888564, 2816.7605753510975, 2827.7455029047383, 2648.589468632365, 2689.690685571165, 2708.5124677558033, 2718.687350954646, 2724.3189202699327, 14771.261436928218, 14964.326605302787, 15085.54743357701, 15094.39622415113, 15119.244037798066, 8139.4709343892455, 8385.454945428588, 8424.199701028392, 8471.49171049739, 8504.404691671585, 5464.836054918319, 5614.831555053355, 5630.774769009235, 5645.5483561314995, 5693.430656934315, 1954.7067939809, 1962.617289628523, 1976.2589029114083, 1981.8612697111207, 1991.1239054815835, 25600.87199564009, 25787.226383208534, 25898.0, 25949.61075583868, 25976.87011564949, 6858.348456896587, 6905.309469053155, 7035.577865328097, 7091.361777440055, 7147.499675022803, 16948.74576881348, 16996.32014719422, 17009.31962721502, 17053.48839534791, 17120.828791712174, 8382.958085209535, 8604.440711353742, 8616.439931404439, 8722.476651400937, 8729.65081396737, 7701.075870895528, 7724.961375193088, 7730.845383092306, 7757.340626046736, 7791.220877912277, 5278.287431196816, 5438.374586922476, 5471.398146203915, 5475.863103422446, 5500.4048825840855, 1467.1417220925664, 1477.2392218007735, 1485.8959872809016, 1598.9360425582847, 1610.9919450402908, 866.9046404895456, 892.067789160329, 899.1143723432414, 900.594732370436, 913.5204017890661, 7729.49274891402, 8121.4721043132, 8478.872816907751, 8510.617022234015, 8651.610677519528, 938.9718308450757, 969.5636963366513, 981.6024510073431, 983.7786498037884, 1004.9397036177853, 5264.736763161826, 5293.867653308621, 5336.813211537577, 5414.296141501658, 5430.945690543123, 20803.1678732852, 22194.667079993815, 22433.887830560743, 22478.775212247994, 22540.774592253867, 20940.267090651752, 21070.683939740917, 21717.0, 21831.345059648236, 22058.88970555169, 11552.191346605658, 11905.702357441134, 11908.106891983136, 11913.285202887855, 11942.522299107939, 8245.79385515343, 8263.504189748634, 8263.586820658822, 8281.254687078192, 8390.28682561989, 17947.20527947247, 18721.812781872006, 18876.811231888056, 19185.712214317075, 19230.519237019187, 18837.529061773566, 19158.808411915703, 19658.60682786365, 19868.10593523266, 20284.898575507028, 10581.10060644823, 10674.0, 10699.14406847466, 10709.518071686949, 10711.250212485364, 14100.237470316211, 14173.36219870129, 14179.0, 14500.709985800437, 14646.194459304841, 12201.572944946884, 12261.570845020382, 12455.190412623151, 12640.87359126397, 12776.169548979287, 12157.1489995703, 12224.266544007389, 12408.193467424588, 12442.502299907903, 12528.436220369922, 9587.808243835223, 10157.644482443078, 10259.076683098558, 10272.537735802056, 10299.279050466395, 7565.2812982766545, 7570.9242907570215, 7596.80934007707, 7600.923990760021, 7647.388208943383, 7357.301056399633, 7405.259474052552, 7455.962720186364, 7471.663775130023, 7517.924820751722], "value": 6822.639219300489, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.384404857904, 510.26012282190834, 526.8761840967373, 532.5582205587383, 533.21373399113, 6285.5914365566205, 6296.370362963701, 6299.905501417476, 6385.744570217185, 6399.8080057598345, 6113.205283313163, 6146.723397447115, 6211.844703882394, 6232.407921247485, 6339.587926784756, 4880.5846304571805, 5091.643584949055, 5118.543099312333, 5143.0, 5164.122099243128, 4958.876028099308, 5008.974955125219, 5012.949870501285, 5019.447860735335, 5042.974785126069, 4954.504549545053, 4961.454240033593, 4969.180085285923, 4998.825041123561, 5017.272495488152, 4918.483559226302, 4919.90160196794, 4925.285833554115, 4973.950260497385, 5022.899542009158, 4482.372467854503, 4483.596476317147, 4488.618467430272, 4502.729836209838, 4518.683692141552, 6064.181335519693, 6171.722272497704, 6177.969110154466, 6198.907016394758, 6207.137858642389, 5699.914501282484, 5725.627834190765, 5727.0, 5750.396208398141, 5769.451902069297, 6381.691753190585, 6424.137000269916, 6435.517336199804, 6439.903401448982, 6442.774502892423, 4846.854594362175, 4860.125177468054, 4866.3673722416215, 4871.14754917888, 4889.242167464014, 6440.903386449207, 6559.672016399209, 6730.528862979642, 6731.865362692719, 6790.49071319653, 4994.051130285234, 5003.749812509433, 5030.622703297267, 5036.571891389273, 5042.899142017139, 5032.572231360376, 5040.429381015709, 5042.369703787027, 5077.365329333833, 5098.515641014098, 5551.250581171571, 5593.216949627055, 5615.157726341, 5678.0, 5682.488576028178, 6484.805455836333, 6527.1815987646805, 6540.542162048611, 6561.704723287464, 6572.732407319467, 1732.3629170434072, 1750.9474715758292, 1754.921028553718, 1757.8729737018095, 1780.9821901780817, 1179.2217136689915, 1220.9328486933305, 1242.9248699874543, 1245.4582256718356, 1250.831137796404, 5553.722313884289, 5574.665520068809, 5640.407757185519, 5648.74580643872, 5695.316562012585, 6618.238902526175, 6650.70071846768, 6675.232348279913, 6707.685848382301, 6721.395074443323, 5504.587155963319, 5522.337319521683, 5572.832815015556, 5641.210230567724, 5642.379338272786, 3472.4791281308007, 3504.491848681927, 3517.3668739627115, 3531.52324436203, 3549.5208146900354, 6414.594527052355, 6467.773627923, 6482.286948435668, 6489.1888513935755, 6556.246376521777, 6377.330380310001, 6788.660566971632, 6810.795676129724, 6883.139607549057, 6956.165260168812, 6215.720292586935, 6337.809865704036, 6380.393862583047, 6403.918726413841, 6412.871742565123, 6156.138140660221, 6527.804165875032, 6542.182227221598, 6564.277929427663, 6613.503987200883, 6473.805785826433, 6552.901706474311, 6569.80290591283, 6577.0, 6639.269680335063, 6417.326180750954, 6430.64631445275, 6436.485081193587, 6487.448566871869, 6508.316626754312, 4970.502949705001, 5038.622103342337, 5050.419201791841, 5050.621203409685, 5057.420715435081, 6411.198600174978, 6450.451711604566, 6545.476361891133, 6745.696443659949, 6765.898511522233, 6359.141515895295, 6419.775307864203, 6465.706858628388, 6469.7412103515335, 6552.6723663817575, 6341.936580634319, 6430.935690643034, 6458.54790164684, 6501.577397469149, 6517.4134327910715, 6412.807615771534, 6416.582922110048, 6472.805815825533, 6476.319986401549, 6479.384458476437, 6431.871362572816, 6543.280239173782, 6611.471082313499, 6617.569857959217, 6626.6686665667485, 6244.375562443901, 6291.276503202007, 6350.587211831216, 6359.173307470184, 6414.550981431255], "value": 5028.316385193403, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [914.2320450821311, 922.7600823785818, 930.279033748845, 938.9718308450747, 941.0918463682549, 19497.610047799037, 20045.699314510293, 20150.69773953392, 20231.898840505793, 20255.291064812754, 18001.189946452407, 18120.093995300216, 18196.545086372815, 18252.269909203616, 18323.450296491126, 954.9999999999983, 968.3995922528043, 973.3867663372066, 974.59554284972, 974.7952929884733, 977.2524019125375, 978.3640633588153, 979.7011911367031, 982.2682101834108, 985.526947065409, 940.1867384712243, 1018.9235807314444, 1020.647876482616, 1026.2508368890715, 1032.4889179855945, 1011.0496133634433, 1020.1634659579148, 1022.7494263905354, 1023.089450389158, 1026.6971243483154, 744.2483092076988, 744.437949348244, 746.7834328044861, 757.6704133701802, 757.9848403031964, 20421.69367459489, 20492.897535512227, 20744.0, 20940.371788846358, 21072.789272107388, 21861.344159675235, 22163.667544986838, 22239.44401389978, 22349.553008939893, 22616.886915565483, 16794.496165115066, 17453.7381939271, 17601.823981760146, 17663.11684415787, 17787.822121778747, 3789.2989796887387, 3820.102275965146, 3824.177801772604, 3836.156045669948, 3841.692664586826, 100428.49785751024, 104640.47679761553, 141097.29451352678, 142690.28654856764, 144213.0, 17442.56393590145, 17481.7377739334, 17610.911945440446, 17671.38150164738, 17690.469285921445, 31615.0, 31727.84136079305, 31739.04782856472, 31743.84128079391, 31944.0, 7978.042634883851, 8389.370797190235, 8389.664413423394, 8438.915610843937, 8460.280876125598, 4396.230659634552, 4444.599986001211, 4484.349769283435, 4496.460424749051, 4512.909741805145, 5339.279197308391, 5387.272718183073, 5404.216388623628, 5436.21174929633, 5474.753636086386, 1080.7460246841986, 1097.6048622495896, 1101.8347247912716, 1103.2553026706942, 1107.684309971654, 677.3429773120066, 684.7397988764237, 704.8872180451103, 708.0865683268526, 711.4557363616838, 12224.877751222552, 12355.69110772238, 12369.62891113268, 12951.676208094685, 13212.207267563977, 682.5120039172016, 683.9589624622539, 695.3394275438354, 699.2308460693197, 702.0522294901943, 5526.336839579196, 5618.381977982419, 5639.238702775073, 5659.745311460994, 5692.6299790513485, 122192.0, 127390.0, 127851.36074319569, 128736.35631821968, 132930.0, 112172.43913780378, 112851.0, 112926.0, 113017.0, 114144.0, 93611.0, 95963.52018239864, 96890.5155474218, 97147.0, 97489.51255243679, 10297.124744396511, 10451.477426128511, 10555.841662375224, 10558.841617375592, 10611.151107911193, 45305.0, 46923.76538117424, 47398.52601474079, 47441.81395465141, 47573.52426475829, 60448.0, 60862.391376085674, 62161.37838621556, 62532.37467625266, 64508.0, 15271.236438178268, 15402.306896189475, 15595.688086238439, 15610.21948902573, 15952.282147303167, 15330.846691533388, 15862.444814431901, 16007.759883601988, 16212.675746484769, 16277.837221627633, 15777.763333549776, 16003.599910002344, 16053.759193611872, 16114.758278626065, 16198.109103999393, 16126.516204513884, 16195.109268990318, 16481.17594120265, 16517.421890233785, 16746.916265418597, 15370.69258614799, 15376.923115384352, 15670.764938525703, 15675.764863526829, 15788.921055394649, 11709.0, 11819.0, 11877.46551405206, 11908.821367679666, 12063.638090857288, 11427.428628568705, 11445.885541144482, 11452.427378631202, 11645.47595358194, 11851.585194518151], "value": 9021.238340108626, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1466.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 115.0, 108.0, 115.0, 109.0, 115.0, 115.0, 113.0, 114.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 118.0, 120.0, 119.0, 119.0, 117.0, 150.0, 120.0, 119.0, 146.0], "value": 125.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 169.0, 167.0, 167.0, 177.0, 169.0, 164.0, 164.0, 166.0, 169.0], "value": 168.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 75.0, 79.0, 76.0, 75.0, 77.0, 84.0, 76.0, 75.0], "value": 78.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 79.0, 88.0, 87.0, 79.0, 87.0, 80.0, 79.0, 86.0, 80.0], "value": 82.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 121.0, 115.0, 115.0, 122.0, 114.0, 119.0, 115.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 149.0, 149.0, 159.0, 158.0, 149.0, 149.0, 149.0], "value": 151.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 97.0, 94.0, 96.0, 94.0, 96.0, 96.0, 101.0, 95.0, 95.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 54.0, 56.0, 53.0, 54.0, 54.0, 54.0, 54.0, 54.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [41.0, 44.0, 43.0, 46.0, 46.0, 47.0, 40.0, 45.0, 41.0, 41.0], "value": 43.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 76.0, 80.0, 75.0, 81.0, 82.0, 75.0, 78.0, 76.0, 83.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 109.0, 116.0, 121.0, 119.0, 124.0, 117.0, 117.0, 120.0, 121.0], "value": 118.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 167.0, 175.0, 171.0, 168.0, 169.0, 170.0, 170.0, 169.0, 169.0], "value": 171.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 74.0, 68.0, 66.0, 67.0, 66.0, 66.0, 65.0], "value": 66.9, "unit": "score"}]}]} 16:03:05 INFO - Return code: 0 16:03:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:03:05 INFO - # TBPL SUCCESS # 16:03:05 INFO - Running post-action listener: _package_coverage_data 16:03:05 INFO - Running post-action listener: _resource_record_post_action 16:03:05 INFO - [mozharness: 2017-09-24 23:03:05.106024Z] Finished run-tests step (success) 16:03:05 INFO - Running post-run listener: _resource_record_post_run 16:03:05 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 9973760; Write bytes: 343834624; Read time: 7584; Write time: 295292 16:03:05 INFO - TinderboxPrint: CPU usage
13.3% 16:03:05 INFO - TinderboxPrint: I/O read bytes / time
9,973,760 / 7,584 16:03:05 INFO - TinderboxPrint: I/O write bytes / time
343,834,624 / 295,292 16:03:05 INFO - TinderboxPrint: CPU idle
8,261.8 (86.7%) 16:03:05 INFO - TinderboxPrint: CPU user
1,203.1 (12.6%) 16:03:05 INFO - TinderboxPrint: Swap in / out
0 / 0 16:03:05 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13656064; Read time: 0; Write time: 1624 16:03:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:03:05 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 9969664; Write bytes: 330178560; Read time: 7564; Write time: 293668 16:03:05 INFO - Running post-run listener: _upload_blobber_files 16:03:05 INFO - Blob upload gear active. 16:03:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:03:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:03:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:03:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:03:06 INFO - (blobuploader) - INFO - Open directory for files ... 16:03:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:03:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:03:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:03:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:03:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:03:07 INFO - (blobuploader) - INFO - Done attempting. 16:03:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:03:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:03:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:03:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:03:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:03:13 INFO - (blobuploader) - INFO - Done attempting. 16:03:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:03:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:03:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:03:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:03:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:03:14 INFO - (blobuploader) - INFO - Done attempting. 16:03:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:03:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:03:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:03:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:03:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:03:15 INFO - (blobuploader) - INFO - Done attempting. 16:03:15 INFO - (blobuploader) - INFO - Iteration through files over. 16:03:15 INFO - Return code: 0 16:03:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:03:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:03:15 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ebe1f9724519346afdb3887513a4f6322f103897e80b7d0948eab0c8bd0e2f1256cdc4c18ae179416f137c9d52a47c476c88dd73b57c0bbd5de9a4e092b1d8a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8fd8b6bf7f4d29d1173f13c36b1d8bbd202bf12eee3d9ff413dce217f404e90c60f922e8c058c3fc38e39da538633bb6680247d9f3ed19817af581e980035df2", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ead61a73566ca671fb1dee247dd750df7f7a5bc4560c57636edd9f22bd7156526cef369c61c11dac76cf7881f89e615d97ed799bb411e77069e9659921ddc22", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/960b00f7bbbda0249a94504162581a7ae1ba58b43b73aaa2e0498f184148df6095af6baa72c949c2a7336bba9dc221f3179eacfcbe3cb9d42714d0afa1a06beb"} 16:03:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:03:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:03:15 INFO - Contents: 16:03:15 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ebe1f9724519346afdb3887513a4f6322f103897e80b7d0948eab0c8bd0e2f1256cdc4c18ae179416f137c9d52a47c476c88dd73b57c0bbd5de9a4e092b1d8a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8fd8b6bf7f4d29d1173f13c36b1d8bbd202bf12eee3d9ff413dce217f404e90c60f922e8c058c3fc38e39da538633bb6680247d9f3ed19817af581e980035df2", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ead61a73566ca671fb1dee247dd750df7f7a5bc4560c57636edd9f22bd7156526cef369c61c11dac76cf7881f89e615d97ed799bb411e77069e9659921ddc22", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/960b00f7bbbda0249a94504162581a7ae1ba58b43b73aaa2e0498f184148df6095af6baa72c949c2a7336bba9dc221f3179eacfcbe3cb9d42714d0afa1a06beb"} 16:03:15 INFO - Running post-run listener: copy_logs_to_upload_dir 16:03:15 INFO - Copying logs to upload dir... 16:03:15 INFO - mkdir: /builds/slave/test/build/upload/logs 16:03:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.307648 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-09-24 16:03:15.504770) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-24 16:03:15.512101) ========= 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=745a4373a2a16df560f216e000000264-1506289779.632996-117999451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ebe1f9724519346afdb3887513a4f6322f103897e80b7d0948eab0c8bd0e2f1256cdc4c18ae179416f137c9d52a47c476c88dd73b57c0bbd5de9a4e092b1d8a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8fd8b6bf7f4d29d1173f13c36b1d8bbd202bf12eee3d9ff413dce217f404e90c60f922e8c058c3fc38e39da538633bb6680247d9f3ed19817af581e980035df2", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ead61a73566ca671fb1dee247dd750df7f7a5bc4560c57636edd9f22bd7156526cef369c61c11dac76cf7881f89e615d97ed799bb411e77069e9659921ddc22", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/960b00f7bbbda0249a94504162581a7ae1ba58b43b73aaa2e0498f184148df6095af6baa72c949c2a7336bba9dc221f3179eacfcbe3cb9d42714d0afa1a06beb"} build_url:https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011417 build_url: 'https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ebe1f9724519346afdb3887513a4f6322f103897e80b7d0948eab0c8bd0e2f1256cdc4c18ae179416f137c9d52a47c476c88dd73b57c0bbd5de9a4e092b1d8a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8fd8b6bf7f4d29d1173f13c36b1d8bbd202bf12eee3d9ff413dce217f404e90c60f922e8c058c3fc38e39da538633bb6680247d9f3ed19817af581e980035df2", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ead61a73566ca671fb1dee247dd750df7f7a5bc4560c57636edd9f22bd7156526cef369c61c11dac76cf7881f89e615d97ed799bb411e77069e9659921ddc22", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/960b00f7bbbda0249a94504162581a7ae1ba58b43b73aaa2e0498f184148df6095af6baa72c949c2a7336bba9dc221f3179eacfcbe3cb9d42714d0afa1a06beb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-24 16:03:15.544065) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:03:15.544520) ========= 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=745a4373a2a16df560f216e000000264-1506289779.632996-117999451 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004502 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:03:15.600931) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-24 16:03:15.601264) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-24 16:03:15.601623) ========= ========= Total master_lag: 0.32 =========