builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-053 starttime: 1505403730.23 results: success (0) revision: ef0f1085d54eae99d62bbf039952b0deb84f396d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-14 08:42:10.226750) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-14 08:42:10.227418) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-14 08:42:10.227689) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-14 08:42:10.266817) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 08:42:10.267072) ========= 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=920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-14 08:42:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 54.7M=0s 2017-09-14 08:42:10 (54.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.085360 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 08:42:10.378660) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 08:42:10.378977) ========= 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=920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017891 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 08:42:10.431741) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 08:42:10.432043) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ef0f1085d54eae99d62bbf039952b0deb84f396d --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 ef0f1085d54eae99d62bbf039952b0deb84f396d --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=920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-14 08:42:10,408 truncating revision to first 12 chars 2017-09-14 08:42:10,408 Setting DEBUG logging. 2017-09-14 08:42:10,408 attempt 1/10 2017-09-14 08:42:10,408 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ef0f1085d54e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-14 08:42:10,662 unpacking tar archive at: mozilla-central-ef0f1085d54e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.550190 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 08:42:11.006106) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-14 08:42:11.009390) ========= script_repo_revision: ef0f1085d54eae99d62bbf039952b0deb84f396d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-14 08:42:11.009745) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-14 08:42:11.010005) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-14 08:42:11.025664) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-14 08:42:11.025931) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:42:11 INFO - MultiFileLogger online at 20170914 08:42:11 in /builds/slave/test 08:42:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 08:42:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:42:11 INFO - {'append_to_log': False, 08:42:11 INFO - 'base_work_dir': '/builds/slave/test', 08:42:11 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:42:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:42:11 INFO - 'branch': 'Firefox-Non-PGO', 08:42:11 INFO - 'buildbot_json_path': 'buildprops.json', 08:42:11 INFO - 'code_coverage': False, 08:42:11 INFO - 'config_files': ('talos/linux_config.py',), 08:42:11 INFO - 'default_actions': ('clobber', 08:42:11 INFO - 'read-buildbot-config', 08:42:11 INFO - 'download-and-extract', 08:42:11 INFO - 'populate-webroot', 08:42:11 INFO - 'create-virtualenv', 08:42:11 INFO - 'install', 08:42:11 INFO - 'setup-mitmproxy', 08:42:11 INFO - 'run-tests'), 08:42:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:42:11 INFO - 'disable_ccov_upload': False, 08:42:11 INFO - 'disable_stylo': False, 08:42:11 INFO - 'download_minidump_stackwalk': True, 08:42:11 INFO - 'download_symbols': 'ondemand', 08:42:11 INFO - 'enable_stylo': False, 08:42:11 INFO - 'enable_webrender': False, 08:42:11 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 08:42:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:42:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:42:11 INFO - 'gecko_profile': False, 08:42:11 INFO - 'gecko_profile_interval': 0, 08:42:11 INFO - 'installer_path': 'installer.exe', 08:42:11 INFO - 'log_level': 'info', 08:42:11 INFO - 'log_name': 'talos', 08:42:11 INFO - 'log_to_console': True, 08:42:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:42:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:42:11 INFO - 'opt_config_files': (), 08:42:11 INFO - 'pip_index': False, 08:42:11 INFO - 'suite': 'dromaeojs-e10s', 08:42:11 INFO - 'system_bits': '32', 08:42:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:42:11 INFO - 'title': 'talos-linux64-ix-053', 08:42:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:42:11 INFO - 'use_talos_json': True, 08:42:11 INFO - 'verify': 'False', 08:42:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:42:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:42:11 INFO - 'work_dir': 'build'} 08:42:11 INFO - [mozharness: 2017-09-14 15:42:11.144894Z] Running clobber step. 08:42:11 INFO - Running pre-action listener: _resource_record_pre_action 08:42:11 INFO - Running main action method: clobber 08:42:11 INFO - rmtree: /builds/slave/test/build 08:42:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:42:12 INFO - Running post-action listener: _resource_record_post_action 08:42:12 INFO - [mozharness: 2017-09-14 15:42:12.652114Z] Finished clobber step (success) 08:42:12 INFO - [mozharness: 2017-09-14 15:42:12.652211Z] Running read-buildbot-config step. 08:42:12 INFO - Running pre-action listener: _resource_record_pre_action 08:42:12 INFO - Running main action method: read_buildbot_config 08:42:12 INFO - Using buildbot properties: 08:42:12 INFO - { 08:42:12 INFO - "project": "", 08:42:12 INFO - "product": "firefox", 08:42:12 INFO - "who": "ryanvm@gmail.com", 08:42:12 INFO - "installer_path": "public/build/target.tar.bz2", 08:42:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 08:42:12 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 08:42:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 08:42:12 INFO - "stage_platform": "linux64", 08:42:12 INFO - "basedir": "/builds/slave/test", 08:42:12 INFO - "buildnumber": 825, 08:42:12 INFO - "platform": "ubuntu64_hw", 08:42:12 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:42:12 INFO - "slavebuilddir": "test", 08:42:12 INFO - "taskId": "WpdFWwXMTFSGPFBQ5j4YpQ", 08:42:12 INFO - "branch": "mozilla-central", 08:42:12 INFO - "script_repo_revision": "production", 08:42:12 INFO - "revision": "ef0f1085d54eae99d62bbf039952b0deb84f396d", 08:42:12 INFO - "slavename": "talos-linux64-ix-053", 08:42:12 INFO - "repo_path": "mozilla-central" 08:42:12 INFO - } 08:42:12 INFO - Finding installer, test and symbols from parent task. 08:42:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WpdFWwXMTFSGPFBQ5j4YpQ'}, attempt #1 08:42:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WpdFWwXMTFSGPFBQ5j4YpQ'}, attempt #1 08:42:13 INFO - Task dependencies: f-zK7lcmQRi0nOkT1XZjYQ 08:42:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ'}, attempt #1 08:42:14 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.tar.bz2 08:42:14 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.test_packages.json 08:42:14 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.crashreporter-symbols.zip 08:42:14 INFO - Running post-action listener: _resource_record_post_action 08:42:14 INFO - [mozharness: 2017-09-14 15:42:14.278638Z] Finished read-buildbot-config step (success) 08:42:14 INFO - [mozharness: 2017-09-14 15:42:14.278854Z] Running download-and-extract step. 08:42:14 INFO - Running pre-action listener: _resource_record_pre_action 08:42:14 INFO - Running main action method: download_and_extract 08:42:14 INFO - mkdir: /builds/slave/test/build/tests 08:42:14 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')]} 08:42:14 INFO - trying https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.test_packages.json 08:42:14 INFO - Downloading https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:42:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:42:14 INFO - Downloaded 989 bytes. 08:42:14 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:42:14 INFO - Using the following test package requirements: 08:42:14 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:42:14 INFO - u'common': [u'target.common.tests.zip'], 08:42:14 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:42:14 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:42:14 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:42:14 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:42:14 INFO - u'mozbase': [u'target.common.tests.zip'], 08:42:14 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:42:14 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:42:14 INFO - u'web-platform': [u'target.common.tests.zip', 08:42:14 INFO - u'target.web-platform.tests.tar.gz'], 08:42:14 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:42:14 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 08:42:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.common.tests.zip 08:42:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:42:14 INFO - Fetch https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.common.tests.zip into memory 08:42:16 INFO - Content-Length response header: 54593741 08:42:16 INFO - Bytes received: 54593741 08:42:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.talos.tests.zip 08:42:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:42:27 INFO - Fetch https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.talos.tests.zip into memory 08:42:28 INFO - Content-Length response header: 14500356 08:42:28 INFO - Bytes received: 14500356 08:42:28 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')]} 08:42:28 INFO - trying https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.tar.bz2 08:42:28 INFO - Downloading https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:42:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:42:31 INFO - Downloaded 61479243 bytes. 08:42:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.tar.bz2 08:42:32 INFO - mkdir: /builds/slave/test/properties 08:42:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:42:32 INFO - Writing to file /builds/slave/test/properties/build_url 08:42:32 INFO - Contents: 08:42:32 INFO - build_url:https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.tar.bz2 08:42:32 INFO - Running post-action listener: _resource_record_post_action 08:42:32 INFO - Running post-action listener: find_tests_for_verification 08:42:32 INFO - Running post-action listener: set_extra_try_arguments 08:42:32 INFO - [mozharness: 2017-09-14 15:42:32.266080Z] Finished download-and-extract step (success) 08:42:32 INFO - [mozharness: 2017-09-14 15:42:32.266200Z] Running populate-webroot step. 08:42:32 INFO - Running pre-action listener: _resource_record_pre_action 08:42:32 INFO - Running main action method: populate_webroot 08:42:32 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:42:32 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:32 INFO - 'tests': ['tresize', 08:42:32 INFO - 'tcanvasmark']}, 08:42:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:42:32 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:32 INFO - 'tests': ['dromaeo_css', 08:42:32 INFO - 'kraken']}, 08:42:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:42:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:42:32 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:42:32 INFO - 'talos_options': ['--disable-stylo'], 08:42:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:42:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:42:32 INFO - 'tests': ['damp', 'tps']}, 08:42:32 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:42:32 INFO - 'talos_options': ['--disable-stylo'], 08:42:32 INFO - 'tests': ['damp', 'tps']}, 08:42:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:42:32 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:32 INFO - 'tests': ['dromaeo_dom']}, 08:42:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:42:32 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:32 INFO - 'tests': ['basic_compositor_video', 08:42:32 INFO - 'glvideo']}, 08:42:32 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:42:32 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:42:32 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:42:32 INFO - 'talos_options': ['--disable-stylo'], 08:42:32 INFO - 'tests': ['ts_paint_webext', 08:42:32 INFO - 'tp5o_webext']}, 08:42:32 INFO - 'other-e10s': {'tests': ['a11yr', 08:42:32 INFO - 'ts_paint', 08:42:32 INFO - 'tpaint', 08:42:32 INFO - 'sessionrestore', 08:42:32 INFO - 'sessionrestore_many_windows', 08:42:32 INFO - 'sessionrestore_no_auto_restore', 08:42:32 INFO - 'tabpaint']}, 08:42:32 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:32 INFO - 'tests': ['a11yr', 08:42:32 INFO - 'ts_paint', 08:42:32 INFO - 'tpaint', 08:42:32 INFO - 'sessionrestore', 08:42:32 INFO - 'sessionrestore_many_windows', 08:42:32 INFO - 'sessionrestore_no_auto_restore', 08:42:32 INFO - 'tabpaint']}, 08:42:32 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 08:42:32 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 08:42:32 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:32 INFO - 'tests': ['bloom_basic_singleton']}, 08:42:32 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:32 INFO - 'tests': ['bloom_basic']}, 08:42:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:42:32 INFO - 'tsvgr_opacity', 08:42:32 INFO - 'tart', 08:42:32 INFO - 'tscrollx', 08:42:32 INFO - 'tsvg_static']}, 08:42:32 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 08:42:32 INFO - 'tests': ['tsvgx', 08:42:32 INFO - 'tsvgr_opacity', 08:42:32 INFO - 'tart', 08:42:32 INFO - 'tscrollx', 08:42:32 INFO - 'tsvg_static']}, 08:42:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:42:32 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:42:32 INFO - 'talos_options': ['--disable-stylo'], 08:42:32 INFO - 'tests': ['tp5o']}, 08:42:32 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:42:32 INFO - 'talos_options': ['--mitmproxy', 08:42:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:42:32 INFO - '--firstNonBlankPaint'], 08:42:32 INFO - 'tests': ['tp6_google', 08:42:32 INFO - 'tp6_youtube', 08:42:32 INFO - 'tp6_amazon', 08:42:32 INFO - 'tp6_facebook']}, 08:42:32 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:42:32 INFO - 'talos_options': ['--disable-stylo', 08:42:32 INFO - '--mitmproxy', 08:42:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:42:32 INFO - '--firstNonBlankPaint'], 08:42:32 INFO - 'tests': ['tp6_google', 08:42:32 INFO - 'tp6_youtube', 08:42:32 INFO - 'tp6_amazon', 08:42:32 INFO - 'tp6_facebook']}, 08:42:32 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:42:32 INFO - 'talos_options': ['--stylo-threads=1', 08:42:32 INFO - '--mitmproxy', 08:42:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:42:32 INFO - '--firstNonBlankPaint'], 08:42:32 INFO - 'tests': ['tp6_google', 08:42:32 INFO - 'tp6_youtube', 08:42:32 INFO - 'tp6_amazon', 08:42:32 INFO - 'tp6_facebook']}, 08:42:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:42:32 INFO - 'talos_options': ['--xperf_path', 08:42:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:42:32 INFO - 'tests': ['tp5n']}, 08:42:32 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 08:42:32 INFO - 'talos_options': ['--disable-stylo', 08:42:32 INFO - '--xperf_path', 08:42:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:42:32 INFO - 'tests': ['tp5n']}}} 08:42:32 INFO - Running post-action listener: _resource_record_post_action 08:42:32 INFO - [mozharness: 2017-09-14 15:42:32.284021Z] Finished populate-webroot step (success) 08:42:32 INFO - [mozharness: 2017-09-14 15:42:32.284113Z] Running create-virtualenv step. 08:42:32 INFO - Running pre-action listener: _resource_record_pre_action 08:42:32 INFO - Running main action method: create_virtualenv 08:42:32 INFO - Creating virtualenv /builds/slave/test/build/venv 08:42:32 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:42:32 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 08:42:32 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:42:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:42:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:42:32 INFO - Installing distribute.............................................................................................................................................................................................done. 08:42:33 INFO - Installing pip...............done. 08:42:33 INFO - Return code: 0 08:42:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:42:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:42:33 INFO - Reading from file tmpfile_stdout 08:42:33 INFO - Output received: 08:42:33 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 08:42:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:42:33 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')]} 08:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:33 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')]} 08:42:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:33 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 0x23351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a5090>, '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 0x26a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269ff90>, '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': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', '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 08:42:33 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 08:42:33 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 08:42:33 INFO - Using env: {'DISPLAY': ':0', 08:42:33 INFO - 'HOME': '/home/cltbld', 08:42:33 INFO - 'LANG': 'en_US.UTF-8', 08:42:33 INFO - 'LANGUAGE': 'en_US:en', 08:42:33 INFO - 'LOGNAME': 'cltbld', 08:42:33 INFO - 'MAIL': '/var/mail/cltbld', 08:42:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:33 INFO - 'MOZ_NO_REMOTE': '1', 08:42:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:33 INFO - 'NO_EM_RESTART': '1', 08:42:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:33 INFO - 'PWD': '/builds/slave/test', 08:42:33 INFO - 'SHELL': '/bin/bash', 08:42:33 INFO - 'SHLVL': '1', 08:42:33 INFO - 'TERM': 'linux', 08:42:33 INFO - 'TMOUT': '86400', 08:42:33 INFO - 'USER': 'cltbld', 08:42:33 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', 08:42:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:33 INFO - '_': '/tools/buildbot/bin/python'} 08:42:34 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:42:34 INFO - Downloading/unpacking pip>=1.5 08:42:34 INFO - Running setup.py egg_info for package pip 08:42:34 INFO - warning: no files found matching 'pip/cacert.pem' 08:42:34 INFO - warning: no files found matching '*.html' under directory 'docs' 08:42:34 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:42:34 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:42:34 INFO - Installing collected packages: pip 08:42:34 INFO - Found existing installation: pip 1.1 08:42:34 INFO - Uninstalling pip: 08:42:34 INFO - Successfully uninstalled pip 08:42:34 INFO - Running setup.py install for pip 08:42:34 INFO - warning: no files found matching 'pip/cacert.pem' 08:42:34 INFO - warning: no files found matching '*.html' under directory 'docs' 08:42:34 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:42:34 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:42:34 INFO - Installing pip script to /builds/slave/test/build/venv/bin 08:42:34 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 08:42:34 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 08:42:34 INFO - Successfully installed pip 08:42:34 INFO - Cleaning up... 08:42:34 INFO - Return code: 0 08:42:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:42:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a5090>, '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 0x26a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269ff90>, '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': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', '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 08:42:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:42:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:42:34 INFO - Using env: {'DISPLAY': ':0', 08:42:34 INFO - 'HOME': '/home/cltbld', 08:42:34 INFO - 'LANG': 'en_US.UTF-8', 08:42:34 INFO - 'LANGUAGE': 'en_US:en', 08:42:34 INFO - 'LOGNAME': 'cltbld', 08:42:34 INFO - 'MAIL': '/var/mail/cltbld', 08:42:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:34 INFO - 'MOZ_NO_REMOTE': '1', 08:42:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:34 INFO - 'NO_EM_RESTART': '1', 08:42:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:34 INFO - 'PWD': '/builds/slave/test', 08:42:34 INFO - 'SHELL': '/bin/bash', 08:42:34 INFO - 'SHLVL': '1', 08:42:34 INFO - 'TERM': 'linux', 08:42:34 INFO - 'TMOUT': '86400', 08:42:34 INFO - 'USER': 'cltbld', 08:42:34 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', 08:42:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:34 INFO - '_': '/tools/buildbot/bin/python'} 08:42:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:34 INFO - Downloading/unpacking psutil>=3.1.1 08:42:34 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 08:42:34 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 08:42:34 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 08:42:34 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 08:42:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:42:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:42:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:42:35 INFO - Installing collected packages: psutil 08:42:35 INFO - Running setup.py install for psutil 08:42:35 INFO - building 'psutil._psutil_linux' extension 08:42:35 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 08:42:36 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 08:42:36 INFO - building 'psutil._psutil_posix' extension 08:42:36 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 08:42:36 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 08:42:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:42:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:42:36 INFO - Successfully installed psutil 08:42:36 INFO - Cleaning up... 08:42:36 INFO - Return code: 0 08:42:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:42:36 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')]} 08:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:36 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')]} 08:42:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:36 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 0x23351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a5090>, '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 0x26a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269ff90>, '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': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', '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 08:42:36 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 08:42:36 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 08:42:36 INFO - Using env: {'DISPLAY': ':0', 08:42:36 INFO - 'HOME': '/home/cltbld', 08:42:36 INFO - 'LANG': 'en_US.UTF-8', 08:42:36 INFO - 'LANGUAGE': 'en_US:en', 08:42:36 INFO - 'LOGNAME': 'cltbld', 08:42:36 INFO - 'MAIL': '/var/mail/cltbld', 08:42:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:36 INFO - 'MOZ_NO_REMOTE': '1', 08:42:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:36 INFO - 'NO_EM_RESTART': '1', 08:42:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:36 INFO - 'PWD': '/builds/slave/test', 08:42:36 INFO - 'SHELL': '/bin/bash', 08:42:36 INFO - 'SHLVL': '1', 08:42:36 INFO - 'TERM': 'linux', 08:42:36 INFO - 'TMOUT': '86400', 08:42:36 INFO - 'USER': 'cltbld', 08:42:36 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', 08:42:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:36 INFO - '_': '/tools/buildbot/bin/python'} 08:42:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:42:36 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 08:42:36 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 08:42:36 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 08:42:36 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 08:42:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:42:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:42:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:42:37 INFO - Installing collected packages: mozsystemmonitor 08:42:37 INFO - Running setup.py install for mozsystemmonitor 08:42:38 INFO - Successfully installed mozsystemmonitor 08:42:38 INFO - Cleaning up... 08:42:38 INFO - Return code: 0 08:42:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:42:38 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')]} 08:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:38 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')]} 08:42:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:38 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 0x23351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a5090>, '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 0x26a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269ff90>, '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': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', '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 08:42:38 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 08:42:38 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 08:42:38 INFO - Using env: {'DISPLAY': ':0', 08:42:38 INFO - 'HOME': '/home/cltbld', 08:42:38 INFO - 'LANG': 'en_US.UTF-8', 08:42:38 INFO - 'LANGUAGE': 'en_US:en', 08:42:38 INFO - 'LOGNAME': 'cltbld', 08:42:38 INFO - 'MAIL': '/var/mail/cltbld', 08:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:38 INFO - 'MOZ_NO_REMOTE': '1', 08:42:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:38 INFO - 'NO_EM_RESTART': '1', 08:42:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:38 INFO - 'PWD': '/builds/slave/test', 08:42:38 INFO - 'SHELL': '/bin/bash', 08:42:38 INFO - 'SHLVL': '1', 08:42:38 INFO - 'TERM': 'linux', 08:42:38 INFO - 'TMOUT': '86400', 08:42:38 INFO - 'USER': 'cltbld', 08:42:38 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', 08:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:38 INFO - '_': '/tools/buildbot/bin/python'} 08:42:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:38 INFO - Downloading/unpacking jsonschema==2.5.1 08:42:38 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 08:42:38 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 08:42:38 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 08:42:38 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 08:42:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:42:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:40 INFO - Downloading functools32-3.2.3-2.tar.gz 08:42:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:42:40 INFO - warning: no files found matching '*.txt' 08:42:40 INFO - no previously-included directories found matching 'build' 08:42:40 INFO - no previously-included directories found matching 'dist' 08:42:40 INFO - no previously-included directories found matching '.git*' 08:42:40 INFO - Installing collected packages: jsonschema, functools32 08:42:40 INFO - Running setup.py install for functools32 08:42:40 INFO - warning: no files found matching '*.txt' 08:42:40 INFO - no previously-included directories found matching 'build' 08:42:40 INFO - no previously-included directories found matching 'dist' 08:42:40 INFO - no previously-included directories found matching '.git*' 08:42:40 INFO - Successfully installed jsonschema functools32 08:42:40 INFO - Cleaning up... 08:42:40 INFO - Return code: 0 08:42:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:42:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a5090>, '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 0x26a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269ff90>, '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': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', '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 08:42:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:42:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:42:40 INFO - Using env: {'DISPLAY': ':0', 08:42:40 INFO - 'HOME': '/home/cltbld', 08:42:40 INFO - 'LANG': 'en_US.UTF-8', 08:42:40 INFO - 'LANGUAGE': 'en_US:en', 08:42:40 INFO - 'LOGNAME': 'cltbld', 08:42:40 INFO - 'MAIL': '/var/mail/cltbld', 08:42:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:40 INFO - 'MOZ_NO_REMOTE': '1', 08:42:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:40 INFO - 'NO_EM_RESTART': '1', 08:42:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:40 INFO - 'PWD': '/builds/slave/test', 08:42:40 INFO - 'SHELL': '/bin/bash', 08:42:40 INFO - 'SHLVL': '1', 08:42:40 INFO - 'TERM': 'linux', 08:42:40 INFO - 'TMOUT': '86400', 08:42:40 INFO - 'USER': 'cltbld', 08:42:40 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', 08:42:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:40 INFO - '_': '/tools/buildbot/bin/python'} 08:42:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:42:40 INFO - Cleaning up... 08:42:40 INFO - Return code: 0 08:42:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:42:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a5090>, '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 0x26a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269ff90>, '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': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', '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 08:42:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:42:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:42:40 INFO - Using env: {'DISPLAY': ':0', 08:42:40 INFO - 'HOME': '/home/cltbld', 08:42:40 INFO - 'LANG': 'en_US.UTF-8', 08:42:40 INFO - 'LANGUAGE': 'en_US:en', 08:42:40 INFO - 'LOGNAME': 'cltbld', 08:42:40 INFO - 'MAIL': '/var/mail/cltbld', 08:42:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:40 INFO - 'MOZ_NO_REMOTE': '1', 08:42:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:40 INFO - 'NO_EM_RESTART': '1', 08:42:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:40 INFO - 'PWD': '/builds/slave/test', 08:42:40 INFO - 'SHELL': '/bin/bash', 08:42:40 INFO - 'SHLVL': '1', 08:42:40 INFO - 'TERM': 'linux', 08:42:40 INFO - 'TMOUT': '86400', 08:42:40 INFO - 'USER': 'cltbld', 08:42:40 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', 08:42:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:40 INFO - '_': '/tools/buildbot/bin/python'} 08:42:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:40 INFO - Downloading/unpacking blobuploader==1.2.4 08:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:41 INFO - Downloading blobuploader-1.2.4.tar.gz 08:42:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:42:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:42:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:42:42 INFO - Downloading docopt-0.6.1.tar.gz 08:42:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:42:42 INFO - Installing collected packages: blobuploader, requests, docopt 08:42:42 INFO - Running setup.py install for blobuploader 08:42:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:42:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:42:42 INFO - Running setup.py install for requests 08:42:42 INFO - Running setup.py install for docopt 08:42:43 INFO - Successfully installed blobuploader requests docopt 08:42:43 INFO - Cleaning up... 08:42:43 INFO - Return code: 0 08:42:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a5090>, '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 0x26a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269ff90>, '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': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', '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 08:42:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:42:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:42:43 INFO - Using env: {'DISPLAY': ':0', 08:42:43 INFO - 'HOME': '/home/cltbld', 08:42:43 INFO - 'LANG': 'en_US.UTF-8', 08:42:43 INFO - 'LANGUAGE': 'en_US:en', 08:42:43 INFO - 'LOGNAME': 'cltbld', 08:42:43 INFO - 'MAIL': '/var/mail/cltbld', 08:42:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:43 INFO - 'MOZ_NO_REMOTE': '1', 08:42:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:43 INFO - 'NO_EM_RESTART': '1', 08:42:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:43 INFO - 'PWD': '/builds/slave/test', 08:42:43 INFO - 'SHELL': '/bin/bash', 08:42:43 INFO - 'SHLVL': '1', 08:42:43 INFO - 'TERM': 'linux', 08:42:43 INFO - 'TMOUT': '86400', 08:42:43 INFO - 'USER': 'cltbld', 08:42:43 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', 08:42:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:43 INFO - '_': '/tools/buildbot/bin/python'} 08:42:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:42:43 INFO - Running setup.py (path:/tmp/pip-UR8m4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:42:43 INFO - Running setup.py (path:/tmp/pip-ohQvPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:42:43 INFO - Running setup.py (path:/tmp/pip-JFJOva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:42:43 INFO - Running setup.py (path:/tmp/pip-0VxsK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:42:43 INFO - Running setup.py (path:/tmp/pip-jsOg1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:42:43 INFO - Running setup.py (path:/tmp/pip-ltGPHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:42:43 INFO - Running setup.py (path:/tmp/pip-Bz0af4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:42:43 INFO - Running setup.py (path:/tmp/pip-BSGo3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:42:43 INFO - Running setup.py (path:/tmp/pip-IHPFcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:42:43 INFO - Running setup.py (path:/tmp/pip-IVbeR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:42:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:42:43 INFO - Running setup.py (path:/tmp/pip-oGxM6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:42:44 INFO - Running setup.py (path:/tmp/pip-NfB_o8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:42:44 INFO - Running setup.py (path:/tmp/pip-BxlaOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:42:44 INFO - Running setup.py (path:/tmp/pip-nAp3yb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:42:44 INFO - Running setup.py (path:/tmp/pip-ULX0f_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:42:44 INFO - Running setup.py (path:/tmp/pip-Uw5cMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:42:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:42:44 INFO - Running setup.py (path:/tmp/pip-XRp_d2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:42:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:42:44 INFO - Running setup.py install for manifestparser 08:42:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:42:44 INFO - Running setup.py install for mozcrash 08:42:44 INFO - Running setup.py install for mozdebug 08:42:44 INFO - Running setup.py install for mozdevice 08:42:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:42:44 INFO - Running setup.py install for mozfile 08:42:44 INFO - Running setup.py install for mozhttpd 08:42:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:42:45 INFO - Running setup.py install for mozinfo 08:42:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:42:45 INFO - Running setup.py install for mozInstall 08:42:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:42:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:42:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:42:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:42:45 INFO - Running setup.py install for mozleak 08:42:45 INFO - Running setup.py install for mozlog 08:42:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:42:45 INFO - Running setup.py install for moznetwork 08:42:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:42:45 INFO - Running setup.py install for mozprocess 08:42:45 INFO - Running setup.py install for mozprofile 08:42:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:42:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:42:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:42:45 INFO - Running setup.py install for mozrunner 08:42:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:42:45 INFO - Running setup.py install for mozscreenshot 08:42:46 INFO - Running setup.py install for moztest 08:42:46 INFO - Running setup.py install for mozversion 08:42:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:42:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:42:46 INFO - Cleaning up... 08:42:46 INFO - Return code: 0 08:42:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a5090>, '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 0x26a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269ff90>, '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': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', '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 08:42:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:42:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:42:46 INFO - Using env: {'DISPLAY': ':0', 08:42:46 INFO - 'HOME': '/home/cltbld', 08:42:46 INFO - 'LANG': 'en_US.UTF-8', 08:42:46 INFO - 'LANGUAGE': 'en_US:en', 08:42:46 INFO - 'LOGNAME': 'cltbld', 08:42:46 INFO - 'MAIL': '/var/mail/cltbld', 08:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:46 INFO - 'MOZ_NO_REMOTE': '1', 08:42:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:46 INFO - 'NO_EM_RESTART': '1', 08:42:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:46 INFO - 'PWD': '/builds/slave/test', 08:42:46 INFO - 'SHELL': '/bin/bash', 08:42:46 INFO - 'SHLVL': '1', 08:42:46 INFO - 'TERM': 'linux', 08:42:46 INFO - 'TMOUT': '86400', 08:42:46 INFO - 'USER': 'cltbld', 08:42:46 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', 08:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:46 INFO - '_': '/tools/buildbot/bin/python'} 08:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:42:46 INFO - Running setup.py (path:/tmp/pip-Yyn0no-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:42:46 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)) 08:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:42:46 INFO - Running setup.py (path:/tmp/pip-X7SANr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:42:46 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)) 08:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:42:46 INFO - Running setup.py (path:/tmp/pip-VFWmt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:42:46 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)) 08:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:42:46 INFO - Running setup.py (path:/tmp/pip-9AhB3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:42:46 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)) 08:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:42:46 INFO - Running setup.py (path:/tmp/pip-qNOGGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:42:46 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)) 08:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:42:46 INFO - Running setup.py (path:/tmp/pip-it1NkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:42:46 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)) 08:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:42:46 INFO - Running setup.py (path:/tmp/pip-ouUW2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:42:46 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)) 08:42:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:42:46 INFO - Running setup.py (path:/tmp/pip-LyYPb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:42:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:42:47 INFO - Running setup.py (path:/tmp/pip-t16DDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:42:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:42:47 INFO - Running setup.py (path:/tmp/pip-1pDxTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:42:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:42:47 INFO - Running setup.py (path:/tmp/pip-qDFV6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:42:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:42:47 INFO - Running setup.py (path:/tmp/pip-6gAE4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:42:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:42:47 INFO - Running setup.py (path:/tmp/pip-hiZSS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:42:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:42:47 INFO - Running setup.py (path:/tmp/pip-6R0TpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:42:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:42:47 INFO - Running setup.py (path:/tmp/pip-rnX3aR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:42:47 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)) 08:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:42:47 INFO - Running setup.py (path:/tmp/pip-VGIv9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:42:47 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)) 08:42:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:42:47 INFO - Running setup.py (path:/tmp/pip-D5hvAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:42:47 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)) 08:42:47 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)) 08:42:47 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)) 08:42:47 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)) 08:42:47 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)) 08:42:47 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)) 08:42:47 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)) 08:42:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:42:47 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 08:42:47 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 08:42:47 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 08:42:47 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 08:42:49 INFO - Downloading blessings-1.6.tar.gz 08:42:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:42:49 INFO - Installing collected packages: blessings 08:42:49 INFO - Running setup.py install for blessings 08:42:49 INFO - Successfully installed blessings 08:42:49 INFO - Cleaning up... 08:42:50 INFO - Return code: 0 08:42:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:42:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:42:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:42:50 INFO - Reading from file tmpfile_stdout 08:42:50 INFO - Current package versions: 08:42:50 INFO - argparse == 1.2.1 08:42:50 INFO - blessings == 1.6 08:42:50 INFO - blobuploader == 1.2.4 08:42:50 INFO - docopt == 0.6.1 08:42:50 INFO - functools32 == 3.2.3-2 08:42:50 INFO - jsonschema == 2.5.1 08:42:50 INFO - manifestparser == 1.1 08:42:50 INFO - mozInstall == 1.13 08:42:50 INFO - mozcrash == 1.0 08:42:50 INFO - mozdebug == 0.1 08:42:50 INFO - mozdevice == 0.51 08:42:50 INFO - mozfile == 1.2 08:42:50 INFO - mozhttpd == 0.7 08:42:50 INFO - mozinfo == 0.10 08:42:50 INFO - mozleak == 0.1 08:42:50 INFO - mozlog == 3.5 08:42:50 INFO - moznetwork == 0.27 08:42:50 INFO - mozprocess == 0.25 08:42:50 INFO - mozprofile == 0.28 08:42:50 INFO - mozrunner == 6.13 08:42:50 INFO - mozscreenshot == 0.1 08:42:50 INFO - mozsystemmonitor == 0.3 08:42:50 INFO - moztest == 0.8 08:42:50 INFO - mozversion == 1.4 08:42:50 INFO - psutil == 3.1.1 08:42:50 INFO - requests == 1.2.3 08:42:50 INFO - wsgiref == 0.1.2 08:42:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:42:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:50 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 0x23351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a5090>, '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 0x26a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269ff90>, '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': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', '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 08:42:50 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 08:42:50 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 08:42:50 INFO - Using env: {'DISPLAY': ':0', 08:42:50 INFO - 'HOME': '/home/cltbld', 08:42:50 INFO - 'LANG': 'en_US.UTF-8', 08:42:50 INFO - 'LANGUAGE': 'en_US:en', 08:42:50 INFO - 'LOGNAME': 'cltbld', 08:42:50 INFO - 'MAIL': '/var/mail/cltbld', 08:42:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:50 INFO - 'MOZ_NO_REMOTE': '1', 08:42:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:50 INFO - 'NO_EM_RESTART': '1', 08:42:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:50 INFO - 'PWD': '/builds/slave/test', 08:42:50 INFO - 'SHELL': '/bin/bash', 08:42:50 INFO - 'SHLVL': '1', 08:42:50 INFO - 'TERM': 'linux', 08:42:50 INFO - 'TMOUT': '86400', 08:42:50 INFO - 'USER': 'cltbld', 08:42:50 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', 08:42:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:50 INFO - '_': '/tools/buildbot/bin/python'} 08:42:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:50 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)) 08:42:50 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)) 08:42:50 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)) 08:42:50 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)) 08:42:50 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)) 08:42:50 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)) 08:42:50 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)) 08:42:50 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)) 08:42:50 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)) 08:42:50 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08: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 08: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 08: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 08: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 08:42:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 08:42:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:42:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:42:51 INFO - Installing collected packages: simplejson 08:42:51 INFO - Running setup.py install for simplejson 08:42:51 INFO - building 'simplejson._speedups' extension 08:42:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 08:42:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 08:42:52 INFO - Successfully installed simplejson 08:42:52 INFO - Cleaning up... 08:42:52 INFO - Return code: 0 08:42:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:42:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23351f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24fea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26a1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26a5090>, '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 0x26a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269ff90>, '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': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', '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 08:42:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:42:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:42:52 INFO - Using env: {'DISPLAY': ':0', 08:42:52 INFO - 'HOME': '/home/cltbld', 08:42:52 INFO - 'LANG': 'en_US.UTF-8', 08:42:52 INFO - 'LANGUAGE': 'en_US:en', 08:42:52 INFO - 'LOGNAME': 'cltbld', 08:42:52 INFO - 'MAIL': '/var/mail/cltbld', 08:42:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:42:52 INFO - 'MOZ_NO_REMOTE': '1', 08:42:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:42:52 INFO - 'NO_EM_RESTART': '1', 08:42:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:42:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:42:52 INFO - 'PWD': '/builds/slave/test', 08:42:52 INFO - 'SHELL': '/bin/bash', 08:42:52 INFO - 'SHLVL': '1', 08:42:52 INFO - 'TERM': 'linux', 08:42:52 INFO - 'TMOUT': '86400', 08:42:52 INFO - 'USER': 'cltbld', 08:42:52 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', 08:42:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:42:52 INFO - '_': '/tools/buildbot/bin/python'} 08:42:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:42:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:42:52 INFO - Cleaning up... 08:42:52 INFO - Return code: 0 08:42:52 INFO - Running post-action listener: _resource_record_post_action 08:42:52 INFO - Running post-action listener: _start_resource_monitoring 08:42:52 INFO - Starting resource monitoring. 08:42:52 INFO - [mozharness: 2017-09-14 15:42:52.951313Z] Finished create-virtualenv step (success) 08:42:52 INFO - [mozharness: 2017-09-14 15:42:52.951777Z] Running install step. 08:42:52 INFO - Running pre-action listener: _resource_record_pre_action 08:42:52 INFO - Running main action method: install 08:42:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:42:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:42:53 INFO - Reading from file tmpfile_stdout 08:42:53 INFO - Detecting whether we're running mozinstall >=1.0... 08:42:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:42:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:42:53 INFO - Reading from file tmpfile_stdout 08:42:53 INFO - Output received: 08:42:53 INFO - Usage: mozinstall [options] installer 08:42:53 INFO - Options: 08:42:53 INFO - -h, --help show this help message and exit 08:42:53 INFO - -d DEST, --destination=DEST 08:42:53 INFO - Directory to install application into. [default: 08:42:53 INFO - "/builds/slave/test"] 08:42:53 INFO - --app=APP Application being installed. [default: firefox] 08:42:53 INFO - mkdir: /builds/slave/test/build/application 08:42:53 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'] 08:42:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:43:11 INFO - Reading from file tmpfile_stdout 08:43:11 INFO - Output received: 08:43:11 INFO - /builds/slave/test/build/application/firefox/firefox 08:43:11 INFO - Running post-action listener: _resource_record_post_action 08:43:11 INFO - [mozharness: 2017-09-14 15:43:11.047997Z] Finished install step (success) 08:43:11 INFO - [mozharness: 2017-09-14 15:43:11.048145Z] Running setup-mitmproxy step. 08:43:11 INFO - Running pre-action listener: _resource_record_pre_action 08:43:11 INFO - Running main action method: setup_mitmproxy 08:43:11 INFO - Skipping: mitmproxy is not required 08:43:11 INFO - Running post-action listener: _resource_record_post_action 08:43:11 INFO - [mozharness: 2017-09-14 15:43:11.048838Z] Finished setup-mitmproxy step (success) 08:43:11 INFO - [mozharness: 2017-09-14 15:43:11.049064Z] Running run-tests step. 08:43:11 INFO - Running pre-action listener: _resource_record_pre_action 08:43:11 INFO - Running pre-action listener: _set_gcov_prefix 08:43:11 INFO - Running main action method: run_tests 08:43:11 WARNING - Try message not found. 08:43:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:43:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:43:11 INFO - Python 2.7.3 08:43:11 INFO - Return code: 0 08:43:11 INFO - grabbing minidump binary from tooltool 08:43:11 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')]} 08:43:11 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 0x26a5090>, '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 0x26a5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x269ff90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:43:11 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 08:43:11 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 08:43:11 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 08:43:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:43:11 INFO - Return code: 0 08:43:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:43:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:43:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:43:11 INFO - ENV: RUST_BACKTRACE is now full 08:43:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:43:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:43:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 08:43:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 08:43:11 INFO - Using env: {'DISPLAY': ':0', 08:43:11 INFO - 'HOME': '/home/cltbld', 08:43:11 INFO - 'LANG': 'en_US.UTF-8', 08:43:11 INFO - 'LANGUAGE': 'en_US:en', 08:43:11 INFO - 'LOGNAME': 'cltbld', 08:43:11 INFO - 'MAIL': '/var/mail/cltbld', 08:43:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:43:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:43:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:11 INFO - 'MOZ_NO_REMOTE': '1', 08:43:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:43:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:43:11 INFO - 'NO_EM_RESTART': '1', 08:43:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:43:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:11 INFO - 'PWD': '/builds/slave/test', 08:43:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:43:11 INFO - 'RUST_BACKTRACE': 'full', 08:43:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:43:11 INFO - 'SHELL': '/bin/bash', 08:43:11 INFO - 'SHLVL': '1', 08:43:11 INFO - 'TERM': 'linux', 08:43:11 INFO - 'TMOUT': '86400', 08:43:11 INFO - 'USER': 'cltbld', 08:43:11 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', 08:43:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:11 INFO - '_': '/tools/buildbot/bin/python'} 08:43:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 08:43:11 INFO - mozversion application_buildid: 20170914145054 08:43:11 INFO - mozversion application_changeset: ef0f1085d54eae99d62bbf039952b0deb84f396d 08:43:11 INFO - mozversion application_display_name: Nightly 08:43:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:43:11 INFO - mozversion application_name: Firefox 08:43:11 INFO - mozversion application_remotingname: firefox 08:43:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:43:11 INFO - mozversion application_vendor: Mozilla 08:43:11 INFO - mozversion application_version: 57.0a1 08:43:11 INFO - mozversion platform_buildid: 20170914145054 08:43:11 INFO - mozversion platform_changeset: ef0f1085d54eae99d62bbf039952b0deb84f396d 08:43:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:43:11 INFO - mozversion platform_version: 57.0a1 08:43:11 INFO - using testdate: 1505403791 08:43:11 INFO - actual date: 1505403791 08:43:11 INFO - starting webserver on 'localhost:41920' 08:43:11 INFO - SUITE-START | Running 2 tests 08:43:11 INFO - TEST-START | dromaeo_css 08:43:11 INFO - Initialising browser for dromaeo_css test... 08:43:11 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41920/getInfo.html -profile /tmp/tmp2pFz8F/profile 08:43:11 INFO - TEST-INFO | started process 14248 (/builds/slave/test/build/application/firefox/firefox http://localhost:41920/getInfo.html) 08:43:17 INFO - TEST-INFO | 14248: exit 0 08:43:17 INFO - Browser initialized. 08:43:17 INFO - Running cycle 1/1 for dromaeo_css test... 08:43:17 INFO - Using env: {'DISPLAY': ':0', 08:43:17 INFO - 'HOME': '/home/cltbld', 08:43:17 INFO - 'JSGC_DISABLE_POISONING': '1', 08:43:17 INFO - 'LANG': 'en_US.UTF-8', 08:43:17 INFO - 'LANGUAGE': 'en_US:en', 08:43:17 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:43:17 INFO - 'LOGNAME': 'cltbld', 08:43:17 INFO - 'MAIL': '/var/mail/cltbld', 08:43:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:43:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:43:17 INFO - 'MOZ_CRASHREPORTER': '1', 08:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:17 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:43:17 INFO - 'MOZ_NO_REMOTE': '1', 08:43:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:43:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:43:17 INFO - 'NO_EM_RESTART': '1', 08:43:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:17 INFO - 'PWD': '/builds/slave/test', 08:43:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:43:17 INFO - 'RUST_BACKTRACE': 'full', 08:43:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:43:17 INFO - 'SHELL': '/bin/bash', 08:43:17 INFO - 'SHLVL': '1', 08:43:17 INFO - 'TERM': 'linux', 08:43:17 INFO - 'TMOUT': '86400', 08:43:17 INFO - 'USER': 'cltbld', 08:43:17 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', 08:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:17 INFO - '_': '/tools/buildbot/bin/python'} 08:43:17 INFO - TEST-INFO | started process 14483 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2pFz8F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:43:18 INFO - PID 14483 | 08:43:18 INFO - PID 14483 | (/builds/slave/test/build/application/firefox/firefox:14533): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:43:18 INFO - PID 14483 | 08:43:29 INFO - PID 14483 | RSS: Main: 170618880 08:43:29 INFO - PID 14483 | 08:46:26 INFO - PID 14483 | Cycle 1(1): loaded http://localhost:41920/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41920/tests/dromaeo/cssquery-ext.html) 08:46:27 INFO - PID 14483 | RSS: Main: 173985792 08:46:27 INFO - PID 14483 | 08:49:29 INFO - PID 14483 | Cycle 1(1): loaded http://localhost:41920/tests/dromaeo/cssquery-ext.html (next: http://localhost:41920/tests/dromaeo/cssquery-jquery.html) 08:49:30 INFO - PID 14483 | RSS: Main: 170594304 08:49:30 INFO - PID 14483 | 08:52:31 INFO - PID 14483 | Cycle 1(1): loaded http://localhost:41920/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41920/tests/dromaeo/cssquery-mootools.html) 08:52:32 INFO - PID 14483 | RSS: Main: 172797952 08:52:32 INFO - PID 14483 | 08:55:34 INFO - PID 14483 | Cycle 1(1): loaded http://localhost:41920/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41920/tests/dromaeo/cssquery-prototype.html) 08:55:34 INFO - PID 14483 | RSS: Main: 170897408 08:55:34 INFO - PID 14483 | 08:58:37 INFO - PID 14483 | Cycle 1(1): loaded http://localhost:41920/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41920/tests/dromaeo/cssquery-yui.html) 08:58:38 INFO - PID 14483 | RSS: Main: 173162496 08:58:38 INFO - PID 14483 | 09:01:40 INFO - PID 14483 | Cycle 1(1): loaded http://localhost:41920/tests/dromaeo/cssquery-yui.html (next: http://localhost:41920/tests/dromaeo/cssquery-dojo.html) 09:01:40 INFO - PID 14483 | RSS: Main: 176259072 09:01:40 INFO - PID 14483 | 09:01:40 INFO - PID 14483 | __start_tp_report 09:01:40 INFO - PID 14483 | _x_x_mozilla_page_load 09:01:40 INFO - PID 14483 | _x_x_mozilla_page_load_details 09:01:40 INFO - PID 14483 | |i|pagename|runs| 09:01:40 INFO - PID 14483 | |0;dojo.html;3157.810531368117;3162.1304141361124;3182.395344884472;3209.069369882734;3219.114743445552;6198.690065496729;6426.967865160674;6431.16394868667;6505.739770409178;6535.444487218586;6368.681565921708;6393.136926514919;6417.486601071914;6425.357464253572;6441.259255185655;5919.1713160157615;5984.700764961745;5997.430244126801;6007.219061521996;6070.514358851304;5798.130280457944;5973.790917317895;5995.6702381369005;6050.455459008688;6064.666443345614;5808.82573522795;5854.76580936762;5933.199018132562;5976.581639285252;5991.880162396727;5857.26784151981;5858.209141765849;5961.582689211757;5961.850953726148;5966.791162309299;5866.2960444746905;5895.115732640095;5947.10793380992;5965.343812180657;5968.492678122365;6205.317415084337;6341.714622841983;6441.935580644227;6511;6545.967270163619;6814.114165158548;6834.58992460449;6838.829029274308;6851.109355783766;6861.759838405632;7018.684159212848;7057.435405167575;7068.858622827566;7071.222165561784;7073.045138906234;6279.843003924847;6418.614883107029;6436.742530298782;6439.613623182578;6534.1505604271615;7068.646567671699;7085.787426377217;7087.0432491614;7089.007538944552;7090.361867431956;6733.696983635748;6734.191896972395;6736.157980252468;6744.190697116377;6748.595084294958;6759.966200168968;6830.18037835453;6830.282820303896;6833.453323734188;6868.656567171622;6787.728490860409;6795.694193761293;6806.93193068063;6956.234814170437;6962.408195303456;6894.620795856175;6926.030355750199;7022.087128673342;7023.648817559101;7034.788956331318;5229.633925625246;5248.501392367719;5285.841424757264;5309.601779866525;5313.814016509404;4488.865334039984;4563.429571303587;4566.292224705176;4633.305004249322;4635.930461043087;5972.283326000907;5996.430339117776;6004.939950600526;6008.128821320972;6020.638761674313;7003.5797852129035;7009.894851577231;7079.469039821931;7089.7873063808165;7091.11361079865;5900.675462849585;5941.465268125889;5999.460048595647;6001.639901605918;6010.068439391901;5566.332040155207;5644.604877658607;5668.603197776199;5668.8866222675315;5671.546276297886;6610.471162307004;6709.832254193684;6713.832154196184;6727.562708424035;6736.2590115087305;6901.206361268418;6982.720691172296;6984.650767461607;7029.61337126463;7033.753818616324;6690.297518760654;6715.127033485615;6770.796876093725;6794.93205067943;6801.693923773541;6673.065770791996;6792.626405547742;6816.250212476524;6840;6929.099217101744;6842.452603791784;6895.172579290517;6896.482763792836;6913.792586222421;6920.861582768417;6639.70121344531;6647.900281495685;6670.266270710315;6677.2655007948115;6679.532432729662;6735.25912149673;6786.558873673195;6806.387425131762;6813.557118787263;6835.316468353125;6680.3987641112535;6688;6729.360710732472;6763.7632682855865;6769.864602708017;6545.476361891133;6608.207015158212;6724.529282950147;6770.1537307836525;6847.726090956306;6557.639329836905;6570.671466426755;6628.204615446178;6636.43590294811;6640.535162538576 09:01:40 INFO - PID 14483 | |1;ext.html;820.3437250199838;842.3093063687777;861.1474640106296;863.7970077031896;865.9307255419567;38969.61030389695;39252.21495570087;39614.2077158457;39709.80145099274;39889.40165897507;35972.64027359726;39352.60647393525;39953.60046399535;40037;40072.79963600185;7459.701611935529;7575.204603516634;7591.696332146707;7595.202503737111;7612.086549614054;7532.924670753305;7580.848383032364;7639.04511936008;7641.159472458025;7643.61781910905;7624.428167887403;7643.121041080263;7655.885161722578;7679.961600191991;7684.616768981581;7596.316331530161;7598.544087354749;7605.163432022473;7628.54228746277;7633.732819351324;7475.102987641518;7482.551046937201;7543.094828620546;7543.585102819343;7543.962280188619;36632.633673663455;36790.8160459196;37072;37245.813770930974;37278.44082338767;3415.3681568909833;3426.246225830338;3430.828458577061;3447.931041379158;3460.965390346115;30199.396012079855;30230.54654180189;30329.848350758326;30368.8481557593;30444.238894027603;4973.477784832613;5180.041692286902;5191.195364718475;5236.528712415863;5244.842654720326;178488;179551;181544;182788;193914;46137.7693111539;46525.30212046822;46623;46626;46683;47529;47639;47718.5228147721;47814.521854781706;48042;14982.175980321186;15113.47102851417;15140.394384224508;15148.848511514743;15191.924040379727;12286.385680715928;12334.629961101182;12379.319137447346;12404.503819847285;12409.75180496385;12277.631671049705;12474.75050499003;12523;12523.311217883103;12657.303848288433;5044.8234311798915;5070.467600901852;5122.795088196505;5185.274061631375;5251.925489431653;972.8005758819424;1003.1573478278135;1003.4581326083833;1012.2964539644876;1027.99486002571;21954.45113872166;22157.224497142524;22274.665880011813;22495.66256506154;22878.885605571864;1076.4779082145153;1100.4882729530807;1106.3859557945418;1107.8172101603145;1113.493360520961;11751.823722644167;11936.701582460508;11946.76106477883;11993.160478766402;12061.396930153458;227986;229324;229342;229710;229791;49891.750541247784;50035.49964500381;50047.74976125096;50126.749366253665;50175;1412.166821575255;1430.9427622894968;1439.5105664074056;1447.0304895719682;1447.6742732885016;21417.46456338604;21815.781842181375;21897.890510547346;22102.77897221007;22235.77764222337;34921.301573968885;34954.30091398107;34979.47530786989;35003.82498087493;35003.82498087595;196062;196349;196431;197592;197867;30209;31395.37209255848;31495.21261968469;31563.52654710135;31574;38963.220735585695;39763.80118099391;39947.40078898877;40110;40907;29433.55849662214;29559.26101847471;30045;30087.548686769278;31036;29706.702932970395;30312.5453118199;30322.54516182215;30384.088477345715;30844.845775770977;33219.003429897144;33904.1523961893;33916;34139.31721365608;34168;29805.254868628457;30065.699343007163;30211.85934451118;30708.385832283675;30758.69241307648;21959.890200548896;22309.094086222693;22408;22548.774512254666;22757.544849102593 09:01:40 INFO - PID 14483 | |2;jquery.html;568.9914651280232;571.5027925704636;572.1703529881671;575.6200907401117;575.6344721102099;21698.674519882195;22280;22329.665055024187;22389.328320150402;22392.440188995264;23424.945877435515;24503.142390016354;24598.50802983939;24826;24889.62665560018;6229.127922090911;6416.518761092913;6421.678916054178;6422.421982021617;6442.194725659293;6345.111684364193;6434.195725534309;6436.581622194542;6442.033694945772;6443.484521238313;6234.937650623481;6416.967915160418;6418.42234198922;6489.61062336259;6511.57674751142;6508;6531.4448271896945;6550.6069635822005;6552.901706474407;6556.999999999976;5799.88400231993;6054.364291749347;6072.271327440735;6076.027835546335;6083.361247069039;28258.576121358197;28757.424851503063;28759;28806.279843003882;28861;2005.929792457257;2024.4736368544284;2029.2897485879823;2036.6232247034345;2037.5313677854176;19182.424527264066;19572;19808.70286945697;19854;20007.499812504804;2430;2483.2674361063528;2497.063601149569;2502.87485625718;2511.3344963584664;123342.38328808389;128408.35795820961;131995;141252;141707.29146354305;14164.36260368286;14345.282735863166;14442.566722998326;14623.707525849424;14815.777503411477;22860.31419057431;22878.42803929915;22924.54150916972;22932.770672293398;23006.19478318251;9882.851757223647;10017.949910250549;10033.218289482238;10047.849282260771;10055;9158.816823663623;9222.815543689088;9294.070592940787;9311.394759340757;9449.149576538008;8758.912410875937;8975;9071.727848164566;9087.863682044775;9112.681056163003;2639.736026397345;2639.7624213820463;2642.7621514063826;2671.2202233838902;2733.1117386849355;948.9857652135223;968.7917097823861;974.2644303550774;982.3123813330698;992.4144754594823;18665.253389864523;18712.719309210373;18968.241270349306;19021.14404851785;19046.90476547636;981.2444417798398;1009.9798004039878;1018.6282007067367;1025.9230557708202;1037.1391744851755;10488.790224195473;10494.42280674555;10598.788024239471;10624.734381640366;10644.25490215693;440991;443110;450011;454273;460919;106193;108820.4558977216;109319;109838.45080774705;112788.43605781918;1081.000074930686;1128;1143.451143451148;1146.9942650286696;1150.3615493401242;16743.91628041852;17110.40113596068;17167.57081072983;17314.567135821704;17359.479215623553;52490;53095.46904530905;53503.73248133734;54568.27158642132;55298;64885.351146487934;65752.34247657462;66026;66070.33929660641;67166.328336716;13734.45062197501;14101.435942562593;14108.647283817576;14120.576382708536;14127.293635318398;21597.784022160205;21938.341849744535;21984.340469785933;22093.226737064124;22281.331560052575;17537.649247015244;17877.553061173574;17891.19489622944;17960.730589040915;18003.189856456753;12475.814797594214;13081.803772943604;15386.153761543223;15845.52463426099;16453.83546164523;17020.74468882943;17603.11984400749;17767.289308428037;17770.20034098443;18059.54851128732;13678.93160534191;13866.237356945061;13872.791908121184;13901.443942242196;13936.4425422986;17652.64409862775;17741.911290443466;17926.820731792515;17958.281668733627;18003.09984500796 09:01:40 INFO - PID 14483 | |3;mootools.html;1331.2411925202632;1383.8408583012956;1390.6245313765285;1391.5616580777062;1398.3497673581783;14931.925340373296;15552.766708499381;15617.609559761011;15620.687586248268;15620.765688514653;15122.848771512281;16233;16240.187990600452;16244.75632865505;16284.75572866408;2810.0445848411537;2858.785591080667;2867.6272084628968;2871.7271859173447;2874.1090262018843;2642.9867850660817;2790.525610646195;2829.5472724364004;2850.5154123799;2854.457653045925;2812.2547524905813;2833.504136776056;2869.2539939615745;2870.3254735137225;2871.8994835180774;2729.6314997475383;2748.9037883674077;2758.5310497215523;2795.6784969728333;2796.384795345021;2717.7689896358634;2727.1136880513714;2746.6841313249033;2758.434520923206;2761.2682639100453;14845.406183752637;14989.775153372708;15092.622684432867;15145.697086058328;15153.469628563058;7947.9205207948335;8216.26053655174;8227.053888802804;8238.752837414888;8250.876236856451;5402.37872644647;5625.38120806711;5669.206311116447;5690.40250773667;5777.075667893159;1844.3452574336052;1866.1602278974515;1868.9719654205198;1923.1249781349452;1929.5754933914516;26435.735642643518;26507;26644.33389165267;26764.197074087613;26918.192454226406;6852.417544508772;6867.866801977718;7030.945358196312;7116.822079447952;7123.536970096927;17404.39084632032;17430.215640295963;17442.825571744375;17511.912440437973;17532.123393830258;7953.761387158394;8045.115037345888;8066.717664881701;8075.878861817077;8108.148644392254;7089.291070892869;7191.712331506786;7211.062561866924;7217;7242.891356629655;5238.1618940969265;5411.729413529308;5441.972790136103;5474.945250547444;5486.862828429242;1572.905625662464;1589.2053973013494;1593.8724902007812;1602.583328334618;1605.702944955187;867.7266661001764;884.195382202193;895.7671005538607;896.0994200828126;905.8233644439402;7381.963090184515;7437.181909989897;7464.813379665443;7493.475456718086;7512.060992375953;708.4014008163094;725.2131437390491;732.2201855024382;732.4470025130992;737.3142977031413;5122.129238029544;5190.6107041971245;5208.661437006583;5295.02042122201;5337.332833395825;20740.481487962923;20824.47938801542;21664.675029874565;21693.457663558536;22024.559508810056;21045.263415780373;21106.261280855095;21439.142434302765;21892.45268868291;22083.447913801963;9523.809523809485;9899.604015839286;11111.499982500807;11143.498542565603;11527.193096483334;8171.632276547451;8435.8734618982;8454.66181352739;8480.279176270085;8615.267702245128;18090.27638894482;18521.35175268859;18674.253029879197;19042.904785475985;19373.70939435938;18722.719159212895;18794.436166915013;19082.713759293343;19134.138963746387;19184.52038698988;10585.206109541661;10606.734831628963;10677.14582833387;10709;10711.89288107109;13764.105333153313;13889.236092015037;13894.374753135931;13916.721665566429;13940.512082077079;9789;10107.090361867467;10124.443155626217;10213.642522511676;10390.740231494274;10327.122194613241;10426.374417534973;10632.574697012033;10653.520591573242;10791.40647264408;9346.345755797302;9375.906240937504;9421.104995025462;9428.905710943078;9448;7003.7548685795755;7052.400545953651;7106.822329441805;7142.8214294640975;7202.927970720226;7008.299170083154;7009.285078908187;7137.750178743927;7147.070880785673;7203.063601731741 09:01:40 INFO - PID 14483 | |4;prototype.html;504.8662104542297;505.1740404438744;529.8013245033112;531.9201920490881;533.7944891216886;6347.904781428282;6359.904601430976;6368.04479328101;6373.788980093785;6408.359164083589;6239.719212635431;6291.458592644808;6292.307846136932;6302;6314.684265786704;5213.374395072597;5226.425093239741;5254.973725131369;5259.3951695555115;5264.868378290555;4914.631402644798;5094.293411707083;5257.7108259045735;5265.684058956455;5291.814786482474;4932.950670493303;4981.402231732197;5004.299398084271;5009;5027.547520723134;4982.875428114308;4995.1757959936385;4997.950020499785;5011.448740638527;5026.095302845523;4403.933940990888;4486.798094085774;4489.663275254369;4490.10197960406;4511.390962220091;6076.210092687967;6145.170401995762;6191.628502289877;6206.224221972254;6221.4089661482085;5680.630759000652;5682.17608446777;5688.146777983336;5703.31560212773;5713.371529131792;6516.9674151629415;6556.573822701509;6571.7371305147735;6576.309487503791;6586.308437614078;4677.4620918594455;4864.440589332236;4905.852824415273;4909.558139767438;4920.8031678732805;6796.4562834973085;6804.761833335859;6868.416184624313;6912.827179320507;6951.339622735832;5005.599552035828;5007.39911210657;5038.294638750578;5065.417476990119;5076.847694569169;5040.2691609716385;5081.136206844845;5100.158473851773;5100.699321673023;5125.026864657134;5630;5674.921764864663;5724.313082430135;5743;5749.68376739275;6589.76935807254;6636.336366363422;6645.568038077509;6671.43292820106;6688.431483323875;1759.9120043997748;1766.7968183659043;1768.5490199999176;1779.7330400440037;1791.7849858016862;1166.7491489329834;1215.3619349841404;1224.6326102169314;1224.9142560020891;1232.5562797392931;5666.4050274721385;5743.253377061039;5755.740991655385;5764.596478246566;5766.855828604318;6701.966490167518;6741.629210393476;6764.932350676529;6780.186377634715;6805.3875151236625;5628.437156284339;5657.264555607745;5660.122680984454;5689.288838895138;5741.483266506035;3515.876944306988;3546.1489242581592;3550.946735798965;3566.411542095525;3595.2449985502813;6502.739890404331;6529.216494020653;6539.836504087436;6541.771038013647;6581.703823327962;6440;6769.627670478072;6904.240533541307;6908.171019477695;6915.861682766417;6412.711427985659;6449.226092868792;6460.063290822711;6460.806175814733;6462.192225971754;6436.549441539048;6597.967010164918;6620.271770105381;6627.7348906045145;6655;6527.608343499405;6580.736770529125;6674.332566743481;6683.7660681875905;6690.899636505359;6475.449586785176;6499.642519661464;6538.705758240795;6569.737210511717;6579.2104947406615;4928.704277743347;4994.701377641767;5034.370703662043;5058.392992840735;5066.9239961401345;6520.315366886598;6542.574732642363;6627.403533681992;6778.762743303961;6841.673830897519;6411.326810684997;6444.355564443706;6458.7093580789915;6498.350164983463;6557.639329836905;6514.9348506514325;6536.8365790855605;6542.378474044958;6546.821572116874;6561.475081993524;6456.063870738622;6460.321666225167;6473.514486413444;6502.3172566881685;6524.347565243438;6611.768588099393;6621.834454138685;6650.534462587572;6653.534252602271;6670.199576050905;6409.166808314889;6416.807495775134;6433.967830160819;6455.483561315177;6462.321456247027 09:01:40 INFO - PID 14483 | |5;yui.html;919.5080631861955;925.2829057480454;928.4336554701636;937.0161330602865;954.4034978138664;18251.452456426312;18644.720329195072;18656.813431865678;18686.252549898003;18725.812741872578;18315.084245787722;18519.074046297697;18635.44093677192;18663.44009679712;18694.439166824985;947.5593848860261;949.8812648418948;951.6383774165841;952.4523399045542;952.7618095476131;967.9177269932064;973.756560859785;973.7808992976594;976.9853452198223;980.1178938954927;986.4081551069373;1020.0513522424143;1026.2816028779846;1037.3827572594294;1040.1938497664332;1030.9845352319721;1030.9896901030968;1031.128696251666;1031.8349064149738;1032.7779527401608;725.6081715873422;742.3430264216207;747.4805010517692;750.0624219725344;750.246002767221;19608.11763470633;19658;19714.70427943582;19803.306884258982;19901.203951841908;21778.564428711496;21949.78050219493;22234.55530889373;22441.887790561104;22620.773792262033;17736.379226727004;17950.19224134904;17988.190531426117;18056.09719514019;18101.366452174112;3677.2093999789904;3713.0160507465544;3715.256948610289;3719.8140092995513;3729.4032954727113;100599;142494.28752856274;143398.99999999895;143420;144921;17368.826311736975;17419.564510887074;17942.192601332972;17988.460346189637;18081.638367232583;31911.202219944687;32602.83698581492;32692.34615307728;32695.673043269737;32762.836185819397;9117.863232051524;9191.264698824078;9214.44713317203;9223.907760922439;9266.860997085048;4090.8568200112854;4167.062410957571;4167.854125105668;4171.395147703627;4178.080822219107;5281.683099014072;5308.575313974873;5404.216388623628;5424.267723857229;5434.91847622286;1030.3354336452946;1030.8711411073616;1056.1709058389129;1056.7569459024314;1058.1587637827945;657.6744511466759;668.7258224128084;679.7009315900988;683.7162577530369;690.9032735417046;10804;10923.836142457869;10935.343879367263;12369.752604947851;12414.317212553397;664.1266734244454;664.1432552007856;668.9565178263397;687.2783577243874;691.1395312835552;5275.498827611371;5409.24270602116;5462.617616766788;5468.179773033997;5488.588355873246;115767;115783.42108289573;116211.41894290474;118539;119973.40013299878;105040;107134;108719;109395.45302273438;111370;98302;99659.50170249102;100425;100832.49583752034;101133.49433252787;9993.400395976265;10125.341852779546;10238.53926573291;10341.9482902585;10540.262181647518;41428.37857432081;42556.574434255264;43653;43843.78078109589;43876.56123438852;54944.45055549553;55536.72231638816;55767.16349254683;55917.88164236774;57603.42396575981;14736.410543578137;14935.701285974003;14970.850291497381;14971.850281497482;15157.696846062796;16490.505284841474;16541.503754887373;16673.981128687716;16763.329466821677;16826;13470.52853150135;13566.389512471766;13577;13723.245221512514;13728.313584320544;15505.457308994582;15653.37386504527;15763.763543547086;15800.209989500709;15817.525474235792;14292.785608216092;14479.203643799692;14547.781783273047;14585.197814120325;14610.415583376545;11051.557937682725;11323;11391.544338226378;11403.543858245577;11438.08495320389;11130.888691113309;11211.159163062639;11342.60300889465;11527.250728702942;11542.711432214262 09:01:40 INFO - PID 14483 | __end_tp_report 09:01:40 INFO - PID 14483 | __start_cc_report 09:01:40 INFO - PID 14483 | _x_x_mozilla_cycle_collect,351 09:01:40 INFO - PID 14483 | __end_cc_report 09:01:40 INFO - PID 14483 | __startTimestamp1505404900441__endTimestamp 09:01:40 INFO - PID 14483 | 09:01:40 INFO - PID 14483 | ------- Summary: start ------- 09:01:40 INFO - PID 14483 | Number of tests: 6 09:01:40 INFO - PID 14483 | 09:01:40 INFO - PID 14483 | [#0] dojo.html Cycles:165 Average:6337.24 Median:6639.70 stddev:793.29 (11.9%) stddev-sans-first:755.50 09:01:40 INFO - PID 14483 | Values: 3157.8 3162.1 3182.4 3209.1 3219.1 6198.7 6427.0 6431.2 6505.7 6535.4 6368.7 6393.1 6417.5 6425.4 6441.3 5919.2 5984.7 5997.4 6007.2 6070.5 5798.1 5973.8 5995.7 6050.5 6064.7 5808.8 5854.8 5933.2 5976.6 5991.9 5857.3 5858.2 5961.6 5961.9 5966.8 5866.3 5895.1 5947.1 5965.3 5968.5 6205.3 6341.7 6441.9 6511.0 6546.0 6814.1 6834.6 6838.8 6851.1 6861.8 7018.7 7057.4 7068.9 7071.2 7073.0 6279.8 6418.6 6436.7 6439.6 6534.2 7068.6 7085.8 7087.0 7089.0 7090.4 6733.7 6734.2 6736.2 6744.2 6748.6 6760.0 6830.2 6830.3 6833.5 6868.7 6787.7 6795.7 6806.9 6956.2 6962.4 6894.6 6926.0 7022.1 7023.6 7034.8 5229.6 5248.5 5285.8 5309.6 5313.8 4488.9 4563.4 4566.3 4633.3 4635.9 5972.3 5996.4 6004.9 6008.1 6020.6 7003.6 7009.9 7079.5 7089.8 7091.1 5900.7 5941.5 5999.5 6001.6 6010.1 5566.3 5644.6 5668.6 5668.9 5671.5 6610.5 6709.8 6713.8 6727.6 6736.3 6901.2 6982.7 6984.7 7029.6 7033.8 6690.3 6715.1 6770.8 6794.9 6801.7 6673.1 6792.6 6816.3 6840.0 6929.1 6842.5 6895.2 6896.5 6913.8 6920.9 6639.7 6647.9 6670.3 6677.3 6679.5 6735.3 6786.6 6806.4 6813.6 6835.3 6680.4 6688.0 6729.4 6763.8 6769.9 6545.5 6608.2 6724.5 6770.2 6847.7 6557.6 6570.7 6628.2 6636.4 6640.5 09:01:40 INFO - PID 14483 | 09:01:40 INFO - PID 14483 | [#1] ext.html Cycles:180 Average:36246.61 Median:33910.08 stddev:52890.26 (156.0%) stddev-sans-first:52971.74 09:01:40 INFO - PID 14483 | Values: 820.3 842.3 861.1 863.8 865.9 38969.6 39252.2 39614.2 39709.8 39889.4 35972.6 39352.6 39953.6 40037.0 40072.8 7459.7 7575.2 7591.7 7595.2 7612.1 7532.9 7580.8 7639.0 7641.2 7643.6 7624.4 7643.1 7655.9 7680.0 7684.6 7596.3 7598.5 7605.2 7628.5 7633.7 7475.1 7482.6 7543.1 7543.6 7544.0 36632.6 36790.8 37072.0 37245.8 37278.4 3415.4 3426.2 3430.8 3447.9 3461.0 30199.4 30230.5 30329.8 30368.8 30444.2 4973.5 5180.0 5191.2 5236.5 5244.8 178488.0 179551.0 181544.0 182788.0 193914.0 46137.8 46525.3 46623.0 46626.0 46683.0 47529.0 47639.0 47718.5 47814.5 48042.0 14982.2 15113.5 15140.4 15148.8 15191.9 12286.4 12334.6 12379.3 12404.5 12409.8 12277.6 12474.8 12523.0 12523.3 12657.3 5044.8 5070.5 5122.8 5185.3 5251.9 972.8 1003.2 1003.5 1012.3 1028.0 21954.5 22157.2 22274.7 22495.7 22878.9 1076.5 1100.5 1106.4 1107.8 1113.5 11751.8 11936.7 11946.8 11993.2 12061.4 227986.0 229324.0 229342.0 229710.0 229791.0 49891.8 50035.5 50047.7 50126.7 50175.0 1412.2 1430.9 1439.5 1447.0 1447.7 21417.5 21815.8 21897.9 22102.8 22235.8 34921.3 34954.3 34979.5 35003.8 35003.8 196062.0 196349.0 196431.0 197592.0 197867.0 30209.0 31395.4 31495.2 31563.5 31574.0 38963.2 39763.8 39947.4 40110.0 40907.0 29433.6 29559.3 30045.0 30087.5 31036.0 29706.7 30312.5 30322.5 30384.1 30844.8 33219.0 33904.2 33916.0 34139.3 34168.0 29805.3 30065.7 30211.9 30708.4 30758.7 21959.9 22309.1 22408.0 22548.8 22757.5 09:01:40 INFO - PID 14483 | 09:01:40 INFO - PID 14483 | [#2] jquery.html Cycles:180 Average:32783.60 Median:22359.50 stddev:76061.54 (340.2%) stddev-sans-first:76236.45 09:01:40 INFO - PID 14483 | Values: 569.0 571.5 572.2 575.6 575.6 21698.7 22280.0 22329.7 22389.3 22392.4 23424.9 24503.1 24598.5 24826.0 24889.6 6229.1 6416.5 6421.7 6422.4 6442.2 6345.1 6434.2 6436.6 6442.0 6443.5 6234.9 6417.0 6418.4 6489.6 6511.6 6508.0 6531.4 6550.6 6552.9 6557.0 5799.9 6054.4 6072.3 6076.0 6083.4 28258.6 28757.4 28759.0 28806.3 28861.0 2005.9 2024.5 2029.3 2036.6 2037.5 19182.4 19572.0 19808.7 19854.0 20007.5 2430.0 2483.3 2497.1 2502.9 2511.3 123342.4 128408.4 131995.0 141252.0 141707.3 14164.4 14345.3 14442.6 14623.7 14815.8 22860.3 22878.4 22924.5 22932.8 23006.2 9882.9 10017.9 10033.2 10047.8 10055.0 9158.8 9222.8 9294.1 9311.4 9449.1 8758.9 8975.0 9071.7 9087.9 9112.7 2639.7 2639.8 2642.8 2671.2 2733.1 949.0 968.8 974.3 982.3 992.4 18665.3 18712.7 18968.2 19021.1 19046.9 981.2 1010.0 1018.6 1025.9 1037.1 10488.8 10494.4 10598.8 10624.7 10644.3 440991.0 443110.0 450011.0 454273.0 460919.0 106193.0 108820.5 109319.0 109838.5 112788.4 1081.0 1128.0 1143.5 1147.0 1150.4 16743.9 17110.4 17167.6 17314.6 17359.5 52490.0 53095.5 53503.7 54568.3 55298.0 64885.4 65752.3 66026.0 66070.3 67166.3 13734.5 14101.4 14108.6 14120.6 14127.3 21597.8 21938.3 21984.3 22093.2 22281.3 17537.6 17877.6 17891.2 17960.7 18003.2 12475.8 13081.8 15386.2 15845.5 16453.8 17020.7 17603.1 17767.3 17770.2 18059.5 13678.9 13866.2 13872.8 13901.4 13936.4 17652.6 17741.9 17926.8 17958.3 18003.1 09:01:40 INFO - PID 14483 | 09:01:40 INFO - PID 14483 | [#3] mootools.html Cycles:180 Average:9272.95 Median:2775.90 stddev:6674.77 (240.5%) stddev-sans-first:6666.82 09:01:40 INFO - PID 14483 | Values: 1331.2 1383.8 1390.6 1391.6 1398.3 14931.9 15552.8 15617.6 15620.7 15620.8 15122.8 16233.0 16240.2 16244.8 16284.8 2810.0 2858.8 2867.6 2871.7 2874.1 2643.0 2790.5 2829.5 2850.5 2854.5 2812.3 2833.5 2869.3 2870.3 2871.9 2729.6 2748.9 2758.5 2795.7 2796.4 2717.8 2727.1 2746.7 2758.4 2761.3 14845.4 14989.8 15092.6 15145.7 15153.5 7947.9 8216.3 8227.1 8238.8 8250.9 5402.4 5625.4 5669.2 5690.4 5777.1 1844.3 1866.2 1869.0 1923.1 1929.6 26435.7 26507.0 26644.3 26764.2 26918.2 6852.4 6867.9 7030.9 7116.8 7123.5 17404.4 17430.2 17442.8 17511.9 17532.1 7953.8 8045.1 8066.7 8075.9 8108.1 7089.3 7191.7 7211.1 7217.0 7242.9 5238.2 5411.7 5442.0 5474.9 5486.9 1572.9 1589.2 1593.9 1602.6 1605.7 867.7 884.2 895.8 896.1 905.8 7382.0 7437.2 7464.8 7493.5 7512.1 708.4 725.2 732.2 732.4 737.3 5122.1 5190.6 5208.7 5295.0 5337.3 20740.5 20824.5 21664.7 21693.5 22024.6 21045.3 21106.3 21439.1 21892.5 22083.4 9523.8 9899.6 11111.5 11143.5 11527.2 8171.6 8435.9 8454.7 8480.3 8615.3 18090.3 18521.4 18674.3 19042.9 19373.7 18722.7 18794.4 19082.7 19134.1 19184.5 10585.2 10606.7 10677.1 10709.0 10711.9 13764.1 13889.2 13894.4 13916.7 13940.5 9789.0 10107.1 10124.4 10213.6 10390.7 10327.1 10426.4 10632.6 10653.5 10791.4 9346.3 9375.9 9421.1 9428.9 9448.0 7003.8 7052.4 7106.8 7142.8 7202.9 7008.3 7009.3 7137.8 7147.1 7203.1 09:01:40 INFO - PID 14483 | 09:01:40 INFO - PID 14483 | [#4] prototype.html Cycles:180 Average:5532.77 Median:6265.59 stddev:1543.29 (24.6%) stddev-sans-first:1500.77 09:01:40 INFO - PID 14483 | Values: 504.9 505.2 529.8 531.9 533.8 6347.9 6359.9 6368.0 6373.8 6408.4 6239.7 6291.5 6292.3 6302.0 6314.7 5213.4 5226.4 5255.0 5259.4 5264.9 4914.6 5094.3 5257.7 5265.7 5291.8 4933.0 4981.4 5004.3 5009.0 5027.5 4982.9 4995.2 4998.0 5011.4 5026.1 4403.9 4486.8 4489.7 4490.1 4511.4 6076.2 6145.2 6191.6 6206.2 6221.4 5680.6 5682.2 5688.1 5703.3 5713.4 6517.0 6556.6 6571.7 6576.3 6586.3 4677.5 4864.4 4905.9 4909.6 4920.8 6796.5 6804.8 6868.4 6912.8 6951.3 5005.6 5007.4 5038.3 5065.4 5076.8 5040.3 5081.1 5100.2 5100.7 5125.0 5630.0 5674.9 5724.3 5743.0 5749.7 6589.8 6636.3 6645.6 6671.4 6688.4 1759.9 1766.8 1768.5 1779.7 1791.8 1166.7 1215.4 1224.6 1224.9 1232.6 5666.4 5743.3 5755.7 5764.6 5766.9 6702.0 6741.6 6764.9 6780.2 6805.4 5628.4 5657.3 5660.1 5689.3 5741.5 3515.9 3546.1 3550.9 3566.4 3595.2 6502.7 6529.2 6539.8 6541.8 6581.7 6440.0 6769.6 6904.2 6908.2 6915.9 6412.7 6449.2 6460.1 6460.8 6462.2 6436.5 6598.0 6620.3 6627.7 6655.0 6527.6 6580.7 6674.3 6683.8 6690.9 6475.4 6499.6 6538.7 6569.7 6579.2 4928.7 4994.7 5034.4 5058.4 5066.9 6520.3 6542.6 6627.4 6778.8 6841.7 6411.3 6444.4 6458.7 6498.4 6557.6 6514.9 6536.8 6542.4 6546.8 6561.5 6456.1 6460.3 6473.5 6502.3 6524.3 6611.8 6621.8 6650.5 6653.5 6670.2 6409.2 6416.8 6434.0 6455.5 6462.3 09:01:40 INFO - PID 14483 | 09:01:40 INFO - PID 14483 | [#5] yui.html Cycles:180 Average:23965.84 Median:18650.77 stddev:34825.69 (186.7%) stddev-sans-first:34880.39 09:01:40 INFO - PID 14483 | Values: 919.5 925.3 928.4 937.0 954.4 18251.5 18644.7 18656.8 18686.3 18725.8 18315.1 18519.1 18635.4 18663.4 18694.4 947.6 949.9 951.6 952.5 952.8 967.9 973.8 973.8 977.0 980.1 986.4 1020.1 1026.3 1037.4 1040.2 1031.0 1031.0 1031.1 1031.8 1032.8 725.6 742.3 747.5 750.1 750.2 19608.1 19658.0 19714.7 19803.3 19901.2 21778.6 21949.8 22234.6 22441.9 22620.8 17736.4 17950.2 17988.2 18056.1 18101.4 3677.2 3713.0 3715.3 3719.8 3729.4 100599.0 142494.3 143399.0 143420.0 144921.0 17368.8 17419.6 17942.2 17988.5 18081.6 31911.2 32602.8 32692.3 32695.7 32762.8 9117.9 9191.3 9214.4 9223.9 9266.9 4090.9 4167.1 4167.9 4171.4 4178.1 5281.7 5308.6 5404.2 5424.3 5434.9 1030.3 1030.9 1056.2 1056.8 1058.2 657.7 668.7 679.7 683.7 690.9 10804.0 10923.8 10935.3 12369.8 12414.3 664.1 664.1 669.0 687.3 691.1 5275.5 5409.2 5462.6 5468.2 5488.6 115767.0 115783.4 116211.4 118539.0 119973.4 105040.0 107134.0 108719.0 109395.5 111370.0 98302.0 99659.5 100425.0 100832.5 101133.5 9993.4 10125.3 10238.5 10341.9 10540.3 41428.4 42556.6 43653.0 43843.8 43876.6 54944.5 55536.7 55767.2 55917.9 57603.4 14736.4 14935.7 14970.9 14971.9 15157.7 16490.5 16541.5 16674.0 16763.3 16826.0 13470.5 13566.4 13577.0 13723.2 13728.3 15505.5 15653.4 15763.8 15800.2 15817.5 14292.8 14479.2 14547.8 14585.2 14610.4 11051.6 11323.0 11391.5 11403.5 11438.1 11130.9 11211.2 11342.6 11527.3 11542.7 09:01:40 INFO - PID 14483 | -------- Summary: end -------- 09:01:40 INFO - PID 14483 | 09:01:41 INFO - TEST-INFO | 14483: exit 0 09:01:41 INFO - TEST-OK | dromaeo_css | took 1109847ms 09:01:41 INFO - TEST-START | kraken 09:01:41 INFO - Initialising browser for kraken test... 09:01:41 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41920/getInfo.html -profile /tmp/tmpVvgH26/profile 09:01:41 INFO - TEST-INFO | started process 14858 (/builds/slave/test/build/application/firefox/firefox http://localhost:41920/getInfo.html) 09:01:46 INFO - TEST-INFO | 14858: exit 0 09:01:46 INFO - Browser initialized. 09:01:46 INFO - Running cycle 1/1 for kraken test... 09:01:46 INFO - Using env: {'DISPLAY': ':0', 09:01:46 INFO - 'HOME': '/home/cltbld', 09:01:46 INFO - 'JSGC_DISABLE_POISONING': '1', 09:01:46 INFO - 'LANG': 'en_US.UTF-8', 09:01:46 INFO - 'LANGUAGE': 'en_US:en', 09:01:46 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:01:46 INFO - 'LOGNAME': 'cltbld', 09:01:46 INFO - 'MAIL': '/var/mail/cltbld', 09:01:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:01:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:01:46 INFO - 'MOZ_CRASHREPORTER': '1', 09:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:46 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:01:46 INFO - 'MOZ_NO_REMOTE': '1', 09:01:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:01:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:01:46 INFO - 'NO_EM_RESTART': '1', 09:01:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:46 INFO - 'PWD': '/builds/slave/test', 09:01:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:01:46 INFO - 'RUST_BACKTRACE': 'full', 09:01:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:01:46 INFO - 'SHELL': '/bin/bash', 09:01:46 INFO - 'SHLVL': '1', 09:01:46 INFO - 'TERM': 'linux', 09:01:46 INFO - 'TMOUT': '86400', 09:01:46 INFO - 'USER': 'cltbld', 09:01:46 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765', 09:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:46 INFO - '_': '/tools/buildbot/bin/python'} 09:01:47 INFO - TEST-INFO | started process 15089 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVvgH26/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:47 INFO - PID 15089 | 09:01:47 INFO - PID 15089 | (/builds/slave/test/build/application/firefox/firefox:15138): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:01:47 INFO - PID 15089 | 09:01:58 INFO - PID 15089 | RSS: Main: 173047808 09:01:58 INFO - PID 15089 | 09:02:00 INFO - PID 15089 | Cycle 1(1): loaded http://localhost:41920/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41920/tests/kraken/driver.html?testName=audio-beat-detection) 09:02:01 INFO - PID 15089 | RSS: Main: 178061312 09:02:01 INFO - PID 15089 | 09:02:04 INFO - PID 15089 | Cycle 1(1): loaded http://localhost:41920/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41920/tests/kraken/driver.html?testName=audio-dft) 09:02:05 INFO - PID 15089 | RSS: Main: 178184192 09:02:05 INFO - PID 15089 | 09:02:08 INFO - PID 15089 | Cycle 1(1): loaded http://localhost:41920/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41920/tests/kraken/driver.html?testName=audio-fft) 09:02:08 INFO - PID 15089 | RSS: Main: 174247936 09:02:08 INFO - PID 15089 | 09:02:10 INFO - PID 15089 | Cycle 1(1): loaded http://localhost:41920/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41920/tests/kraken/driver.html?testName=audio-oscillator) 09:02:11 INFO - PID 15089 | RSS: Main: 175382528 09:02:11 INFO - PID 15089 | 09:02:13 INFO - PID 15089 | Cycle 1(1): loaded http://localhost:41920/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41920/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:02:14 INFO - PID 15089 | RSS: Main: 175452160 09:02:14 INFO - PID 15089 | 09:02:17 INFO - PID 15089 | Cycle 1(1): loaded http://localhost:41920/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41920/tests/kraken/driver.html?testName=imaging-darkroom) 09:02:18 INFO - PID 15089 | RSS: Main: 178180096 09:02:18 INFO - PID 15089 | 09:02:21 INFO - PID 15089 | Cycle 1(1): loaded http://localhost:41920/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41920/tests/kraken/driver.html?testName=imaging-desaturate) 09:02:22 INFO - PID 15089 | RSS: Main: 177594368 09:02:22 INFO - PID 15089 | 09:02:25 INFO - PID 15089 | Cycle 1(1): loaded http://localhost:41920/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41920/tests/kraken/driver.html?testName=json-parse-financial) 09:02:26 INFO - PID 15089 | RSS: Main: 177905664 09:02:26 INFO - PID 15089 | 09:02:27 INFO - PID 15089 | Cycle 1(1): loaded http://localhost:41920/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41920/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:02:28 INFO - PID 15089 | RSS: Main: 177844224 09:02:28 INFO - PID 15089 | 09:02:30 INFO - PID 15089 | Cycle 1(1): loaded http://localhost:41920/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41920/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:02:30 INFO - PID 15089 | RSS: Main: 177893376 09:02:30 INFO - PID 15089 | 09:02:32 INFO - PID 15089 | Cycle 1(1): loaded http://localhost:41920/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41920/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:02:33 INFO - PID 15089 | RSS: Main: 174981120 09:02:33 INFO - PID 15089 | 09:02:36 INFO - PID 15089 | Cycle 1(1): loaded http://localhost:41920/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41920/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:02:36 INFO - PID 15089 | RSS: Main: 175378432 09:02:36 INFO - PID 15089 | 09:02:39 INFO - PID 15089 | Cycle 1(1): loaded http://localhost:41920/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41920/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:02:40 INFO - PID 15089 | RSS: Main: 175104000 09:02:40 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | Cycle 1(1): loaded http://localhost:41920/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41920/tests/kraken/driver.html?testName=ai-astar) 09:02:42 INFO - PID 15089 | RSS: Main: 176451584 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | __start_tp_report 09:02:42 INFO - PID 15089 | _x_x_mozilla_page_load 09:02:42 INFO - PID 15089 | _x_x_mozilla_page_load_details 09:02:42 INFO - PID 15089 | |i|pagename|runs| 09:02:42 INFO - PID 15089 | |0;ai-astar;107;107;107;106;109;102;109;107;108;107 09:02:42 INFO - PID 15089 | |1;audio-beat-detection;115;115;126;120;116;116;120;115;115;124 09:02:42 INFO - PID 15089 | |2;audio-dft;163;162;164;162;163;165;164;166;168;167 09:02:42 INFO - PID 15089 | |3;audio-fft;75;74;76;73;74;74;74;72;75;75 09:02:42 INFO - PID 15089 | |4;audio-oscillator;79;81;78;79;80;83;78;80;79;80 09:02:42 INFO - PID 15089 | |5;imaging-gaussian-blur;113;114;113;118;114;114;114;114;114;124 09:02:42 INFO - PID 15089 | |6;imaging-darkroom;148;148;147;147;147;148;148;148;148;150 09:02:42 INFO - PID 15089 | |7;imaging-desaturate;99;94;94;94;94;94;93;94;94;94 09:02:42 INFO - PID 15089 | |8;json-parse-financial;57;54;54;56;54;53;53;52;53;53 09:02:42 INFO - PID 15089 | |9;json-stringify-tinderbox;39;39;39;39;38;38;38;38;38;39 09:02:42 INFO - PID 15089 | |10;stanford-crypto-aes;79;78;76;76;76;78;75;76;81;76 09:02:42 INFO - PID 15089 | |11;stanford-crypto-ccm;118;120;116;109;117;115;112;115;110;116 09:02:42 INFO - PID 15089 | |12;stanford-crypto-pbkdf2;174;171;177;182;171;171;174;177;171;172 09:02:42 INFO - PID 15089 | |13;stanford-crypto-sha256-iterative;65;65;63;67;69;64;66;66;66;64 09:02:42 INFO - PID 15089 | __end_tp_report 09:02:42 INFO - PID 15089 | __start_cc_report 09:02:42 INFO - PID 15089 | _x_x_mozilla_cycle_collect,942 09:02:42 INFO - PID 15089 | __end_cc_report 09:02:42 INFO - PID 15089 | __startTimestamp1505404962389__endTimestamp 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | ------- Summary: start ------- 09:02:42 INFO - PID 15089 | Number of tests: 14 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | [#0] ai-astar Cycles:10 Average:106.90 Median:107.00 stddev:1.97 (1.8%) stddev-sans-first:2.09 09:02:42 INFO - PID 15089 | Values: 107.0 107.0 107.0 106.0 109.0 102.0 109.0 107.0 108.0 107.0 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | [#1] audio-beat-detection Cycles:10 Average:118.20 Median:118.00 stddev:4.10 (3.5%) stddev-sans-first:4.19 09:02:42 INFO - PID 15089 | Values: 115.0 115.0 126.0 120.0 116.0 116.0 120.0 115.0 115.0 124.0 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | [#2] audio-dft Cycles:10 Average:164.40 Median:164.50 stddev:2.07 (1.3%) stddev-sans-first:2.13 09:02:42 INFO - PID 15089 | Values: 163.0 162.0 164.0 162.0 163.0 165.0 164.0 166.0 168.0 167.0 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | [#3] audio-fft Cycles:10 Average:74.20 Median:74.50 stddev:1.14 (1.5%) stddev-sans-first:1.17 09:02:42 INFO - PID 15089 | Values: 75.0 74.0 76.0 73.0 74.0 74.0 74.0 72.0 75.0 75.0 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | [#4] audio-oscillator Cycles:10 Average:79.70 Median:80.00 stddev:1.49 (1.9%) stddev-sans-first:1.56 09:02:42 INFO - PID 15089 | Values: 79.0 81.0 78.0 79.0 80.0 83.0 78.0 80.0 79.0 80.0 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | [#5] imaging-gaussian-blur Cycles:10 Average:115.20 Median:114.00 stddev:3.39 (3.0%) stddev-sans-first:3.50 09:02:42 INFO - PID 15089 | Values: 113.0 114.0 113.0 118.0 114.0 114.0 114.0 114.0 114.0 124.0 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 09:02:42 INFO - PID 15089 | Values: 148.0 148.0 147.0 147.0 147.0 148.0 148.0 148.0 148.0 150.0 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.00 stddev:1.65 (1.8%) stddev-sans-first:0.33 09:02:42 INFO - PID 15089 | Values: 99.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:1.52 (2.8%) stddev-sans-first:1.13 09:02:42 INFO - PID 15089 | Values: 57.0 54.0 54.0 56.0 54.0 53.0 53.0 52.0 53.0 53.0 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | [#9] json-stringify-tinderbox Cycles:10 Average:38.50 Median:39.00 stddev:0.53 (1.4%) stddev-sans-first:0.53 09:02:42 INFO - PID 15089 | Values: 39.0 39.0 39.0 39.0 38.0 38.0 38.0 38.0 38.0 39.0 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | [#10] stanford-crypto-aes Cycles:10 Average:77.10 Median:77.00 stddev:1.85 (2.4%) stddev-sans-first:1.83 09:02:42 INFO - PID 15089 | Values: 79.0 78.0 76.0 76.0 76.0 78.0 75.0 76.0 81.0 76.0 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | [#11] stanford-crypto-ccm Cycles:10 Average:114.80 Median:116.00 stddev:3.49 (3.0%) stddev-sans-first:3.50 09:02:42 INFO - PID 15089 | Values: 118.0 120.0 116.0 109.0 117.0 115.0 112.0 115.0 110.0 116.0 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.00 Median:174.00 stddev:3.68 (2.1%) stddev-sans-first:3.91 09:02:42 INFO - PID 15089 | Values: 174.0 171.0 177.0 182.0 171.0 171.0 174.0 177.0 171.0 172.0 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:1.72 (2.6%) stddev-sans-first:1.81 09:02:42 INFO - PID 15089 | Values: 65.0 65.0 63.0 67.0 69.0 64.0 66.0 66.0 66.0 64.0 09:02:42 INFO - PID 15089 | -------- Summary: end -------- 09:02:42 INFO - PID 15089 | 09:02:42 INFO - PID 15089 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:02:43 INFO - TEST-INFO | 15089: exit 0 09:02:43 INFO - TEST-OK | kraken | took 61880ms 09:02:43 INFO - SUITE-END | took 1171s 09:02:43 INFO - Completed test suite (00:19:32) 09:02:43 INFO - javascript benchmark 09:02:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8413.299524566155, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3157.810531368117, 3162.1304141361124, 3182.395344884472, 3209.069369882734, 3219.114743445552, 6198.690065496729, 6426.967865160674, 6431.16394868667, 6505.739770409178, 6535.444487218586, 6368.681565921708, 6393.136926514919, 6417.486601071914, 6425.357464253572, 6441.259255185655, 5919.1713160157615, 5984.700764961745, 5997.430244126801, 6007.219061521996, 6070.514358851304, 5798.130280457944, 5973.790917317895, 5995.6702381369005, 6050.455459008688, 6064.666443345614, 5808.82573522795, 5854.76580936762, 5933.199018132562, 5976.581639285252, 5991.880162396727, 5857.26784151981, 5858.209141765849, 5961.582689211757, 5961.850953726148, 5966.791162309299, 5866.2960444746905, 5895.115732640095, 5947.10793380992, 5965.343812180657, 5968.492678122365, 6205.317415084337, 6341.714622841983, 6441.935580644227, 6511.0, 6545.967270163619, 6814.114165158548, 6834.58992460449, 6838.829029274308, 6851.109355783766, 6861.759838405632, 7018.684159212848, 7057.435405167575, 7068.858622827566, 7071.222165561784, 7073.045138906234, 6279.843003924847, 6418.614883107029, 6436.742530298782, 6439.613623182578, 6534.1505604271615, 7068.646567671699, 7085.787426377217, 7087.0432491614, 7089.007538944552, 7090.361867431956, 6733.696983635748, 6734.191896972395, 6736.157980252468, 6744.190697116377, 6748.595084294958, 6759.966200168968, 6830.18037835453, 6830.282820303896, 6833.453323734188, 6868.656567171622, 6787.728490860409, 6795.694193761293, 6806.93193068063, 6956.234814170437, 6962.408195303456, 6894.620795856175, 6926.030355750199, 7022.087128673342, 7023.648817559101, 7034.788956331318, 5229.633925625246, 5248.501392367719, 5285.841424757264, 5309.601779866525, 5313.814016509404, 4488.865334039984, 4563.429571303587, 4566.292224705176, 4633.305004249322, 4635.930461043087, 5972.283326000907, 5996.430339117776, 6004.939950600526, 6008.128821320972, 6020.638761674313, 7003.5797852129035, 7009.894851577231, 7079.469039821931, 7089.7873063808165, 7091.11361079865, 5900.675462849585, 5941.465268125889, 5999.460048595647, 6001.639901605918, 6010.068439391901, 5566.332040155207, 5644.604877658607, 5668.603197776199, 5668.8866222675315, 5671.546276297886, 6610.471162307004, 6709.832254193684, 6713.832154196184, 6727.562708424035, 6736.2590115087305, 6901.206361268418, 6982.720691172296, 6984.650767461607, 7029.61337126463, 7033.753818616324, 6690.297518760654, 6715.127033485615, 6770.796876093725, 6794.93205067943, 6801.693923773541, 6673.065770791996, 6792.626405547742, 6816.250212476524, 6840.0, 6929.099217101744, 6842.452603791784, 6895.172579290517, 6896.482763792836, 6913.792586222421, 6920.861582768417, 6639.70121344531, 6647.900281495685, 6670.266270710315, 6677.2655007948115, 6679.532432729662, 6735.25912149673, 6786.558873673195, 6806.387425131762, 6813.557118787263, 6835.316468353125, 6680.3987641112535, 6688.0, 6729.360710732472, 6763.7632682855865, 6769.864602708017, 6545.476361891133, 6608.207015158212, 6724.529282950147, 6770.1537307836525, 6847.726090956306, 6557.639329836905, 6570.671466426755, 6628.204615446178, 6636.43590294811, 6640.535162538576], "value": 6274.039001338554, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.3437250199838, 842.3093063687777, 861.1474640106296, 863.7970077031896, 865.9307255419567, 38969.61030389695, 39252.21495570087, 39614.2077158457, 39709.80145099274, 39889.40165897507, 35972.64027359726, 39352.60647393525, 39953.60046399535, 40037.0, 40072.79963600185, 7459.701611935529, 7575.204603516634, 7591.696332146707, 7595.202503737111, 7612.086549614054, 7532.924670753305, 7580.848383032364, 7639.04511936008, 7641.159472458025, 7643.61781910905, 7624.428167887403, 7643.121041080263, 7655.885161722578, 7679.961600191991, 7684.616768981581, 7596.316331530161, 7598.544087354749, 7605.163432022473, 7628.54228746277, 7633.732819351324, 7475.102987641518, 7482.551046937201, 7543.094828620546, 7543.585102819343, 7543.962280188619, 36632.633673663455, 36790.8160459196, 37072.0, 37245.813770930974, 37278.44082338767, 3415.3681568909833, 3426.246225830338, 3430.828458577061, 3447.931041379158, 3460.965390346115, 30199.396012079855, 30230.54654180189, 30329.848350758326, 30368.8481557593, 30444.238894027603, 4973.477784832613, 5180.041692286902, 5191.195364718475, 5236.528712415863, 5244.842654720326, 178488.0, 179551.0, 181544.0, 182788.0, 193914.0, 46137.7693111539, 46525.30212046822, 46623.0, 46626.0, 46683.0, 47529.0, 47639.0, 47718.5228147721, 47814.521854781706, 48042.0, 14982.175980321186, 15113.47102851417, 15140.394384224508, 15148.848511514743, 15191.924040379727, 12286.385680715928, 12334.629961101182, 12379.319137447346, 12404.503819847285, 12409.75180496385, 12277.631671049705, 12474.75050499003, 12523.0, 12523.311217883103, 12657.303848288433, 5044.8234311798915, 5070.467600901852, 5122.795088196505, 5185.274061631375, 5251.925489431653, 972.8005758819424, 1003.1573478278135, 1003.4581326083833, 1012.2964539644876, 1027.99486002571, 21954.45113872166, 22157.224497142524, 22274.665880011813, 22495.66256506154, 22878.885605571864, 1076.4779082145153, 1100.4882729530807, 1106.3859557945418, 1107.8172101603145, 1113.493360520961, 11751.823722644167, 11936.701582460508, 11946.76106477883, 11993.160478766402, 12061.396930153458, 227986.0, 229324.0, 229342.0, 229710.0, 229791.0, 49891.750541247784, 50035.49964500381, 50047.74976125096, 50126.749366253665, 50175.0, 1412.166821575255, 1430.9427622894968, 1439.5105664074056, 1447.0304895719682, 1447.6742732885016, 21417.46456338604, 21815.781842181375, 21897.890510547346, 22102.77897221007, 22235.77764222337, 34921.301573968885, 34954.30091398107, 34979.47530786989, 35003.82498087493, 35003.82498087595, 196062.0, 196349.0, 196431.0, 197592.0, 197867.0, 30209.0, 31395.37209255848, 31495.21261968469, 31563.52654710135, 31574.0, 38963.220735585695, 39763.80118099391, 39947.40078898877, 40110.0, 40907.0, 29433.55849662214, 29559.26101847471, 30045.0, 30087.548686769278, 31036.0, 29706.702932970395, 30312.5453118199, 30322.54516182215, 30384.088477345715, 30844.845775770977, 33219.003429897144, 33904.1523961893, 33916.0, 34139.31721365608, 34168.0, 29805.254868628457, 30065.699343007163, 30211.85934451118, 30708.385832283675, 30758.69241307648, 21959.890200548896, 22309.094086222693, 22408.0, 22548.774512254666, 22757.544849102593], "value": 16459.21581224347, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.9914651280232, 571.5027925704636, 572.1703529881671, 575.6200907401117, 575.6344721102099, 21698.674519882195, 22280.0, 22329.665055024187, 22389.328320150402, 22392.440188995264, 23424.945877435515, 24503.142390016354, 24598.50802983939, 24826.0, 24889.62665560018, 6229.127922090911, 6416.518761092913, 6421.678916054178, 6422.421982021617, 6442.194725659293, 6345.111684364193, 6434.195725534309, 6436.581622194542, 6442.033694945772, 6443.484521238313, 6234.937650623481, 6416.967915160418, 6418.42234198922, 6489.61062336259, 6511.57674751142, 6508.0, 6531.4448271896945, 6550.6069635822005, 6552.901706474407, 6556.999999999976, 5799.88400231993, 6054.364291749347, 6072.271327440735, 6076.027835546335, 6083.361247069039, 28258.576121358197, 28757.424851503063, 28759.0, 28806.279843003882, 28861.0, 2005.929792457257, 2024.4736368544284, 2029.2897485879823, 2036.6232247034345, 2037.5313677854176, 19182.424527264066, 19572.0, 19808.70286945697, 19854.0, 20007.499812504804, 2430.0, 2483.2674361063528, 2497.063601149569, 2502.87485625718, 2511.3344963584664, 123342.38328808389, 128408.35795820961, 131995.0, 141252.0, 141707.29146354305, 14164.36260368286, 14345.282735863166, 14442.566722998326, 14623.707525849424, 14815.777503411477, 22860.31419057431, 22878.42803929915, 22924.54150916972, 22932.770672293398, 23006.19478318251, 9882.851757223647, 10017.949910250549, 10033.218289482238, 10047.849282260771, 10055.0, 9158.816823663623, 9222.815543689088, 9294.070592940787, 9311.394759340757, 9449.149576538008, 8758.912410875937, 8975.0, 9071.727848164566, 9087.863682044775, 9112.681056163003, 2639.736026397345, 2639.7624213820463, 2642.7621514063826, 2671.2202233838902, 2733.1117386849355, 948.9857652135223, 968.7917097823861, 974.2644303550774, 982.3123813330698, 992.4144754594823, 18665.253389864523, 18712.719309210373, 18968.241270349306, 19021.14404851785, 19046.90476547636, 981.2444417798398, 1009.9798004039878, 1018.6282007067367, 1025.9230557708202, 1037.1391744851755, 10488.790224195473, 10494.42280674555, 10598.788024239471, 10624.734381640366, 10644.25490215693, 440991.0, 443110.0, 450011.0, 454273.0, 460919.0, 106193.0, 108820.4558977216, 109319.0, 109838.45080774705, 112788.43605781918, 1081.000074930686, 1128.0, 1143.451143451148, 1146.9942650286696, 1150.3615493401242, 16743.91628041852, 17110.40113596068, 17167.57081072983, 17314.567135821704, 17359.479215623553, 52490.0, 53095.46904530905, 53503.73248133734, 54568.27158642132, 55298.0, 64885.351146487934, 65752.34247657462, 66026.0, 66070.33929660641, 67166.328336716, 13734.45062197501, 14101.435942562593, 14108.647283817576, 14120.576382708536, 14127.293635318398, 21597.784022160205, 21938.341849744535, 21984.340469785933, 22093.226737064124, 22281.331560052575, 17537.649247015244, 17877.553061173574, 17891.19489622944, 17960.730589040915, 18003.189856456753, 12475.814797594214, 13081.803772943604, 15386.153761543223, 15845.52463426099, 16453.83546164523, 17020.74468882943, 17603.11984400749, 17767.289308428037, 17770.20034098443, 18059.54851128732, 13678.93160534191, 13866.237356945061, 13872.791908121184, 13901.443942242196, 13936.4425422986, 17652.64409862775, 17741.911290443466, 17926.820731792515, 17958.281668733627, 18003.09984500796], "value": 11606.17972872712, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.2411925202632, 1383.8408583012956, 1390.6245313765285, 1391.5616580777062, 1398.3497673581783, 14931.925340373296, 15552.766708499381, 15617.609559761011, 15620.687586248268, 15620.765688514653, 15122.848771512281, 16233.0, 16240.187990600452, 16244.75632865505, 16284.75572866408, 2810.0445848411537, 2858.785591080667, 2867.6272084628968, 2871.7271859173447, 2874.1090262018843, 2642.9867850660817, 2790.525610646195, 2829.5472724364004, 2850.5154123799, 2854.457653045925, 2812.2547524905813, 2833.504136776056, 2869.2539939615745, 2870.3254735137225, 2871.8994835180774, 2729.6314997475383, 2748.9037883674077, 2758.5310497215523, 2795.6784969728333, 2796.384795345021, 2717.7689896358634, 2727.1136880513714, 2746.6841313249033, 2758.434520923206, 2761.2682639100453, 14845.406183752637, 14989.775153372708, 15092.622684432867, 15145.697086058328, 15153.469628563058, 7947.9205207948335, 8216.26053655174, 8227.053888802804, 8238.752837414888, 8250.876236856451, 5402.37872644647, 5625.38120806711, 5669.206311116447, 5690.40250773667, 5777.075667893159, 1844.3452574336052, 1866.1602278974515, 1868.9719654205198, 1923.1249781349452, 1929.5754933914516, 26435.735642643518, 26507.0, 26644.33389165267, 26764.197074087613, 26918.192454226406, 6852.417544508772, 6867.866801977718, 7030.945358196312, 7116.822079447952, 7123.536970096927, 17404.39084632032, 17430.215640295963, 17442.825571744375, 17511.912440437973, 17532.123393830258, 7953.761387158394, 8045.115037345888, 8066.717664881701, 8075.878861817077, 8108.148644392254, 7089.291070892869, 7191.712331506786, 7211.062561866924, 7217.0, 7242.891356629655, 5238.1618940969265, 5411.729413529308, 5441.972790136103, 5474.945250547444, 5486.862828429242, 1572.905625662464, 1589.2053973013494, 1593.8724902007812, 1602.583328334618, 1605.702944955187, 867.7266661001764, 884.195382202193, 895.7671005538607, 896.0994200828126, 905.8233644439402, 7381.963090184515, 7437.181909989897, 7464.813379665443, 7493.475456718086, 7512.060992375953, 708.4014008163094, 725.2131437390491, 732.2201855024382, 732.4470025130992, 737.3142977031413, 5122.129238029544, 5190.6107041971245, 5208.661437006583, 5295.02042122201, 5337.332833395825, 20740.481487962923, 20824.47938801542, 21664.675029874565, 21693.457663558536, 22024.559508810056, 21045.263415780373, 21106.261280855095, 21439.142434302765, 21892.45268868291, 22083.447913801963, 9523.809523809485, 9899.604015839286, 11111.499982500807, 11143.498542565603, 11527.193096483334, 8171.632276547451, 8435.8734618982, 8454.66181352739, 8480.279176270085, 8615.267702245128, 18090.27638894482, 18521.35175268859, 18674.253029879197, 19042.904785475985, 19373.70939435938, 18722.719159212895, 18794.436166915013, 19082.713759293343, 19134.138963746387, 19184.52038698988, 10585.206109541661, 10606.734831628963, 10677.14582833387, 10709.0, 10711.89288107109, 13764.105333153313, 13889.236092015037, 13894.374753135931, 13916.721665566429, 13940.512082077079, 9789.0, 10107.090361867467, 10124.443155626217, 10213.642522511676, 10390.740231494274, 10327.122194613241, 10426.374417534973, 10632.574697012033, 10653.520591573242, 10791.40647264408, 9346.345755797302, 9375.906240937504, 9421.104995025462, 9428.905710943078, 9448.0, 7003.7548685795755, 7052.400545953651, 7106.822329441805, 7142.8214294640975, 7202.927970720226, 7008.299170083154, 7009.285078908187, 7137.750178743927, 7147.070880785673, 7203.063601731741], "value": 6617.1047640946335, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.8662104542297, 505.1740404438744, 529.8013245033112, 531.9201920490881, 533.7944891216886, 6347.904781428282, 6359.904601430976, 6368.04479328101, 6373.788980093785, 6408.359164083589, 6239.719212635431, 6291.458592644808, 6292.307846136932, 6302.0, 6314.684265786704, 5213.374395072597, 5226.425093239741, 5254.973725131369, 5259.3951695555115, 5264.868378290555, 4914.631402644798, 5094.293411707083, 5257.7108259045735, 5265.684058956455, 5291.814786482474, 4932.950670493303, 4981.402231732197, 5004.299398084271, 5009.0, 5027.547520723134, 4982.875428114308, 4995.1757959936385, 4997.950020499785, 5011.448740638527, 5026.095302845523, 4403.933940990888, 4486.798094085774, 4489.663275254369, 4490.10197960406, 4511.390962220091, 6076.210092687967, 6145.170401995762, 6191.628502289877, 6206.224221972254, 6221.4089661482085, 5680.630759000652, 5682.17608446777, 5688.146777983336, 5703.31560212773, 5713.371529131792, 6516.9674151629415, 6556.573822701509, 6571.7371305147735, 6576.309487503791, 6586.308437614078, 4677.4620918594455, 4864.440589332236, 4905.852824415273, 4909.558139767438, 4920.8031678732805, 6796.4562834973085, 6804.761833335859, 6868.416184624313, 6912.827179320507, 6951.339622735832, 5005.599552035828, 5007.39911210657, 5038.294638750578, 5065.417476990119, 5076.847694569169, 5040.2691609716385, 5081.136206844845, 5100.158473851773, 5100.699321673023, 5125.026864657134, 5630.0, 5674.921764864663, 5724.313082430135, 5743.0, 5749.68376739275, 6589.76935807254, 6636.336366363422, 6645.568038077509, 6671.43292820106, 6688.431483323875, 1759.9120043997748, 1766.7968183659043, 1768.5490199999176, 1779.7330400440037, 1791.7849858016862, 1166.7491489329834, 1215.3619349841404, 1224.6326102169314, 1224.9142560020891, 1232.5562797392931, 5666.4050274721385, 5743.253377061039, 5755.740991655385, 5764.596478246566, 5766.855828604318, 6701.966490167518, 6741.629210393476, 6764.932350676529, 6780.186377634715, 6805.3875151236625, 5628.437156284339, 5657.264555607745, 5660.122680984454, 5689.288838895138, 5741.483266506035, 3515.876944306988, 3546.1489242581592, 3550.946735798965, 3566.411542095525, 3595.2449985502813, 6502.739890404331, 6529.216494020653, 6539.836504087436, 6541.771038013647, 6581.703823327962, 6440.0, 6769.627670478072, 6904.240533541307, 6908.171019477695, 6915.861682766417, 6412.711427985659, 6449.226092868792, 6460.063290822711, 6460.806175814733, 6462.192225971754, 6436.549441539048, 6597.967010164918, 6620.271770105381, 6627.7348906045145, 6655.0, 6527.608343499405, 6580.736770529125, 6674.332566743481, 6683.7660681875905, 6690.899636505359, 6475.449586785176, 6499.642519661464, 6538.705758240795, 6569.737210511717, 6579.2104947406615, 4928.704277743347, 4994.701377641767, 5034.370703662043, 5058.392992840735, 5066.9239961401345, 6520.315366886598, 6542.574732642363, 6627.403533681992, 6778.762743303961, 6841.673830897519, 6411.326810684997, 6444.355564443706, 6458.7093580789915, 6498.350164983463, 6557.639329836905, 6514.9348506514325, 6536.8365790855605, 6542.378474044958, 6546.821572116874, 6561.475081993524, 6456.063870738622, 6460.321666225167, 6473.514486413444, 6502.3172566881685, 6524.347565243438, 6611.768588099393, 6621.834454138685, 6650.534462587572, 6653.534252602271, 6670.199576050905, 6409.166808314889, 6416.807495775134, 6433.967830160819, 6455.483561315177, 6462.321456247027], "value": 5078.549324032411, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [919.5080631861955, 925.2829057480454, 928.4336554701636, 937.0161330602865, 954.4034978138664, 18251.452456426312, 18644.720329195072, 18656.813431865678, 18686.252549898003, 18725.812741872578, 18315.084245787722, 18519.074046297697, 18635.44093677192, 18663.44009679712, 18694.439166824985, 947.5593848860261, 949.8812648418948, 951.6383774165841, 952.4523399045542, 952.7618095476131, 967.9177269932064, 973.756560859785, 973.7808992976594, 976.9853452198223, 980.1178938954927, 986.4081551069373, 1020.0513522424143, 1026.2816028779846, 1037.3827572594294, 1040.1938497664332, 1030.9845352319721, 1030.9896901030968, 1031.128696251666, 1031.8349064149738, 1032.7779527401608, 725.6081715873422, 742.3430264216207, 747.4805010517692, 750.0624219725344, 750.246002767221, 19608.11763470633, 19658.0, 19714.70427943582, 19803.306884258982, 19901.203951841908, 21778.564428711496, 21949.78050219493, 22234.55530889373, 22441.887790561104, 22620.773792262033, 17736.379226727004, 17950.19224134904, 17988.190531426117, 18056.09719514019, 18101.366452174112, 3677.2093999789904, 3713.0160507465544, 3715.256948610289, 3719.8140092995513, 3729.4032954727113, 100599.0, 142494.28752856274, 143398.99999999895, 143420.0, 144921.0, 17368.826311736975, 17419.564510887074, 17942.192601332972, 17988.460346189637, 18081.638367232583, 31911.202219944687, 32602.83698581492, 32692.34615307728, 32695.673043269737, 32762.836185819397, 9117.863232051524, 9191.264698824078, 9214.44713317203, 9223.907760922439, 9266.860997085048, 4090.8568200112854, 4167.062410957571, 4167.854125105668, 4171.395147703627, 4178.080822219107, 5281.683099014072, 5308.575313974873, 5404.216388623628, 5424.267723857229, 5434.91847622286, 1030.3354336452946, 1030.8711411073616, 1056.1709058389129, 1056.7569459024314, 1058.1587637827945, 657.6744511466759, 668.7258224128084, 679.7009315900988, 683.7162577530369, 690.9032735417046, 10804.0, 10923.836142457869, 10935.343879367263, 12369.752604947851, 12414.317212553397, 664.1266734244454, 664.1432552007856, 668.9565178263397, 687.2783577243874, 691.1395312835552, 5275.498827611371, 5409.24270602116, 5462.617616766788, 5468.179773033997, 5488.588355873246, 115767.0, 115783.42108289573, 116211.41894290474, 118539.0, 119973.40013299878, 105040.0, 107134.0, 108719.0, 109395.45302273438, 111370.0, 98302.0, 99659.50170249102, 100425.0, 100832.49583752034, 101133.49433252787, 9993.400395976265, 10125.341852779546, 10238.53926573291, 10341.9482902585, 10540.262181647518, 41428.37857432081, 42556.574434255264, 43653.0, 43843.78078109589, 43876.56123438852, 54944.45055549553, 55536.72231638816, 55767.16349254683, 55917.88164236774, 57603.42396575981, 14736.410543578137, 14935.701285974003, 14970.850291497381, 14971.850281497482, 15157.696846062796, 16490.505284841474, 16541.503754887373, 16673.981128687716, 16763.329466821677, 16826.0, 13470.52853150135, 13566.389512471766, 13577.0, 13723.245221512514, 13728.313584320544, 15505.457308994582, 15653.37386504527, 15763.763543547086, 15800.209989500709, 15817.525474235792, 14292.785608216092, 14479.203643799692, 14547.781783273047, 14585.197814120325, 14610.415583376545, 11051.557937682725, 11323.0, 11391.544338226378, 11403.543858245577, 11438.08495320389, 11130.888691113309, 11211.159163062639, 11342.60300889465, 11527.250728702942, 11542.711432214262], "value": 8804.830512025752, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1424.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 107.0, 107.0, 106.0, 109.0, 102.0, 109.0, 107.0, 108.0, 107.0], "value": 106.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 126.0, 120.0, 116.0, 116.0, 120.0, 115.0, 115.0, 124.0], "value": 118.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 164.0, 162.0, 163.0, 165.0, 164.0, 166.0, 168.0, 167.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 76.0, 73.0, 74.0, 74.0, 74.0, 72.0, 75.0, 75.0], "value": 74.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 78.0, 79.0, 80.0, 83.0, 78.0, 80.0, 79.0, 80.0], "value": 79.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 118.0, 114.0, 114.0, 114.0, 114.0, 114.0, 124.0], "value": 115.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 147.0, 147.0, 148.0, 148.0, 148.0, 148.0, 150.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 54.0, 56.0, 54.0, 53.0, 53.0, 52.0, 53.0, 53.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 39.0, 39.0, 38.0, 38.0, 38.0, 38.0, 38.0, 39.0], "value": 38.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 76.0, 76.0, 76.0, 78.0, 75.0, 76.0, 81.0, 76.0], "value": 77.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 120.0, 116.0, 109.0, 117.0, 115.0, 112.0, 115.0, 110.0, 116.0], "value": 114.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 171.0, 177.0, 182.0, 171.0, 171.0, 174.0, 177.0, 171.0, 172.0], "value": 174.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 63.0, 67.0, 69.0, 64.0, 66.0, 66.0, 66.0, 64.0], "value": 65.5, "unit": "score"}]}]} 09:02:43 INFO - Return code: 0 09:02:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:02:43 INFO - # TBPL SUCCESS # 09:02:43 INFO - Running post-action listener: _package_coverage_data 09:02:43 INFO - Running post-action listener: _resource_record_post_action 09:02:43 INFO - [mozharness: 2017-09-14 16:02:43.678737Z] Finished run-tests step (success) 09:02:43 INFO - Running post-run listener: _resource_record_post_run 09:02:43 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 344821760; Read time: 8; Write time: 191644 09:02:43 INFO - TinderboxPrint: CPU usage
13.1% 09:02:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 09:02:43 INFO - TinderboxPrint: I/O write bytes / time
344,821,760 / 191,644 09:02:43 INFO - TinderboxPrint: CPU idle
8,243.2 (86.9%) 09:02:43 INFO - TinderboxPrint: CPU user
1,194.6 (12.6%) 09:02:43 INFO - TinderboxPrint: Swap in / out
0 / 0 09:02:43 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13176832; Read time: 0; Write time: 1920 09:02:43 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:02:43 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 331644928; Read time: 0; Write time: 189724 09:02:44 INFO - Running post-run listener: _upload_blobber_files 09:02:44 INFO - Blob upload gear active. 09:02:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:02:44 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 09:02:44 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:02:44 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:02:44 INFO - (blobuploader) - INFO - Open directory for files ... 09:02:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:02:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:02:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:45 INFO - (blobuploader) - INFO - Done attempting. 09:02:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:02:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:02:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:46 INFO - (blobuploader) - INFO - Done attempting. 09:02:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:02:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:02:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:47 INFO - (blobuploader) - INFO - Done attempting. 09:02:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:02:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:02:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:02:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:02:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:02:54 INFO - (blobuploader) - INFO - Done attempting. 09:02:54 INFO - (blobuploader) - INFO - Iteration through files over. 09:02:54 INFO - Return code: 0 09:02:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:02:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:02:54 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ba1cdc4a167140f9fcf6d79d764a6eb1214fbddc5128ae7434cf6db5c430d30230bc852addf9316bb47eb915eb19f441f85352db6fda1d0563dd321764ba2352", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1625a2591f7b203e91da0d8191a988b4ffe63c1616c9c9a43adeffcb2c380e2385ef52af510e641d3232362e50c5452be394c6fbb890bf00d88192da6f2d7d67", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/702914aec60886f30fc0eb9c57d5011ef8ab2d006a2fd8eb06be64252119a969ff0d4a101b785b03d1b374a12bb56a721fe22eabd2648aba115dd5c923a7d01c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b717ec03e05971348736193923e56ef190f1af5c03383a74b02bdab689c50c53eb51e11419fa8f9da6f1e766954b0fad3ce254c5c2d5a0bb2e803aea14f0226a"} 09:02:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:02:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:02:54 INFO - Contents: 09:02:54 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ba1cdc4a167140f9fcf6d79d764a6eb1214fbddc5128ae7434cf6db5c430d30230bc852addf9316bb47eb915eb19f441f85352db6fda1d0563dd321764ba2352", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1625a2591f7b203e91da0d8191a988b4ffe63c1616c9c9a43adeffcb2c380e2385ef52af510e641d3232362e50c5452be394c6fbb890bf00d88192da6f2d7d67", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/702914aec60886f30fc0eb9c57d5011ef8ab2d006a2fd8eb06be64252119a969ff0d4a101b785b03d1b374a12bb56a721fe22eabd2648aba115dd5c923a7d01c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b717ec03e05971348736193923e56ef190f1af5c03383a74b02bdab689c50c53eb51e11419fa8f9da6f1e766954b0fad3ce254c5c2d5a0bb2e803aea14f0226a"} 09:02:54 INFO - Running post-run listener: copy_logs_to_upload_dir 09:02:54 INFO - Copying logs to upload dir... 09:02:54 INFO - mkdir: /builds/slave/test/build/upload/logs 09:02:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.657772 ========= master_lag: 0.33 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-14 09:02:55.018403) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-14 09:02:55.025587) ========= 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=920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765 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-Non-PGO/sha512/ba1cdc4a167140f9fcf6d79d764a6eb1214fbddc5128ae7434cf6db5c430d30230bc852addf9316bb47eb915eb19f441f85352db6fda1d0563dd321764ba2352", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1625a2591f7b203e91da0d8191a988b4ffe63c1616c9c9a43adeffcb2c380e2385ef52af510e641d3232362e50c5452be394c6fbb890bf00d88192da6f2d7d67", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/702914aec60886f30fc0eb9c57d5011ef8ab2d006a2fd8eb06be64252119a969ff0d4a101b785b03d1b374a12bb56a721fe22eabd2648aba115dd5c923a7d01c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b717ec03e05971348736193923e56ef190f1af5c03383a74b02bdab689c50c53eb51e11419fa8f9da6f1e766954b0fad3ce254c5c2d5a0bb2e803aea14f0226a"} build_url:https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011350 build_url: 'https://queue.taskcluster.net/v1/task/f-zK7lcmQRi0nOkT1XZjYQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ba1cdc4a167140f9fcf6d79d764a6eb1214fbddc5128ae7434cf6db5c430d30230bc852addf9316bb47eb915eb19f441f85352db6fda1d0563dd321764ba2352", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1625a2591f7b203e91da0d8191a988b4ffe63c1616c9c9a43adeffcb2c380e2385ef52af510e641d3232362e50c5452be394c6fbb890bf00d88192da6f2d7d67", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/702914aec60886f30fc0eb9c57d5011ef8ab2d006a2fd8eb06be64252119a969ff0d4a101b785b03d1b374a12bb56a721fe22eabd2648aba115dd5c923a7d01c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b717ec03e05971348736193923e56ef190f1af5c03383a74b02bdab689c50c53eb51e11419fa8f9da6f1e766954b0fad3ce254c5c2d5a0bb2e803aea14f0226a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-14 09:02:55.055054) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 09:02:55.055389) ========= 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=920470bf432669ad7e3a5387000001ca-1505402431.622766-1784532765 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004601 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 09:02:55.136042) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-14 09:02:55.139382) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-14 09:02:55.139700) ========= ========= Total master_lag: 0.51 =========