builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-056 starttime: 1505344493.17 results: success (0) revision: 0b3646aa9cbf3f77ee574a553ade9685e9229ab5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 16:14:53.175240) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 16:14:53.175664) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 16:14:53.175932) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 16:14:53.191916) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:14:53.192266) ========= 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=52faae308a556c440401f8e5000001e8-1505344492.352962-411523862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-13 16:14:53-- 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% 661K=0.02s 2017-09-13 16:14:53 (661 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.201967 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:14:53.412424) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:14:53.412896) ========= 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=52faae308a556c440401f8e5000001e8-1505344492.352962-411523862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.024533 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:14:53.463095) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:14:53.463432) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0b3646aa9cbf3f77ee574a553ade9685e9229ab5 --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 0b3646aa9cbf3f77ee574a553ade9685e9229ab5 --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=52faae308a556c440401f8e5000001e8-1505344492.352962-411523862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-13 16:14:53,513 truncating revision to first 12 chars 2017-09-13 16:14:53,513 Setting DEBUG logging. 2017-09-13 16:14:53,514 attempt 1/10 2017-09-13 16:14:53,514 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0b3646aa9cbf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-13 16:14:53,878 unpacking tar archive at: mozilla-central-0b3646aa9cbf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.692621 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:14:54.172066) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 16:14:54.172390) ========= script_repo_revision: 0b3646aa9cbf3f77ee574a553ade9685e9229ab5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 16:14:54.172747) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 16:14:54.173006) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 16:14:54.186282) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-13 16:14:54.186566) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1505344492.352962-411523862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:14:54 INFO - MultiFileLogger online at 20170913 16:14:54 in /builds/slave/test 16:14:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:14:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:14:54 INFO - {'append_to_log': False, 16:14:54 INFO - 'base_work_dir': '/builds/slave/test', 16:14:54 INFO - 'blob_upload_branch': 'Firefox', 16:14:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:14:54 INFO - 'branch': 'Firefox', 16:14:54 INFO - 'buildbot_json_path': 'buildprops.json', 16:14:54 INFO - 'code_coverage': False, 16:14:54 INFO - 'config_files': ('talos/linux_config.py',), 16:14:54 INFO - 'default_actions': ('clobber', 16:14:54 INFO - 'read-buildbot-config', 16:14:54 INFO - 'download-and-extract', 16:14:54 INFO - 'populate-webroot', 16:14:54 INFO - 'create-virtualenv', 16:14:54 INFO - 'install', 16:14:54 INFO - 'setup-mitmproxy', 16:14:54 INFO - 'run-tests'), 16:14:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:14:54 INFO - 'disable_ccov_upload': False, 16:14:54 INFO - 'disable_stylo': False, 16:14:54 INFO - 'download_minidump_stackwalk': True, 16:14:54 INFO - 'download_symbols': 'ondemand', 16:14:54 INFO - 'enable_stylo': False, 16:14:54 INFO - 'enable_webrender': False, 16:14:54 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:14:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:14:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:14:54 INFO - 'gecko_profile': False, 16:14:54 INFO - 'gecko_profile_interval': 0, 16:14:54 INFO - 'installer_path': 'installer.exe', 16:14:54 INFO - 'log_level': 'info', 16:14:54 INFO - 'log_name': 'talos', 16:14:54 INFO - 'log_to_console': True, 16:14:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:14:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:14:54 INFO - 'opt_config_files': (), 16:14:54 INFO - 'pip_index': False, 16:14:54 INFO - 'suite': 'dromaeojs-e10s', 16:14:54 INFO - 'system_bits': '32', 16:14:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:14:54 INFO - 'title': 'talos-linux64-ix-056', 16:14:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:14:54 INFO - 'use_talos_json': True, 16:14:54 INFO - 'verify': 'False', 16:14:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:14:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:14:54 INFO - 'work_dir': 'build'} 16:14:54 INFO - [mozharness: 2017-09-13 23:14:54.380201Z] Running clobber step. 16:14:54 INFO - Running pre-action listener: _resource_record_pre_action 16:14:54 INFO - Running main action method: clobber 16:14:54 INFO - rmtree: /builds/slave/test/build 16:14:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:14:56 INFO - Running post-action listener: _resource_record_post_action 16:14:56 INFO - [mozharness: 2017-09-13 23:14:56.008590Z] Finished clobber step (success) 16:14:56 INFO - [mozharness: 2017-09-13 23:14:56.008687Z] Running read-buildbot-config step. 16:14:56 INFO - Running pre-action listener: _resource_record_pre_action 16:14:56 INFO - Running main action method: read_buildbot_config 16:14:56 INFO - Using buildbot properties: 16:14:56 INFO - { 16:14:56 INFO - "project": "", 16:14:56 INFO - "product": "firefox", 16:14:56 INFO - "who": "archaeopteryx@coole-files.de", 16:14:56 INFO - "installer_path": "public/build/target.tar.bz2", 16:14:56 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:14:56 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:14:56 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 16:14:56 INFO - "stage_platform": "linux64-pgo", 16:14:56 INFO - "basedir": "/builds/slave/test", 16:14:56 INFO - "buildnumber": 182, 16:14:56 INFO - "platform": "ubuntu64_hw", 16:14:56 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:14:56 INFO - "slavebuilddir": "test", 16:14:56 INFO - "taskId": "UJHMtoddQGiHKK3OYIoBQQ", 16:14:56 INFO - "branch": "mozilla-central", 16:14:56 INFO - "script_repo_revision": "production", 16:14:56 INFO - "revision": "0b3646aa9cbf3f77ee574a553ade9685e9229ab5", 16:14:56 INFO - "slavename": "talos-linux64-ix-056", 16:14:56 INFO - "repo_path": "mozilla-central" 16:14:56 INFO - } 16:14:56 INFO - Finding installer, test and symbols from parent task. 16:14:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UJHMtoddQGiHKK3OYIoBQQ'}, attempt #1 16:14:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UJHMtoddQGiHKK3OYIoBQQ'}, attempt #1 16:14:56 INFO - Task dependencies: EE2T5AdfROa4pbnRYPTKTA 16:14:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA'}, attempt #1 16:14:57 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.tar.bz2 16:14:57 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.test_packages.json 16:14:57 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.crashreporter-symbols.zip 16:14:57 INFO - Running post-action listener: _resource_record_post_action 16:14:57 INFO - [mozharness: 2017-09-13 23:14:57.363058Z] Finished read-buildbot-config step (success) 16:14:57 INFO - [mozharness: 2017-09-13 23:14:57.363273Z] Running download-and-extract step. 16:14:57 INFO - Running pre-action listener: _resource_record_pre_action 16:14:57 INFO - Running main action method: download_and_extract 16:14:57 INFO - mkdir: /builds/slave/test/build/tests 16:14:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:57 INFO - trying https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.test_packages.json 16:14:57 INFO - Downloading https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:14:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:14:57 INFO - Downloaded 989 bytes. 16:14:57 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:14:57 INFO - Using the following test package requirements: 16:14:57 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:14:57 INFO - u'common': [u'target.common.tests.zip'], 16:14:57 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:14:57 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:14:57 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:14:57 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:14:57 INFO - u'mozbase': [u'target.common.tests.zip'], 16:14:57 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:14:57 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:14:57 INFO - u'web-platform': [u'target.common.tests.zip', 16:14:57 INFO - u'target.web-platform.tests.tar.gz'], 16:14:57 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:14:57 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 16:14:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.common.tests.zip 16:14:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:14:57 INFO - Fetch https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.common.tests.zip into memory 16:14:59 INFO - Content-Length response header: 55174506 16:14:59 INFO - Bytes received: 55174506 16:15:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.talos.tests.zip 16:15:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:15:09 INFO - Fetch https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.talos.tests.zip into memory 16:15:10 INFO - Content-Length response header: 14499226 16:15:10 INFO - Bytes received: 14499226 16:15:10 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')]} 16:15:10 INFO - trying https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.tar.bz2 16:15:10 INFO - Downloading https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:15:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:15:12 INFO - Downloaded 62066042 bytes. 16:15:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.tar.bz2 16:15:12 INFO - mkdir: /builds/slave/test/properties 16:15:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:15:12 INFO - Writing to file /builds/slave/test/properties/build_url 16:15:12 INFO - Contents: 16:15:12 INFO - build_url:https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.tar.bz2 16:15:12 INFO - Running post-action listener: _resource_record_post_action 16:15:12 INFO - Running post-action listener: find_tests_for_verification 16:15:12 INFO - Running post-action listener: set_extra_try_arguments 16:15:12 INFO - [mozharness: 2017-09-13 23:15:12.543195Z] Finished download-and-extract step (success) 16:15:12 INFO - [mozharness: 2017-09-13 23:15:12.543348Z] Running populate-webroot step. 16:15:12 INFO - Running pre-action listener: _resource_record_pre_action 16:15:12 INFO - Running main action method: populate_webroot 16:15:12 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:15:12 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:15:12 INFO - 'tests': ['tresize', 16:15:12 INFO - 'tcanvasmark']}, 16:15:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:15:12 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:15:12 INFO - 'tests': ['dromaeo_css', 16:15:12 INFO - 'kraken']}, 16:15:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:15:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:15:12 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:15:12 INFO - 'talos_options': ['--disable-stylo'], 16:15:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:15:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:15:12 INFO - 'tests': ['damp', 'tps']}, 16:15:12 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:15:12 INFO - 'talos_options': ['--disable-stylo'], 16:15:12 INFO - 'tests': ['damp', 'tps']}, 16:15:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:15:12 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:15:12 INFO - 'tests': ['dromaeo_dom']}, 16:15:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:15:12 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:15:12 INFO - 'tests': ['basic_compositor_video', 16:15:12 INFO - 'glvideo']}, 16:15:12 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:15:12 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:15:12 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:15:12 INFO - 'talos_options': ['--disable-stylo'], 16:15:12 INFO - 'tests': ['ts_paint_webext', 16:15:12 INFO - 'tp5o_webext']}, 16:15:12 INFO - 'other-e10s': {'tests': ['a11yr', 16:15:12 INFO - 'ts_paint', 16:15:12 INFO - 'tpaint', 16:15:12 INFO - 'sessionrestore', 16:15:12 INFO - 'sessionrestore_many_windows', 16:15:12 INFO - 'sessionrestore_no_auto_restore', 16:15:12 INFO - 'tabpaint']}, 16:15:12 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:15:12 INFO - 'tests': ['a11yr', 16:15:12 INFO - 'ts_paint', 16:15:12 INFO - 'tpaint', 16:15:12 INFO - 'sessionrestore', 16:15:12 INFO - 'sessionrestore_many_windows', 16:15:12 INFO - 'sessionrestore_no_auto_restore', 16:15:12 INFO - 'tabpaint']}, 16:15:12 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:15:12 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:15:12 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:15:12 INFO - 'tests': ['bloom_basic_singleton']}, 16:15:12 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:15:12 INFO - 'tests': ['bloom_basic']}, 16:15:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:15:12 INFO - 'tsvgr_opacity', 16:15:12 INFO - 'tart', 16:15:12 INFO - 'tscrollx', 16:15:12 INFO - 'tsvg_static']}, 16:15:12 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:15:12 INFO - 'tests': ['tsvgx', 16:15:12 INFO - 'tsvgr_opacity', 16:15:12 INFO - 'tart', 16:15:12 INFO - 'tscrollx', 16:15:12 INFO - 'tsvg_static']}, 16:15:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:15:12 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:15:12 INFO - 'talos_options': ['--disable-stylo'], 16:15:12 INFO - 'tests': ['tp5o']}, 16:15:12 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:15:12 INFO - 'talos_options': ['--mitmproxy', 16:15:12 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:15:12 INFO - '--firstNonBlankPaint'], 16:15:12 INFO - 'tests': ['tp6_google', 16:15:12 INFO - 'tp6_youtube', 16:15:12 INFO - 'tp6_amazon', 16:15:12 INFO - 'tp6_facebook']}, 16:15:12 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:15:12 INFO - 'talos_options': ['--disable-stylo', 16:15:12 INFO - '--mitmproxy', 16:15:12 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:15:12 INFO - '--firstNonBlankPaint'], 16:15:12 INFO - 'tests': ['tp6_google', 16:15:12 INFO - 'tp6_youtube', 16:15:12 INFO - 'tp6_amazon', 16:15:12 INFO - 'tp6_facebook']}, 16:15:12 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:15:12 INFO - 'talos_options': ['--stylo-threads=1', 16:15:12 INFO - '--mitmproxy', 16:15:12 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:15:12 INFO - '--firstNonBlankPaint'], 16:15:12 INFO - 'tests': ['tp6_google', 16:15:12 INFO - 'tp6_youtube', 16:15:12 INFO - 'tp6_amazon', 16:15:12 INFO - 'tp6_facebook']}, 16:15:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:15:12 INFO - 'talos_options': ['--xperf_path', 16:15:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:15:12 INFO - 'tests': ['tp5n']}, 16:15:12 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:15:12 INFO - 'talos_options': ['--disable-stylo', 16:15:12 INFO - '--xperf_path', 16:15:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:15:12 INFO - 'tests': ['tp5n']}}} 16:15:12 INFO - Running post-action listener: _resource_record_post_action 16:15:12 INFO - [mozharness: 2017-09-13 23:15:12.566687Z] Finished populate-webroot step (success) 16:15:12 INFO - [mozharness: 2017-09-13 23:15:12.566792Z] Running create-virtualenv step. 16:15:12 INFO - Running pre-action listener: _resource_record_pre_action 16:15:12 INFO - Running main action method: create_virtualenv 16:15:12 INFO - Creating virtualenv /builds/slave/test/build/venv 16:15:12 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:15:12 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:15:12 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:15:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:15:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:15:14 INFO - Installing distribute.............................................................................................................................................................................................done. 16:15:14 INFO - Installing pip...............done. 16:15:14 INFO - Return code: 0 16:15:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:15:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:15:14 INFO - Reading from file tmpfile_stdout 16:15:14 INFO - Output received: 16:15:14 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:15:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:15: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')]} 16:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15: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')]} 16:15:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:14 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 0x235a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2523a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca090>, '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 0x26cad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c4f90>, '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': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', '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 16:15:14 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 16:15:14 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 16:15:14 INFO - Using env: {'DISPLAY': ':0', 16:15:14 INFO - 'HOME': '/home/cltbld', 16:15:14 INFO - 'LANG': 'en_US.UTF-8', 16:15:14 INFO - 'LANGUAGE': 'en_US:en', 16:15:14 INFO - 'LOGNAME': 'cltbld', 16:15:14 INFO - 'MAIL': '/var/mail/cltbld', 16:15:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:14 INFO - 'MOZ_NO_REMOTE': '1', 16:15:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:14 INFO - 'NO_EM_RESTART': '1', 16:15:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:14 INFO - 'PWD': '/builds/slave/test', 16:15:14 INFO - 'SHELL': '/bin/bash', 16:15:14 INFO - 'SHLVL': '1', 16:15:14 INFO - 'TERM': 'linux', 16:15:14 INFO - 'TMOUT': '86400', 16:15:14 INFO - 'USER': 'cltbld', 16:15:14 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', 16:15:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:14 INFO - '_': '/tools/buildbot/bin/python'} 16:15:16 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:15:16 INFO - Downloading/unpacking pip>=1.5 16:15:16 INFO - Running setup.py egg_info for package pip 16:15:16 INFO - warning: no files found matching 'pip/cacert.pem' 16:15:16 INFO - warning: no files found matching '*.html' under directory 'docs' 16:15:16 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:15:16 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:15:16 INFO - Installing collected packages: pip 16:15:16 INFO - Found existing installation: pip 1.1 16:15:16 INFO - Uninstalling pip: 16:15:16 INFO - Successfully uninstalled pip 16:15:16 INFO - Running setup.py install for pip 16:15:16 INFO - warning: no files found matching 'pip/cacert.pem' 16:15:16 INFO - warning: no files found matching '*.html' under directory 'docs' 16:15:16 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:15:16 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:15:16 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:15:16 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:15:16 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:15:16 INFO - Successfully installed pip 16:15:16 INFO - Cleaning up... 16:15:16 INFO - Return code: 0 16:15:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x235a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2523a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca090>, '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 0x26cad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c4f90>, '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': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', '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 16:15:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:15:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:15:16 INFO - Using env: {'DISPLAY': ':0', 16:15:16 INFO - 'HOME': '/home/cltbld', 16:15:16 INFO - 'LANG': 'en_US.UTF-8', 16:15:16 INFO - 'LANGUAGE': 'en_US:en', 16:15:16 INFO - 'LOGNAME': 'cltbld', 16:15:16 INFO - 'MAIL': '/var/mail/cltbld', 16:15:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:16 INFO - 'MOZ_NO_REMOTE': '1', 16:15:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:16 INFO - 'NO_EM_RESTART': '1', 16:15:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:16 INFO - 'PWD': '/builds/slave/test', 16:15:16 INFO - 'SHELL': '/bin/bash', 16:15:16 INFO - 'SHLVL': '1', 16:15:16 INFO - 'TERM': 'linux', 16:15:16 INFO - 'TMOUT': '86400', 16:15:16 INFO - 'USER': 'cltbld', 16:15:16 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', 16:15:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:16 INFO - '_': '/tools/buildbot/bin/python'} 16:15:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:16 INFO - Downloading/unpacking psutil>=3.1.1 16:15:16 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 16:15:16 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 16:15:16 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 16:15:16 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 16:15:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:15:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:15:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:15:17 INFO - Installing collected packages: psutil 16:15:17 INFO - Running setup.py install for psutil 16:15:17 INFO - building 'psutil._psutil_linux' extension 16:15:17 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 16:15:17 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 16:15:17 INFO - building 'psutil._psutil_posix' extension 16:15:17 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 16:15:17 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 16:15:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:15:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:15:18 INFO - Successfully installed psutil 16:15:18 INFO - Cleaning up... 16:15:18 INFO - Return code: 0 16:15:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:15:18 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')]} 16:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:18 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')]} 16:15:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:18 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 0x235a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2523a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca090>, '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 0x26cad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c4f90>, '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': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', '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 16:15:18 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 16:15:18 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 16:15:18 INFO - Using env: {'DISPLAY': ':0', 16:15:18 INFO - 'HOME': '/home/cltbld', 16:15:18 INFO - 'LANG': 'en_US.UTF-8', 16:15:18 INFO - 'LANGUAGE': 'en_US:en', 16:15:18 INFO - 'LOGNAME': 'cltbld', 16:15:18 INFO - 'MAIL': '/var/mail/cltbld', 16:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:18 INFO - 'MOZ_NO_REMOTE': '1', 16:15:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:18 INFO - 'NO_EM_RESTART': '1', 16:15:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:18 INFO - 'PWD': '/builds/slave/test', 16:15:18 INFO - 'SHELL': '/bin/bash', 16:15:18 INFO - 'SHLVL': '1', 16:15:18 INFO - 'TERM': 'linux', 16:15:18 INFO - 'TMOUT': '86400', 16:15:18 INFO - 'USER': 'cltbld', 16:15:18 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', 16:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:18 INFO - '_': '/tools/buildbot/bin/python'} 16:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:15:18 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 16:15:18 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 16:15:18 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 16:15:18 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 16:15:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:15:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:15:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:15:19 INFO - Installing collected packages: mozsystemmonitor 16:15:19 INFO - Running setup.py install for mozsystemmonitor 16:15:19 INFO - Successfully installed mozsystemmonitor 16:15:19 INFO - Cleaning up... 16:15:20 INFO - Return code: 0 16:15:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2523a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca090>, '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 0x26cad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c4f90>, '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': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', '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 16:15:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:15:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:15:21 INFO - Using env: {'DISPLAY': ':0', 16:15:21 INFO - 'HOME': '/home/cltbld', 16:15:21 INFO - 'LANG': 'en_US.UTF-8', 16:15:21 INFO - 'LANGUAGE': 'en_US:en', 16:15:21 INFO - 'LOGNAME': 'cltbld', 16:15:21 INFO - 'MAIL': '/var/mail/cltbld', 16:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:21 INFO - 'MOZ_NO_REMOTE': '1', 16:15:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:21 INFO - 'NO_EM_RESTART': '1', 16:15:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:21 INFO - 'PWD': '/builds/slave/test', 16:15:21 INFO - 'SHELL': '/bin/bash', 16:15:21 INFO - 'SHLVL': '1', 16:15:21 INFO - 'TERM': 'linux', 16:15:21 INFO - 'TMOUT': '86400', 16:15:21 INFO - 'USER': 'cltbld', 16:15:21 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', 16:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:21 INFO - '_': '/tools/buildbot/bin/python'} 16:15:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:22 INFO - Downloading/unpacking jsonschema==2.5.1 16:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:15:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:23 INFO - Downloading functools32-3.2.3-2.tar.gz 16:15:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:15:23 INFO - warning: no files found matching '*.txt' 16:15:23 INFO - no previously-included directories found matching 'build' 16:15:23 INFO - no previously-included directories found matching 'dist' 16:15:23 INFO - no previously-included directories found matching '.git*' 16:15:23 INFO - Installing collected packages: jsonschema, functools32 16:15:23 INFO - Running setup.py install for functools32 16:15:23 INFO - warning: no files found matching '*.txt' 16:15:23 INFO - no previously-included directories found matching 'build' 16:15:23 INFO - no previously-included directories found matching 'dist' 16:15:23 INFO - no previously-included directories found matching '.git*' 16:15:23 INFO - Successfully installed jsonschema functools32 16:15:23 INFO - Cleaning up... 16:15:23 INFO - Return code: 0 16:15:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:15:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2523a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca090>, '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 0x26cad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c4f90>, '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': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', '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 16:15:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:15:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:15:23 INFO - Using env: {'DISPLAY': ':0', 16:15:23 INFO - 'HOME': '/home/cltbld', 16:15:23 INFO - 'LANG': 'en_US.UTF-8', 16:15:23 INFO - 'LANGUAGE': 'en_US:en', 16:15:23 INFO - 'LOGNAME': 'cltbld', 16:15:23 INFO - 'MAIL': '/var/mail/cltbld', 16:15:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:23 INFO - 'MOZ_NO_REMOTE': '1', 16:15:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:23 INFO - 'NO_EM_RESTART': '1', 16:15:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:23 INFO - 'PWD': '/builds/slave/test', 16:15:23 INFO - 'SHELL': '/bin/bash', 16:15:23 INFO - 'SHLVL': '1', 16:15:23 INFO - 'TERM': 'linux', 16:15:23 INFO - 'TMOUT': '86400', 16:15:23 INFO - 'USER': 'cltbld', 16:15:23 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', 16:15:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:23 INFO - '_': '/tools/buildbot/bin/python'} 16:15:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:15:24 INFO - Cleaning up... 16:15:24 INFO - Return code: 0 16:15:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:15:24 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')]} 16:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:24 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')]} 16:15:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:24 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 0x235a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2523a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca090>, '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 0x26cad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c4f90>, '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': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', '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 16:15:24 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 16:15:24 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 16:15:24 INFO - Using env: {'DISPLAY': ':0', 16:15:24 INFO - 'HOME': '/home/cltbld', 16:15:24 INFO - 'LANG': 'en_US.UTF-8', 16:15:24 INFO - 'LANGUAGE': 'en_US:en', 16:15:24 INFO - 'LOGNAME': 'cltbld', 16:15:24 INFO - 'MAIL': '/var/mail/cltbld', 16:15:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:24 INFO - 'MOZ_NO_REMOTE': '1', 16:15:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:24 INFO - 'NO_EM_RESTART': '1', 16:15:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:24 INFO - 'PWD': '/builds/slave/test', 16:15:24 INFO - 'SHELL': '/bin/bash', 16:15:24 INFO - 'SHLVL': '1', 16:15:24 INFO - 'TERM': 'linux', 16:15:24 INFO - 'TMOUT': '86400', 16:15:24 INFO - 'USER': 'cltbld', 16:15:24 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', 16:15:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:24 INFO - '_': '/tools/buildbot/bin/python'} 16:15:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:24 INFO - Downloading/unpacking blobuploader==1.2.4 16:15:24 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 16:15:24 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 16:15:24 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 16:15:24 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 16:15:25 INFO - Downloading blobuploader-1.2.4.tar.gz 16:15:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:15:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:15:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:25 INFO - Downloading docopt-0.6.1.tar.gz 16:15:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:15:25 INFO - Installing collected packages: blobuploader, requests, docopt 16:15:25 INFO - Running setup.py install for blobuploader 16:15:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:15:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:15:26 INFO - Running setup.py install for requests 16:15:26 INFO - Running setup.py install for docopt 16:15:26 INFO - Successfully installed blobuploader requests docopt 16:15:26 INFO - Cleaning up... 16:15:26 INFO - Return code: 0 16:15:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2523a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca090>, '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 0x26cad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c4f90>, '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': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', '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 16:15:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:15:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:15:26 INFO - Using env: {'DISPLAY': ':0', 16:15:26 INFO - 'HOME': '/home/cltbld', 16:15:26 INFO - 'LANG': 'en_US.UTF-8', 16:15:26 INFO - 'LANGUAGE': 'en_US:en', 16:15:26 INFO - 'LOGNAME': 'cltbld', 16:15:26 INFO - 'MAIL': '/var/mail/cltbld', 16:15:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:26 INFO - 'MOZ_NO_REMOTE': '1', 16:15:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:26 INFO - 'NO_EM_RESTART': '1', 16:15:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:26 INFO - 'PWD': '/builds/slave/test', 16:15:26 INFO - 'SHELL': '/bin/bash', 16:15:26 INFO - 'SHLVL': '1', 16:15:26 INFO - 'TERM': 'linux', 16:15:26 INFO - 'TMOUT': '86400', 16:15:26 INFO - 'USER': 'cltbld', 16:15:26 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', 16:15:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:26 INFO - '_': '/tools/buildbot/bin/python'} 16:15:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:15:26 INFO - Running setup.py (path:/tmp/pip-NlzRpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:15:26 INFO - Running setup.py (path:/tmp/pip-cxSGBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:15:26 INFO - Running setup.py (path:/tmp/pip-hpROa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:15:26 INFO - Running setup.py (path:/tmp/pip-00u4c6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:15:27 INFO - Running setup.py (path:/tmp/pip-yZrDFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:15:27 INFO - Running setup.py (path:/tmp/pip-heVL1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:15:27 INFO - Running setup.py (path:/tmp/pip-BBKnfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:15:27 INFO - Running setup.py (path:/tmp/pip-fcqI5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:15:27 INFO - Running setup.py (path:/tmp/pip-Ld66j5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:15:27 INFO - Running setup.py (path:/tmp/pip-Bueeq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:15:27 INFO - Running setup.py (path:/tmp/pip-JcntOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:15:27 INFO - Running setup.py (path:/tmp/pip-vgd9r0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:15:27 INFO - Running setup.py (path:/tmp/pip-31YVnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:15:27 INFO - Running setup.py (path:/tmp/pip-3ZTV7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:27 INFO - Running setup.py (path:/tmp/pip-t76xo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:15:27 INFO - Running setup.py (path:/tmp/pip-8vm5ow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:15:27 INFO - Running setup.py (path:/tmp/pip-jzriRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:15:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:15:27 INFO - Running setup.py install for manifestparser 16:15:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:15:28 INFO - Running setup.py install for mozcrash 16:15:28 INFO - Running setup.py install for mozdebug 16:15:28 INFO - Running setup.py install for mozdevice 16:15:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:15:28 INFO - Running setup.py install for mozfile 16:15:28 INFO - Running setup.py install for mozhttpd 16:15:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:15:28 INFO - Running setup.py install for mozinfo 16:15:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:15:28 INFO - Running setup.py install for mozInstall 16:15:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:15:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:15:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:15:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:15:28 INFO - Running setup.py install for mozleak 16:15:28 INFO - Running setup.py install for mozlog 16:15:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Running setup.py install for moznetwork 16:15:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Running setup.py install for mozprocess 16:15:29 INFO - Running setup.py install for mozprofile 16:15:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Running setup.py install for mozrunner 16:15:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Running setup.py install for mozscreenshot 16:15:29 INFO - Running setup.py install for moztest 16:15:29 INFO - Running setup.py install for mozversion 16:15:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:15:29 INFO - Cleaning up... 16:15:29 INFO - Return code: 0 16:15:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2523a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca090>, '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 0x26cad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c4f90>, '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': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', '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 16:15:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:15:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:15:29 INFO - Using env: {'DISPLAY': ':0', 16:15:29 INFO - 'HOME': '/home/cltbld', 16:15:29 INFO - 'LANG': 'en_US.UTF-8', 16:15:29 INFO - 'LANGUAGE': 'en_US:en', 16:15:29 INFO - 'LOGNAME': 'cltbld', 16:15:29 INFO - 'MAIL': '/var/mail/cltbld', 16:15:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:29 INFO - 'MOZ_NO_REMOTE': '1', 16:15:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:29 INFO - 'NO_EM_RESTART': '1', 16:15:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:29 INFO - 'PWD': '/builds/slave/test', 16:15:29 INFO - 'SHELL': '/bin/bash', 16:15:29 INFO - 'SHLVL': '1', 16:15:29 INFO - 'TERM': 'linux', 16:15:29 INFO - 'TMOUT': '86400', 16:15:29 INFO - 'USER': 'cltbld', 16:15:29 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', 16:15:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:29 INFO - '_': '/tools/buildbot/bin/python'} 16:15:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:15:30 INFO - Running setup.py (path:/tmp/pip-cLrlwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:15:30 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)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:15:30 INFO - Running setup.py (path:/tmp/pip-_U8SJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:15:30 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)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:15:30 INFO - Running setup.py (path:/tmp/pip-lUs23O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:15:30 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)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:15:30 INFO - Running setup.py (path:/tmp/pip-T194JX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:15:30 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)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:15:30 INFO - Running setup.py (path:/tmp/pip-w5j3Ey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:15:30 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)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:15:30 INFO - Running setup.py (path:/tmp/pip-gTY2o3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:15:30 INFO - Running setup.py (path:/tmp/pip-DnNrmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:15:30 INFO - Running setup.py (path:/tmp/pip-15beQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:15:30 INFO - Running setup.py (path:/tmp/pip-btyAhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:15:30 INFO - Running setup.py (path:/tmp/pip-VHI9vf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:15:30 INFO - Running setup.py (path:/tmp/pip-KOkMvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:15:30 INFO - Running setup.py (path:/tmp/pip-sIqAWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:15:30 INFO - Running setup.py (path:/tmp/pip-LLVpEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:15:30 INFO - Running setup.py (path:/tmp/pip-Wh_qDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:31 INFO - Running setup.py (path:/tmp/pip-dhXxd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:15:31 INFO - Running setup.py (path:/tmp/pip-On3vRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:15:31 INFO - Running setup.py (path:/tmp/pip-biBMxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:15:31 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)) 16:15:31 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)) 16:15:31 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)) 16:15:31 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)) 16:15:31 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)) 16:15:31 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)) 16:15:31 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)) 16:15:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:15:31 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 16:15:31 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 16:15:31 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 16:15:31 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 16:15:32 INFO - Downloading blessings-1.6.tar.gz 16:15:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:15:32 INFO - Installing collected packages: blessings 16:15:32 INFO - Running setup.py install for blessings 16:15:32 INFO - Successfully installed blessings 16:15:32 INFO - Cleaning up... 16:15:32 INFO - Return code: 0 16:15:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:15:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:15:32 INFO - Reading from file tmpfile_stdout 16:15:32 INFO - Current package versions: 16:15:32 INFO - argparse == 1.2.1 16:15:32 INFO - blessings == 1.6 16:15:32 INFO - blobuploader == 1.2.4 16:15:32 INFO - docopt == 0.6.1 16:15:32 INFO - functools32 == 3.2.3-2 16:15:32 INFO - jsonschema == 2.5.1 16:15:32 INFO - manifestparser == 1.1 16:15:32 INFO - mozInstall == 1.13 16:15:32 INFO - mozcrash == 1.0 16:15:32 INFO - mozdebug == 0.1 16:15:32 INFO - mozdevice == 0.51 16:15:32 INFO - mozfile == 1.2 16:15:32 INFO - mozhttpd == 0.7 16:15:32 INFO - mozinfo == 0.10 16:15:32 INFO - mozleak == 0.1 16:15:32 INFO - mozlog == 3.5 16:15:32 INFO - moznetwork == 0.27 16:15:32 INFO - mozprocess == 0.25 16:15:32 INFO - mozprofile == 0.28 16:15:32 INFO - mozrunner == 6.13 16:15:32 INFO - mozscreenshot == 0.1 16:15:32 INFO - mozsystemmonitor == 0.3 16:15:32 INFO - moztest == 0.8 16:15:32 INFO - mozversion == 1.4 16:15:32 INFO - psutil == 3.1.1 16:15:32 INFO - requests == 1.2.3 16:15:32 INFO - wsgiref == 0.1.2 16:15:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2523a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca090>, '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 0x26cad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c4f90>, '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': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', '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 16:15:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:15:32 INFO - Using env: {'DISPLAY': ':0', 16:15:32 INFO - 'HOME': '/home/cltbld', 16:15:32 INFO - 'LANG': 'en_US.UTF-8', 16:15:32 INFO - 'LANGUAGE': 'en_US:en', 16:15:32 INFO - 'LOGNAME': 'cltbld', 16:15:32 INFO - 'MAIL': '/var/mail/cltbld', 16:15:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:32 INFO - 'MOZ_NO_REMOTE': '1', 16:15:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:32 INFO - 'NO_EM_RESTART': '1', 16:15:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:32 INFO - 'PWD': '/builds/slave/test', 16:15:32 INFO - 'SHELL': '/bin/bash', 16:15:32 INFO - 'SHLVL': '1', 16:15:32 INFO - 'TERM': 'linux', 16:15:32 INFO - 'TMOUT': '86400', 16:15:32 INFO - 'USER': 'cltbld', 16:15:32 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', 16:15:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:32 INFO - '_': '/tools/buildbot/bin/python'} 16:15:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:33 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)) 16:15:33 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)) 16:15:33 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)) 16:15:33 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)) 16:15:33 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)) 16:15:33 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)) 16:15:33 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)) 16:15:33 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)) 16:15:33 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)) 16:15:33 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:15:33 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 16:15:33 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 16:15:33 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 16:15:33 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 16:15:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:15:35 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)) 16:15:35 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)) 16:15:35 INFO - Installing collected packages: simplejson 16:15:35 INFO - Running setup.py install for simplejson 16:15:35 INFO - building 'simplejson._speedups' extension 16:15:35 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 16:15:36 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 16:15:36 INFO - Successfully installed simplejson 16:15:36 INFO - Cleaning up... 16:15:36 INFO - Return code: 0 16:15:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:15: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')]} 16:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15: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')]} 16:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x235a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2523a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26ca090>, '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 0x26cad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c4f90>, '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': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', '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 16:15: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', 'jsonschema'] in /builds/slave/test/build 16:15: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 jsonschema 16:15:36 INFO - Using env: {'DISPLAY': ':0', 16:15:36 INFO - 'HOME': '/home/cltbld', 16:15:36 INFO - 'LANG': 'en_US.UTF-8', 16:15:36 INFO - 'LANGUAGE': 'en_US:en', 16:15:36 INFO - 'LOGNAME': 'cltbld', 16:15:36 INFO - 'MAIL': '/var/mail/cltbld', 16:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:36 INFO - 'MOZ_NO_REMOTE': '1', 16:15:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:36 INFO - 'NO_EM_RESTART': '1', 16:15:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:36 INFO - 'PWD': '/builds/slave/test', 16:15:36 INFO - 'SHELL': '/bin/bash', 16:15:36 INFO - 'SHLVL': '1', 16:15:36 INFO - 'TERM': 'linux', 16:15:36 INFO - 'TMOUT': '86400', 16:15:36 INFO - 'USER': 'cltbld', 16:15:36 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', 16:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:36 INFO - '_': '/tools/buildbot/bin/python'} 16:15:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:15:36 INFO - Cleaning up... 16:15:36 INFO - Return code: 0 16:15:36 INFO - Running post-action listener: _resource_record_post_action 16:15:36 INFO - Running post-action listener: _start_resource_monitoring 16:15:36 INFO - Starting resource monitoring. 16:15:36 INFO - [mozharness: 2017-09-13 23:15:36.507028Z] Finished create-virtualenv step (success) 16:15:36 INFO - [mozharness: 2017-09-13 23:15:36.507622Z] Running install step. 16:15:36 INFO - Running pre-action listener: _resource_record_pre_action 16:15:36 INFO - Running main action method: install 16:15:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:15:36 INFO - Reading from file tmpfile_stdout 16:15:36 INFO - Detecting whether we're running mozinstall >=1.0... 16:15:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:15:36 INFO - Reading from file tmpfile_stdout 16:15:36 INFO - Output received: 16:15:36 INFO - Usage: mozinstall [options] installer 16:15:36 INFO - Options: 16:15:36 INFO - -h, --help show this help message and exit 16:15:36 INFO - -d DEST, --destination=DEST 16:15:36 INFO - Directory to install application into. [default: 16:15:36 INFO - "/builds/slave/test"] 16:15:36 INFO - --app=APP Application being installed. [default: firefox] 16:15:36 INFO - mkdir: /builds/slave/test/build/application 16:15:36 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'] 16:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:15:54 INFO - Reading from file tmpfile_stdout 16:15:54 INFO - Output received: 16:15:54 INFO - /builds/slave/test/build/application/firefox/firefox 16:15:54 INFO - Running post-action listener: _resource_record_post_action 16:15:54 INFO - [mozharness: 2017-09-13 23:15:54.502945Z] Finished install step (success) 16:15:54 INFO - [mozharness: 2017-09-13 23:15:54.503196Z] Running setup-mitmproxy step. 16:15:54 INFO - Running pre-action listener: _resource_record_pre_action 16:15:54 INFO - Running main action method: setup_mitmproxy 16:15:54 INFO - Skipping: mitmproxy is not required 16:15:54 INFO - Running post-action listener: _resource_record_post_action 16:15:54 INFO - [mozharness: 2017-09-13 23:15:54.504315Z] Finished setup-mitmproxy step (success) 16:15:54 INFO - [mozharness: 2017-09-13 23:15:54.504538Z] Running run-tests step. 16:15:54 INFO - Running pre-action listener: _resource_record_pre_action 16:15:54 INFO - Running pre-action listener: _set_gcov_prefix 16:15:54 INFO - Running main action method: run_tests 16:15:54 WARNING - Try message not found. 16:15:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:15:54 INFO - Python 2.7.3 16:15:54 INFO - Return code: 0 16:15:54 INFO - grabbing minidump binary from tooltool 16:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:54 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 0x26ca090>, '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 0x26cad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c4f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:15:54 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 16:15:54 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 16:15:54 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 16:15:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:15:54 INFO - Return code: 0 16:15:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:15:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:15:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:15:54 INFO - ENV: RUST_BACKTRACE is now full 16:15:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:15:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:15:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 16:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-056 --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 16:15:54 INFO - Using env: {'DISPLAY': ':0', 16:15:54 INFO - 'HOME': '/home/cltbld', 16:15:54 INFO - 'LANG': 'en_US.UTF-8', 16:15:54 INFO - 'LANGUAGE': 'en_US:en', 16:15:54 INFO - 'LOGNAME': 'cltbld', 16:15:54 INFO - 'MAIL': '/var/mail/cltbld', 16:15:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:15:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:54 INFO - 'MOZ_NO_REMOTE': '1', 16:15:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:15:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:54 INFO - 'NO_EM_RESTART': '1', 16:15:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:54 INFO - 'PWD': '/builds/slave/test', 16:15:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:15:54 INFO - 'RUST_BACKTRACE': 'full', 16:15:54 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:15:54 INFO - 'SHELL': '/bin/bash', 16:15:54 INFO - 'SHLVL': '1', 16:15:54 INFO - 'TERM': 'linux', 16:15:54 INFO - 'TMOUT': '86400', 16:15:54 INFO - 'USER': 'cltbld', 16:15:54 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', 16:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:54 INFO - '_': '/tools/buildbot/bin/python'} 16:15:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 16:15:54 INFO - mozversion application_buildid: 20170913215701 16:15:54 INFO - mozversion application_changeset: 0b3646aa9cbf3f77ee574a553ade9685e9229ab5 16:15:54 INFO - mozversion application_display_name: Nightly 16:15:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:15:54 INFO - mozversion application_name: Firefox 16:15:54 INFO - mozversion application_remotingname: firefox 16:15:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:15:54 INFO - mozversion application_vendor: Mozilla 16:15:54 INFO - mozversion application_version: 57.0a1 16:15:54 INFO - mozversion platform_buildid: 20170913215701 16:15:54 INFO - mozversion platform_changeset: 0b3646aa9cbf3f77ee574a553ade9685e9229ab5 16:15:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:15:54 INFO - mozversion platform_version: 57.0a1 16:15:54 INFO - using testdate: 1505344554 16:15:54 INFO - actual date: 1505344554 16:15:54 INFO - starting webserver on 'localhost:41558' 16:15:54 INFO - SUITE-START | Running 2 tests 16:15:54 INFO - TEST-START | dromaeo_css 16:15:54 INFO - Initialising browser for dromaeo_css test... 16:15:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41558/getInfo.html -profile /tmp/tmptfrJc3/profile 16:15:54 INFO - TEST-INFO | started process 16089 (/builds/slave/test/build/application/firefox/firefox http://localhost:41558/getInfo.html) 16:16:01 INFO - TEST-INFO | 16089: exit 0 16:16:01 INFO - Browser initialized. 16:16:01 INFO - Running cycle 1/1 for dromaeo_css test... 16:16:01 INFO - Using env: {'DISPLAY': ':0', 16:16:01 INFO - 'HOME': '/home/cltbld', 16:16:01 INFO - 'JSGC_DISABLE_POISONING': '1', 16:16:01 INFO - 'LANG': 'en_US.UTF-8', 16:16:01 INFO - 'LANGUAGE': 'en_US:en', 16:16:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:16:01 INFO - 'LOGNAME': 'cltbld', 16:16:01 INFO - 'MAIL': '/var/mail/cltbld', 16:16:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:16:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:16:01 INFO - 'MOZ_CRASHREPORTER': '1', 16:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:16:01 INFO - 'MOZ_NO_REMOTE': '1', 16:16:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:16:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:01 INFO - 'NO_EM_RESTART': '1', 16:16:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:01 INFO - 'PWD': '/builds/slave/test', 16:16:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:16:01 INFO - 'RUST_BACKTRACE': 'full', 16:16:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:16:01 INFO - 'SHELL': '/bin/bash', 16:16:01 INFO - 'SHLVL': '1', 16:16:01 INFO - 'TERM': 'linux', 16:16:01 INFO - 'TMOUT': '86400', 16:16:01 INFO - 'USER': 'cltbld', 16:16:01 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', 16:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:01 INFO - '_': '/tools/buildbot/bin/python'} 16:16:01 INFO - TEST-INFO | started process 16326 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptfrJc3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:16:02 INFO - PID 16326 | 16:16:02 INFO - PID 16326 | (/builds/slave/test/build/application/firefox/firefox:16375): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:16:02 INFO - PID 16326 | 16:16:12 INFO - PID 16326 | RSS: Main: 166514688 16:16:12 INFO - PID 16326 | 16:19:09 INFO - PID 16326 | Cycle 1(1): loaded http://localhost:41558/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41558/tests/dromaeo/cssquery-ext.html) 16:19:10 INFO - PID 16326 | RSS: Main: 170713088 16:19:10 INFO - PID 16326 | 16:22:12 INFO - PID 16326 | Cycle 1(1): loaded http://localhost:41558/tests/dromaeo/cssquery-ext.html (next: http://localhost:41558/tests/dromaeo/cssquery-jquery.html) 16:22:12 INFO - PID 16326 | RSS: Main: 166502400 16:22:12 INFO - PID 16326 | 16:25:14 INFO - PID 16326 | Cycle 1(1): loaded http://localhost:41558/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41558/tests/dromaeo/cssquery-mootools.html) 16:25:15 INFO - PID 16326 | RSS: Main: 169000960 16:25:15 INFO - PID 16326 | 16:28:16 INFO - PID 16326 | Cycle 1(1): loaded http://localhost:41558/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41558/tests/dromaeo/cssquery-prototype.html) 16:28:17 INFO - PID 16326 | RSS: Main: 167346176 16:28:17 INFO - PID 16326 | 16:31:20 INFO - PID 16326 | Cycle 1(1): loaded http://localhost:41558/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41558/tests/dromaeo/cssquery-yui.html) 16:31:20 INFO - PID 16326 | RSS: Main: 169123840 16:31:20 INFO - PID 16326 | 16:34:22 INFO - PID 16326 | Cycle 1(1): loaded http://localhost:41558/tests/dromaeo/cssquery-yui.html (next: http://localhost:41558/tests/dromaeo/cssquery-dojo.html) 16:34:22 INFO - PID 16326 | RSS: Main: 172175360 16:34:22 INFO - PID 16326 | 16:34:22 INFO - PID 16326 | __start_tp_report 16:34:22 INFO - PID 16326 | _x_x_mozilla_page_load 16:34:22 INFO - PID 16326 | _x_x_mozilla_page_load_details 16:34:22 INFO - PID 16326 | |i|pagename|runs| 16:34:22 INFO - PID 16326 | |0;dojo.html;3825.751326163799;3840.4623352730614;3840.884773456797;3856.363699989502;3883.883483495496;6162.661053642048;6175.351588083248;6251.531135164858;6257.843553911154;6297.433231009209;6271.278802937663;6312.431881130698;6362.840928976778;6388.48892088633;6429.35706429358;5590.552755779528;5713.5714821388565;5726.112452569859;5727.312722473309;5753.194552762617;224.08368426188878;5346.572274218072;5576.553875689955;5684.317881854184;5706;5604.159376093598;5607.383187849333;5700.429957004267;5703.116017017348;5712.11462223356;5595.38450770415;5623.578231632644;5634.492895639412;5686.1755045518585;5693.060644993571;5604.355499117612;5606.467385598362;5680.744366503517;5693.743781529841;5695.316562012585;6248.843778905563;6254.374562543755;6282.905756413657;6290.968545157245;6346.30190679025;6730.427913627347;6770.72917083316;6774.93225067748;6824.351686589766;6857.691403886837;6641.900371494431;6731.798046058626;6731.9663401683165;6739.5619284746335;6741.730330786762;6290.591111577733;6423.967880160616;6431.131797207364;6442.774502892423;6456;6901.965490172567;6999.545029573062;7035.401990830836;7103.2541583133025;7122.2521635228595;6495.805125846232;6627.271000189975;6704.161979752531;6714.597124172566;6764.458843292525;6803.625800581016;6818.829529261808;6826.556273842184;6829.146356705412;6832.350926661959;6745.9662701686175;6762.154730658668;6832.521723479309;6839.179298484214;6843.418309443753;6726.697298621574;6736.831579210559;6826.556273842184;6858.828529286708;6859.519833611699;4911.508849115131;5001.624878134082;5003.849884503471;5010.574101201439;5013.824516141917;4475.283954567253;4481.417415735934;4514.954850451453;4542.68201225911;4569.588737013684;6206.472449841724;6239.937600624026;6249.031400132987;6265.185525881606;6265.686715664289;6640.435562977104;6678.699458524378;6765.357291057342;6827.112475378269;6883.380495755406;6055.1220073090035;6242.500599951993;6320.5891617044745;6323.20959880015;6342.556021078481;5306.469353064663;5455.809046683347;5468.261784659099;5551.084071128299;5554.6944918029085;6931.7227310907;7058.082449281561;7073.186583542855;7076.893846592306;7094.787156385317;6608.966955165193;6767.729290828509;6776.254611992578;6783.728650854009;6862.348076932683;6899.103116594811;7026.578405295699;7055.470839686941;7131.82170445743;7156.64216789148;6674.799756007327;6693.230278518033;6701.061851340914;6760.459163266829;6778.898316525355;6811.250762416229;6892;6893.4140598047725;6895.724171033303;6898.724051037902;6514.771982980573;6520.706568204348;6558.278589355263;6583.637899915359;6638.468922486286;6433.260175079926;6483;6519.445847103049;6524.836879078061;6592.7692530763115;6497.577657452251;6517.152770139852;6576.243731970884;6592.70332835014;6621.470282377494;6567.113439685581;6666.333366663295;6677.031830384664;6717.798466046026;6739.865202696017;6341.619502829845;6396.360363963752;6402.87194256103;6405.51958603116;6441.613503189824 16:34:22 INFO - PID 16326 | |1;ext.html;871.6554284922365;919.1589695428679;928.6656803550723;957.8706874571935;976.8095221431814;44180.337294940604;44738.55261447385;44801.551984480146;44832.103357932865;44984.77507612458;41015.79492102535;41393.37909931353;41397.37903931436;41433.378499322534;41460.79269603655;7343.04540409746;7467.477276590641;7477.551346919202;7481.289277518655;7482.21436749139;7301.744438944638;7380.409567234635;7393.482456228066;7394.186639469654;7399.445041621873;7883.172266911978;8026.558539280337;8043.517388956652;8050.838983220332;8057.113717491101;7895.486793358442;7933.444658873881;7944.443888927777;7955.403344749138;7966.044074711014;7928.7621371358955;8123.187681231889;8138.145494723028;8162.632681529345;8163.7142700005215;40947.385789213185;41006;41188;41427;41466.585334146876;3773.6226377362045;3778.301014312361;3783;3783.5459744830796;3833.4824798652103;32984.175395615064;33026.504602430985;33185.66814331874;33222.16944576381;33281.83359083213;5276.419593844674;5281.471852814726;5319.361676598833;5335.573154147659;5349.598780091509;207000;209293;215065;215847;253506;55162.724186379615;55292.72353638206;55470.72264638651;55544;55547.722261389245;57900.420995790344;58091;58137;58141.70929145412;58291.417085829446;17090.230939607747;17184.742228866577;17203.1398430078;17215.397461088804;17396.39112631052;13994;14017.579472615838;14018.509352172829;14018.878489720797;14044.789328160086;15945.123018233877;15959.042457452359;15983.12092834882;16033.919830401006;16052.438164664414;6344.841378965562;6365.108884756138;6416.133821934072;6482.254540727782;6489.091527186198;1065.898739619735;1084.1814430105221;1088.216484131424;1091.3342860854873;1096.1833434091652;26121.738782612312;26125.08562200314;26169.607455888177;26276.605850912252;26296.47407051848;1195.1574140231116;1200.3158199826016;1200.3218181727295;1208.5649166300125;1210.9757804843853;13542.59372218835;13545.59363219105;13558.864411355957;13559;13842.792358114637;282392;284525;284756;285042;285099;56608.43391566032;56983;57018.7149064252;57417.71291143518;57574.424255757745;1486.8067151270266;1520.931558079889;1522.117172040214;1528.587281434029;1532.6472817199208;25189.748102519472;25223.87388063048;25626.23121306364;25842.612360814226;25859.741402585732;41884.58115418807;41959.160816784104;41990;42060;42073.579264206965;237737;237787;237835;237842;237911;38342.616573833904;40067.79966100151;40573.59426405816;40800;40905;50460.49539504705;51667.483325166264;51881.7405912968;51911.2213316793;52159;36574.085647859014;37318;37626.2474750498;38026.429603555414;38184.61815381922;36361;36733.63266367302;37119.25761484701;37366.62633373631;37779.244415112094;43540;43593.78203108964;43619;43746.562534375524;43981.78009109934;35154;35390.82304588461;35631.82184089063;35802.462963055055;36115;26359.4728105433;26404.20787376382;26416.339591510365;26577.601335980362;27152.321191969568 16:34:22 INFO - PID 16326 | |2;jquery.html;582.8571999860033;587.1720873568264;591.6982339007105;594.1651978969546;614.5329549542347;22785.772142278573;23544.29367118987;23599.41001474964;23792.643110353336;23854.165104221356;24237.515249694996;24940.376490587743;25086.874565627146;25105.62341564878;25330.873345633245;6613.239477460093;6651.767188148416;6656.900146497806;6664.700088496029;6666.833329166761;6477.838054048663;6607.108040414555;6735.696893639798;6780.220274668427;6782.6947787349445;6664.900291451904;6667.066610674485;6672.59964402135;6678.732850685967;6683.53215274931;6185.938140618604;6365.58623689461;6374.330695277022;6401.07184458251;6711.395974362306;5973.133895585159;5975.551833612496;5978.491828194608;6006.099085137221;6024.1566180734735;28726.281842953886;29288.414231715247;29387.706122938926;29506.70493295061;29518.55722164169;2085.1763553396477;2091.069474084033;2097.811196992278;2114.693369461435;2122.7452705675264;19694;21132.894335528377;21172.047257873277;21295.14819407222;21307.68038479424;2480.888360023785;2517.4335774450783;2563.6667233259745;2573.1122762646855;2582.586786114213;136301;142277;142904.28547857195;143076.7076987692;153313;14970.47603333878;15534.84465155334;15855.603609909844;16011.199440028186;16057.839421605635;24755.504889902462;24904;24972.375690607518;24980.750192498206;25007.749922500905;10274.691759247235;10399.896001040044;10508.211884108723;10558.841617375592;10611.89388106109;9459.716208513755;9510.857337139949;9545.236381089497;9604.95197524008;9654.372465789702;9458.290628202774;9604.75988100303;9861.211103111735;9880.654177103766;9959.452230127268;2639.4984952858895;2666.6133410655348;2679.4775018871524;2795.7064508226345;2817.7041410651996;1022.1158697726562;1035.3322107240938;1039.3504059962524;1047.9633212837514;1048.6015314180563;20199.293024744064;20414.88146600123;20455;20588.27941022057;20601.793982060288;1070.5664205996584;1093.7429704019548;1105.3588918427454;1121.8878112188715;1125.876153623101;11318.207725459139;11512.424378781028;11709.238899471507;11709.88290117105;11725.941370293094;575519;598888;608585;610272;648690;127675;127843;130552.3472382632;130740.3462982698;131758;1225.3873063468266;1252.8308678328308;1306.8366454193226;1317.4269192900927;1322.9801552976712;21647.567048659253;21744;21834.454138646663;21866.125354985623;21889.67165492487;62369;64229.357706422335;65260;65744.34255657574;66146;82895.585522072;83499;84154;84508;84528;14833.258337083318;15042.473513426978;15121.319540620478;15124.621884452537;15140.848591513944;22996.885015575484;23306.65040024367;23500.647490287316;23511.647325290476;23700.170494032627;17995.550111247325;18380.448586542425;19170.520736981685;19259;19267.22931082797;16150.03099814015;16974.74537881908;17270;17293.481195564153;17869.73195402044;19105;19370.12834422426;19847.900760496104;19931.900340498203;20278.79721202769;14538.854611454173;14750.483733069292;14959.850401495845;14986.700265994838;15011.474598389004;18961.525961851064;19104.33134840274;19177.232910683415;19180.808191917902;19216.903915480332 16:34:22 INFO - PID 16326 | |3;mootools.html;1392.951246706365;1450.5358285348686;1465.1062851660486;1472.4772705689486;1475.7638777795555;16883.915580422097;17519.649607007854;17536.12319384032;17536.64926701465;17545.82454175458;17078;17117.058561779097;17129.657406851857;17244.137793110356;17290.740638890427;2817.985910070452;2896.623438952933;2899.1302609217255;2900.2894290898757;2902.7097290271017;2747.4505098980185;2808.5365914624062;2826.7879909006806;2828.5615729561846;2829.80191386603;2742.3555464465835;2850.5866649335835;2852.9572056419065;2853.400785834967;2860.141957412778;2683.5437975544205;2694.434168824549;2716.4431291585374;2718.0894400375896;2722.3466368071718;2833.5183018886837;2846.288427893027;2846.644169478815;2857.985710071457;2861.113054952971;16451.095189764557;16534.09062501562;16711.080890551017;16757.41349052777;16899.83100168995;8262.091169971298;8526.914730852735;8528.147185281485;8544.957275213646;8545.871811922827;5446.972765136188;5544.833654990357;5581.6930068846195;5592.636478628917;5648.6893220872835;2053.1068984991575;2063.2056658186707;2067.555475572759;2111.19774485696;2111.883846388448;29907;30289.394212115854;30343.848280758455;30640.8467957661;30649.38701225963;7087.92912070883;7118.608476533736;7139.286071392819;7262.564246145248;7289.489735718449;19976.3008294709;19997.500062498264;19999.200031998847;20019.39941801748;20027.399178024683;8052.476589021695;8318.251357377865;8340.833183336299;8344.207300306462;8344.62449189788;7583.886241706379;7589.658465369072;7619.123800762873;7644.579548124795;7704.383649308042;5475.917861232085;5631.802886898939;5647.237622920935;5656.208130861601;5665.575081868877;1446.8553144685657;1472.9116253024854;1567.3495499367862;1627.21080276066;1668.1825905306312;973.8490533967247;975.9756006099905;982.4350998176077;986.1568359052907;1000.4047591682937;8605.526696031653;8631.438956467935;8736.475811451206;8765.824683506422;8775.692850750193;1039.3088596083671;1046.4663021858908;1065.4193464561833;1068.930519516229;1069.315637991686;5148.639595228298;5154.793808247703;5253.290805741252;5253.553447956967;5263.894722105537;23864.284071477883;24554.508909821703;25084;25154.37114072126;25250.24249272488;24314.988261214;24574.385640359134;24749.752502474745;24980.250592482254;25083.62374564383;12310.322932238634;12321;12497.375131243583;12508.562200323127;12529.74940501185;9076.183143517115;9086.681966131153;9115.863262051207;9140.908590914272;9142.085791420805;20344.898275508527;20658.483537911674;20756;20889.26887558928;21367;21328.253511127037;21729.348119556438;21755;21805.454863628536;22356.552868942854;11984.40077996114;12111.48606424197;12112.818307725567;12119.45462454174;12139.453724582592;15249.6187595311;15250.466233681767;15421.074735515906;15421.768673470131;15437.0737755735;12330.383480826102;12369.567065152676;12435;12741.299228542148;12793;12134.271943683407;12254.326012069421;12274.202176858476;12631.557895473614;12800.6799830005;11011.449427528752;11025.669229923114;11062.17033722458;11242;11249.268797528135;8318.251357377865;8418.284445821928;8492.830143397221;8512.319014478951;8550.74347769568;7970.561619111005;7980.201979801974;8142.4300298978505;8176.713815016446;8226.506409615442 16:34:22 INFO - PID 16326 | |4;prototype.html;545.5580979406682;558.3467343208446;569.2485918587465;575.8214953364454;581.1167026120297;6242.906356404652;6306.432421082101;6309.305976342599;6388.329225431326;6399.328070552595;6136.785212517563;6222.782202622909;6223.471004964572;6236.750529978806;6286.308506064329;5072.771725272353;5074.238864170386;5077.086124497589;5098.286239926413;5124.410692770323;4798.496157903405;4933.4706241065205;5027.622928280375;5039.44566097729;5061.367329083864;4880.048390563841;4907.601333619919;4921.114199444099;4943.826966056189;4949.232868905326;4822.841039119382;4855.198892182803;4926.802927882881;4930.80276788928;4950.702957822542;4460.353248778942;4475.351074094271;4483.932741008887;4488.618467430272;4516.977415112936;6145.84635384119;6194.721237544276;6195.645654651249;6196.96901515494;6238.407351301619;5729.88540229193;5743.109817978178;5777.971110144465;5796.3913789052385;5818.7381567829125;6495.1556297681;6547.639879806608;6567.835804104888;6587.176602924635;6591.6704164792045;4774.522547745232;5000.74996250186;5003.949960500421;5022.59819214462;5026.648134630577;6589.7808905352185;6639.3692599203;6787.287334829821;6833.794986150423;6924.792256232271;5477.424870388551;5581.190727344593;5609.467100625434;5623.722392431072;5651.971740141273;5685.346185188673;5704.201411802351;5710.086386178275;5729.828105156852;5778.711064446761;6081.604695694766;6088.178095957078;6124.5100391968535;6142.754289828446;6145.692715364214;6557.245916719543;6590.934090659032;6591.274959754519;6595.208574971034;6618.782707792943;1924.0475964397713;1965.4398496428448;1971.7929617389968;1977.9703304450445;1981.2966396928882;1328.6346254780012;1374.6425929258464;1437.9856201437851;1447.536788227757;1491.395984626228;5900.350961394243;6006.747469698863;6009.549283803644;6076.605020673731;6079.908801367983;6488.578242414228;6575.210974682973;6575.375339342755;6641.634710090895;6646.966765166143;6034.818955431344;6063.727132279058;6064.4845188158615;6082.36135205797;6108.014895159003;3414.5561077059824;3506.4214404623463;3518.876839310663;3527.153483164022;3564.2515071835205;6660.93511011588;6807.4894382921475;6854.862902741917;6897.2757860423935;6959.512834101564;6756.040748182972;6808;6819.693113809891;6842.349976752201;6880.724771009203;6854.691538880663;6883.380495755406;6914.308569143045;6945.930540694529;6947.235804061549;6417.903731444126;6561.376669216417;6562.5406221564035;6576.638284894377;6677.966610166918;6516.348365163446;6516.6415847128865;6529.269743517876;6656.800295991128;6688.899666504909;6859.279775623488;6861.701330054288;6874.175098987954;6926;6957.269486704025;5105.412877518985;5144.074066668036;5160.845174644766;5161.793528258828;5176.741162941913;6775.356341147583;6796.626185559644;6814.057993471837;6821.658917054227;6826.590404575742;6424.550281480251;6492.5455218134275;6509.698060388036;6519.706613202512;6522.5760325578685;6365.172527571386;6429.9678501607195;6448.806535803933;6486.578372405778;6532;6352.205029786879;6405.295417504164;6437.227532695919;6461.289258181502;6468.482521398371;6498.805035848932;6512.395041983055;6542.149520562297;6581.80254592363;6582.341765823379;6349.460295874902;6393.1688880445245;6416.615003099829;6430.196225471816;6471.191101112361 16:34:22 INFO - PID 16326 | |5;yui.html;949.4777872170305;962.4177372689522;974.0405700385123;986.3145114145667;1002.3334482569089;19163.233470661173;19674.409767706955;19720;19736.21055157792;19754.111065002075;18927.242910283607;19198.52003699905;19471;19768.802311976877;19788.802111978875;1049.7218237167158;1066.6799960012004;1066.8239740442857;1067.3062509368895;1069.4278560969883;1052.3159946035062;1071.6356438810803;1076.0261962923528;1078.1805827571025;1083.9241253112286;1151.3897634253858;1162.1342100135369;1165.341582006168;1167.1246565076192;1170.7541416302586;1092.1863211907141;1127.261643623428;1135.018209249002;1136.2898188632105;1163.6160067177811;832.134580036761;832.2634468495364;833.1085738260064;833.2334252487726;833.3541505333354;20613.690794638092;20763.792362076336;21040;21044.5791084179;21044.684329735068;22285.108595656155;22666.66000010001;22815.31554053381;22821.88589057044;22826.65760013601;17745.911270443696;18053.458396248134;18166.63666726658;18214;18286.45140645783;3813.7139714521522;3997.5802540733384;4024.1549274652357;4029.3553031514816;4038.0308725905866;151524;154291;154695;156770;156947;17453.476395707894;17454.563635909202;17601.295948162187;17611.207495662726;17652;31669.366612667618;33111.33777324488;33187.170320741694;33361.49957750636;33515;8746.737597872074;8781.604827782765;8812.07473215303;8821.029686734453;8823.205911467998;4500.594946454769;4585.679002469862;4601.033782905561;4603.401557797465;4610.654200934943;5943.910841337383;6087.421694938974;6123.663198524035;6153.2000839891425;6157.599756015845;1072.3190773858655;1103.3269705479652;1106.9280496767844;1108.9223754337281;1121.428071683431;739.9408047356306;744.4788647946459;748.1807420874134;759.3924860111888;770.0912922751207;12769.808452873214;13168;13436.328183590977;13890.791638125436;14153.646158845906;725.4921554911583;729.657061181244;730.4740586777511;730.7369347035063;744.2892038103598;5880.647161170344;5894.823155305347;6026.156338112668;6093.878122437526;6158.199434073631;143216.28391857975;144314;146038;147356;148334.25832870984;130912.3454382741;133596;134296.32851835873;135391;136942;116724.41637791757;116815;117368;118159;118403.40798295953;11188.66434006981;11269.211155219056;11408.771824563297;11568.016718579014;11652.242604230698;52913.20630190627;54552;54621.453785461636;55534.16698749441;56386;69387;69616.7344938958;70048.64975675089;70785;70830;15688;15944.681106377575;16329.101899395648;16485.670286593962;16492.505224843273;18836.905815471382;19062.428127156207;19133.617327653646;19229.615407692047;19276.22895084181;15959.122248276457;15984.120873352078;16113.194340282704;16134.838651613803;16227.432039878548;17507.649847002733;17766.46700598984;17783;17890.28438862431;17895.73156402681;15528.689426211638;15579.844201558293;15724;15933.840661593236;15953.282102305191;11959.461824217757;11981.76036479248;12134.878651213729;12174.634760957186;12231.877681223074;10740.140788736726;10756.569737210424;10773.353598784099;10896.237263391804;11073.72315692082 16:34:22 INFO - PID 16326 | __end_tp_report 16:34:22 INFO - PID 16326 | __start_cc_report 16:34:22 INFO - PID 16326 | _x_x_mozilla_cycle_collect,363 16:34:22 INFO - PID 16326 | __end_cc_report 16:34:22 INFO - PID 16326 | __startTimestamp1505345662655__endTimestamp 16:34:22 INFO - PID 16326 | 16:34:22 INFO - PID 16326 | ------- Summary: start ------- 16:34:22 INFO - PID 16326 | Number of tests: 6 16:34:22 INFO - PID 16326 | 16:34:22 INFO - PID 16326 | [#0] dojo.html Cycles:165 Average:6222.24 Median:6495.81 stddev:876.57 (13.5%) stddev-sans-first:858.86 16:34:22 INFO - PID 16326 | Values: 3825.8 3840.5 3840.9 3856.4 3883.9 6162.7 6175.4 6251.5 6257.8 6297.4 6271.3 6312.4 6362.8 6388.5 6429.4 5590.6 5713.6 5726.1 5727.3 5753.2 224.1 5346.6 5576.6 5684.3 5706.0 5604.2 5607.4 5700.4 5703.1 5712.1 5595.4 5623.6 5634.5 5686.2 5693.1 5604.4 5606.5 5680.7 5693.7 5695.3 6248.8 6254.4 6282.9 6291.0 6346.3 6730.4 6770.7 6774.9 6824.4 6857.7 6641.9 6731.8 6732.0 6739.6 6741.7 6290.6 6424.0 6431.1 6442.8 6456.0 6902.0 6999.5 7035.4 7103.3 7122.3 6495.8 6627.3 6704.2 6714.6 6764.5 6803.6 6818.8 6826.6 6829.1 6832.4 6746.0 6762.2 6832.5 6839.2 6843.4 6726.7 6736.8 6826.6 6858.8 6859.5 4911.5 5001.6 5003.8 5010.6 5013.8 4475.3 4481.4 4515.0 4542.7 4569.6 6206.5 6239.9 6249.0 6265.2 6265.7 6640.4 6678.7 6765.4 6827.1 6883.4 6055.1 6242.5 6320.6 6323.2 6342.6 5306.5 5455.8 5468.3 5551.1 5554.7 6931.7 7058.1 7073.2 7076.9 7094.8 6609.0 6767.7 6776.3 6783.7 6862.3 6899.1 7026.6 7055.5 7131.8 7156.6 6674.8 6693.2 6701.1 6760.5 6778.9 6811.3 6892.0 6893.4 6895.7 6898.7 6514.8 6520.7 6558.3 6583.6 6638.5 6433.3 6483.0 6519.4 6524.8 6592.8 6497.6 6517.2 6576.2 6592.7 6621.5 6567.1 6666.3 6677.0 6717.8 6739.9 6341.6 6396.4 6402.9 6405.5 6441.6 16:34:22 INFO - PID 16326 | 16:34:22 INFO - PID 16326 | [#1] ext.html Cycles:180 Average:43315.18 Median:39205.21 stddev:64777.28 (165.2%) stddev-sans-first:64880.60 16:34:22 INFO - PID 16326 | Values: 871.7 919.2 928.7 957.9 976.8 44180.3 44738.6 44801.6 44832.1 44984.8 41015.8 41393.4 41397.4 41433.4 41460.8 7343.0 7467.5 7477.6 7481.3 7482.2 7301.7 7380.4 7393.5 7394.2 7399.4 7883.2 8026.6 8043.5 8050.8 8057.1 7895.5 7933.4 7944.4 7955.4 7966.0 7928.8 8123.2 8138.1 8162.6 8163.7 40947.4 41006.0 41188.0 41427.0 41466.6 3773.6 3778.3 3783.0 3783.5 3833.5 32984.2 33026.5 33185.7 33222.2 33281.8 5276.4 5281.5 5319.4 5335.6 5349.6 207000.0 209293.0 215065.0 215847.0 253506.0 55162.7 55292.7 55470.7 55544.0 55547.7 57900.4 58091.0 58137.0 58141.7 58291.4 17090.2 17184.7 17203.1 17215.4 17396.4 13994.0 14017.6 14018.5 14018.9 14044.8 15945.1 15959.0 15983.1 16033.9 16052.4 6344.8 6365.1 6416.1 6482.3 6489.1 1065.9 1084.2 1088.2 1091.3 1096.2 26121.7 26125.1 26169.6 26276.6 26296.5 1195.2 1200.3 1200.3 1208.6 1211.0 13542.6 13545.6 13558.9 13559.0 13842.8 282392.0 284525.0 284756.0 285042.0 285099.0 56608.4 56983.0 57018.7 57417.7 57574.4 1486.8 1520.9 1522.1 1528.6 1532.6 25189.7 25223.9 25626.2 25842.6 25859.7 41884.6 41959.2 41990.0 42060.0 42073.6 237737.0 237787.0 237835.0 237842.0 237911.0 38342.6 40067.8 40573.6 40800.0 40905.0 50460.5 51667.5 51881.7 51911.2 52159.0 36574.1 37318.0 37626.2 38026.4 38184.6 36361.0 36733.6 37119.3 37366.6 37779.2 43540.0 43593.8 43619.0 43746.6 43981.8 35154.0 35390.8 35631.8 35802.5 36115.0 26359.5 26404.2 26416.3 26577.6 27152.3 16:34:22 INFO - PID 16326 | 16:34:22 INFO - PID 16326 | [#2] jquery.html Cycles:180 Average:39585.89 Median:22891.33 stddev:101701.50 (444.3%) stddev-sans-first:101944.64 16:34:22 INFO - PID 16326 | Values: 582.9 587.2 591.7 594.2 614.5 22785.8 23544.3 23599.4 23792.6 23854.2 24237.5 24940.4 25086.9 25105.6 25330.9 6613.2 6651.8 6656.9 6664.7 6666.8 6477.8 6607.1 6735.7 6780.2 6782.7 6664.9 6667.1 6672.6 6678.7 6683.5 6185.9 6365.6 6374.3 6401.1 6711.4 5973.1 5975.6 5978.5 6006.1 6024.2 28726.3 29288.4 29387.7 29506.7 29518.6 2085.2 2091.1 2097.8 2114.7 2122.7 19694.0 21132.9 21172.0 21295.1 21307.7 2480.9 2517.4 2563.7 2573.1 2582.6 136301.0 142277.0 142904.3 143076.7 153313.0 14970.5 15534.8 15855.6 16011.2 16057.8 24755.5 24904.0 24972.4 24980.8 25007.7 10274.7 10399.9 10508.2 10558.8 10611.9 9459.7 9510.9 9545.2 9605.0 9654.4 9458.3 9604.8 9861.2 9880.7 9959.5 2639.5 2666.6 2679.5 2795.7 2817.7 1022.1 1035.3 1039.4 1048.0 1048.6 20199.3 20414.9 20455.0 20588.3 20601.8 1070.6 1093.7 1105.4 1121.9 1125.9 11318.2 11512.4 11709.2 11709.9 11725.9 575519.0 598888.0 608585.0 610272.0 648690.0 127675.0 127843.0 130552.3 130740.3 131758.0 1225.4 1252.8 1306.8 1317.4 1323.0 21647.6 21744.0 21834.5 21866.1 21889.7 62369.0 64229.4 65260.0 65744.3 66146.0 82895.6 83499.0 84154.0 84508.0 84528.0 14833.3 15042.5 15121.3 15124.6 15140.8 22996.9 23306.7 23500.6 23511.6 23700.2 17995.6 18380.4 19170.5 19259.0 19267.2 16150.0 16974.7 17270.0 17293.5 17869.7 19105.0 19370.1 19847.9 19931.9 20278.8 14538.9 14750.5 14959.9 14986.7 15011.5 18961.5 19104.3 19177.2 19180.8 19216.9 16:34:22 INFO - PID 16326 | 16:34:22 INFO - PID 16326 | [#3] mootools.html Cycles:180 Average:10367.71 Median:13966.89 stddev:7697.75 (55.1%) stddev-sans-first:7689.81 16:34:22 INFO - PID 16326 | Values: 1393.0 1450.5 1465.1 1472.5 1475.8 16883.9 17519.6 17536.1 17536.6 17545.8 17078.0 17117.1 17129.7 17244.1 17290.7 2818.0 2896.6 2899.1 2900.3 2902.7 2747.5 2808.5 2826.8 2828.6 2829.8 2742.4 2850.6 2853.0 2853.4 2860.1 2683.5 2694.4 2716.4 2718.1 2722.3 2833.5 2846.3 2846.6 2858.0 2861.1 16451.1 16534.1 16711.1 16757.4 16899.8 8262.1 8526.9 8528.1 8545.0 8545.9 5447.0 5544.8 5581.7 5592.6 5648.7 2053.1 2063.2 2067.6 2111.2 2111.9 29907.0 30289.4 30343.8 30640.8 30649.4 7087.9 7118.6 7139.3 7262.6 7289.5 19976.3 19997.5 19999.2 20019.4 20027.4 8052.5 8318.3 8340.8 8344.2 8344.6 7583.9 7589.7 7619.1 7644.6 7704.4 5475.9 5631.8 5647.2 5656.2 5665.6 1446.9 1472.9 1567.3 1627.2 1668.2 973.8 976.0 982.4 986.2 1000.4 8605.5 8631.4 8736.5 8765.8 8775.7 1039.3 1046.5 1065.4 1068.9 1069.3 5148.6 5154.8 5253.3 5253.6 5263.9 23864.3 24554.5 25084.0 25154.4 25250.2 24315.0 24574.4 24749.8 24980.3 25083.6 12310.3 12321.0 12497.4 12508.6 12529.7 9076.2 9086.7 9115.9 9140.9 9142.1 20344.9 20658.5 20756.0 20889.3 21367.0 21328.3 21729.3 21755.0 21805.5 22356.6 11984.4 12111.5 12112.8 12119.5 12139.5 15249.6 15250.5 15421.1 15421.8 15437.1 12330.4 12369.6 12435.0 12741.3 12793.0 12134.3 12254.3 12274.2 12631.6 12800.7 11011.4 11025.7 11062.2 11242.0 11249.3 8318.3 8418.3 8492.8 8512.3 8550.7 7970.6 7980.2 8142.4 8176.7 8226.5 16:34:22 INFO - PID 16326 | 16:34:22 INFO - PID 16326 | [#4] prototype.html Cycles:180 Average:5616.34 Median:6223.13 stddev:1528.98 (24.6%) stddev-sans-first:1485.14 16:34:22 INFO - PID 16326 | Values: 545.6 558.3 569.2 575.8 581.1 6242.9 6306.4 6309.3 6388.3 6399.3 6136.8 6222.8 6223.5 6236.8 6286.3 5072.8 5074.2 5077.1 5098.3 5124.4 4798.5 4933.5 5027.6 5039.4 5061.4 4880.0 4907.6 4921.1 4943.8 4949.2 4822.8 4855.2 4926.8 4930.8 4950.7 4460.4 4475.4 4483.9 4488.6 4517.0 6145.8 6194.7 6195.6 6197.0 6238.4 5729.9 5743.1 5778.0 5796.4 5818.7 6495.2 6547.6 6567.8 6587.2 6591.7 4774.5 5000.7 5003.9 5022.6 5026.6 6589.8 6639.4 6787.3 6833.8 6924.8 5477.4 5581.2 5609.5 5623.7 5652.0 5685.3 5704.2 5710.1 5729.8 5778.7 6081.6 6088.2 6124.5 6142.8 6145.7 6557.2 6590.9 6591.3 6595.2 6618.8 1924.0 1965.4 1971.8 1978.0 1981.3 1328.6 1374.6 1438.0 1447.5 1491.4 5900.4 6006.7 6009.5 6076.6 6079.9 6488.6 6575.2 6575.4 6641.6 6647.0 6034.8 6063.7 6064.5 6082.4 6108.0 3414.6 3506.4 3518.9 3527.2 3564.3 6660.9 6807.5 6854.9 6897.3 6959.5 6756.0 6808.0 6819.7 6842.3 6880.7 6854.7 6883.4 6914.3 6945.9 6947.2 6417.9 6561.4 6562.5 6576.6 6678.0 6516.3 6516.6 6529.3 6656.8 6688.9 6859.3 6861.7 6874.2 6926.0 6957.3 5105.4 5144.1 5160.8 5161.8 5176.7 6775.4 6796.6 6814.1 6821.7 6826.6 6424.6 6492.5 6509.7 6519.7 6522.6 6365.2 6430.0 6448.8 6486.6 6532.0 6352.2 6405.3 6437.2 6461.3 6468.5 6498.8 6512.4 6542.1 6581.8 6582.3 6349.5 6393.2 6416.6 6430.2 6471.2 16:34:22 INFO - PID 16326 | 16:34:22 INFO - PID 16326 | [#5] yui.html Cycles:180 Average:27767.22 Median:17893.01 stddev:41919.61 (234.3%) stddev-sans-first:41988.85 16:34:22 INFO - PID 16326 | Values: 949.5 962.4 974.0 986.3 1002.3 19163.2 19674.4 19720.0 19736.2 19754.1 18927.2 19198.5 19471.0 19768.8 19788.8 1049.7 1066.7 1066.8 1067.3 1069.4 1052.3 1071.6 1076.0 1078.2 1083.9 1151.4 1162.1 1165.3 1167.1 1170.8 1092.2 1127.3 1135.0 1136.3 1163.6 832.1 832.3 833.1 833.2 833.4 20613.7 20763.8 21040.0 21044.6 21044.7 22285.1 22666.7 22815.3 22821.9 22826.7 17745.9 18053.5 18166.6 18214.0 18286.5 3813.7 3997.6 4024.2 4029.4 4038.0 151524.0 154291.0 154695.0 156770.0 156947.0 17453.5 17454.6 17601.3 17611.2 17652.0 31669.4 33111.3 33187.2 33361.5 33515.0 8746.7 8781.6 8812.1 8821.0 8823.2 4500.6 4585.7 4601.0 4603.4 4610.7 5943.9 6087.4 6123.7 6153.2 6157.6 1072.3 1103.3 1106.9 1108.9 1121.4 739.9 744.5 748.2 759.4 770.1 12769.8 13168.0 13436.3 13890.8 14153.6 725.5 729.7 730.5 730.7 744.3 5880.6 5894.8 6026.2 6093.9 6158.2 143216.3 144314.0 146038.0 147356.0 148334.3 130912.3 133596.0 134296.3 135391.0 136942.0 116724.4 116815.0 117368.0 118159.0 118403.4 11188.7 11269.2 11408.8 11568.0 11652.2 52913.2 54552.0 54621.5 55534.2 56386.0 69387.0 69616.7 70048.6 70785.0 70830.0 15688.0 15944.7 16329.1 16485.7 16492.5 18836.9 19062.4 19133.6 19229.6 19276.2 15959.1 15984.1 16113.2 16134.8 16227.4 17507.6 17766.5 17783.0 17890.3 17895.7 15528.7 15579.8 15724.0 15933.8 15953.3 11959.5 11981.8 12134.9 12174.6 12231.9 10740.1 10756.6 10773.4 10896.2 11073.7 16:34:22 INFO - PID 16326 | -------- Summary: end -------- 16:34:22 INFO - PID 16326 | 16:34:22 INFO - PID 16326 | 16:34:22 INFO - PID 16326 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 16:34:22 INFO - PID 16326 | 16:34:23 INFO - TEST-INFO | 16326: exit 0 16:34:23 INFO - TEST-OK | dromaeo_css | took 1108600ms 16:34:23 INFO - TEST-START | kraken 16:34:23 INFO - Initialising browser for kraken test... 16:34:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41558/getInfo.html -profile /tmp/tmpd70LXH/profile 16:34:23 INFO - TEST-INFO | started process 16696 (/builds/slave/test/build/application/firefox/firefox http://localhost:41558/getInfo.html) 16:34:29 INFO - TEST-INFO | 16696: exit 0 16:34:29 INFO - Browser initialized. 16:34:29 INFO - Running cycle 1/1 for kraken test... 16:34:29 INFO - Using env: {'DISPLAY': ':0', 16:34:29 INFO - 'HOME': '/home/cltbld', 16:34:29 INFO - 'JSGC_DISABLE_POISONING': '1', 16:34:29 INFO - 'LANG': 'en_US.UTF-8', 16:34:29 INFO - 'LANGUAGE': 'en_US:en', 16:34:29 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:34:29 INFO - 'LOGNAME': 'cltbld', 16:34:29 INFO - 'MAIL': '/var/mail/cltbld', 16:34:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:34:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:34:29 INFO - 'MOZ_CRASHREPORTER': '1', 16:34:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:34:29 INFO - 'MOZ_NO_REMOTE': '1', 16:34:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:34:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:29 INFO - 'NO_EM_RESTART': '1', 16:34:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:29 INFO - 'PWD': '/builds/slave/test', 16:34:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:34:29 INFO - 'RUST_BACKTRACE': 'full', 16:34:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:34:29 INFO - 'SHELL': '/bin/bash', 16:34:29 INFO - 'SHLVL': '1', 16:34:29 INFO - 'TERM': 'linux', 16:34:29 INFO - 'TMOUT': '86400', 16:34:29 INFO - 'USER': 'cltbld', 16:34:29 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1505344492.352962-411523862', 16:34:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:29 INFO - '_': '/tools/buildbot/bin/python'} 16:34:29 INFO - TEST-INFO | started process 16937 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd70LXH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:34:30 INFO - PID 16937 | 16:34:30 INFO - PID 16937 | (/builds/slave/test/build/application/firefox/firefox:16989): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:34:30 INFO - PID 16937 | 16:34:40 INFO - PID 16937 | RSS: Main: 166793216 16:34:40 INFO - PID 16937 | 16:34:43 INFO - PID 16937 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41558/tests/kraken/driver.html?testName=audio-beat-detection) 16:34:44 INFO - PID 16937 | RSS: Main: 173387776 16:34:44 INFO - PID 16937 | 16:34:46 INFO - PID 16937 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41558/tests/kraken/driver.html?testName=audio-dft) 16:34:47 INFO - PID 16937 | RSS: Main: 173584384 16:34:47 INFO - PID 16937 | 16:34:50 INFO - PID 16937 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41558/tests/kraken/driver.html?testName=audio-fft) 16:34:50 INFO - PID 16937 | RSS: Main: 167989248 16:34:50 INFO - PID 16937 | 16:34:53 INFO - PID 16937 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41558/tests/kraken/driver.html?testName=audio-oscillator) 16:34:53 INFO - PID 16937 | RSS: Main: 169021440 16:34:53 INFO - PID 16937 | 16:34:55 INFO - PID 16937 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41558/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:34:56 INFO - PID 16937 | RSS: Main: 169222144 16:34:56 INFO - PID 16937 | 16:34:59 INFO - PID 16937 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41558/tests/kraken/driver.html?testName=imaging-darkroom) 16:35:00 INFO - PID 16937 | RSS: Main: 172863488 16:35:00 INFO - PID 16937 | 16:35:03 INFO - PID 16937 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41558/tests/kraken/driver.html?testName=imaging-desaturate) 16:35:04 INFO - PID 16937 | RSS: Main: 171769856 16:35:04 INFO - PID 16937 | 16:35:07 INFO - PID 16937 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41558/tests/kraken/driver.html?testName=json-parse-financial) 16:35:07 INFO - PID 16937 | RSS: Main: 171737088 16:35:07 INFO - PID 16937 | 16:35:09 INFO - PID 16937 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41558/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:35:09 INFO - PID 16937 | RSS: Main: 171532288 16:35:09 INFO - PID 16937 | 16:35:11 INFO - PID 16937 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:35:12 INFO - PID 16937 | RSS: Main: 171642880 16:35:12 INFO - PID 16937 | 16:35:14 INFO - PID 16937 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:35:14 INFO - PID 16937 | RSS: Main: 168923136 16:35:14 INFO - PID 16937 | 16:35:17 INFO - PID 16937 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:35:18 INFO - PID 16937 | RSS: Main: 168300544 16:35:18 INFO - PID 16937 | 16:35:20 INFO - PID 16937 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:35:21 INFO - PID 16937 | RSS: Main: 169775104 16:35:21 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | Cycle 1(1): loaded http://localhost:41558/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41558/tests/kraken/driver.html?testName=ai-astar) 16:35:23 INFO - PID 16937 | RSS: Main: 170856448 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | __start_tp_report 16:35:23 INFO - PID 16937 | _x_x_mozilla_page_load 16:35:23 INFO - PID 16937 | _x_x_mozilla_page_load_details 16:35:23 INFO - PID 16937 | |i|pagename|runs| 16:35:23 INFO - PID 16937 | |0;ai-astar;106;106;104;106;107;96;106;106;107;105 16:35:23 INFO - PID 16937 | |1;audio-beat-detection;114;112;116;112;117;114;113;115;154;115 16:35:23 INFO - PID 16937 | |2;audio-dft;162;162;162;164;163;163;164;165;172;162 16:35:23 INFO - PID 16937 | |3;audio-fft;74;72;73;74;73;73;76;74;74;73 16:35:23 INFO - PID 16937 | |4;audio-oscillator;79;78;78;77;78;77;78;77;78;79 16:35:23 INFO - PID 16937 | |5;imaging-gaussian-blur;114;114;114;114;113;115;114;115;115;113 16:35:23 INFO - PID 16937 | |6;imaging-darkroom;154;151;152;151;149;152;153;151;150;150 16:35:23 INFO - PID 16937 | |7;imaging-desaturate;94;94;94;96;95;94;94;94;96;94 16:35:23 INFO - PID 16937 | |8;json-parse-financial;49;48;44;45;46;45;45;47;46;59 16:35:23 INFO - PID 16937 | |9;json-stringify-tinderbox;38;37;37;37;37;37;37;37;37;36 16:35:23 INFO - PID 16937 | |10;stanford-crypto-aes;73;73;75;70;74;73;72;71;74;76 16:35:23 INFO - PID 16937 | |11;stanford-crypto-ccm;112;106;104;106;109;103;112;112;107;104 16:35:23 INFO - PID 16937 | |12;stanford-crypto-pbkdf2;164;156;157;157;161;158;153;163;159;153 16:35:23 INFO - PID 16937 | |13;stanford-crypto-sha256-iterative;63;63;61;61;61;61;66;60;59;60 16:35:23 INFO - PID 16937 | __end_tp_report 16:35:23 INFO - PID 16937 | __start_cc_report 16:35:23 INFO - PID 16937 | _x_x_mozilla_cycle_collect,894 16:35:23 INFO - PID 16937 | __end_cc_report 16:35:23 INFO - PID 16937 | __startTimestamp1505345723386__endTimestamp 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | ------- Summary: start ------- 16:35:23 INFO - PID 16937 | Number of tests: 14 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | [#0] ai-astar Cycles:10 Average:104.90 Median:106.00 stddev:3.25 (3.1%) stddev-sans-first:3.42 16:35:23 INFO - PID 16937 | Values: 106.0 106.0 104.0 106.0 107.0 96.0 106.0 106.0 107.0 105.0 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | [#1] audio-beat-detection Cycles:10 Average:118.20 Median:115.00 stddev:12.68 (11.0%) stddev-sans-first:13.36 16:35:23 INFO - PID 16937 | Values: 114.0 112.0 116.0 112.0 117.0 114.0 113.0 115.0 154.0 115.0 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | [#2] audio-dft Cycles:10 Average:163.90 Median:163.50 stddev:3.03 (1.9%) stddev-sans-first:3.14 16:35:23 INFO - PID 16937 | Values: 162.0 162.0 162.0 164.0 163.0 163.0 164.0 165.0 172.0 162.0 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | [#3] audio-fft Cycles:10 Average:73.60 Median:74.00 stddev:1.07 (1.5%) stddev-sans-first:1.13 16:35:23 INFO - PID 16937 | Values: 74.0 72.0 73.0 74.0 73.0 73.0 76.0 74.0 74.0 73.0 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:78.00 stddev:0.74 (0.9%) stddev-sans-first:0.67 16:35:23 INFO - PID 16937 | Values: 79.0 78.0 78.0 77.0 78.0 77.0 78.0 77.0 78.0 79.0 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 16:35:23 INFO - PID 16937 | Values: 114.0 114.0 114.0 114.0 113.0 115.0 114.0 115.0 115.0 113.0 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | [#6] imaging-darkroom Cycles:10 Average:151.30 Median:151.50 stddev:1.49 (1.0%) stddev-sans-first:1.22 16:35:23 INFO - PID 16937 | Values: 154.0 151.0 152.0 151.0 149.0 152.0 153.0 151.0 150.0 150.0 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 16:35:23 INFO - PID 16937 | Values: 94.0 94.0 94.0 96.0 95.0 94.0 94.0 94.0 96.0 94.0 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | [#8] json-parse-financial Cycles:10 Average:47.40 Median:46.50 stddev:4.35 (9.4%) stddev-sans-first:4.58 16:35:23 INFO - PID 16937 | Values: 49.0 48.0 44.0 45.0 46.0 45.0 45.0 47.0 46.0 59.0 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | [#9] json-stringify-tinderbox Cycles:10 Average:37.00 Median:37.00 stddev:0.47 (1.3%) stddev-sans-first:0.33 16:35:23 INFO - PID 16937 | Values: 38.0 37.0 37.0 37.0 37.0 37.0 37.0 37.0 37.0 36.0 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | [#10] stanford-crypto-aes Cycles:10 Average:73.10 Median:73.50 stddev:1.79 (2.4%) stddev-sans-first:1.90 16:35:23 INFO - PID 16937 | Values: 73.0 73.0 75.0 70.0 74.0 73.0 72.0 71.0 74.0 76.0 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | [#11] stanford-crypto-ccm Cycles:10 Average:107.50 Median:108.00 stddev:3.54 (3.3%) stddev-sans-first:3.35 16:35:23 INFO - PID 16937 | Values: 112.0 106.0 104.0 106.0 109.0 103.0 112.0 112.0 107.0 104.0 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.10 Median:158.50 stddev:3.75 (2.4%) stddev-sans-first:3.32 16:35:23 INFO - PID 16937 | Values: 164.0 156.0 157.0 157.0 161.0 158.0 153.0 163.0 159.0 153.0 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:61.00 stddev:2.01 (3.3%) stddev-sans-first:2.06 16:35:23 INFO - PID 16937 | Values: 63.0 63.0 61.0 61.0 61.0 61.0 66.0 60.0 59.0 60.0 16:35:23 INFO - PID 16937 | -------- Summary: end -------- 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 16:35:23 INFO - PID 16937 | 16:35:23 INFO - PID 16937 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:35:23 INFO - TEST-INFO | 16937: exit 0 16:35:24 INFO - TEST-OK | kraken | took 60582ms 16:35:24 INFO - SUITE-END | took 1169s 16:35:24 INFO - Completed test suite (00:19:29) 16:35:24 INFO - javascript benchmark 16:35:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9008.113327323292, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3825.751326163799, 3840.4623352730614, 3840.884773456797, 3856.363699989502, 3883.883483495496, 6162.661053642048, 6175.351588083248, 6251.531135164858, 6257.843553911154, 6297.433231009209, 6271.278802937663, 6312.431881130698, 6362.840928976778, 6388.48892088633, 6429.35706429358, 5590.552755779528, 5713.5714821388565, 5726.112452569859, 5727.312722473309, 5753.194552762617, 224.08368426188878, 5346.572274218072, 5576.553875689955, 5684.317881854184, 5706.0, 5604.159376093598, 5607.383187849333, 5700.429957004267, 5703.116017017348, 5712.11462223356, 5595.38450770415, 5623.578231632644, 5634.492895639412, 5686.1755045518585, 5693.060644993571, 5604.355499117612, 5606.467385598362, 5680.744366503517, 5693.743781529841, 5695.316562012585, 6248.843778905563, 6254.374562543755, 6282.905756413657, 6290.968545157245, 6346.30190679025, 6730.427913627347, 6770.72917083316, 6774.93225067748, 6824.351686589766, 6857.691403886837, 6641.900371494431, 6731.798046058626, 6731.9663401683165, 6739.5619284746335, 6741.730330786762, 6290.591111577733, 6423.967880160616, 6431.131797207364, 6442.774502892423, 6456.0, 6901.965490172567, 6999.545029573062, 7035.401990830836, 7103.2541583133025, 7122.2521635228595, 6495.805125846232, 6627.271000189975, 6704.161979752531, 6714.597124172566, 6764.458843292525, 6803.625800581016, 6818.829529261808, 6826.556273842184, 6829.146356705412, 6832.350926661959, 6745.9662701686175, 6762.154730658668, 6832.521723479309, 6839.179298484214, 6843.418309443753, 6726.697298621574, 6736.831579210559, 6826.556273842184, 6858.828529286708, 6859.519833611699, 4911.508849115131, 5001.624878134082, 5003.849884503471, 5010.574101201439, 5013.824516141917, 4475.283954567253, 4481.417415735934, 4514.954850451453, 4542.68201225911, 4569.588737013684, 6206.472449841724, 6239.937600624026, 6249.031400132987, 6265.185525881606, 6265.686715664289, 6640.435562977104, 6678.699458524378, 6765.357291057342, 6827.112475378269, 6883.380495755406, 6055.1220073090035, 6242.500599951993, 6320.5891617044745, 6323.20959880015, 6342.556021078481, 5306.469353064663, 5455.809046683347, 5468.261784659099, 5551.084071128299, 5554.6944918029085, 6931.7227310907, 7058.082449281561, 7073.186583542855, 7076.893846592306, 7094.787156385317, 6608.966955165193, 6767.729290828509, 6776.254611992578, 6783.728650854009, 6862.348076932683, 6899.103116594811, 7026.578405295699, 7055.470839686941, 7131.82170445743, 7156.64216789148, 6674.799756007327, 6693.230278518033, 6701.061851340914, 6760.459163266829, 6778.898316525355, 6811.250762416229, 6892.0, 6893.4140598047725, 6895.724171033303, 6898.724051037902, 6514.771982980573, 6520.706568204348, 6558.278589355263, 6583.637899915359, 6638.468922486286, 6433.260175079926, 6483.0, 6519.445847103049, 6524.836879078061, 6592.7692530763115, 6497.577657452251, 6517.152770139852, 6576.243731970884, 6592.70332835014, 6621.470282377494, 6567.113439685581, 6666.333366663295, 6677.031830384664, 6717.798466046026, 6739.865202696017, 6341.619502829845, 6396.360363963752, 6402.87194256103, 6405.51958603116, 6441.613503189824], "value": 6164.364212434748, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [871.6554284922365, 919.1589695428679, 928.6656803550723, 957.8706874571935, 976.8095221431814, 44180.337294940604, 44738.55261447385, 44801.551984480146, 44832.103357932865, 44984.77507612458, 41015.79492102535, 41393.37909931353, 41397.37903931436, 41433.378499322534, 41460.79269603655, 7343.04540409746, 7467.477276590641, 7477.551346919202, 7481.289277518655, 7482.21436749139, 7301.744438944638, 7380.409567234635, 7393.482456228066, 7394.186639469654, 7399.445041621873, 7883.172266911978, 8026.558539280337, 8043.517388956652, 8050.838983220332, 8057.113717491101, 7895.486793358442, 7933.444658873881, 7944.443888927777, 7955.403344749138, 7966.044074711014, 7928.7621371358955, 8123.187681231889, 8138.145494723028, 8162.632681529345, 8163.7142700005215, 40947.385789213185, 41006.0, 41188.0, 41427.0, 41466.585334146876, 3773.6226377362045, 3778.301014312361, 3783.0, 3783.5459744830796, 3833.4824798652103, 32984.175395615064, 33026.504602430985, 33185.66814331874, 33222.16944576381, 33281.83359083213, 5276.419593844674, 5281.471852814726, 5319.361676598833, 5335.573154147659, 5349.598780091509, 207000.0, 209293.0, 215065.0, 215847.0, 253506.0, 55162.724186379615, 55292.72353638206, 55470.72264638651, 55544.0, 55547.722261389245, 57900.420995790344, 58091.0, 58137.0, 58141.70929145412, 58291.417085829446, 17090.230939607747, 17184.742228866577, 17203.1398430078, 17215.397461088804, 17396.39112631052, 13994.0, 14017.579472615838, 14018.509352172829, 14018.878489720797, 14044.789328160086, 15945.123018233877, 15959.042457452359, 15983.12092834882, 16033.919830401006, 16052.438164664414, 6344.841378965562, 6365.108884756138, 6416.133821934072, 6482.254540727782, 6489.091527186198, 1065.898739619735, 1084.1814430105221, 1088.216484131424, 1091.3342860854873, 1096.1833434091652, 26121.738782612312, 26125.08562200314, 26169.607455888177, 26276.605850912252, 26296.47407051848, 1195.1574140231116, 1200.3158199826016, 1200.3218181727295, 1208.5649166300125, 1210.9757804843853, 13542.59372218835, 13545.59363219105, 13558.864411355957, 13559.0, 13842.792358114637, 282392.0, 284525.0, 284756.0, 285042.0, 285099.0, 56608.43391566032, 56983.0, 57018.7149064252, 57417.71291143518, 57574.424255757745, 1486.8067151270266, 1520.931558079889, 1522.117172040214, 1528.587281434029, 1532.6472817199208, 25189.748102519472, 25223.87388063048, 25626.23121306364, 25842.612360814226, 25859.741402585732, 41884.58115418807, 41959.160816784104, 41990.0, 42060.0, 42073.579264206965, 237737.0, 237787.0, 237835.0, 237842.0, 237911.0, 38342.616573833904, 40067.79966100151, 40573.59426405816, 40800.0, 40905.0, 50460.49539504705, 51667.483325166264, 51881.7405912968, 51911.2213316793, 52159.0, 36574.085647859014, 37318.0, 37626.2474750498, 38026.429603555414, 38184.61815381922, 36361.0, 36733.63266367302, 37119.25761484701, 37366.62633373631, 37779.244415112094, 43540.0, 43593.78203108964, 43619.0, 43746.562534375524, 43981.78009109934, 35154.0, 35390.82304588461, 35631.82184089063, 35802.462963055055, 36115.0, 26359.4728105433, 26404.20787376382, 26416.339591510365, 26577.601335980362, 27152.321191969568], "value": 18856.44265510601, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.8571999860033, 587.1720873568264, 591.6982339007105, 594.1651978969546, 614.5329549542347, 22785.772142278573, 23544.29367118987, 23599.41001474964, 23792.643110353336, 23854.165104221356, 24237.515249694996, 24940.376490587743, 25086.874565627146, 25105.62341564878, 25330.873345633245, 6613.239477460093, 6651.767188148416, 6656.900146497806, 6664.700088496029, 6666.833329166761, 6477.838054048663, 6607.108040414555, 6735.696893639798, 6780.220274668427, 6782.6947787349445, 6664.900291451904, 6667.066610674485, 6672.59964402135, 6678.732850685967, 6683.53215274931, 6185.938140618604, 6365.58623689461, 6374.330695277022, 6401.07184458251, 6711.395974362306, 5973.133895585159, 5975.551833612496, 5978.491828194608, 6006.099085137221, 6024.1566180734735, 28726.281842953886, 29288.414231715247, 29387.706122938926, 29506.70493295061, 29518.55722164169, 2085.1763553396477, 2091.069474084033, 2097.811196992278, 2114.693369461435, 2122.7452705675264, 19694.0, 21132.894335528377, 21172.047257873277, 21295.14819407222, 21307.68038479424, 2480.888360023785, 2517.4335774450783, 2563.6667233259745, 2573.1122762646855, 2582.586786114213, 136301.0, 142277.0, 142904.28547857195, 143076.7076987692, 153313.0, 14970.47603333878, 15534.84465155334, 15855.603609909844, 16011.199440028186, 16057.839421605635, 24755.504889902462, 24904.0, 24972.375690607518, 24980.750192498206, 25007.749922500905, 10274.691759247235, 10399.896001040044, 10508.211884108723, 10558.841617375592, 10611.89388106109, 9459.716208513755, 9510.857337139949, 9545.236381089497, 9604.95197524008, 9654.372465789702, 9458.290628202774, 9604.75988100303, 9861.211103111735, 9880.654177103766, 9959.452230127268, 2639.4984952858895, 2666.6133410655348, 2679.4775018871524, 2795.7064508226345, 2817.7041410651996, 1022.1158697726562, 1035.3322107240938, 1039.3504059962524, 1047.9633212837514, 1048.6015314180563, 20199.293024744064, 20414.88146600123, 20455.0, 20588.27941022057, 20601.793982060288, 1070.5664205996584, 1093.7429704019548, 1105.3588918427454, 1121.8878112188715, 1125.876153623101, 11318.207725459139, 11512.424378781028, 11709.238899471507, 11709.88290117105, 11725.941370293094, 575519.0, 598888.0, 608585.0, 610272.0, 648690.0, 127675.0, 127843.0, 130552.3472382632, 130740.3462982698, 131758.0, 1225.3873063468266, 1252.8308678328308, 1306.8366454193226, 1317.4269192900927, 1322.9801552976712, 21647.567048659253, 21744.0, 21834.454138646663, 21866.125354985623, 21889.67165492487, 62369.0, 64229.357706422335, 65260.0, 65744.34255657574, 66146.0, 82895.585522072, 83499.0, 84154.0, 84508.0, 84528.0, 14833.258337083318, 15042.473513426978, 15121.319540620478, 15124.621884452537, 15140.848591513944, 22996.885015575484, 23306.65040024367, 23500.647490287316, 23511.647325290476, 23700.170494032627, 17995.550111247325, 18380.448586542425, 19170.520736981685, 19259.0, 19267.22931082797, 16150.03099814015, 16974.74537881908, 17270.0, 17293.481195564153, 17869.73195402044, 19105.0, 19370.12834422426, 19847.900760496104, 19931.900340498203, 20278.79721202769, 14538.854611454173, 14750.483733069292, 14959.850401495845, 14986.700265994838, 15011.474598389004, 18961.525961851064, 19104.33134840274, 19177.232910683415, 19180.808191917902, 19216.903915480332], "value": 12593.337764212407, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1392.951246706365, 1450.5358285348686, 1465.1062851660486, 1472.4772705689486, 1475.7638777795555, 16883.915580422097, 17519.649607007854, 17536.12319384032, 17536.64926701465, 17545.82454175458, 17078.0, 17117.058561779097, 17129.657406851857, 17244.137793110356, 17290.740638890427, 2817.985910070452, 2896.623438952933, 2899.1302609217255, 2900.2894290898757, 2902.7097290271017, 2747.4505098980185, 2808.5365914624062, 2826.7879909006806, 2828.5615729561846, 2829.80191386603, 2742.3555464465835, 2850.5866649335835, 2852.9572056419065, 2853.400785834967, 2860.141957412778, 2683.5437975544205, 2694.434168824549, 2716.4431291585374, 2718.0894400375896, 2722.3466368071718, 2833.5183018886837, 2846.288427893027, 2846.644169478815, 2857.985710071457, 2861.113054952971, 16451.095189764557, 16534.09062501562, 16711.080890551017, 16757.41349052777, 16899.83100168995, 8262.091169971298, 8526.914730852735, 8528.147185281485, 8544.957275213646, 8545.871811922827, 5446.972765136188, 5544.833654990357, 5581.6930068846195, 5592.636478628917, 5648.6893220872835, 2053.1068984991575, 2063.2056658186707, 2067.555475572759, 2111.19774485696, 2111.883846388448, 29907.0, 30289.394212115854, 30343.848280758455, 30640.8467957661, 30649.38701225963, 7087.92912070883, 7118.608476533736, 7139.286071392819, 7262.564246145248, 7289.489735718449, 19976.3008294709, 19997.500062498264, 19999.200031998847, 20019.39941801748, 20027.399178024683, 8052.476589021695, 8318.251357377865, 8340.833183336299, 8344.207300306462, 8344.62449189788, 7583.886241706379, 7589.658465369072, 7619.123800762873, 7644.579548124795, 7704.383649308042, 5475.917861232085, 5631.802886898939, 5647.237622920935, 5656.208130861601, 5665.575081868877, 1446.8553144685657, 1472.9116253024854, 1567.3495499367862, 1627.21080276066, 1668.1825905306312, 973.8490533967247, 975.9756006099905, 982.4350998176077, 986.1568359052907, 1000.4047591682937, 8605.526696031653, 8631.438956467935, 8736.475811451206, 8765.824683506422, 8775.692850750193, 1039.3088596083671, 1046.4663021858908, 1065.4193464561833, 1068.930519516229, 1069.315637991686, 5148.639595228298, 5154.793808247703, 5253.290805741252, 5253.553447956967, 5263.894722105537, 23864.284071477883, 24554.508909821703, 25084.0, 25154.37114072126, 25250.24249272488, 24314.988261214, 24574.385640359134, 24749.752502474745, 24980.250592482254, 25083.62374564383, 12310.322932238634, 12321.0, 12497.375131243583, 12508.562200323127, 12529.74940501185, 9076.183143517115, 9086.681966131153, 9115.863262051207, 9140.908590914272, 9142.085791420805, 20344.898275508527, 20658.483537911674, 20756.0, 20889.26887558928, 21367.0, 21328.253511127037, 21729.348119556438, 21755.0, 21805.454863628536, 22356.552868942854, 11984.40077996114, 12111.48606424197, 12112.818307725567, 12119.45462454174, 12139.453724582592, 15249.6187595311, 15250.466233681767, 15421.074735515906, 15421.768673470131, 15437.0737755735, 12330.383480826102, 12369.567065152676, 12435.0, 12741.299228542148, 12793.0, 12134.271943683407, 12254.326012069421, 12274.202176858476, 12631.557895473614, 12800.6799830005, 11011.449427528752, 11025.669229923114, 11062.17033722458, 11242.0, 11249.268797528135, 8318.251357377865, 8418.284445821928, 8492.830143397221, 8512.319014478951, 8550.74347769568, 7970.561619111005, 7980.201979801974, 8142.4300298978505, 8176.713815016446, 8226.506409615442], "value": 7261.574306059375, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.5580979406682, 558.3467343208446, 569.2485918587465, 575.8214953364454, 581.1167026120297, 6242.906356404652, 6306.432421082101, 6309.305976342599, 6388.329225431326, 6399.328070552595, 6136.785212517563, 6222.782202622909, 6223.471004964572, 6236.750529978806, 6286.308506064329, 5072.771725272353, 5074.238864170386, 5077.086124497589, 5098.286239926413, 5124.410692770323, 4798.496157903405, 4933.4706241065205, 5027.622928280375, 5039.44566097729, 5061.367329083864, 4880.048390563841, 4907.601333619919, 4921.114199444099, 4943.826966056189, 4949.232868905326, 4822.841039119382, 4855.198892182803, 4926.802927882881, 4930.80276788928, 4950.702957822542, 4460.353248778942, 4475.351074094271, 4483.932741008887, 4488.618467430272, 4516.977415112936, 6145.84635384119, 6194.721237544276, 6195.645654651249, 6196.96901515494, 6238.407351301619, 5729.88540229193, 5743.109817978178, 5777.971110144465, 5796.3913789052385, 5818.7381567829125, 6495.1556297681, 6547.639879806608, 6567.835804104888, 6587.176602924635, 6591.6704164792045, 4774.522547745232, 5000.74996250186, 5003.949960500421, 5022.59819214462, 5026.648134630577, 6589.7808905352185, 6639.3692599203, 6787.287334829821, 6833.794986150423, 6924.792256232271, 5477.424870388551, 5581.190727344593, 5609.467100625434, 5623.722392431072, 5651.971740141273, 5685.346185188673, 5704.201411802351, 5710.086386178275, 5729.828105156852, 5778.711064446761, 6081.604695694766, 6088.178095957078, 6124.5100391968535, 6142.754289828446, 6145.692715364214, 6557.245916719543, 6590.934090659032, 6591.274959754519, 6595.208574971034, 6618.782707792943, 1924.0475964397713, 1965.4398496428448, 1971.7929617389968, 1977.9703304450445, 1981.2966396928882, 1328.6346254780012, 1374.6425929258464, 1437.9856201437851, 1447.536788227757, 1491.395984626228, 5900.350961394243, 6006.747469698863, 6009.549283803644, 6076.605020673731, 6079.908801367983, 6488.578242414228, 6575.210974682973, 6575.375339342755, 6641.634710090895, 6646.966765166143, 6034.818955431344, 6063.727132279058, 6064.4845188158615, 6082.36135205797, 6108.014895159003, 3414.5561077059824, 3506.4214404623463, 3518.876839310663, 3527.153483164022, 3564.2515071835205, 6660.93511011588, 6807.4894382921475, 6854.862902741917, 6897.2757860423935, 6959.512834101564, 6756.040748182972, 6808.0, 6819.693113809891, 6842.349976752201, 6880.724771009203, 6854.691538880663, 6883.380495755406, 6914.308569143045, 6945.930540694529, 6947.235804061549, 6417.903731444126, 6561.376669216417, 6562.5406221564035, 6576.638284894377, 6677.966610166918, 6516.348365163446, 6516.6415847128865, 6529.269743517876, 6656.800295991128, 6688.899666504909, 6859.279775623488, 6861.701330054288, 6874.175098987954, 6926.0, 6957.269486704025, 5105.412877518985, 5144.074066668036, 5160.845174644766, 5161.793528258828, 5176.741162941913, 6775.356341147583, 6796.626185559644, 6814.057993471837, 6821.658917054227, 6826.590404575742, 6424.550281480251, 6492.5455218134275, 6509.698060388036, 6519.706613202512, 6522.5760325578685, 6365.172527571386, 6429.9678501607195, 6448.806535803933, 6486.578372405778, 6532.0, 6352.205029786879, 6405.295417504164, 6437.227532695919, 6461.289258181502, 6468.482521398371, 6498.805035848932, 6512.395041983055, 6542.149520562297, 6581.80254592363, 6582.341765823379, 6349.460295874902, 6393.1688880445245, 6416.615003099829, 6430.196225471816, 6471.191101112361], "value": 5188.458502220519, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [949.4777872170305, 962.4177372689522, 974.0405700385123, 986.3145114145667, 1002.3334482569089, 19163.233470661173, 19674.409767706955, 19720.0, 19736.21055157792, 19754.111065002075, 18927.242910283607, 19198.52003699905, 19471.0, 19768.802311976877, 19788.802111978875, 1049.7218237167158, 1066.6799960012004, 1066.8239740442857, 1067.3062509368895, 1069.4278560969883, 1052.3159946035062, 1071.6356438810803, 1076.0261962923528, 1078.1805827571025, 1083.9241253112286, 1151.3897634253858, 1162.1342100135369, 1165.341582006168, 1167.1246565076192, 1170.7541416302586, 1092.1863211907141, 1127.261643623428, 1135.018209249002, 1136.2898188632105, 1163.6160067177811, 832.134580036761, 832.2634468495364, 833.1085738260064, 833.2334252487726, 833.3541505333354, 20613.690794638092, 20763.792362076336, 21040.0, 21044.5791084179, 21044.684329735068, 22285.108595656155, 22666.66000010001, 22815.31554053381, 22821.88589057044, 22826.65760013601, 17745.911270443696, 18053.458396248134, 18166.63666726658, 18214.0, 18286.45140645783, 3813.7139714521522, 3997.5802540733384, 4024.1549274652357, 4029.3553031514816, 4038.0308725905866, 151524.0, 154291.0, 154695.0, 156770.0, 156947.0, 17453.476395707894, 17454.563635909202, 17601.295948162187, 17611.207495662726, 17652.0, 31669.366612667618, 33111.33777324488, 33187.170320741694, 33361.49957750636, 33515.0, 8746.737597872074, 8781.604827782765, 8812.07473215303, 8821.029686734453, 8823.205911467998, 4500.594946454769, 4585.679002469862, 4601.033782905561, 4603.401557797465, 4610.654200934943, 5943.910841337383, 6087.421694938974, 6123.663198524035, 6153.2000839891425, 6157.599756015845, 1072.3190773858655, 1103.3269705479652, 1106.9280496767844, 1108.9223754337281, 1121.428071683431, 739.9408047356306, 744.4788647946459, 748.1807420874134, 759.3924860111888, 770.0912922751207, 12769.808452873214, 13168.0, 13436.328183590977, 13890.791638125436, 14153.646158845906, 725.4921554911583, 729.657061181244, 730.4740586777511, 730.7369347035063, 744.2892038103598, 5880.647161170344, 5894.823155305347, 6026.156338112668, 6093.878122437526, 6158.199434073631, 143216.28391857975, 144314.0, 146038.0, 147356.0, 148334.25832870984, 130912.3454382741, 133596.0, 134296.32851835873, 135391.0, 136942.0, 116724.41637791757, 116815.0, 117368.0, 118159.0, 118403.40798295953, 11188.66434006981, 11269.211155219056, 11408.771824563297, 11568.016718579014, 11652.242604230698, 52913.20630190627, 54552.0, 54621.453785461636, 55534.16698749441, 56386.0, 69387.0, 69616.7344938958, 70048.64975675089, 70785.0, 70830.0, 15688.0, 15944.681106377575, 16329.101899395648, 16485.670286593962, 16492.505224843273, 18836.905815471382, 19062.428127156207, 19133.617327653646, 19229.615407692047, 19276.22895084181, 15959.122248276457, 15984.120873352078, 16113.194340282704, 16134.838651613803, 16227.432039878548, 17507.649847002733, 17766.46700598984, 17783.0, 17890.28438862431, 17895.73156402681, 15528.689426211638, 15579.844201558293, 15724.0, 15933.840661593236, 15953.282102305191, 11959.461824217757, 11981.76036479248, 12134.878651213729, 12174.634760957186, 12231.877681223074, 10740.140788736726, 10756.569737210424, 10773.353598784099, 10896.237263391804, 11073.72315692082], "value": 9687.639541554627, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1383.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 106.0, 104.0, 106.0, 107.0, 96.0, 106.0, 106.0, 107.0, 105.0], "value": 104.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 116.0, 112.0, 117.0, 114.0, 113.0, 115.0, 154.0, 115.0], "value": 118.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 164.0, 163.0, 163.0, 164.0, 165.0, 172.0, 162.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 73.0, 74.0, 73.0, 73.0, 76.0, 74.0, 74.0, 73.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 77.0, 78.0, 77.0, 78.0, 77.0, 78.0, 79.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 113.0, 115.0, 114.0, 115.0, 115.0, 113.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 151.0, 152.0, 151.0, 149.0, 152.0, 153.0, 151.0, 150.0, 150.0], "value": 151.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 96.0, 95.0, 94.0, 94.0, 94.0, 96.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 44.0, 45.0, 46.0, 45.0, 45.0, 47.0, 46.0, 59.0], "value": 47.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 36.0], "value": 37.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 75.0, 70.0, 74.0, 73.0, 72.0, 71.0, 74.0, 76.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 104.0, 106.0, 109.0, 103.0, 112.0, 112.0, 107.0, 104.0], "value": 107.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 156.0, 157.0, 157.0, 161.0, 158.0, 153.0, 163.0, 159.0, 153.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 61.0, 61.0, 61.0, 61.0, 66.0, 60.0, 59.0, 60.0], "value": 61.5, "unit": "score"}]}]} 16:35:24 INFO - Return code: 0 16:35:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:35:24 INFO - # TBPL SUCCESS # 16:35:24 INFO - Running post-action listener: _package_coverage_data 16:35:24 INFO - Running post-action listener: _resource_record_post_action 16:35:24 INFO - [mozharness: 2017-09-13 23:35:24.204489Z] Finished run-tests step (success) 16:35:24 INFO - Running post-run listener: _resource_record_post_run 16:35:24 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 45056; Write bytes: 331386880; Read time: 68; Write time: 284540 16:35:24 INFO - TinderboxPrint: CPU usage
13.1% 16:35:24 INFO - TinderboxPrint: I/O read bytes / time
45,056 / 68 16:35:24 INFO - TinderboxPrint: I/O write bytes / time
331,386,880 / 284,540 16:35:24 INFO - TinderboxPrint: CPU idle
8,220.0 (86.9%) 16:35:24 INFO - TinderboxPrint: CPU user
1,187.8 (12.6%) 16:35:24 INFO - TinderboxPrint: Swap in / out
0 / 0 16:35:24 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13258752; Read time: 0; Write time: 1164 16:35:24 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:35:24 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 45056; Write bytes: 318128128; Read time: 68; Write time: 283376 16:35:24 INFO - Running post-run listener: _upload_blobber_files 16:35:24 INFO - Blob upload gear active. 16:35:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:35:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:35:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:35:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:35:25 INFO - (blobuploader) - INFO - Open directory for files ... 16:35:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:35:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:35:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:26 INFO - (blobuploader) - INFO - Done attempting. 16:35:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:35:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:35:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:31 INFO - (blobuploader) - INFO - Done attempting. 16:35:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:35:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:35:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:32 INFO - (blobuploader) - INFO - Done attempting. 16:35:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:35:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:35:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:33 INFO - (blobuploader) - INFO - Done attempting. 16:35:33 INFO - (blobuploader) - INFO - Iteration through files over. 16:35:33 INFO - Return code: 0 16:35:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:35:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:35:33 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bd52a92682033b80476318421122052ac48464843389ec6bb7b68dde2a9b0075dcb9822d33dd8fcd8c8680679d42484fc4cd903888b418410e848e1e484352f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4f6fc027f5ab54847233c1b2af9cbe4c66aedacb8171e3550acca6b7008401c411a044bb76cbc17c5250d75ade1aeaf28e6aaa30eb0b44262798091158a1748", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fca550cb5952808549384acf4152f92e4b311d0636d592d6e5993ed7926d708cf7a1ecc6150755fd583c10293a87b1ccdf3bfe1604179ed454dc59ad5e4c0869", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e4ae0335d45a0414817748d323e883c5b8387cb822f468f2710b1d7ed3f48edd788b75ffa62cc3b7aeb29aeaef5203d2858b4bc4bbc1bc4b2a0c8ec27164e37"} 16:35:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:35:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:35:33 INFO - Contents: 16:35:33 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bd52a92682033b80476318421122052ac48464843389ec6bb7b68dde2a9b0075dcb9822d33dd8fcd8c8680679d42484fc4cd903888b418410e848e1e484352f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4f6fc027f5ab54847233c1b2af9cbe4c66aedacb8171e3550acca6b7008401c411a044bb76cbc17c5250d75ade1aeaf28e6aaa30eb0b44262798091158a1748", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fca550cb5952808549384acf4152f92e4b311d0636d592d6e5993ed7926d708cf7a1ecc6150755fd583c10293a87b1ccdf3bfe1604179ed454dc59ad5e4c0869", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e4ae0335d45a0414817748d323e883c5b8387cb822f468f2710b1d7ed3f48edd788b75ffa62cc3b7aeb29aeaef5203d2858b4bc4bbc1bc4b2a0c8ec27164e37"} 16:35:33 INFO - Running post-run listener: copy_logs_to_upload_dir 16:35:33 INFO - Copying logs to upload dir... 16:35:33 INFO - mkdir: /builds/slave/test/build/upload/logs 16:35:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.079919 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-13 16:35:33.296193) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 16:35:33.299517) ========= 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=52faae308a556c440401f8e5000001e8-1505344492.352962-411523862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bd52a92682033b80476318421122052ac48464843389ec6bb7b68dde2a9b0075dcb9822d33dd8fcd8c8680679d42484fc4cd903888b418410e848e1e484352f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4f6fc027f5ab54847233c1b2af9cbe4c66aedacb8171e3550acca6b7008401c411a044bb76cbc17c5250d75ade1aeaf28e6aaa30eb0b44262798091158a1748", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fca550cb5952808549384acf4152f92e4b311d0636d592d6e5993ed7926d708cf7a1ecc6150755fd583c10293a87b1ccdf3bfe1604179ed454dc59ad5e4c0869", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e4ae0335d45a0414817748d323e883c5b8387cb822f468f2710b1d7ed3f48edd788b75ffa62cc3b7aeb29aeaef5203d2858b4bc4bbc1bc4b2a0c8ec27164e37"} build_url:https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011293 build_url: 'https://queue.taskcluster.net/v1/task/EE2T5AdfROa4pbnRYPTKTA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bd52a92682033b80476318421122052ac48464843389ec6bb7b68dde2a9b0075dcb9822d33dd8fcd8c8680679d42484fc4cd903888b418410e848e1e484352f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4f6fc027f5ab54847233c1b2af9cbe4c66aedacb8171e3550acca6b7008401c411a044bb76cbc17c5250d75ade1aeaf28e6aaa30eb0b44262798091158a1748", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fca550cb5952808549384acf4152f92e4b311d0636d592d6e5993ed7926d708cf7a1ecc6150755fd583c10293a87b1ccdf3bfe1604179ed454dc59ad5e4c0869", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e4ae0335d45a0414817748d323e883c5b8387cb822f468f2710b1d7ed3f48edd788b75ffa62cc3b7aeb29aeaef5203d2858b4bc4bbc1bc4b2a0c8ec27164e37"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 16:35:33.327479) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:35:33.327788) ========= 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=52faae308a556c440401f8e5000001e8-1505344492.352962-411523862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004442 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:35:33.410073) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 16:35:33.410393) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 16:35:33.410702) ========= ========= Total master_lag: 0.18 =========