builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-075 starttime: 1506555905.14 results: success (0) revision: 69e3f89816455e567f1a20b694fd6afd549c82c7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 16:45:05.140097) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 16:45:05.140569) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 16:45:05.140844) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 16:45:05.155664) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 16:45:05.156040) ========= 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=fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-27 16:45:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 54.7M=0s 2017-09-27 16:45:05 (54.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117271 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 16:45:05.399407) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 16:45:05.399765) ========= 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=fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014894 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 16:45:05.434172) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 16:45:05.434510) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 69e3f89816455e567f1a20b694fd6afd549c82c7 --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 69e3f89816455e567f1a20b694fd6afd549c82c7 --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=fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-27 16:45:05,474 truncating revision to first 12 chars 2017-09-27 16:45:05,474 Setting DEBUG logging. 2017-09-27 16:45:05,474 attempt 1/10 2017-09-27 16:45:05,474 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/69e3f8981645?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-27 16:45:06,325 unpacking tar archive at: mozilla-central-69e3f8981645/testing/mozharness/ program finished with exit code 0 elapsedTime=1.117179 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 16:45:06.570424) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 16:45:06.574955) ========= script_repo_revision: 69e3f89816455e567f1a20b694fd6afd549c82c7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 16:45:06.575460) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 16:45:06.575725) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 16:45:06.593823) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-27 16:45:06.594228) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:45:06 INFO - MultiFileLogger online at 20170927 16:45:06 in /builds/slave/test 16:45:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:45:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:45:06 INFO - {'append_to_log': False, 16:45:06 INFO - 'base_work_dir': '/builds/slave/test', 16:45:06 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:45:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:45:06 INFO - 'branch': 'Firefox-Non-PGO', 16:45:06 INFO - 'buildbot_json_path': 'buildprops.json', 16:45:06 INFO - 'code_coverage': False, 16:45:06 INFO - 'config_files': ('talos/linux_config.py',), 16:45:06 INFO - 'default_actions': ('clobber', 16:45:06 INFO - 'read-buildbot-config', 16:45:06 INFO - 'download-and-extract', 16:45:06 INFO - 'populate-webroot', 16:45:06 INFO - 'create-virtualenv', 16:45:06 INFO - 'install', 16:45:06 INFO - 'setup-mitmproxy', 16:45:06 INFO - 'run-tests'), 16:45:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:45:06 INFO - 'disable_ccov_upload': False, 16:45:06 INFO - 'disable_stylo': False, 16:45:06 INFO - 'download_minidump_stackwalk': True, 16:45:06 INFO - 'download_symbols': 'ondemand', 16:45:06 INFO - 'enable_stylo': False, 16:45:06 INFO - 'enable_webrender': False, 16:45:06 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:45:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:45:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:45:06 INFO - 'gecko_profile': False, 16:45:06 INFO - 'gecko_profile_interval': 0, 16:45:06 INFO - 'installer_path': 'installer.exe', 16:45:06 INFO - 'log_level': 'info', 16:45:06 INFO - 'log_name': 'talos', 16:45:06 INFO - 'log_to_console': True, 16:45:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:45:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:45:06 INFO - 'opt_config_files': (), 16:45:06 INFO - 'pip_index': False, 16:45:06 INFO - 'suite': 'dromaeojs-e10s', 16:45:06 INFO - 'system_bits': '32', 16:45:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:45:06 INFO - 'title': 'talos-linux64-ix-075', 16:45:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:45:06 INFO - 'use_talos_json': True, 16:45:06 INFO - 'verify': 'False', 16:45:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:45:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:45:06 INFO - 'work_dir': 'build'} 16:45:06 INFO - [mozharness: 2017-09-27 23:45:06.783315Z] Running clobber step. 16:45:06 INFO - Running pre-action listener: _resource_record_pre_action 16:45:06 INFO - Running main action method: clobber 16:45:06 INFO - rmtree: /builds/slave/test/build 16:45:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:45:07 INFO - Running post-action listener: _resource_record_post_action 16:45:07 INFO - [mozharness: 2017-09-27 23:45:07.778268Z] Finished clobber step (success) 16:45:07 INFO - [mozharness: 2017-09-27 23:45:07.778366Z] Running read-buildbot-config step. 16:45:07 INFO - Running pre-action listener: _resource_record_pre_action 16:45:07 INFO - Running main action method: read_buildbot_config 16:45:07 INFO - Using buildbot properties: 16:45:07 INFO - { 16:45:07 INFO - "project": "", 16:45:07 INFO - "product": "firefox", 16:45:07 INFO - "who": "archaeopteryx@coole-files.de", 16:45:07 INFO - "installer_path": "public/build/target.tar.bz2", 16:45:07 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:45:07 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:45:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 16:45:07 INFO - "stage_platform": "linux64", 16:45:07 INFO - "basedir": "/builds/slave/test", 16:45:07 INFO - "buildnumber": 874, 16:45:07 INFO - "platform": "ubuntu64_hw", 16:45:07 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:45:07 INFO - "slavebuilddir": "test", 16:45:07 INFO - "taskId": "WBo0TXQURVarQQer67b_sg", 16:45:07 INFO - "branch": "mozilla-central", 16:45:07 INFO - "script_repo_revision": "production", 16:45:07 INFO - "revision": "69e3f89816455e567f1a20b694fd6afd549c82c7", 16:45:07 INFO - "slavename": "talos-linux64-ix-075", 16:45:07 INFO - "repo_path": "mozilla-central" 16:45:07 INFO - } 16:45:07 INFO - Finding installer, test and symbols from parent task. 16:45:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WBo0TXQURVarQQer67b_sg'}, attempt #1 16:45:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WBo0TXQURVarQQer67b_sg'}, attempt #1 16:45:08 INFO - Task dependencies: dp8k4SKiS8qEBmLBTOvhFQ 16:45:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ'}, attempt #1 16:45:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.tar.bz2 16:45:09 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.test_packages.json 16:45:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.crashreporter-symbols.zip 16:45:09 INFO - Running post-action listener: _resource_record_post_action 16:45:09 INFO - [mozharness: 2017-09-27 23:45:09.167923Z] Finished read-buildbot-config step (success) 16:45:09 INFO - [mozharness: 2017-09-27 23:45:09.168140Z] Running download-and-extract step. 16:45:09 INFO - Running pre-action listener: _resource_record_pre_action 16:45:09 INFO - Running main action method: download_and_extract 16:45:09 INFO - mkdir: /builds/slave/test/build/tests 16:45:09 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:45:09 INFO - trying https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.test_packages.json 16:45:09 INFO - Downloading https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:45:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:45:09 INFO - Downloaded 989 bytes. 16:45:09 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:45:09 INFO - Using the following test package requirements: 16:45:09 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:45:09 INFO - u'common': [u'target.common.tests.zip'], 16:45:09 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:45:09 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:45:09 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:45:09 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:45:09 INFO - u'mozbase': [u'target.common.tests.zip'], 16:45:09 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:45:09 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:45:09 INFO - u'web-platform': [u'target.common.tests.zip', 16:45:09 INFO - u'target.web-platform.tests.tar.gz'], 16:45:09 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:45:09 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 16:45:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.common.tests.zip 16:45:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:45:09 INFO - Fetch https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.common.tests.zip into memory 16:45:10 INFO - Content-Length response header: 54945885 16:45:10 INFO - Bytes received: 54945885 16:45:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.talos.tests.zip 16:45:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:45:21 INFO - Fetch https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.talos.tests.zip into memory 16:45:22 INFO - Content-Length response header: 14513779 16:45:22 INFO - Bytes received: 14513779 16:45:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:22 INFO - trying https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.tar.bz2 16:45:22 INFO - Downloading https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:45:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:45:23 INFO - Downloaded 61765788 bytes. 16:45:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.tar.bz2 16:45:23 INFO - mkdir: /builds/slave/test/properties 16:45:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:45:23 INFO - Writing to file /builds/slave/test/properties/build_url 16:45:23 INFO - Contents: 16:45:23 INFO - build_url:https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.tar.bz2 16:45:23 INFO - Running post-action listener: _resource_record_post_action 16:45:23 INFO - Running post-action listener: find_tests_for_verification 16:45:23 INFO - Running post-action listener: set_extra_try_arguments 16:45:23 INFO - [mozharness: 2017-09-27 23:45:23.915146Z] Finished download-and-extract step (success) 16:45:23 INFO - [mozharness: 2017-09-27 23:45:23.915244Z] Running populate-webroot step. 16:45:23 INFO - Running pre-action listener: _resource_record_pre_action 16:45:23 INFO - Running main action method: populate_webroot 16:45:23 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:45:23 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:45:23 INFO - 'tests': ['tresize', 16:45:23 INFO - 'tcanvasmark']}, 16:45:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:45:23 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:45:23 INFO - 'tests': ['dromaeo_css', 16:45:23 INFO - 'kraken']}, 16:45:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:45:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:45:23 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:45:23 INFO - 'talos_options': ['--disable-stylo'], 16:45:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:45:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:45:23 INFO - 'tests': ['damp', 'tps']}, 16:45:23 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:45:23 INFO - 'talos_options': ['--disable-stylo'], 16:45:23 INFO - 'tests': ['damp', 'tps']}, 16:45:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:45:23 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:45:23 INFO - 'tests': ['dromaeo_dom']}, 16:45:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:45:23 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:45:23 INFO - 'tests': ['basic_compositor_video', 16:45:23 INFO - 'glvideo']}, 16:45:23 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:45:23 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:45:23 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:45:23 INFO - 'talos_options': ['--disable-stylo'], 16:45:23 INFO - 'tests': ['ts_paint_webext', 16:45:23 INFO - 'tp5o_webext']}, 16:45:23 INFO - 'other-e10s': {'tests': ['a11yr', 16:45:23 INFO - 'ts_paint', 16:45:23 INFO - 'tpaint', 16:45:23 INFO - 'sessionrestore', 16:45:23 INFO - 'sessionrestore_many_windows', 16:45:23 INFO - 'sessionrestore_no_auto_restore', 16:45:23 INFO - 'tabpaint']}, 16:45:23 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:45:23 INFO - 'tests': ['a11yr', 16:45:23 INFO - 'ts_paint', 16:45:23 INFO - 'tpaint', 16:45:23 INFO - 'sessionrestore', 16:45:23 INFO - 'sessionrestore_many_windows', 16:45:23 INFO - 'sessionrestore_no_auto_restore', 16:45:23 INFO - 'tabpaint']}, 16:45:23 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 16:45:23 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 16:45:23 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:45:23 INFO - 'tests': ['perf_reftest_singletons']}, 16:45:23 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:45:23 INFO - 'tests': ['perf_reftest']}, 16:45:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:45:23 INFO - 'tsvgr_opacity', 16:45:23 INFO - 'tart', 16:45:23 INFO - 'tscrollx', 16:45:23 INFO - 'tsvg_static']}, 16:45:23 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:45:23 INFO - 'tests': ['tsvgx', 16:45:23 INFO - 'tsvgr_opacity', 16:45:23 INFO - 'tart', 16:45:23 INFO - 'tscrollx', 16:45:23 INFO - 'tsvg_static']}, 16:45:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:45:23 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:45:23 INFO - 'talos_options': ['--disable-stylo'], 16:45:23 INFO - 'tests': ['tp5o']}, 16:45:23 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:45:23 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:45:23 INFO - 'talos_options': ['--mitmproxy', 16:45:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:45:23 INFO - '--firstNonBlankPaint'], 16:45:23 INFO - 'tests': ['tp6_google', 16:45:23 INFO - 'tp6_youtube', 16:45:23 INFO - 'tp6_amazon', 16:45:23 INFO - 'tp6_facebook']}, 16:45:23 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:45:23 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:45:23 INFO - 'talos_options': ['--disable-stylo', 16:45:23 INFO - '--mitmproxy', 16:45:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:45:23 INFO - '--firstNonBlankPaint'], 16:45:23 INFO - 'tests': ['tp6_google', 16:45:23 INFO - 'tp6_youtube', 16:45:23 INFO - 'tp6_amazon', 16:45:23 INFO - 'tp6_facebook']}, 16:45:23 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:45:23 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:45:23 INFO - 'talos_options': ['--stylo-threads=1', 16:45:23 INFO - '--mitmproxy', 16:45:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:45:23 INFO - '--firstNonBlankPaint'], 16:45:23 INFO - 'tests': ['tp6_google', 16:45:23 INFO - 'tp6_youtube', 16:45:23 INFO - 'tp6_amazon', 16:45:23 INFO - 'tp6_facebook']}, 16:45:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:45:23 INFO - 'talos_options': ['--xperf_path', 16:45:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:45:23 INFO - 'tests': ['tp5n']}, 16:45:23 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:45:23 INFO - 'talos_options': ['--disable-stylo', 16:45:23 INFO - '--xperf_path', 16:45:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:45:23 INFO - 'tests': ['tp5n']}}} 16:45:23 INFO - Running post-action listener: _resource_record_post_action 16:45:23 INFO - [mozharness: 2017-09-27 23:45:23.930728Z] Finished populate-webroot step (success) 16:45:23 INFO - [mozharness: 2017-09-27 23:45:23.930818Z] Running create-virtualenv step. 16:45:23 INFO - Running pre-action listener: _resource_record_pre_action 16:45:23 INFO - Running main action method: create_virtualenv 16:45:23 INFO - Creating virtualenv /builds/slave/test/build/venv 16:45:23 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:45:23 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:45:23 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:45:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:45:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:45:24 INFO - Installing distribute.............................................................................................................................................................................................done. 16:45:24 INFO - Installing pip...............done. 16:45:24 INFO - Return code: 0 16:45:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:45:24 INFO - Reading from file tmpfile_stdout 16:45:24 INFO - Output received: 16:45:24 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:45:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:45: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:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45: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:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x228cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ed60>, '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 0x242fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242fef0>, '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': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', '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:45: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', 'pip>=1.5'] in /builds/slave/test/build 16:45: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 pip>=1.5 16:45:24 INFO - Using env: {'DISPLAY': ':0', 16:45:24 INFO - 'HOME': '/home/cltbld', 16:45:24 INFO - 'LANG': 'en_US.UTF-8', 16:45:24 INFO - 'LANGUAGE': 'en_US:en', 16:45:24 INFO - 'LOGNAME': 'cltbld', 16:45:24 INFO - 'MAIL': '/var/mail/cltbld', 16:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:24 INFO - 'MOZ_NO_REMOTE': '1', 16:45:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:45:24 INFO - 'NO_EM_RESTART': '1', 16:45:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:24 INFO - 'PWD': '/builds/slave/test', 16:45:24 INFO - 'SHELL': '/bin/bash', 16:45:24 INFO - 'SHLVL': '1', 16:45:24 INFO - 'TERM': 'linux', 16:45:24 INFO - 'TMOUT': '86400', 16:45:24 INFO - 'USER': 'cltbld', 16:45:24 INFO - 'XDG_SESSION_COOKIE': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', 16:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:24 INFO - '_': '/tools/buildbot/bin/python'} 16:45:26 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:45:26 INFO - Downloading/unpacking pip>=1.5 16:45:26 INFO - Running setup.py egg_info for package pip 16:45:26 INFO - warning: no files found matching 'pip/cacert.pem' 16:45:26 INFO - warning: no files found matching '*.html' under directory 'docs' 16:45:26 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:45:26 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:45:26 INFO - Installing collected packages: pip 16:45:26 INFO - Found existing installation: pip 1.1 16:45:26 INFO - Uninstalling pip: 16:45:26 INFO - Successfully uninstalled pip 16:45:26 INFO - Running setup.py install for pip 16:45:26 INFO - warning: no files found matching 'pip/cacert.pem' 16:45:26 INFO - warning: no files found matching '*.html' under directory 'docs' 16:45:26 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:45:26 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:45:26 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:45:26 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:45:26 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:45:26 INFO - Successfully installed pip 16:45:26 INFO - Cleaning up... 16:45:26 INFO - Return code: 0 16:45:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:45: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:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45: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:45:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:26 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 0x20c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x228cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ed60>, '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 0x242fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242fef0>, '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': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', '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:45:26 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:45:26 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:45:26 INFO - Using env: {'DISPLAY': ':0', 16:45:26 INFO - 'HOME': '/home/cltbld', 16:45:26 INFO - 'LANG': 'en_US.UTF-8', 16:45:26 INFO - 'LANGUAGE': 'en_US:en', 16:45:26 INFO - 'LOGNAME': 'cltbld', 16:45:26 INFO - 'MAIL': '/var/mail/cltbld', 16:45:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:26 INFO - 'MOZ_NO_REMOTE': '1', 16:45:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:45:26 INFO - 'NO_EM_RESTART': '1', 16:45:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:45:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:26 INFO - 'PWD': '/builds/slave/test', 16:45:26 INFO - 'SHELL': '/bin/bash', 16:45:26 INFO - 'SHLVL': '1', 16:45:26 INFO - 'TERM': 'linux', 16:45:26 INFO - 'TMOUT': '86400', 16:45:26 INFO - 'USER': 'cltbld', 16:45:26 INFO - 'XDG_SESSION_COOKIE': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', 16:45:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:26 INFO - '_': '/tools/buildbot/bin/python'} 16:45:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:26 INFO - Downloading/unpacking psutil>=3.1.1 16:45:26 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:45:26 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:45:26 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:45:26 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:45:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:45:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:45:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:45:27 INFO - Installing collected packages: psutil 16:45:27 INFO - Running setup.py install for psutil 16:45:27 INFO - building 'psutil._psutil_linux' extension 16:45:27 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:45:27 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:45:27 INFO - building 'psutil._psutil_posix' extension 16:45:27 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:45:27 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:45:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:45:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:45:28 INFO - Successfully installed psutil 16:45:28 INFO - Cleaning up... 16:45:28 INFO - Return code: 0 16:45:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:28 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 0x20c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x228cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ed60>, '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 0x242fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242fef0>, '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': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', '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:45:28 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:45:28 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:45:28 INFO - Using env: {'DISPLAY': ':0', 16:45:28 INFO - 'HOME': '/home/cltbld', 16:45:28 INFO - 'LANG': 'en_US.UTF-8', 16:45:28 INFO - 'LANGUAGE': 'en_US:en', 16:45:28 INFO - 'LOGNAME': 'cltbld', 16:45:28 INFO - 'MAIL': '/var/mail/cltbld', 16:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:28 INFO - 'MOZ_NO_REMOTE': '1', 16:45:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:45:28 INFO - 'NO_EM_RESTART': '1', 16:45:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:28 INFO - 'PWD': '/builds/slave/test', 16:45:28 INFO - 'SHELL': '/bin/bash', 16:45:28 INFO - 'SHLVL': '1', 16:45:28 INFO - 'TERM': 'linux', 16:45:28 INFO - 'TMOUT': '86400', 16:45:28 INFO - 'USER': 'cltbld', 16:45:28 INFO - 'XDG_SESSION_COOKIE': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', 16:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:28 INFO - '_': '/tools/buildbot/bin/python'} 16:45:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:45:28 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:45:28 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:45:28 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:45:28 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:45:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:45:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:45:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:45:29 INFO - Installing collected packages: mozsystemmonitor 16:45:29 INFO - Running setup.py install for mozsystemmonitor 16:45:29 INFO - Successfully installed mozsystemmonitor 16:45:29 INFO - Cleaning up... 16:45:29 INFO - Return code: 0 16:45:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:45: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:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45: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:45:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:29 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 0x20c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x228cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ed60>, '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 0x242fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242fef0>, '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': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', '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:45:29 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:45:29 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:45:29 INFO - Using env: {'DISPLAY': ':0', 16:45:29 INFO - 'HOME': '/home/cltbld', 16:45:30 INFO - 'LANG': 'en_US.UTF-8', 16:45:30 INFO - 'LANGUAGE': 'en_US:en', 16:45:30 INFO - 'LOGNAME': 'cltbld', 16:45:30 INFO - 'MAIL': '/var/mail/cltbld', 16:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:30 INFO - 'MOZ_NO_REMOTE': '1', 16:45:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:45:30 INFO - 'NO_EM_RESTART': '1', 16:45:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:45:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:30 INFO - 'PWD': '/builds/slave/test', 16:45:30 INFO - 'SHELL': '/bin/bash', 16:45:30 INFO - 'SHLVL': '1', 16:45:30 INFO - 'TERM': 'linux', 16:45:30 INFO - 'TMOUT': '86400', 16:45:30 INFO - 'USER': 'cltbld', 16:45:30 INFO - 'XDG_SESSION_COOKIE': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', 16:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:30 INFO - '_': '/tools/buildbot/bin/python'} 16:45:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:30 INFO - Downloading/unpacking jsonschema==2.5.1 16:45:30 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:45:30 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:45:30 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:45:30 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:45:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:45:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:45: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:45: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:45: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:45: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:45:31 INFO - Downloading functools32-3.2.3-2.tar.gz 16:45:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:45:32 INFO - warning: no files found matching '*.txt' 16:45:32 INFO - no previously-included directories found matching 'build' 16:45:32 INFO - no previously-included directories found matching 'dist' 16:45:32 INFO - no previously-included directories found matching '.git*' 16:45:32 INFO - Installing collected packages: jsonschema, functools32 16:45:32 INFO - Running setup.py install for functools32 16:45:32 INFO - warning: no files found matching '*.txt' 16:45:32 INFO - no previously-included directories found matching 'build' 16:45:32 INFO - no previously-included directories found matching 'dist' 16:45:32 INFO - no previously-included directories found matching '.git*' 16:45:32 INFO - Successfully installed jsonschema functools32 16:45:32 INFO - Cleaning up... 16:45:32 INFO - Return code: 0 16:45:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:45: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:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45: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:45:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:32 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 0x20c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x228cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ed60>, '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 0x242fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242fef0>, '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': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', '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:45:32 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:45:32 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:45:32 INFO - Using env: {'DISPLAY': ':0', 16:45:32 INFO - 'HOME': '/home/cltbld', 16:45:32 INFO - 'LANG': 'en_US.UTF-8', 16:45:32 INFO - 'LANGUAGE': 'en_US:en', 16:45:32 INFO - 'LOGNAME': 'cltbld', 16:45:32 INFO - 'MAIL': '/var/mail/cltbld', 16:45:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:32 INFO - 'MOZ_NO_REMOTE': '1', 16:45:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:45:32 INFO - 'NO_EM_RESTART': '1', 16:45:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:45:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:32 INFO - 'PWD': '/builds/slave/test', 16:45:32 INFO - 'SHELL': '/bin/bash', 16:45:32 INFO - 'SHLVL': '1', 16:45:32 INFO - 'TERM': 'linux', 16:45:32 INFO - 'TMOUT': '86400', 16:45:32 INFO - 'USER': 'cltbld', 16:45:32 INFO - 'XDG_SESSION_COOKIE': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', 16:45:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:32 INFO - '_': '/tools/buildbot/bin/python'} 16:45:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:45:32 INFO - Cleaning up... 16:45:32 INFO - Return code: 0 16:45:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:45: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:45:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45: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:45:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:32 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 0x20c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x228cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ed60>, '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 0x242fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242fef0>, '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': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', '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:45:32 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:45:32 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:45:32 INFO - Using env: {'DISPLAY': ':0', 16:45:32 INFO - 'HOME': '/home/cltbld', 16:45:32 INFO - 'LANG': 'en_US.UTF-8', 16:45:32 INFO - 'LANGUAGE': 'en_US:en', 16:45:32 INFO - 'LOGNAME': 'cltbld', 16:45:32 INFO - 'MAIL': '/var/mail/cltbld', 16:45:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:32 INFO - 'MOZ_NO_REMOTE': '1', 16:45:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:45:32 INFO - 'NO_EM_RESTART': '1', 16:45:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:45:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:32 INFO - 'PWD': '/builds/slave/test', 16:45:32 INFO - 'SHELL': '/bin/bash', 16:45:32 INFO - 'SHLVL': '1', 16:45:32 INFO - 'TERM': 'linux', 16:45:32 INFO - 'TMOUT': '86400', 16:45:32 INFO - 'USER': 'cltbld', 16:45:32 INFO - 'XDG_SESSION_COOKIE': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', 16:45:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:32 INFO - '_': '/tools/buildbot/bin/python'} 16:45:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:33 INFO - Downloading/unpacking blobuploader==1.2.4 16:45: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:45: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:45: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:45: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:45:34 INFO - Downloading blobuploader-1.2.4.tar.gz 16:45:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:45:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:45:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:34 INFO - Downloading docopt-0.6.1.tar.gz 16:45:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:45:34 INFO - Installing collected packages: blobuploader, requests, docopt 16:45:34 INFO - Running setup.py install for blobuploader 16:45:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:45:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:45:34 INFO - Running setup.py install for requests 16:45:35 INFO - Running setup.py install for docopt 16:45:35 INFO - Successfully installed blobuploader requests docopt 16:45:35 INFO - Cleaning up... 16:45:35 INFO - Return code: 0 16:45:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:35 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:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:35 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:45:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:35 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 0x20c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x228cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ed60>, '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 0x242fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242fef0>, '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': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', '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:45:35 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:45:35 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:45:35 INFO - Using env: {'DISPLAY': ':0', 16:45:35 INFO - 'HOME': '/home/cltbld', 16:45:35 INFO - 'LANG': 'en_US.UTF-8', 16:45:35 INFO - 'LANGUAGE': 'en_US:en', 16:45:35 INFO - 'LOGNAME': 'cltbld', 16:45:35 INFO - 'MAIL': '/var/mail/cltbld', 16:45:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:35 INFO - 'MOZ_NO_REMOTE': '1', 16:45:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:45:35 INFO - 'NO_EM_RESTART': '1', 16:45:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:45:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:35 INFO - 'PWD': '/builds/slave/test', 16:45:35 INFO - 'SHELL': '/bin/bash', 16:45:35 INFO - 'SHLVL': '1', 16:45:35 INFO - 'TERM': 'linux', 16:45:35 INFO - 'TMOUT': '86400', 16:45:35 INFO - 'USER': 'cltbld', 16:45:35 INFO - 'XDG_SESSION_COOKIE': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', 16:45:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:35 INFO - '_': '/tools/buildbot/bin/python'} 16:45:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:45:35 INFO - Running setup.py (path:/tmp/pip-vTqUlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:45:35 INFO - Running setup.py (path:/tmp/pip-uXLDP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:45:35 INFO - Running setup.py (path:/tmp/pip-uA3mYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:45:35 INFO - Running setup.py (path:/tmp/pip-WMorZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:45:35 INFO - Running setup.py (path:/tmp/pip-X68QIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:45:35 INFO - Running setup.py (path:/tmp/pip-SlCRwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:45:36 INFO - Running setup.py (path:/tmp/pip-ViTGHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:45:36 INFO - Running setup.py (path:/tmp/pip-P_T_eU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:45:36 INFO - Running setup.py (path:/tmp/pip-DuQBHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:45:36 INFO - Running setup.py (path:/tmp/pip-vibLOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:45:36 INFO - Running setup.py (path:/tmp/pip-qPzitx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:45:36 INFO - Running setup.py (path:/tmp/pip-U9Eldt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:45:36 INFO - Running setup.py (path:/tmp/pip-SzENR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:45:36 INFO - Running setup.py (path:/tmp/pip-WtJpDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:36 INFO - Running setup.py (path:/tmp/pip-065DHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:45:36 INFO - Running setup.py (path:/tmp/pip-BmeUV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:45:36 INFO - Running setup.py (path:/tmp/pip-aCnm1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:45:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:45:36 INFO - Running setup.py install for manifestparser 16:45:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:45:36 INFO - Running setup.py install for mozcrash 16:45:37 INFO - Running setup.py install for mozdebug 16:45:37 INFO - Running setup.py install for mozdevice 16:45:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:45:37 INFO - Running setup.py install for mozfile 16:45:37 INFO - Running setup.py install for mozhttpd 16:45:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:45:37 INFO - Running setup.py install for mozinfo 16:45:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:45:37 INFO - Running setup.py install for mozInstall 16:45:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:45:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:45:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:45:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:45:37 INFO - Running setup.py install for mozleak 16:45:37 INFO - Running setup.py install for mozlog 16:45:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:45:37 INFO - Running setup.py install for moznetwork 16:45:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:45:37 INFO - Running setup.py install for mozprocess 16:45:38 INFO - Running setup.py install for mozprofile 16:45:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:45:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:45:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:45:38 INFO - Running setup.py install for mozrunner 16:45:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:45:38 INFO - Running setup.py install for mozscreenshot 16:45:38 INFO - Running setup.py install for moztest 16:45:38 INFO - Running setup.py install for mozversion 16:45:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:45:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:45:38 INFO - Cleaning up... 16:45:38 INFO - Return code: 0 16:45:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:38 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 0x20c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x228cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ed60>, '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 0x242fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242fef0>, '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': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', '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:45:38 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:45:38 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:45:38 INFO - Using env: {'DISPLAY': ':0', 16:45:38 INFO - 'HOME': '/home/cltbld', 16:45:38 INFO - 'LANG': 'en_US.UTF-8', 16:45:38 INFO - 'LANGUAGE': 'en_US:en', 16:45:38 INFO - 'LOGNAME': 'cltbld', 16:45:38 INFO - 'MAIL': '/var/mail/cltbld', 16:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:38 INFO - 'MOZ_NO_REMOTE': '1', 16:45:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:45:38 INFO - 'NO_EM_RESTART': '1', 16:45:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:38 INFO - 'PWD': '/builds/slave/test', 16:45:38 INFO - 'SHELL': '/bin/bash', 16:45:38 INFO - 'SHLVL': '1', 16:45:38 INFO - 'TERM': 'linux', 16:45:38 INFO - 'TMOUT': '86400', 16:45:38 INFO - 'USER': 'cltbld', 16:45:38 INFO - 'XDG_SESSION_COOKIE': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', 16:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:38 INFO - '_': '/tools/buildbot/bin/python'} 16:45:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:45:38 INFO - Running setup.py (path:/tmp/pip-uhy6Kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:45:38 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:45:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:45:38 INFO - Running setup.py (path:/tmp/pip-EcHw2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:45:38 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:45:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:45:38 INFO - Running setup.py (path:/tmp/pip-WRcam1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:45:39 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:45:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:45:39 INFO - Running setup.py (path:/tmp/pip-NO0QbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:45:39 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:45:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:45:39 INFO - Running setup.py (path:/tmp/pip-mUED4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:45:39 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:45:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:45:39 INFO - Running setup.py (path:/tmp/pip-SL0jgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:45:39 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:45:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:45:39 INFO - Running setup.py (path:/tmp/pip-JNSyHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:45:39 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:45:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:45:39 INFO - Running setup.py (path:/tmp/pip-o_lzy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:45:39 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:45:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:45:39 INFO - Running setup.py (path:/tmp/pip-Rwuh7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:45:39 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:45:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:45:39 INFO - Running setup.py (path:/tmp/pip-tIVNz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:45:39 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:45:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:45:39 INFO - Running setup.py (path:/tmp/pip-1H_3bE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:45:39 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:45:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:45:39 INFO - Running setup.py (path:/tmp/pip-R5MeOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:45:39 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:45:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:45:39 INFO - Running setup.py (path:/tmp/pip-qBCkaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:45:39 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:45:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:45:39 INFO - Running setup.py (path:/tmp/pip-d_c5Ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:45:39 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:45:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:39 INFO - Running setup.py (path:/tmp/pip-IhaIjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:39 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:45:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:45:39 INFO - Running setup.py (path:/tmp/pip-FP7v4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:45:39 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:45:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:45:39 INFO - Running setup.py (path:/tmp/pip-Di3weR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:45:40 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:45:40 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:45:40 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:45:40 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:45:40 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:45:40 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:45:40 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:45:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:41 INFO - Downloading blessings-1.6.tar.gz 16:45:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:45:41 INFO - Installing collected packages: blessings 16:45:41 INFO - Running setup.py install for blessings 16:45:41 INFO - Successfully installed blessings 16:45:41 INFO - Cleaning up... 16:45:41 INFO - Return code: 0 16:45:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:45:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:45:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:41 INFO - Reading from file tmpfile_stdout 16:45:41 INFO - Current package versions: 16:45:41 INFO - argparse == 1.2.1 16:45:41 INFO - blessings == 1.6 16:45:41 INFO - blobuploader == 1.2.4 16:45:41 INFO - docopt == 0.6.1 16:45:41 INFO - functools32 == 3.2.3-2 16:45:41 INFO - jsonschema == 2.5.1 16:45:41 INFO - manifestparser == 1.1 16:45:41 INFO - mozInstall == 1.13 16:45:41 INFO - mozcrash == 1.0 16:45:41 INFO - mozdebug == 0.1 16:45:41 INFO - mozdevice == 0.51 16:45:41 INFO - mozfile == 1.2 16:45:41 INFO - mozhttpd == 0.7 16:45:41 INFO - mozinfo == 0.10 16:45:41 INFO - mozleak == 0.1 16:45:41 INFO - mozlog == 3.5 16:45:41 INFO - moznetwork == 0.27 16:45:41 INFO - mozprocess == 0.25 16:45:41 INFO - mozprofile == 0.28 16:45:41 INFO - mozrunner == 6.13 16:45:41 INFO - mozscreenshot == 0.1 16:45:41 INFO - mozsystemmonitor == 0.3 16:45:41 INFO - moztest == 0.8 16:45:41 INFO - mozversion == 1.4 16:45:41 INFO - psutil == 3.1.1 16:45:41 INFO - requests == 1.2.3 16:45:41 INFO - wsgiref == 0.1.2 16:45:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:41 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:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:41 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:45:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:41 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 0x20c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x228cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ed60>, '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 0x242fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242fef0>, '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': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', '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:45:41 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:45:41 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:45:41 INFO - Using env: {'DISPLAY': ':0', 16:45:41 INFO - 'HOME': '/home/cltbld', 16:45:41 INFO - 'LANG': 'en_US.UTF-8', 16:45:41 INFO - 'LANGUAGE': 'en_US:en', 16:45:41 INFO - 'LOGNAME': 'cltbld', 16:45:41 INFO - 'MAIL': '/var/mail/cltbld', 16:45:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:41 INFO - 'MOZ_NO_REMOTE': '1', 16:45:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:45:41 INFO - 'NO_EM_RESTART': '1', 16:45:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:45:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:41 INFO - 'PWD': '/builds/slave/test', 16:45:41 INFO - 'SHELL': '/bin/bash', 16:45:41 INFO - 'SHLVL': '1', 16:45:41 INFO - 'TERM': 'linux', 16:45:41 INFO - 'TMOUT': '86400', 16:45:41 INFO - 'USER': 'cltbld', 16:45:41 INFO - 'XDG_SESSION_COOKIE': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', 16:45:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:41 INFO - '_': '/tools/buildbot/bin/python'} 16:45:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:41 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:45:41 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:45:41 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:45:41 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:45:41 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:45:41 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:45:41 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:45:41 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:45:41 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:45:41 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:45:43 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:45:43 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:45:43 INFO - Installing collected packages: simplejson 16:45:43 INFO - Running setup.py install for simplejson 16:45:43 INFO - building 'simplejson._speedups' extension 16:45:43 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:45:43 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:45:44 INFO - Successfully installed simplejson 16:45:44 INFO - Cleaning up... 16:45:44 INFO - Return code: 0 16:45:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:45:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:44 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 0x20c41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x228cb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24319c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ed60>, '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 0x242fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242fef0>, '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': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', '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:45:44 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:45:44 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:45:44 INFO - Using env: {'DISPLAY': ':0', 16:45:44 INFO - 'HOME': '/home/cltbld', 16:45:44 INFO - 'LANG': 'en_US.UTF-8', 16:45:44 INFO - 'LANGUAGE': 'en_US:en', 16:45:44 INFO - 'LOGNAME': 'cltbld', 16:45:44 INFO - 'MAIL': '/var/mail/cltbld', 16:45:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:44 INFO - 'MOZ_NO_REMOTE': '1', 16:45:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:45:44 INFO - 'NO_EM_RESTART': '1', 16:45:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:45:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:44 INFO - 'PWD': '/builds/slave/test', 16:45:44 INFO - 'SHELL': '/bin/bash', 16:45:44 INFO - 'SHLVL': '1', 16:45:44 INFO - 'TERM': 'linux', 16:45:44 INFO - 'TMOUT': '86400', 16:45:44 INFO - 'USER': 'cltbld', 16:45:44 INFO - 'XDG_SESSION_COOKIE': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', 16:45:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:44 INFO - '_': '/tools/buildbot/bin/python'} 16:45:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:45:44 INFO - Cleaning up... 16:45:44 INFO - Return code: 0 16:45:44 INFO - Running post-action listener: _resource_record_post_action 16:45:44 INFO - Running post-action listener: _start_resource_monitoring 16:45:44 INFO - Starting resource monitoring. 16:45:44 INFO - [mozharness: 2017-09-27 23:45:44.354099Z] Finished create-virtualenv step (success) 16:45:44 INFO - [mozharness: 2017-09-27 23:45:44.354573Z] Running install step. 16:45:44 INFO - Running pre-action listener: _resource_record_pre_action 16:45:44 INFO - Running main action method: install 16:45:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:45:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:44 INFO - Reading from file tmpfile_stdout 16:45:44 INFO - Detecting whether we're running mozinstall >=1.0... 16:45:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:45:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:45:44 INFO - Reading from file tmpfile_stdout 16:45:44 INFO - Output received: 16:45:44 INFO - Usage: mozinstall [options] installer 16:45:44 INFO - Options: 16:45:44 INFO - -h, --help show this help message and exit 16:45:44 INFO - -d DEST, --destination=DEST 16:45:44 INFO - Directory to install application into. [default: 16:45:44 INFO - "/builds/slave/test"] 16:45:44 INFO - --app=APP Application being installed. [default: firefox] 16:45:44 INFO - mkdir: /builds/slave/test/build/application 16:45:44 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:45:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:46:02 INFO - Reading from file tmpfile_stdout 16:46:02 INFO - Output received: 16:46:02 INFO - /builds/slave/test/build/application/firefox/firefox 16:46:02 INFO - Running post-action listener: _resource_record_post_action 16:46:02 INFO - [mozharness: 2017-09-27 23:46:02.580375Z] Finished install step (success) 16:46:02 INFO - [mozharness: 2017-09-27 23:46:02.580628Z] Running setup-mitmproxy step. 16:46:02 INFO - Running pre-action listener: _resource_record_pre_action 16:46:02 INFO - Running main action method: setup_mitmproxy 16:46:02 INFO - Skipping: mitmproxy is not required 16:46:02 INFO - Running post-action listener: _resource_record_post_action 16:46:02 INFO - [mozharness: 2017-09-27 23:46:02.581889Z] Finished setup-mitmproxy step (success) 16:46:02 INFO - [mozharness: 2017-09-27 23:46:02.582110Z] Running run-tests step. 16:46:02 INFO - Running pre-action listener: _resource_record_pre_action 16:46:02 INFO - Running pre-action listener: _set_gcov_prefix 16:46:02 INFO - Running main action method: run_tests 16:46:02 WARNING - Try message not found. 16:46:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:46:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:46:02 INFO - Python 2.7.3 16:46:02 INFO - Return code: 0 16:46:02 INFO - grabbing minidump binary from tooltool 16:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:46:02 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 0x242ed60>, '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 0x242fa60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x242fef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:46:02 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:46:02 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:46:02 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:46:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:46:02 INFO - Return code: 0 16:46:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:46:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:46:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:46:02 INFO - ENV: RUST_BACKTRACE is now full 16:46:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:46:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:46:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-075', '--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:46:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-075 --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:46:02 INFO - Using env: {'DISPLAY': ':0', 16:46:02 INFO - 'HOME': '/home/cltbld', 16:46:02 INFO - 'LANG': 'en_US.UTF-8', 16:46:02 INFO - 'LANGUAGE': 'en_US:en', 16:46:02 INFO - 'LOGNAME': 'cltbld', 16:46:02 INFO - 'MAIL': '/var/mail/cltbld', 16:46:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:46:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:46:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:02 INFO - 'MOZ_NO_REMOTE': '1', 16:46:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:46:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:46:02 INFO - 'NO_EM_RESTART': '1', 16:46:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:46:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:02 INFO - 'PWD': '/builds/slave/test', 16:46:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:46:02 INFO - 'RUST_BACKTRACE': 'full', 16:46:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:46:02 INFO - 'SHELL': '/bin/bash', 16:46:02 INFO - 'SHLVL': '1', 16:46:02 INFO - 'TERM': 'linux', 16:46:02 INFO - 'TMOUT': '86400', 16:46:02 INFO - 'USER': 'cltbld', 16:46:02 INFO - 'XDG_SESSION_COOKIE': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', 16:46:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:02 INFO - '_': '/tools/buildbot/bin/python'} 16:46:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-075', '--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:46:02 INFO - mozversion application_buildid: 20170927215532 16:46:02 INFO - mozversion application_changeset: 69e3f89816455e567f1a20b694fd6afd549c82c7 16:46:02 INFO - mozversion application_display_name: Nightly 16:46:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:46:02 INFO - mozversion application_name: Firefox 16:46:02 INFO - mozversion application_remotingname: firefox 16:46:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:46:02 INFO - mozversion application_vendor: Mozilla 16:46:02 INFO - mozversion application_version: 58.0a1 16:46:02 INFO - mozversion platform_buildid: 20170927215532 16:46:02 INFO - mozversion platform_changeset: 69e3f89816455e567f1a20b694fd6afd549c82c7 16:46:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:46:02 INFO - mozversion platform_version: 58.0a1 16:46:02 INFO - using testdate: 1506555962 16:46:02 INFO - actual date: 1506555962 16:46:02 INFO - starting webserver on 'localhost:38474' 16:46:02 INFO - SUITE-START | Running 2 tests 16:46:02 INFO - TEST-START | dromaeo_css 16:46:02 INFO - Initialising browser for dromaeo_css test... 16:46:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38474/getInfo.html -profile /tmp/tmpgpwaqy/profile 16:46:02 INFO - TEST-INFO | started process 15725 (/builds/slave/test/build/application/firefox/firefox http://localhost:38474/getInfo.html) 16:46:09 INFO - TEST-INFO | 15725: exit 0 16:46:09 INFO - Browser initialized. 16:46:09 INFO - Running cycle 1/1 for dromaeo_css test... 16:46:09 INFO - Using env: {'DISPLAY': ':0', 16:46:09 INFO - 'HOME': '/home/cltbld', 16:46:09 INFO - 'JSGC_DISABLE_POISONING': '1', 16:46:09 INFO - 'LANG': 'en_US.UTF-8', 16:46:09 INFO - 'LANGUAGE': 'en_US:en', 16:46:09 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:46:09 INFO - 'LOGNAME': 'cltbld', 16:46:09 INFO - 'MAIL': '/var/mail/cltbld', 16:46:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:46:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:46:09 INFO - 'MOZ_CRASHREPORTER': '1', 16:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:09 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:46:09 INFO - 'MOZ_NO_REMOTE': '1', 16:46:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:46:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:46:09 INFO - 'NO_EM_RESTART': '1', 16:46:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:09 INFO - 'PWD': '/builds/slave/test', 16:46:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:46:09 INFO - 'RUST_BACKTRACE': 'full', 16:46:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:46:09 INFO - 'SHELL': '/bin/bash', 16:46:09 INFO - 'SHLVL': '1', 16:46:09 INFO - 'TERM': 'linux', 16:46:09 INFO - 'TMOUT': '86400', 16:46:09 INFO - 'USER': 'cltbld', 16:46:09 INFO - 'XDG_SESSION_COOKIE': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', 16:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:09 INFO - '_': '/tools/buildbot/bin/python'} 16:46:09 INFO - TEST-INFO | started process 15956 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgpwaqy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:46:10 INFO - PID 15956 | 16:46:10 INFO - PID 15956 | (/builds/slave/test/build/application/firefox/firefox:16005): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:46:10 INFO - PID 15956 | 16:46:20 INFO - PID 15956 | RSS: Main: 151719936 16:46:20 INFO - PID 15956 | 16:49:18 INFO - PID 15956 | Cycle 1(1): loaded http://localhost:38474/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38474/tests/dromaeo/cssquery-ext.html) 16:49:19 INFO - PID 15956 | RSS: Main: 149712896 16:49:19 INFO - PID 15956 | 16:52:21 INFO - PID 15956 | Cycle 1(1): loaded http://localhost:38474/tests/dromaeo/cssquery-ext.html (next: http://localhost:38474/tests/dromaeo/cssquery-jquery.html) 16:52:22 INFO - PID 15956 | RSS: Main: 150491136 16:52:22 INFO - PID 15956 | 16:55:23 INFO - PID 15956 | Cycle 1(1): loaded http://localhost:38474/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38474/tests/dromaeo/cssquery-mootools.html) 16:55:24 INFO - PID 15956 | RSS: Main: 151764992 16:55:24 INFO - PID 15956 | 16:58:26 INFO - PID 15956 | Cycle 1(1): loaded http://localhost:38474/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38474/tests/dromaeo/cssquery-prototype.html) 16:58:26 INFO - PID 15956 | RSS: Main: 151433216 16:58:26 INFO - PID 15956 | 17:01:30 INFO - PID 15956 | Cycle 1(1): loaded http://localhost:38474/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38474/tests/dromaeo/cssquery-yui.html) 17:01:30 INFO - PID 15956 | RSS: Main: 154337280 17:01:30 INFO - PID 15956 | 17:04:32 INFO - PID 15956 | Cycle 1(1): loaded http://localhost:38474/tests/dromaeo/cssquery-yui.html (next: http://localhost:38474/tests/dromaeo/cssquery-dojo.html) 17:04:32 INFO - PID 15956 | RSS: Main: 155303936 17:04:32 INFO - PID 15956 | 17:04:32 INFO - PID 15956 | __start_tp_report 17:04:32 INFO - PID 15956 | _x_x_mozilla_page_load 17:04:32 INFO - PID 15956 | _x_x_mozilla_page_load_details 17:04:32 INFO - PID 15956 | |i|pagename|runs| 17:04:32 INFO - PID 15956 | |0;dojo.html;3250.154959710475;3259.6577359377266;3289.0626171541107;3294.4399452093144;3303.752218583607;6268.968655156729;6360.745570177199;6376.872462550752;6393.872122557552;6477.935220647792;6325.304216536178;6335.651539165344;6337.556371054028;6372.394622510854;6402.679866006704;5791.623544469596;5791.971040144815;5819.883602327962;5854.063349864023;5856.56075794315;5908.852278693024;5926.762929482816;5944.732487038072;5981.58128930975;6015.157877897098;5848.824535263949;5852.385499522553;5912.5861189716525;5925.703714814242;5933.851653708649;5636.323641163046;5742.109972954198;5776.59563830532;5834.212381328509;5959.165716799652;5691.26013618227;5764.538836893039;5839.0949402842625;5840.532757379399;5855;6174.073888916699;6282.96858515709;6337.556371054028;6394.488440924715;6418.390252926011;6664.8000559983275;6680.899786503206;6687.933120668779;6688.83277918051;6708.329167083301;6752.2234942981695;6879.071325371061;6985.685644146026;6993.615351155684;7003.5097543172;6362.141110949962;6368.649724265164;6396.712147953353;6433.260175079832;6450.387213214737;6927.134108236471;7068.92931070693;7073.328033836778;7169.964150179216;7174.820629484305;6687.164104486939;6730.730770769114;6740.696668649923;6785.117934668461;6798.354156355138;6829;6881.13985751781;6914.585124892523;6925.58446493202;6929.099217101744;6808.2170550386345;6825.897611535831;6898.413634840995;6907.516473846883;6918.169819621577;6673.232578253466;6787.762428314985;6793.796186114425;6817;6884.208316043619;5060.240963855451;5068.189089745697;5076.162433198481;5080.212567052039;5085.440601533828;4441.955580444219;4449.354843547668;4455.977720111378;4467;4469.5530446955045;5782.479576838105;5864.736086876015;5934.821955341346;5940.495057920039;5943.465088142088;6647.468202543785;6757.526973111933;6828.283030281848;6892.138482689664;6962;5862.062070068854;5874.676892770939;5886.911696324559;5887.1463637772285;5891.145783861316;5781.19063331134;5862.530997520188;5882.735276912549;5914.260717410324;5946.43508866657;6476.319986401454;6669.466442684574;6669.899951500732;6693.966530167318;6714.798556043326;6869.107016087876;6918;6927.134108236471;6966.373026427443;7004.15950085993;6414.550981431255;6505.642189679613;6508.967455162694;6574.704138313692;6698.296678848846;6668.199816022108;6673.899891501728;6679.0649309097735;6679.165104361955;6771.62756048422;6910.481713871581;6936.271691472322;6943.722251109899;6958.721651133898;6964.512484126062;6646.2356828965285;6649.301823308483;6663.13379260693;6751.358620931003;6756.087928129639;6641.136652235178;6651.368120028589;6665.300143484864;6766.255711871789;6791.388775010273;6289.213848268966;6406.5515413920575;6408.743650254124;6417.96791016042;6650.567713098633;6612.768553100618;6621.370969757865;6641.0038494226255;6792.558483698544;6793.93206067933;6525.673716314261;6527.738890444329;6534.509911756542;6539.411452969255;6554.770583029571 17:04:32 INFO - PID 15956 | |1;ext.html;848.6944699908029;877.035261212666;895.5925054100381;896.0457113174468;904.4980036079977;39492;40202.19595608086;40246.59753402469;40309.798451007744;40377;37521.81239093801;39434.21131577367;40234.79882600583;40239.79880100603;40241.396379054335;7838.764837054883;7999.240072193162;8002.519848809061;8003.559804210795;8034.839303213932;7856.371490280792;8021.077576078767;8021.799455013613;8029.197080291982;8033.236842499982;7999.880001799977;8065.2741253287195;8090.9886264216975;8097.2307630774985;8101.068377136646;8008.799780005488;8020.197980201991;8033.598320084031;8040.517568945824;8048.114707382183;7880.330171935392;7940.761777146695;7945.324647404977;7978.720744773963;7993.600319983978;36220;36239.27521449556;36322.81838590817;36381.27237455236;36497;3555.2000799820094;3589.7128229741556;3593.281343731238;3603.6036036036016;3609.657082577151;29941.850290748407;29955.25111872199;29963.550546741815;30002.699973000428;30207.848960755055;4971.304017437562;5196.428392876781;5207.531322180984;5228.320318358589;5280.260763493114;180175;181847;181935;182029;182097;44872.3269150963;44932.3260151098;44955.55044449579;45017.324740128926;45109.097818043454;46886.296705549445;51768;52404.73797631064;52529;52588.73705631447;15940.601484962968;16020.359185632677;16077;16111.516654500383;16173.59566010859;13109.60671179866;13170.47318107265;13189.340532973312;13219.074664773374;13228.669283267995;13911.443542258196;14050.859491404955;14079.929600351932;14118.788218176736;14265.714685706434;5576.375356018621;5637.323521177403;5675.432456754292;5681.77272909079;5738.225339579187;974.449436068619;1005.1305620638095;1013.6097602423121;1013.7986486013949;1023.0843395161289;22218.888905555694;22258.777412225994;22459;22570;22631;1107.335598640825;1108.296231892754;1117.675873996532;1120.708615759892;1124.4321617583068;11829.822552661717;11914.46384912681;11961.282323060645;12066.913977742046;12233.3271670059;221233;222381;222921;223708;223892;43045.56954430478;47912.28131578029;48773.51226487761;49506.75246623816;50016.49983500191;1435.6769726811592;1468.1924941282252;1470.7132109238596;1484.547213100015;1489.3149151390264;25460.618090729025;26262.474750505266;26284.21147365582;26356;26428.339291517867;35141.47287790634;35230.11924701903;35250.47124293189;35253;35273.47089793706;172763;173498;173499;173553.13223433803;174090;32481.187970300933;32504.674953250164;32641.510377343882;33008.66991330056;33071.33857322888;39649.60350396574;40652.79673601613;40747.1850562993;40968.180636387704;40996.385054224804;28729.281767955967;30098.518966525473;30246.09261722152;30284.697153028188;31084;29756.256093597833;29794.553081703358;29860.552091718208;30122.69877301199;30512;33765;33808.830955845064;33878.830605846815;34053.31893362069;34070.48894266637;29502.114936551938;29797.70202298036;29929.251768705955;29967.4006519864;30061.69938300589;22668.65997010079;22784.202552911232;22858.885705571367;22885.199018034957;23101.19145829888 17:04:32 INFO - PID 15956 | |2;jquery.html;598.9490893274071;601.6450294326348;602.8372339468347;609.2019454514586;612.7365232949829;22074.33776986691;22852.31443056709;22855.77144228557;22885.656715149285;22893.4276643084;24210.27369178923;24296;25680.871595642042;25841.095561655347;25890.352741181432;6690.464762818987;6829.282925292847;6863.279355667658;6874.862502749942;6875.759348422806;6475.352464753534;6716.429103526206;6770.458363330922;6822.590644561342;6825.078614387068;6711.5301928865;6717;6717;6719.63042032688;6720.899186512206;6667.6666166691475;6704.229013663418;6711.664416779142;6714.56355336904;6717.697703603325;6228.813135605939;6230.376962303779;6237.937620623781;6244.313125556185;6249.78125765596;29118.85440572805;29480.26299342512;29740;29911.252218694488;29976.70023299761;2056.249468943825;2087.269455690511;2093.7906209379094;2099.370188943326;2100.2754049852774;18914.621707565908;19389.418317450498;19452;19492.31776887816;19511.512212194666;2360.8870061295215;2429.9028038878423;2450.4486490539666;2470.295965649781;2475.789557887582;121808;126347;136357;143593.28203359124;144143;14995.775063374058;15148;15219.847801522064;15393.615159621098;15512.767308490382;24066.51866962651;24195;24320.391990200387;24444.877775611007;24447.63328550073;10207.336523125972;10356.443165007373;10423.42671153094;10490.370577765358;10548.103411210133;9523.333366664405;9571.61713531465;9591.088846559565;9596.472194029395;9672.75818104553;9437.386569872937;9603.0230628504;9648.131668149901;9661.178799802095;9677.419354838732;2799.80401371902;2816.1129244288395;2818.126380821952;2830.6461692288462;2878.2228798224364;982.832918403873;996.5316301338504;997.7904640025675;997.9401235925868;1004.4726518577805;19765.40703778889;19877.701834472493;20010.69983950242;20142.295019674242;20465.07907144182;1035.5236591167995;1041.598984391015;1057.1384321777728;1061.3525749292926;1062.3201151263197;10925.672229833117;11007.944960275147;11090.390028548345;11108.333499989865;11249.232121542109;513508;514425;520176;521486;526600;107253.46373268084;108552.45723771489;108735.45632271946;110219;114642;1058.5025038232018;1115.2416356877402;1130.7625398666207;1161.96514104577;1205.102198861836;20557;20788.27241046556;20798.792012080292;20843.270485532932;21032.474188144806;53217.73391133019;54076.18885716639;54657;54823;55646.72176639091;67283.66358168177;68036.31963680498;68220.3177968214;68455;68475.31524684689;14711;14833.703325933637;15067.623309417353;15069.623259418164;15092.39630414771;23102;23358.18246361369;23424.53150937006;23436.531269374856;23718.762812371657;18626.2549498024;18735.250589976247;18906.14922328471;18954.620907582048;19024;16571.585710357336;16620.168991550134;16814.495565133064;16861.747073794148;17281.65436691234;18811.435656930313;18833.90583047076;18879.528011799815;19194.424167275003;19213.711794322815;14676.486322978644;14677.633059173606;14738.410463581336;14742.189179595225;14899;18101.18544665467;18236.45290641283;18368.17343219585;18399.264029439208;18554.165062571945 17:04:32 INFO - PID 15956 | |3;mootools.html;1317.0498084291187;1376.8210132682757;1393.2546087843007;1401.3273628658237;1402.0466083063516;15071.773923391158;15329.46346877858;16526.917365413185;16565.171741412913;16582.66834663306;16133.757993630075;16444.260008299625;16446.506604801845;16485.34058637653;16497.91751041243;2560.8207425480223;2661;2766.6265054217724;2833.7166283371603;2833.943321133576;2747.7801775857984;2782.123631056211;2785.958210626832;2786.6656001279775;2787.205646390779;2706.188143556935;2749.3264150283107;2754.4215714699935;2758.2001219646277;2759.323965628424;2692.17888543994;2703.297142742891;2727.359070618403;2735.5076086304457;2736.0287098080194;2572.7041390240174;2609.8434093954424;2657.548216803148;2660.268426182796;2675.077098401068;14399.856001440061;14718.632034199123;14728.779068313877;14737.926310368486;14740.189289589069;8057.556834374107;8286.047104582989;8294.543800090993;8325.042620098706;8338.666453341859;5294.523492885659;5456.181572764117;5459.3994660587305;5572.832815015556;5581.05122129239;1973.5460844005815;1987.473319570315;1994.4315869976988;1996.4709352021725;2030.3604364625096;27250.5912411314;27253.454930901273;27370.45259094807;27415;27494.312642184104;6779.2881747416795;6809.625470599066;6814.829629259309;6856.074429951893;6915.135608048994;17764;17764.289428422977;17765.911170444324;17783;17790.733139002925;7776.183500732342;7960.641771120314;7979.521228726295;7988.560629165451;8008.359331253486;5624.91562626561;6467.902981455282;6479.481641468671;7368.042154519985;7410.5183163094225;4947.332110165154;5052.090623687698;5114.590832733373;5141.280220769096;5186.066508028515;1536.5467187179809;1604.5186444066733;1627.2189349112493;1627.462937230711;1635.1088656682136;894.5214310344065;900.9278958039912;907.2696479334068;917.4403613795579;923.9815203696023;7632.236776322435;7647.502912310682;7658.387329013665;7712.074551053909;7730.690772369155;699.3076853914689;711.8078118907866;730.8720973829559;741.2587412587412;750.5009168902727;5108.208227724708;5180.533751962342;5207.765650545659;5285.497877701612;5396.19057141424;21390.57163513082;21696.566068678538;21979.560408791735;22199.223027194294;22202;21877.562448750938;22472;22495.100195992305;22537.323880283617;22548.0980760771;10282.845757313646;11014.724631884266;11123.221374503708;11130.332180069221;11137.665870023913;8215.753527394188;8249.670013199404;8269.83460330802;8305.543195124326;8385.706500272461;18666;18837.15232814554;18841.340553081125;18842.3405180818;19154.71267930954;19250.518737031685;19460.708089378386;19969.60060798805;20030.198792048155;20426.89786551058;11110.222284440011;11217.887821121685;11372.658820235407;11380.146489013192;11381.317120972768;13885.166889986633;14173.645658858612;14173.787393188904;14188.929055354658;14194.290285485478;11199.10407167441;11364.318140911571;11379.886201138213;11623.88376116228;11713.297202167898;11116.777664446827;11220.270682405617;11279.041281491165;11384.772304554028;11581.82627260575;10118.291719579853;10174.13519850821;10201.795964080527;10207.591696332063;10209.489525523546;7226.7109315626785;7234.02340684001;7234.204237533972;7239.8914016288745;7242.529235599669;7238.457115716447;7315.890261646186;7333.989104871705;7358.22738612438;7445.329920307198 17:04:32 INFO - PID 15956 | |4;prototype.html;507.4418140045948;525.8632755483574;546.6610701365153;548.3502050070664;557.180944012302;6344;6355.618662880224;6356.904646430307;6362.491000719943;6449.903251451232;6247.6251424914535;6295;6306.59007164534;6319.620822750639;6368.866429746029;5166.055348451394;5188.481151884819;5190.740462976836;5196.948030519703;5203.245529398235;4770.61835053195;4968.329275547809;5098.541131298182;5128.333316668846;5171.500264923167;4950.752462376885;4964.156093464102;4967.3542439483;4977.129002424579;5000.44995050546;4893.779779909913;4938.703677779345;4957.479464656195;4966.106100901836;4968.428630707479;4356;4456.420665313521;4464.397306363632;4477.9104417911785;4492.550744925514;6081.756729730805;6139.539534534883;6158.353372895871;6160.168377269101;6170.7531698732;5642.689652069134;5648.717564121818;5674.801381951612;5689.0328644130595;5725.427457254283;6621.73513059477;6650.035744816974;6663.833404164886;6668.233153187397;6708.798736037927;4668.206404911173;4917.164082106051;4917.385326834145;4925.359703238556;4926.778294976734;6728.333058483138;6797.320267973163;6804.325664141407;6881.724731010703;6910.032595436593;4951.826686066042;4964.553190212898;5005.074061298671;5018.648694591417;5052.873678158076;5076.187809950464;5117.8464646060675;5118.129917913963;5128.640995130305;5129.538341549279;5639.364584270597;5644.6613203207935;5690.772369105272;5714.771409143587;5728.885422291531;6640.136782218377;6682.758861951949;6682.83292917681;6705.262421133769;6723;1715.6997525432998;1723.8534724548304;1763.2329936477672;1778.315348590802;1780.777402824647;1158.6697791129488;1219.4634360881184;1227.619437974213;1235.6540168752763;1241.9068569857116;5619.925553973337;5646.887062258814;5674.751554657969;5721.027425337703;5821.505172060422;6549.365102368231;6585.9012114818315;6590.736370545124;6597.670116494252;6642.535022548472;5575.609707320448;5601.243832082617;5678.801241956512;5679.943200568024;5702.572307076985;3510.3330367230146;3519.1026288296835;3546.8226588670977;3550.9644903551152;3552.3605750964557;6327.354887729131;6367.395097465734;6372.7132279047555;6386.808395748135;6460.515461340418;6267.341929097471;6820.6930688119155;6837.863242735118;6858.794236172923;6869.656517174121;6285.245770507477;6293.874122517524;6308.968455157695;6446.580972236789;6490.766754316664;6532.216134063943;6598.175228096488;6660.300668429745;6734;6768.729250830109;6636.539961208527;6730.158730158731;6763.560368576027;6781.728730850711;6815.591064536144;6423.96788016057;6446.387593178828;6471.255805582418;6485.578437401554;6487.929491633828;4996.700197987987;5000.274960130833;5018.824341148043;5030.37120359955;5060.063888180698;6442.420182183626;6509.544331896722;6566.474682025331;6822.11312529368;6856.211535673262;6269.655168965751;6519.152510173647;6519.152510173647;6540.247871494648;6589.04458853454;6446.903296450463;6475.546711730133;6583.868322633616;6587.176602924635;6603.174603174603;6462.063000864942;6499.1876015498065;6523.412892839668;6525.184351956005;6547.279799222177;6665.166854143232;6691.698873550799;6703.497237707289;6741.966290168518;6745.494087943522;6351.904721429275;6393.808185754435;6423.100765892872;6469.2560355557835;6517.4460170886005 17:04:32 INFO - PID 15956 | |5;yui.html;902.2601466797221;905.6105874473977;928.5960607135892;928.8885333759949;940.2384068904188;19090.713639295405;20050.49873753157;20082.59834803307;20104.39686809396;20138.69791953122;18379.816201837977;18548.721769173473;18761;18845.81154188461;19020.14409351579;973.7370909854318;992.3599278465385;997.840345544713;998.6754304850916;1001.1240164855751;948.0708905272802;989.6882482018141;990.6829814459376;991.9652812151576;993.0913214408807;1061.9044286014257;1061.9893801061967;1062.1449732964961;1064.5528877871275;1065.0255016659746;1036.9014943580348;1047.9685609431654;1048.3238311289215;1050.1861057680283;1052.4053909541085;717.4834119433999;734.7501849371239;749.7076140305285;750.1186106325047;777.801660576555;17705.38031168903;17719.911400443045;17737.467875963743;17795.644087118315;17805.376811811653;21398.14407423701;21808.781912180835;21817.454563635878;22177.556448871095;22328;17569.12154392288;17801.554961126076;17831.01929393883;17849.821501784947;17930.641387172313;3620.149264922741;3645.143391303042;3646.179609587848;3647.1611529348133;3648.945265821015;129497.35251323777;132336;134640;134834.32582837122;137104.999999999;16498;16520.586985325463;16570.088645124393;16619;16776.496705098867;30350.84824575863;31231.219219519695;31232.687673123433;31238.375232495222;31384;8641.524716140673;8707.434016789015;8731.738047858575;8737.30101591871;8751.474911505331;4130.545639979599;4240.554741752072;4247.5752424757275;4251.043515209115;4254.425652536868;5194.714290713971;5269.973650131725;5279.287296215039;5318.335208098988;5331.893362132736;977.7164622259608;979.9020097990144;1005.5726316315537;1006.0995409108807;1007.2345017786535;681.6591704147926;703.2229386527915;710.1265443504415;712.8146681862753;719.5934297122213;11698.35659038744;11995.520179192908;12223.511059557695;12324.137310388187;12628.81056784149;690.3683129936153;703.4513079797766;706.9858602827915;712.593821521738;718.9676464559108;5709.0865461525955;5717.542596592262;5773.509251713652;5826.970865145732;5849.356570777211;118970;119323;119796;120982;121051;97155;100481.49759251303;100959;102485.48757256166;102621.48689256507;89284.5535772317;91282.54358728165;92064;93439;93725.5313723427;10320.43237621938;10370.170386369224;10710.785784284426;10860.456977150952;10908.018278354986;41954.580454195064;42593;43791.34312985244;44264.77867610641;44790.55209447864;52395.73802130965;53836.461635383144;53967.73016134894;54848.725756370965;55675.443245567025;15078.396864125312;15489.612759680647;15540.145292009049;15584.142872141687;15587.298571564534;16157.030578165348;16194;16227.837721622633;16384.83615163833;16419.343226271292;15672.294746736196;15699;15729.528114156594;15841.128737919526;15922.840771592599;15497.45758898433;15763.448279310169;15795.684086318439;15906.204689765234;15914;14780.778288325899;14831.92584037073;14876.851231487546;15042.323095460511;15058.322375493348;11223.158263130135;11482.368469734245;11522.827157592475;11530.59642912494;11563.42182890869;11620.535178592761;11990.100742444525;12103.636890893287;12165.33090680021;12176.391180440765 17:04:32 INFO - PID 15956 | __end_tp_report 17:04:32 INFO - PID 15956 | __start_cc_report 17:04:32 INFO - PID 15956 | _x_x_mozilla_cycle_collect,281 17:04:32 INFO - PID 15956 | __end_cc_report 17:04:32 INFO - PID 15956 | __startTimestamp1506557072493__endTimestamp 17:04:32 INFO - PID 15956 | 17:04:32 INFO - PID 15956 | ------- Summary: start ------- 17:04:32 INFO - PID 15956 | Number of tests: 6 17:04:32 INFO - PID 15956 | 17:04:32 INFO - PID 15956 | [#0] dojo.html Cycles:165 Average:6267.02 Median:6508.97 stddev:779.24 (12.0%) stddev-sans-first:744.82 17:04:32 INFO - PID 15956 | Values: 3250.2 3259.7 3289.1 3294.4 3303.8 6269.0 6360.7 6376.9 6393.9 6477.9 6325.3 6335.7 6337.6 6372.4 6402.7 5791.6 5792.0 5819.9 5854.1 5856.6 5908.9 5926.8 5944.7 5981.6 6015.2 5848.8 5852.4 5912.6 5925.7 5933.9 5636.3 5742.1 5776.6 5834.2 5959.2 5691.3 5764.5 5839.1 5840.5 5855.0 6174.1 6283.0 6337.6 6394.5 6418.4 6664.8 6680.9 6687.9 6688.8 6708.3 6752.2 6879.1 6985.7 6993.6 7003.5 6362.1 6368.6 6396.7 6433.3 6450.4 6927.1 7068.9 7073.3 7170.0 7174.8 6687.2 6730.7 6740.7 6785.1 6798.4 6829.0 6881.1 6914.6 6925.6 6929.1 6808.2 6825.9 6898.4 6907.5 6918.2 6673.2 6787.8 6793.8 6817.0 6884.2 5060.2 5068.2 5076.2 5080.2 5085.4 4442.0 4449.4 4456.0 4467.0 4469.6 5782.5 5864.7 5934.8 5940.5 5943.5 6647.5 6757.5 6828.3 6892.1 6962.0 5862.1 5874.7 5886.9 5887.1 5891.1 5781.2 5862.5 5882.7 5914.3 5946.4 6476.3 6669.5 6669.9 6694.0 6714.8 6869.1 6918.0 6927.1 6966.4 7004.2 6414.6 6505.6 6509.0 6574.7 6698.3 6668.2 6673.9 6679.1 6679.2 6771.6 6910.5 6936.3 6943.7 6958.7 6964.5 6646.2 6649.3 6663.1 6751.4 6756.1 6641.1 6651.4 6665.3 6766.3 6791.4 6289.2 6406.6 6408.7 6418.0 6650.6 6612.8 6621.4 6641.0 6792.6 6793.9 6525.7 6527.7 6534.5 6539.4 6554.8 17:04:32 INFO - PID 15956 | 17:04:32 INFO - PID 15956 | [#1] ext.html Cycles:180 Average:35725.96 Median:33843.83 stddev:50089.52 (148.0%) stddev-sans-first:50161.57 17:04:32 INFO - PID 15956 | Values: 848.7 877.0 895.6 896.0 904.5 39492.0 40202.2 40246.6 40309.8 40377.0 37521.8 39434.2 40234.8 40239.8 40241.4 7838.8 7999.2 8002.5 8003.6 8034.8 7856.4 8021.1 8021.8 8029.2 8033.2 7999.9 8065.3 8091.0 8097.2 8101.1 8008.8 8020.2 8033.6 8040.5 8048.1 7880.3 7940.8 7945.3 7978.7 7993.6 36220.0 36239.3 36322.8 36381.3 36497.0 3555.2 3589.7 3593.3 3603.6 3609.7 29941.9 29955.3 29963.6 30002.7 30207.8 4971.3 5196.4 5207.5 5228.3 5280.3 180175.0 181847.0 181935.0 182029.0 182097.0 44872.3 44932.3 44955.6 45017.3 45109.1 46886.3 51768.0 52404.7 52529.0 52588.7 15940.6 16020.4 16077.0 16111.5 16173.6 13109.6 13170.5 13189.3 13219.1 13228.7 13911.4 14050.9 14079.9 14118.8 14265.7 5576.4 5637.3 5675.4 5681.8 5738.2 974.4 1005.1 1013.6 1013.8 1023.1 22218.9 22258.8 22459.0 22570.0 22631.0 1107.3 1108.3 1117.7 1120.7 1124.4 11829.8 11914.5 11961.3 12066.9 12233.3 221233.0 222381.0 222921.0 223708.0 223892.0 43045.6 47912.3 48773.5 49506.8 50016.5 1435.7 1468.2 1470.7 1484.5 1489.3 25460.6 26262.5 26284.2 26356.0 26428.3 35141.5 35230.1 35250.5 35253.0 35273.5 172763.0 173498.0 173499.0 173553.1 174090.0 32481.2 32504.7 32641.5 33008.7 33071.3 39649.6 40652.8 40747.2 40968.2 40996.4 28729.3 30098.5 30246.1 30284.7 31084.0 29756.3 29794.6 29860.6 30122.7 30512.0 33765.0 33808.8 33878.8 34053.3 34070.5 29502.1 29797.7 29929.3 29967.4 30061.7 22668.7 22784.2 22858.9 22885.2 23101.2 17:04:32 INFO - PID 15956 | 17:04:32 INFO - PID 15956 | [#2] jquery.html Cycles:180 Average:35404.96 Median:22997.71 stddev:86746.77 (377.2%) stddev-sans-first:86950.75 17:04:32 INFO - PID 15956 | Values: 598.9 601.6 602.8 609.2 612.7 22074.3 22852.3 22855.8 22885.7 22893.4 24210.3 24296.0 25680.9 25841.1 25890.4 6690.5 6829.3 6863.3 6874.9 6875.8 6475.4 6716.4 6770.5 6822.6 6825.1 6711.5 6717.0 6717.0 6719.6 6720.9 6667.7 6704.2 6711.7 6714.6 6717.7 6228.8 6230.4 6237.9 6244.3 6249.8 29118.9 29480.3 29740.0 29911.3 29976.7 2056.2 2087.3 2093.8 2099.4 2100.3 18914.6 19389.4 19452.0 19492.3 19511.5 2360.9 2429.9 2450.4 2470.3 2475.8 121808.0 126347.0 136357.0 143593.3 144143.0 14995.8 15148.0 15219.8 15393.6 15512.8 24066.5 24195.0 24320.4 24444.9 24447.6 10207.3 10356.4 10423.4 10490.4 10548.1 9523.3 9571.6 9591.1 9596.5 9672.8 9437.4 9603.0 9648.1 9661.2 9677.4 2799.8 2816.1 2818.1 2830.6 2878.2 982.8 996.5 997.8 997.9 1004.5 19765.4 19877.7 20010.7 20142.3 20465.1 1035.5 1041.6 1057.1 1061.4 1062.3 10925.7 11007.9 11090.4 11108.3 11249.2 513508.0 514425.0 520176.0 521486.0 526600.0 107253.5 108552.5 108735.5 110219.0 114642.0 1058.5 1115.2 1130.8 1162.0 1205.1 20557.0 20788.3 20798.8 20843.3 21032.5 53217.7 54076.2 54657.0 54823.0 55646.7 67283.7 68036.3 68220.3 68455.0 68475.3 14711.0 14833.7 15067.6 15069.6 15092.4 23102.0 23358.2 23424.5 23436.5 23718.8 18626.3 18735.3 18906.1 18954.6 19024.0 16571.6 16620.2 16814.5 16861.7 17281.7 18811.4 18833.9 18879.5 19194.4 19213.7 14676.5 14677.6 14738.4 14742.2 14899.0 18101.2 18236.5 18368.2 18399.3 18554.2 17:04:32 INFO - PID 15956 | 17:04:32 INFO - PID 15956 | [#3] mootools.html Cycles:180 Average:9465.59 Median:2668.04 stddev:6904.29 (258.8%) stddev-sans-first:6896.52 17:04:32 INFO - PID 15956 | Values: 1317.0 1376.8 1393.3 1401.3 1402.0 15071.8 15329.5 16526.9 16565.2 16582.7 16133.8 16444.3 16446.5 16485.3 16497.9 2560.8 2661.0 2766.6 2833.7 2833.9 2747.8 2782.1 2786.0 2786.7 2787.2 2706.2 2749.3 2754.4 2758.2 2759.3 2692.2 2703.3 2727.4 2735.5 2736.0 2572.7 2609.8 2657.5 2660.3 2675.1 14399.9 14718.6 14728.8 14737.9 14740.2 8057.6 8286.0 8294.5 8325.0 8338.7 5294.5 5456.2 5459.4 5572.8 5581.1 1973.5 1987.5 1994.4 1996.5 2030.4 27250.6 27253.5 27370.5 27415.0 27494.3 6779.3 6809.6 6814.8 6856.1 6915.1 17764.0 17764.3 17765.9 17783.0 17790.7 7776.2 7960.6 7979.5 7988.6 8008.4 5624.9 6467.9 6479.5 7368.0 7410.5 4947.3 5052.1 5114.6 5141.3 5186.1 1536.5 1604.5 1627.2 1627.5 1635.1 894.5 900.9 907.3 917.4 924.0 7632.2 7647.5 7658.4 7712.1 7730.7 699.3 711.8 730.9 741.3 750.5 5108.2 5180.5 5207.8 5285.5 5396.2 21390.6 21696.6 21979.6 22199.2 22202.0 21877.6 22472.0 22495.1 22537.3 22548.1 10282.8 11014.7 11123.2 11130.3 11137.7 8215.8 8249.7 8269.8 8305.5 8385.7 18666.0 18837.2 18841.3 18842.3 19154.7 19250.5 19460.7 19969.6 20030.2 20426.9 11110.2 11217.9 11372.7 11380.1 11381.3 13885.2 14173.6 14173.8 14188.9 14194.3 11199.1 11364.3 11379.9 11623.9 11713.3 11116.8 11220.3 11279.0 11384.8 11581.8 10118.3 10174.1 10201.8 10207.6 10209.5 7226.7 7234.0 7234.2 7239.9 7242.5 7238.5 7315.9 7334.0 7358.2 7445.3 17:04:32 INFO - PID 15956 | 17:04:32 INFO - PID 15956 | [#4] prototype.html Cycles:180 Average:5516.36 Median:6257.48 stddev:1543.81 (24.7%) stddev-sans-first:1501.66 17:04:32 INFO - PID 15956 | Values: 507.4 525.9 546.7 548.4 557.2 6344.0 6355.6 6356.9 6362.5 6449.9 6247.6 6295.0 6306.6 6319.6 6368.9 5166.1 5188.5 5190.7 5196.9 5203.2 4770.6 4968.3 5098.5 5128.3 5171.5 4950.8 4964.2 4967.4 4977.1 5000.4 4893.8 4938.7 4957.5 4966.1 4968.4 4356.0 4456.4 4464.4 4477.9 4492.6 6081.8 6139.5 6158.4 6160.2 6170.8 5642.7 5648.7 5674.8 5689.0 5725.4 6621.7 6650.0 6663.8 6668.2 6708.8 4668.2 4917.2 4917.4 4925.4 4926.8 6728.3 6797.3 6804.3 6881.7 6910.0 4951.8 4964.6 5005.1 5018.6 5052.9 5076.2 5117.8 5118.1 5128.6 5129.5 5639.4 5644.7 5690.8 5714.8 5728.9 6640.1 6682.8 6682.8 6705.3 6723.0 1715.7 1723.9 1763.2 1778.3 1780.8 1158.7 1219.5 1227.6 1235.7 1241.9 5619.9 5646.9 5674.8 5721.0 5821.5 6549.4 6585.9 6590.7 6597.7 6642.5 5575.6 5601.2 5678.8 5679.9 5702.6 3510.3 3519.1 3546.8 3551.0 3552.4 6327.4 6367.4 6372.7 6386.8 6460.5 6267.3 6820.7 6837.9 6858.8 6869.7 6285.2 6293.9 6309.0 6446.6 6490.8 6532.2 6598.2 6660.3 6734.0 6768.7 6636.5 6730.2 6763.6 6781.7 6815.6 6424.0 6446.4 6471.3 6485.6 6487.9 4996.7 5000.3 5018.8 5030.4 5060.1 6442.4 6509.5 6566.5 6822.1 6856.2 6269.7 6519.2 6519.2 6540.2 6589.0 6446.9 6475.5 6583.9 6587.2 6603.2 6462.1 6499.2 6523.4 6525.2 6547.3 6665.2 6691.7 6703.5 6742.0 6745.5 6351.9 6393.8 6423.1 6469.3 6517.4 17:04:32 INFO - PID 15956 | 17:04:32 INFO - PID 15956 | [#5] yui.html Cycles:180 Average:23556.98 Median:18803.41 stddev:33750.59 (179.5%) stddev-sans-first:33802.40 17:04:32 INFO - PID 15956 | Values: 902.3 905.6 928.6 928.9 940.2 19090.7 20050.5 20082.6 20104.4 20138.7 18379.8 18548.7 18761.0 18845.8 19020.1 973.7 992.4 997.8 998.7 1001.1 948.1 989.7 990.7 992.0 993.1 1061.9 1062.0 1062.1 1064.6 1065.0 1036.9 1048.0 1048.3 1050.2 1052.4 717.5 734.8 749.7 750.1 777.8 17705.4 17719.9 17737.5 17795.6 17805.4 21398.1 21808.8 21817.5 22177.6 22328.0 17569.1 17801.6 17831.0 17849.8 17930.6 3620.1 3645.1 3646.2 3647.2 3648.9 129497.4 132336.0 134640.0 134834.3 137105.0 16498.0 16520.6 16570.1 16619.0 16776.5 30350.8 31231.2 31232.7 31238.4 31384.0 8641.5 8707.4 8731.7 8737.3 8751.5 4130.5 4240.6 4247.6 4251.0 4254.4 5194.7 5270.0 5279.3 5318.3 5331.9 977.7 979.9 1005.6 1006.1 1007.2 681.7 703.2 710.1 712.8 719.6 11698.4 11995.5 12223.5 12324.1 12628.8 690.4 703.5 707.0 712.6 719.0 5709.1 5717.5 5773.5 5827.0 5849.4 118970.0 119323.0 119796.0 120982.0 121051.0 97155.0 100481.5 100959.0 102485.5 102621.5 89284.6 91282.5 92064.0 93439.0 93725.5 10320.4 10370.2 10710.8 10860.5 10908.0 41954.6 42593.0 43791.3 44264.8 44790.6 52395.7 53836.5 53967.7 54848.7 55675.4 15078.4 15489.6 15540.1 15584.1 15587.3 16157.0 16194.0 16227.8 16384.8 16419.3 15672.3 15699.0 15729.5 15841.1 15922.8 15497.5 15763.4 15795.7 15906.2 15914.0 14780.8 14831.9 14876.9 15042.3 15058.3 11223.2 11482.4 11522.8 11530.6 11563.4 11620.5 11990.1 12103.6 12165.3 12176.4 17:04:32 INFO - PID 15956 | -------- Summary: end -------- 17:04:32 INFO - PID 15956 | 17:04:32 INFO - TEST-INFO | 15956: exit 0 17:04:32 INFO - TEST-OK | dromaeo_css | took 1110024ms 17:04:32 INFO - TEST-START | kraken 17:04:32 INFO - Initialising browser for kraken test... 17:04:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38474/getInfo.html -profile /tmp/tmp1A5G2i/profile 17:04:32 INFO - TEST-INFO | started process 16316 (/builds/slave/test/build/application/firefox/firefox http://localhost:38474/getInfo.html) 17:04:38 INFO - TEST-INFO | 16316: exit 0 17:04:38 INFO - Browser initialized. 17:04:38 INFO - Running cycle 1/1 for kraken test... 17:04:38 INFO - Using env: {'DISPLAY': ':0', 17:04:38 INFO - 'HOME': '/home/cltbld', 17:04:38 INFO - 'JSGC_DISABLE_POISONING': '1', 17:04:38 INFO - 'LANG': 'en_US.UTF-8', 17:04:38 INFO - 'LANGUAGE': 'en_US:en', 17:04:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:04:38 INFO - 'LOGNAME': 'cltbld', 17:04:38 INFO - 'MAIL': '/var/mail/cltbld', 17:04:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:04:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:04:38 INFO - 'MOZ_CRASHREPORTER': '1', 17:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:04:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:04:38 INFO - 'MOZ_NO_REMOTE': '1', 17:04:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:04:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:04:38 INFO - 'NO_EM_RESTART': '1', 17:04:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:04:38 INFO - 'PWD': '/builds/slave/test', 17:04:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:04:38 INFO - 'RUST_BACKTRACE': 'full', 17:04:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:04:38 INFO - 'SHELL': '/bin/bash', 17:04:38 INFO - 'SHLVL': '1', 17:04:38 INFO - 'TERM': 'linux', 17:04:38 INFO - 'TMOUT': '86400', 17:04:38 INFO - 'USER': 'cltbld', 17:04:38 INFO - 'XDG_SESSION_COOKIE': 'fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642', 17:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:04:38 INFO - '_': '/tools/buildbot/bin/python'} 17:04:38 INFO - TEST-INFO | started process 16549 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1A5G2i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:04:39 INFO - PID 16549 | 17:04:39 INFO - PID 16549 | (/builds/slave/test/build/application/firefox/firefox:16597): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:04:39 INFO - PID 16549 | 17:04:49 INFO - PID 16549 | RSS: Main: 151777280 17:04:49 INFO - PID 16549 | 17:04:52 INFO - PID 16549 | Cycle 1(1): loaded http://localhost:38474/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38474/tests/kraken/driver.html?testName=audio-beat-detection) 17:04:53 INFO - PID 16549 | RSS: Main: 157581312 17:04:53 INFO - PID 16549 | 17:04:56 INFO - PID 16549 | Cycle 1(1): loaded http://localhost:38474/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38474/tests/kraken/driver.html?testName=audio-dft) 17:04:56 INFO - PID 16549 | RSS: Main: 158355456 17:04:56 INFO - PID 16549 | 17:04:59 INFO - PID 16549 | Cycle 1(1): loaded http://localhost:38474/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38474/tests/kraken/driver.html?testName=audio-fft) 17:05:00 INFO - PID 16549 | RSS: Main: 155152384 17:05:00 INFO - PID 16549 | 17:05:02 INFO - PID 16549 | Cycle 1(1): loaded http://localhost:38474/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38474/tests/kraken/driver.html?testName=audio-oscillator) 17:05:03 INFO - PID 16549 | RSS: Main: 155062272 17:05:03 INFO - PID 16549 | 17:05:05 INFO - PID 16549 | Cycle 1(1): loaded http://localhost:38474/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38474/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:05:05 INFO - PID 16549 | RSS: Main: 155365376 17:05:05 INFO - PID 16549 | 17:05:09 INFO - PID 16549 | Cycle 1(1): loaded http://localhost:38474/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38474/tests/kraken/driver.html?testName=imaging-darkroom) 17:05:09 INFO - PID 16549 | RSS: Main: 157507584 17:05:09 INFO - PID 16549 | 17:05:13 INFO - PID 16549 | Cycle 1(1): loaded http://localhost:38474/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38474/tests/kraken/driver.html?testName=imaging-desaturate) 17:05:13 INFO - PID 16549 | RSS: Main: 157892608 17:05:13 INFO - PID 16549 | 17:05:16 INFO - PID 16549 | Cycle 1(1): loaded http://localhost:38474/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38474/tests/kraken/driver.html?testName=json-parse-financial) 17:05:17 INFO - PID 16549 | RSS: Main: 158183424 17:05:17 INFO - PID 16549 | 17:05:19 INFO - PID 16549 | Cycle 1(1): loaded http://localhost:38474/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38474/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:05:19 INFO - PID 16549 | RSS: Main: 157614080 17:05:19 INFO - PID 16549 | 17:05:21 INFO - PID 16549 | Cycle 1(1): loaded http://localhost:38474/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38474/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:05:21 INFO - PID 16549 | RSS: Main: 155000832 17:05:21 INFO - PID 16549 | 17:05:24 INFO - PID 16549 | Cycle 1(1): loaded http://localhost:38474/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38474/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:05:24 INFO - PID 16549 | RSS: Main: 154845184 17:05:24 INFO - PID 16549 | 17:05:27 INFO - PID 16549 | Cycle 1(1): loaded http://localhost:38474/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38474/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:05:27 INFO - PID 16549 | RSS: Main: 155115520 17:05:27 INFO - PID 16549 | 17:05:30 INFO - PID 16549 | Cycle 1(1): loaded http://localhost:38474/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38474/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:05:31 INFO - PID 16549 | RSS: Main: 155553792 17:05:31 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | Cycle 1(1): loaded http://localhost:38474/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38474/tests/kraken/driver.html?testName=ai-astar) 17:05:33 INFO - PID 16549 | RSS: Main: 156286976 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | __start_tp_report 17:05:33 INFO - PID 16549 | _x_x_mozilla_page_load 17:05:33 INFO - PID 16549 | _x_x_mozilla_page_load_details 17:05:33 INFO - PID 16549 | |i|pagename|runs| 17:05:33 INFO - PID 16549 | |0;ai-astar;109;106;108;106;107;109;110;108;108;106 17:05:33 INFO - PID 16549 | |1;audio-beat-detection;121;118;118;120;116;121;117;120;119;121 17:05:33 INFO - PID 16549 | |2;audio-dft;165;163;163;163;161;163;162;163;164;168 17:05:33 INFO - PID 16549 | |3;audio-fft;73;74;74;74;75;74;74;73;75;76 17:05:33 INFO - PID 16549 | |4;audio-oscillator;79;84;79;78;79;80;79;79;79;79 17:05:33 INFO - PID 16549 | |5;imaging-gaussian-blur;113;116;116;114;112;114;110;114;115;115 17:05:33 INFO - PID 16549 | |6;imaging-darkroom;153;149;148;148;148;148;148;148;147;152 17:05:33 INFO - PID 16549 | |7;imaging-desaturate;95;94;95;94;95;95;95;94;99;94 17:05:33 INFO - PID 16549 | |8;json-parse-financial;53;53;52;54;54;55;54;55;55;54 17:05:33 INFO - PID 16549 | |9;json-stringify-tinderbox;39;38;39;39;39;39;39;38;39;39 17:05:33 INFO - PID 16549 | |10;stanford-crypto-aes;76;73;73;75;74;72;76;72;76;73 17:05:33 INFO - PID 16549 | |11;stanford-crypto-ccm;113;113;112;110;119;112;120;113;111;113 17:05:33 INFO - PID 16549 | |12;stanford-crypto-pbkdf2;166;182;173;165;177;165;164;170;171;171 17:05:33 INFO - PID 16549 | |13;stanford-crypto-sha256-iterative;70;65;75;65;68;68;72;64;66;66 17:05:33 INFO - PID 16549 | __end_tp_report 17:05:33 INFO - PID 16549 | __start_cc_report 17:05:33 INFO - PID 16549 | _x_x_mozilla_cycle_collect,775 17:05:33 INFO - PID 16549 | __end_cc_report 17:05:33 INFO - PID 16549 | __startTimestamp1506557133514__endTimestamp 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | ------- Summary: start ------- 17:05:33 INFO - PID 16549 | Number of tests: 14 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | [#0] ai-astar Cycles:10 Average:107.70 Median:108.00 stddev:1.42 (1.3%) stddev-sans-first:1.42 17:05:33 INFO - PID 16549 | Values: 109.0 106.0 108.0 106.0 107.0 109.0 110.0 108.0 108.0 106.0 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | [#1] audio-beat-detection Cycles:10 Average:119.10 Median:120.00 stddev:1.79 (1.5%) stddev-sans-first:1.76 17:05:33 INFO - PID 16549 | Values: 121.0 118.0 118.0 120.0 116.0 121.0 117.0 120.0 119.0 121.0 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.00 stddev:1.90 (1.2%) stddev-sans-first:1.94 17:05:33 INFO - PID 16549 | Values: 165.0 163.0 163.0 163.0 161.0 163.0 162.0 163.0 164.0 168.0 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | [#3] audio-fft Cycles:10 Average:74.20 Median:74.00 stddev:0.92 (1.2%) stddev-sans-first:0.87 17:05:33 INFO - PID 16549 | Values: 73.0 74.0 74.0 74.0 75.0 74.0 74.0 73.0 75.0 76.0 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | [#4] audio-oscillator Cycles:10 Average:79.50 Median:79.00 stddev:1.65 (2.1%) stddev-sans-first:1.74 17:05:33 INFO - PID 16549 | Values: 79.0 84.0 79.0 78.0 79.0 80.0 79.0 79.0 79.0 79.0 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.50 stddev:1.85 (1.6%) stddev-sans-first:1.94 17:05:33 INFO - PID 16549 | Values: 113.0 116.0 116.0 114.0 112.0 114.0 110.0 114.0 115.0 115.0 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:148.00 stddev:1.97 (1.3%) stddev-sans-first:1.42 17:05:33 INFO - PID 16549 | Values: 153.0 149.0 148.0 148.0 148.0 148.0 148.0 148.0 147.0 152.0 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:1.49 (1.6%) stddev-sans-first:1.58 17:05:33 INFO - PID 16549 | Values: 95.0 94.0 95.0 94.0 95.0 95.0 95.0 94.0 99.0 94.0 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:0.99 (1.8%) stddev-sans-first:1.00 17:05:33 INFO - PID 16549 | Values: 53.0 53.0 52.0 54.0 54.0 55.0 54.0 55.0 55.0 54.0 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | [#9] json-stringify-tinderbox Cycles:10 Average:38.80 Median:39.00 stddev:0.42 (1.1%) stddev-sans-first:0.44 17:05:33 INFO - PID 16549 | Values: 39.0 38.0 39.0 39.0 39.0 39.0 39.0 38.0 39.0 39.0 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:74.50 stddev:1.63 (2.2%) stddev-sans-first:1.56 17:05:33 INFO - PID 16549 | Values: 76.0 73.0 73.0 75.0 74.0 72.0 76.0 72.0 76.0 73.0 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | [#11] stanford-crypto-ccm Cycles:10 Average:113.60 Median:113.00 stddev:3.27 (2.9%) stddev-sans-first:3.46 17:05:33 INFO - PID 16549 | Values: 113.0 113.0 112.0 110.0 119.0 112.0 120.0 113.0 111.0 113.0 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.40 Median:171.00 stddev:5.82 (3.4%) stddev-sans-first:5.95 17:05:33 INFO - PID 16549 | Values: 166.0 182.0 173.0 165.0 177.0 165.0 164.0 170.0 171.0 171.0 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:3.51 (5.2%) stddev-sans-first:3.64 17:05:33 INFO - PID 16549 | Values: 70.0 65.0 75.0 65.0 68.0 68.0 72.0 64.0 66.0 66.0 17:05:33 INFO - PID 16549 | -------- Summary: end -------- 17:05:33 INFO - PID 16549 | 17:05:33 INFO - PID 16549 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:05:34 INFO - TEST-INFO | 16549: exit 0 17:05:34 INFO - TEST-OK | kraken | took 61245ms 17:05:34 INFO - SUITE-END | took 1171s 17:05:34 INFO - Completed test suite (00:19:31) 17:05:34 INFO - javascript benchmark 17:05:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8513.527429083726, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3250.154959710475, 3259.6577359377266, 3289.0626171541107, 3294.4399452093144, 3303.752218583607, 6268.968655156729, 6360.745570177199, 6376.872462550752, 6393.872122557552, 6477.935220647792, 6325.304216536178, 6335.651539165344, 6337.556371054028, 6372.394622510854, 6402.679866006704, 5791.623544469596, 5791.971040144815, 5819.883602327962, 5854.063349864023, 5856.56075794315, 5908.852278693024, 5926.762929482816, 5944.732487038072, 5981.58128930975, 6015.157877897098, 5848.824535263949, 5852.385499522553, 5912.5861189716525, 5925.703714814242, 5933.851653708649, 5636.323641163046, 5742.109972954198, 5776.59563830532, 5834.212381328509, 5959.165716799652, 5691.26013618227, 5764.538836893039, 5839.0949402842625, 5840.532757379399, 5855.0, 6174.073888916699, 6282.96858515709, 6337.556371054028, 6394.488440924715, 6418.390252926011, 6664.8000559983275, 6680.899786503206, 6687.933120668779, 6688.83277918051, 6708.329167083301, 6752.2234942981695, 6879.071325371061, 6985.685644146026, 6993.615351155684, 7003.5097543172, 6362.141110949962, 6368.649724265164, 6396.712147953353, 6433.260175079832, 6450.387213214737, 6927.134108236471, 7068.92931070693, 7073.328033836778, 7169.964150179216, 7174.820629484305, 6687.164104486939, 6730.730770769114, 6740.696668649923, 6785.117934668461, 6798.354156355138, 6829.0, 6881.13985751781, 6914.585124892523, 6925.58446493202, 6929.099217101744, 6808.2170550386345, 6825.897611535831, 6898.413634840995, 6907.516473846883, 6918.169819621577, 6673.232578253466, 6787.762428314985, 6793.796186114425, 6817.0, 6884.208316043619, 5060.240963855451, 5068.189089745697, 5076.162433198481, 5080.212567052039, 5085.440601533828, 4441.955580444219, 4449.354843547668, 4455.977720111378, 4467.0, 4469.5530446955045, 5782.479576838105, 5864.736086876015, 5934.821955341346, 5940.495057920039, 5943.465088142088, 6647.468202543785, 6757.526973111933, 6828.283030281848, 6892.138482689664, 6962.0, 5862.062070068854, 5874.676892770939, 5886.911696324559, 5887.1463637772285, 5891.145783861316, 5781.19063331134, 5862.530997520188, 5882.735276912549, 5914.260717410324, 5946.43508866657, 6476.319986401454, 6669.466442684574, 6669.899951500732, 6693.966530167318, 6714.798556043326, 6869.107016087876, 6918.0, 6927.134108236471, 6966.373026427443, 7004.15950085993, 6414.550981431255, 6505.642189679613, 6508.967455162694, 6574.704138313692, 6698.296678848846, 6668.199816022108, 6673.899891501728, 6679.0649309097735, 6679.165104361955, 6771.62756048422, 6910.481713871581, 6936.271691472322, 6943.722251109899, 6958.721651133898, 6964.512484126062, 6646.2356828965285, 6649.301823308483, 6663.13379260693, 6751.358620931003, 6756.087928129639, 6641.136652235178, 6651.368120028589, 6665.300143484864, 6766.255711871789, 6791.388775010273, 6289.213848268966, 6406.5515413920575, 6408.743650254124, 6417.96791016042, 6650.567713098633, 6612.768553100618, 6621.370969757865, 6641.0038494226255, 6792.558483698544, 6793.93206067933, 6525.673716314261, 6527.738890444329, 6534.509911756542, 6539.411452969255, 6554.770583029571], "value": 6206.65788945559, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [848.6944699908029, 877.035261212666, 895.5925054100381, 896.0457113174468, 904.4980036079977, 39492.0, 40202.19595608086, 40246.59753402469, 40309.798451007744, 40377.0, 37521.81239093801, 39434.21131577367, 40234.79882600583, 40239.79880100603, 40241.396379054335, 7838.764837054883, 7999.240072193162, 8002.519848809061, 8003.559804210795, 8034.839303213932, 7856.371490280792, 8021.077576078767, 8021.799455013613, 8029.197080291982, 8033.236842499982, 7999.880001799977, 8065.2741253287195, 8090.9886264216975, 8097.2307630774985, 8101.068377136646, 8008.799780005488, 8020.197980201991, 8033.598320084031, 8040.517568945824, 8048.114707382183, 7880.330171935392, 7940.761777146695, 7945.324647404977, 7978.720744773963, 7993.600319983978, 36220.0, 36239.27521449556, 36322.81838590817, 36381.27237455236, 36497.0, 3555.2000799820094, 3589.7128229741556, 3593.281343731238, 3603.6036036036016, 3609.657082577151, 29941.850290748407, 29955.25111872199, 29963.550546741815, 30002.699973000428, 30207.848960755055, 4971.304017437562, 5196.428392876781, 5207.531322180984, 5228.320318358589, 5280.260763493114, 180175.0, 181847.0, 181935.0, 182029.0, 182097.0, 44872.3269150963, 44932.3260151098, 44955.55044449579, 45017.324740128926, 45109.097818043454, 46886.296705549445, 51768.0, 52404.73797631064, 52529.0, 52588.73705631447, 15940.601484962968, 16020.359185632677, 16077.0, 16111.516654500383, 16173.59566010859, 13109.60671179866, 13170.47318107265, 13189.340532973312, 13219.074664773374, 13228.669283267995, 13911.443542258196, 14050.859491404955, 14079.929600351932, 14118.788218176736, 14265.714685706434, 5576.375356018621, 5637.323521177403, 5675.432456754292, 5681.77272909079, 5738.225339579187, 974.449436068619, 1005.1305620638095, 1013.6097602423121, 1013.7986486013949, 1023.0843395161289, 22218.888905555694, 22258.777412225994, 22459.0, 22570.0, 22631.0, 1107.335598640825, 1108.296231892754, 1117.675873996532, 1120.708615759892, 1124.4321617583068, 11829.822552661717, 11914.46384912681, 11961.282323060645, 12066.913977742046, 12233.3271670059, 221233.0, 222381.0, 222921.0, 223708.0, 223892.0, 43045.56954430478, 47912.28131578029, 48773.51226487761, 49506.75246623816, 50016.49983500191, 1435.6769726811592, 1468.1924941282252, 1470.7132109238596, 1484.547213100015, 1489.3149151390264, 25460.618090729025, 26262.474750505266, 26284.21147365582, 26356.0, 26428.339291517867, 35141.47287790634, 35230.11924701903, 35250.47124293189, 35253.0, 35273.47089793706, 172763.0, 173498.0, 173499.0, 173553.13223433803, 174090.0, 32481.187970300933, 32504.674953250164, 32641.510377343882, 33008.66991330056, 33071.33857322888, 39649.60350396574, 40652.79673601613, 40747.1850562993, 40968.180636387704, 40996.385054224804, 28729.281767955967, 30098.518966525473, 30246.09261722152, 30284.697153028188, 31084.0, 29756.256093597833, 29794.553081703358, 29860.552091718208, 30122.69877301199, 30512.0, 33765.0, 33808.830955845064, 33878.830605846815, 34053.31893362069, 34070.48894266637, 29502.114936551938, 29797.70202298036, 29929.251768705955, 29967.4006519864, 30061.69938300589, 22668.65997010079, 22784.202552911232, 22858.885705571367, 22885.199018034957, 23101.19145829888], "value": 16835.199043747478, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.9490893274071, 601.6450294326348, 602.8372339468347, 609.2019454514586, 612.7365232949829, 22074.33776986691, 22852.31443056709, 22855.77144228557, 22885.656715149285, 22893.4276643084, 24210.27369178923, 24296.0, 25680.871595642042, 25841.095561655347, 25890.352741181432, 6690.464762818987, 6829.282925292847, 6863.279355667658, 6874.862502749942, 6875.759348422806, 6475.352464753534, 6716.429103526206, 6770.458363330922, 6822.590644561342, 6825.078614387068, 6711.5301928865, 6717.0, 6717.0, 6719.63042032688, 6720.899186512206, 6667.6666166691475, 6704.229013663418, 6711.664416779142, 6714.56355336904, 6717.697703603325, 6228.813135605939, 6230.376962303779, 6237.937620623781, 6244.313125556185, 6249.78125765596, 29118.85440572805, 29480.26299342512, 29740.0, 29911.252218694488, 29976.70023299761, 2056.249468943825, 2087.269455690511, 2093.7906209379094, 2099.370188943326, 2100.2754049852774, 18914.621707565908, 19389.418317450498, 19452.0, 19492.31776887816, 19511.512212194666, 2360.8870061295215, 2429.9028038878423, 2450.4486490539666, 2470.295965649781, 2475.789557887582, 121808.0, 126347.0, 136357.0, 143593.28203359124, 144143.0, 14995.775063374058, 15148.0, 15219.847801522064, 15393.615159621098, 15512.767308490382, 24066.51866962651, 24195.0, 24320.391990200387, 24444.877775611007, 24447.63328550073, 10207.336523125972, 10356.443165007373, 10423.42671153094, 10490.370577765358, 10548.103411210133, 9523.333366664405, 9571.61713531465, 9591.088846559565, 9596.472194029395, 9672.75818104553, 9437.386569872937, 9603.0230628504, 9648.131668149901, 9661.178799802095, 9677.419354838732, 2799.80401371902, 2816.1129244288395, 2818.126380821952, 2830.6461692288462, 2878.2228798224364, 982.832918403873, 996.5316301338504, 997.7904640025675, 997.9401235925868, 1004.4726518577805, 19765.40703778889, 19877.701834472493, 20010.69983950242, 20142.295019674242, 20465.07907144182, 1035.5236591167995, 1041.598984391015, 1057.1384321777728, 1061.3525749292926, 1062.3201151263197, 10925.672229833117, 11007.944960275147, 11090.390028548345, 11108.333499989865, 11249.232121542109, 513508.0, 514425.0, 520176.0, 521486.0, 526600.0, 107253.46373268084, 108552.45723771489, 108735.45632271946, 110219.0, 114642.0, 1058.5025038232018, 1115.2416356877402, 1130.7625398666207, 1161.96514104577, 1205.102198861836, 20557.0, 20788.27241046556, 20798.792012080292, 20843.270485532932, 21032.474188144806, 53217.73391133019, 54076.18885716639, 54657.0, 54823.0, 55646.72176639091, 67283.66358168177, 68036.31963680498, 68220.3177968214, 68455.0, 68475.31524684689, 14711.0, 14833.703325933637, 15067.623309417353, 15069.623259418164, 15092.39630414771, 23102.0, 23358.18246361369, 23424.53150937006, 23436.531269374856, 23718.762812371657, 18626.2549498024, 18735.250589976247, 18906.14922328471, 18954.620907582048, 19024.0, 16571.585710357336, 16620.168991550134, 16814.495565133064, 16861.747073794148, 17281.65436691234, 18811.435656930313, 18833.90583047076, 18879.528011799815, 19194.424167275003, 19213.711794322815, 14676.486322978644, 14677.633059173606, 14738.410463581336, 14742.189179595225, 14899.0, 18101.18544665467, 18236.45290641283, 18368.17343219585, 18399.264029439208, 18554.165062571945], "value": 12168.409988671523, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.0498084291187, 1376.8210132682757, 1393.2546087843007, 1401.3273628658237, 1402.0466083063516, 15071.773923391158, 15329.46346877858, 16526.917365413185, 16565.171741412913, 16582.66834663306, 16133.757993630075, 16444.260008299625, 16446.506604801845, 16485.34058637653, 16497.91751041243, 2560.8207425480223, 2661.0, 2766.6265054217724, 2833.7166283371603, 2833.943321133576, 2747.7801775857984, 2782.123631056211, 2785.958210626832, 2786.6656001279775, 2787.205646390779, 2706.188143556935, 2749.3264150283107, 2754.4215714699935, 2758.2001219646277, 2759.323965628424, 2692.17888543994, 2703.297142742891, 2727.359070618403, 2735.5076086304457, 2736.0287098080194, 2572.7041390240174, 2609.8434093954424, 2657.548216803148, 2660.268426182796, 2675.077098401068, 14399.856001440061, 14718.632034199123, 14728.779068313877, 14737.926310368486, 14740.189289589069, 8057.556834374107, 8286.047104582989, 8294.543800090993, 8325.042620098706, 8338.666453341859, 5294.523492885659, 5456.181572764117, 5459.3994660587305, 5572.832815015556, 5581.05122129239, 1973.5460844005815, 1987.473319570315, 1994.4315869976988, 1996.4709352021725, 2030.3604364625096, 27250.5912411314, 27253.454930901273, 27370.45259094807, 27415.0, 27494.312642184104, 6779.2881747416795, 6809.625470599066, 6814.829629259309, 6856.074429951893, 6915.135608048994, 17764.0, 17764.289428422977, 17765.911170444324, 17783.0, 17790.733139002925, 7776.183500732342, 7960.641771120314, 7979.521228726295, 7988.560629165451, 8008.359331253486, 5624.91562626561, 6467.902981455282, 6479.481641468671, 7368.042154519985, 7410.5183163094225, 4947.332110165154, 5052.090623687698, 5114.590832733373, 5141.280220769096, 5186.066508028515, 1536.5467187179809, 1604.5186444066733, 1627.2189349112493, 1627.462937230711, 1635.1088656682136, 894.5214310344065, 900.9278958039912, 907.2696479334068, 917.4403613795579, 923.9815203696023, 7632.236776322435, 7647.502912310682, 7658.387329013665, 7712.074551053909, 7730.690772369155, 699.3076853914689, 711.8078118907866, 730.8720973829559, 741.2587412587412, 750.5009168902727, 5108.208227724708, 5180.533751962342, 5207.765650545659, 5285.497877701612, 5396.19057141424, 21390.57163513082, 21696.566068678538, 21979.560408791735, 22199.223027194294, 22202.0, 21877.562448750938, 22472.0, 22495.100195992305, 22537.323880283617, 22548.0980760771, 10282.845757313646, 11014.724631884266, 11123.221374503708, 11130.332180069221, 11137.665870023913, 8215.753527394188, 8249.670013199404, 8269.83460330802, 8305.543195124326, 8385.706500272461, 18666.0, 18837.15232814554, 18841.340553081125, 18842.3405180818, 19154.71267930954, 19250.518737031685, 19460.708089378386, 19969.60060798805, 20030.198792048155, 20426.89786551058, 11110.222284440011, 11217.887821121685, 11372.658820235407, 11380.146489013192, 11381.317120972768, 13885.166889986633, 14173.645658858612, 14173.787393188904, 14188.929055354658, 14194.290285485478, 11199.10407167441, 11364.318140911571, 11379.886201138213, 11623.88376116228, 11713.297202167898, 11116.777664446827, 11220.270682405617, 11279.041281491165, 11384.772304554028, 11581.82627260575, 10118.291719579853, 10174.13519850821, 10201.795964080527, 10207.591696332063, 10209.489525523546, 7226.7109315626785, 7234.02340684001, 7234.204237533972, 7239.8914016288745, 7242.529235599669, 7238.457115716447, 7315.890261646186, 7333.989104871705, 7358.22738612438, 7445.329920307198], "value": 6686.329373578115, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.4418140045948, 525.8632755483574, 546.6610701365153, 548.3502050070664, 557.180944012302, 6344.0, 6355.618662880224, 6356.904646430307, 6362.491000719943, 6449.903251451232, 6247.6251424914535, 6295.0, 6306.59007164534, 6319.620822750639, 6368.866429746029, 5166.055348451394, 5188.481151884819, 5190.740462976836, 5196.948030519703, 5203.245529398235, 4770.61835053195, 4968.329275547809, 5098.541131298182, 5128.333316668846, 5171.500264923167, 4950.752462376885, 4964.156093464102, 4967.3542439483, 4977.129002424579, 5000.44995050546, 4893.779779909913, 4938.703677779345, 4957.479464656195, 4966.106100901836, 4968.428630707479, 4356.0, 4456.420665313521, 4464.397306363632, 4477.9104417911785, 4492.550744925514, 6081.756729730805, 6139.539534534883, 6158.353372895871, 6160.168377269101, 6170.7531698732, 5642.689652069134, 5648.717564121818, 5674.801381951612, 5689.0328644130595, 5725.427457254283, 6621.73513059477, 6650.035744816974, 6663.833404164886, 6668.233153187397, 6708.798736037927, 4668.206404911173, 4917.164082106051, 4917.385326834145, 4925.359703238556, 4926.778294976734, 6728.333058483138, 6797.320267973163, 6804.325664141407, 6881.724731010703, 6910.032595436593, 4951.826686066042, 4964.553190212898, 5005.074061298671, 5018.648694591417, 5052.873678158076, 5076.187809950464, 5117.8464646060675, 5118.129917913963, 5128.640995130305, 5129.538341549279, 5639.364584270597, 5644.6613203207935, 5690.772369105272, 5714.771409143587, 5728.885422291531, 6640.136782218377, 6682.758861951949, 6682.83292917681, 6705.262421133769, 6723.0, 1715.6997525432998, 1723.8534724548304, 1763.2329936477672, 1778.315348590802, 1780.777402824647, 1158.6697791129488, 1219.4634360881184, 1227.619437974213, 1235.6540168752763, 1241.9068569857116, 5619.925553973337, 5646.887062258814, 5674.751554657969, 5721.027425337703, 5821.505172060422, 6549.365102368231, 6585.9012114818315, 6590.736370545124, 6597.670116494252, 6642.535022548472, 5575.609707320448, 5601.243832082617, 5678.801241956512, 5679.943200568024, 5702.572307076985, 3510.3330367230146, 3519.1026288296835, 3546.8226588670977, 3550.9644903551152, 3552.3605750964557, 6327.354887729131, 6367.395097465734, 6372.7132279047555, 6386.808395748135, 6460.515461340418, 6267.341929097471, 6820.6930688119155, 6837.863242735118, 6858.794236172923, 6869.656517174121, 6285.245770507477, 6293.874122517524, 6308.968455157695, 6446.580972236789, 6490.766754316664, 6532.216134063943, 6598.175228096488, 6660.300668429745, 6734.0, 6768.729250830109, 6636.539961208527, 6730.158730158731, 6763.560368576027, 6781.728730850711, 6815.591064536144, 6423.96788016057, 6446.387593178828, 6471.255805582418, 6485.578437401554, 6487.929491633828, 4996.700197987987, 5000.274960130833, 5018.824341148043, 5030.37120359955, 5060.063888180698, 6442.420182183626, 6509.544331896722, 6566.474682025331, 6822.11312529368, 6856.211535673262, 6269.655168965751, 6519.152510173647, 6519.152510173647, 6540.247871494648, 6589.04458853454, 6446.903296450463, 6475.546711730133, 6583.868322633616, 6587.176602924635, 6603.174603174603, 6462.063000864942, 6499.1876015498065, 6523.412892839668, 6525.184351956005, 6547.279799222177, 6665.166854143232, 6691.698873550799, 6703.497237707289, 6741.966290168518, 6745.494087943522, 6351.904721429275, 6393.808185754435, 6423.100765892872, 6469.2560355557835, 6517.4460170886005], "value": 5066.191863116913, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [902.2601466797221, 905.6105874473977, 928.5960607135892, 928.8885333759949, 940.2384068904188, 19090.713639295405, 20050.49873753157, 20082.59834803307, 20104.39686809396, 20138.69791953122, 18379.816201837977, 18548.721769173473, 18761.0, 18845.81154188461, 19020.14409351579, 973.7370909854318, 992.3599278465385, 997.840345544713, 998.6754304850916, 1001.1240164855751, 948.0708905272802, 989.6882482018141, 990.6829814459376, 991.9652812151576, 993.0913214408807, 1061.9044286014257, 1061.9893801061967, 1062.1449732964961, 1064.5528877871275, 1065.0255016659746, 1036.9014943580348, 1047.9685609431654, 1048.3238311289215, 1050.1861057680283, 1052.4053909541085, 717.4834119433999, 734.7501849371239, 749.7076140305285, 750.1186106325047, 777.801660576555, 17705.38031168903, 17719.911400443045, 17737.467875963743, 17795.644087118315, 17805.376811811653, 21398.14407423701, 21808.781912180835, 21817.454563635878, 22177.556448871095, 22328.0, 17569.12154392288, 17801.554961126076, 17831.01929393883, 17849.821501784947, 17930.641387172313, 3620.149264922741, 3645.143391303042, 3646.179609587848, 3647.1611529348133, 3648.945265821015, 129497.35251323777, 132336.0, 134640.0, 134834.32582837122, 137104.999999999, 16498.0, 16520.586985325463, 16570.088645124393, 16619.0, 16776.496705098867, 30350.84824575863, 31231.219219519695, 31232.687673123433, 31238.375232495222, 31384.0, 8641.524716140673, 8707.434016789015, 8731.738047858575, 8737.30101591871, 8751.474911505331, 4130.545639979599, 4240.554741752072, 4247.5752424757275, 4251.043515209115, 4254.425652536868, 5194.714290713971, 5269.973650131725, 5279.287296215039, 5318.335208098988, 5331.893362132736, 977.7164622259608, 979.9020097990144, 1005.5726316315537, 1006.0995409108807, 1007.2345017786535, 681.6591704147926, 703.2229386527915, 710.1265443504415, 712.8146681862753, 719.5934297122213, 11698.35659038744, 11995.520179192908, 12223.511059557695, 12324.137310388187, 12628.81056784149, 690.3683129936153, 703.4513079797766, 706.9858602827915, 712.593821521738, 718.9676464559108, 5709.0865461525955, 5717.542596592262, 5773.509251713652, 5826.970865145732, 5849.356570777211, 118970.0, 119323.0, 119796.0, 120982.0, 121051.0, 97155.0, 100481.49759251303, 100959.0, 102485.48757256166, 102621.48689256507, 89284.5535772317, 91282.54358728165, 92064.0, 93439.0, 93725.5313723427, 10320.43237621938, 10370.170386369224, 10710.785784284426, 10860.456977150952, 10908.018278354986, 41954.580454195064, 42593.0, 43791.34312985244, 44264.77867610641, 44790.55209447864, 52395.73802130965, 53836.461635383144, 53967.73016134894, 54848.725756370965, 55675.443245567025, 15078.396864125312, 15489.612759680647, 15540.145292009049, 15584.142872141687, 15587.298571564534, 16157.030578165348, 16194.0, 16227.837721622633, 16384.83615163833, 16419.343226271292, 15672.294746736196, 15699.0, 15729.528114156594, 15841.128737919526, 15922.840771592599, 15497.45758898433, 15763.448279310169, 15795.684086318439, 15906.204689765234, 15914.0, 14780.778288325899, 14831.92584037073, 14876.851231487546, 15042.323095460511, 15058.322375493348, 11223.158263130135, 11482.368469734245, 11522.827157592475, 11530.59642912494, 11563.42182890869, 11620.535178592761, 11990.100742444525, 12103.636890893287, 12165.33090680021, 12176.391180440765], "value": 8840.009997351166, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1420.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 106.0, 108.0, 106.0, 107.0, 109.0, 110.0, 108.0, 108.0, 106.0], "value": 107.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 118.0, 118.0, 120.0, 116.0, 121.0, 117.0, 120.0, 119.0, 121.0], "value": 119.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 163.0, 163.0, 161.0, 163.0, 162.0, 163.0, 164.0, 168.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 74.0, 75.0, 74.0, 74.0, 73.0, 75.0, 76.0], "value": 74.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 84.0, 79.0, 78.0, 79.0, 80.0, 79.0, 79.0, 79.0, 79.0], "value": 79.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 116.0, 114.0, 112.0, 114.0, 110.0, 114.0, 115.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 149.0, 148.0, 148.0, 148.0, 148.0, 148.0, 148.0, 147.0, 152.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 99.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 52.0, 54.0, 54.0, 55.0, 54.0, 55.0, 55.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 39.0, 39.0, 39.0, 39.0, 39.0, 38.0, 39.0, 39.0], "value": 38.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 73.0, 75.0, 74.0, 72.0, 76.0, 72.0, 76.0, 73.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 112.0, 110.0, 119.0, 112.0, 120.0, 113.0, 111.0, 113.0], "value": 113.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 182.0, 173.0, 165.0, 177.0, 165.0, 164.0, 170.0, 171.0, 171.0], "value": 170.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 65.0, 75.0, 65.0, 68.0, 68.0, 72.0, 64.0, 66.0, 66.0], "value": 67.9, "unit": "score"}]}]} 17:05:34 INFO - Return code: 0 17:05:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:05:34 INFO - # TBPL SUCCESS # 17:05:34 INFO - Running post-action listener: _package_coverage_data 17:05:34 INFO - Running post-action listener: _resource_record_post_action 17:05:34 INFO - [mozharness: 2017-09-28 00:05:34.806163Z] Finished run-tests step (success) 17:05:34 INFO - Running post-run listener: _resource_record_post_run 17:05:34 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 341721088; Read time: 8; Write time: 193684 17:05:34 INFO - TinderboxPrint: CPU usage
13.1% 17:05:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 17:05:34 INFO - TinderboxPrint: I/O write bytes / time
341,721,088 / 193,684 17:05:34 INFO - TinderboxPrint: CPU idle
8,245.3 (86.9%) 17:05:34 INFO - TinderboxPrint: CPU user
1,193.7 (12.6%) 17:05:34 INFO - TinderboxPrint: Swap in / out
0 / 0 17:05:34 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10133504; Read time: 0; Write time: 1116 17:05:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:05:34 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 331587584; Read time: 0; Write time: 192568 17:05:35 INFO - Running post-run listener: _upload_blobber_files 17:05:35 INFO - Blob upload gear active. 17:05:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:05:35 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 17:05:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:05:35 INFO - (blobuploader) - INFO - Open directory for files ... 17:05:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:05:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:05:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:36 INFO - (blobuploader) - INFO - Done attempting. 17:05:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:05:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:05:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:37 INFO - (blobuploader) - INFO - Done attempting. 17:05:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:05:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:05:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:44 INFO - (blobuploader) - INFO - Done attempting. 17:05:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:05:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:05:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:45 INFO - (blobuploader) - INFO - Done attempting. 17:05:45 INFO - (blobuploader) - INFO - Iteration through files over. 17:05:45 INFO - Return code: 0 17:05:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:05:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:05:45 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa84724f43c4e812ec97bb9c31f2127956e2bf19a73c4ea78766ddcc34858939794e917db3c33b16b53e4c2e1f34778c8c28a95cf786d71f3cae2aaf14ae6ae4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c7fdac55b546416b6432e81f2e75f2557254bcdb62783b50a8088d741764b8e7f25a1e313c532d6512472bb11872ae47749dc5881f476b5c01dc33a0b42c02c1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4bcf5f328dfb5b3ed17b4650e2e6da9d819be0473c6eaa43dcc595da4949ed1ab0c6a795b63a21054b28e18f19d202103fef8c90e37a96e0beb667f0b75b6478", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5cdb6d0e186577af2fdb4d9ee5f1324eb96d91eb2a490e5f566f279bde208ae8334194eff53338bf1b80854fbab95ebcdac63861b3607c8f074af73e77daee26"} 17:05:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:05:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:05:45 INFO - Contents: 17:05:45 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa84724f43c4e812ec97bb9c31f2127956e2bf19a73c4ea78766ddcc34858939794e917db3c33b16b53e4c2e1f34778c8c28a95cf786d71f3cae2aaf14ae6ae4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c7fdac55b546416b6432e81f2e75f2557254bcdb62783b50a8088d741764b8e7f25a1e313c532d6512472bb11872ae47749dc5881f476b5c01dc33a0b42c02c1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4bcf5f328dfb5b3ed17b4650e2e6da9d819be0473c6eaa43dcc595da4949ed1ab0c6a795b63a21054b28e18f19d202103fef8c90e37a96e0beb667f0b75b6478", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5cdb6d0e186577af2fdb4d9ee5f1324eb96d91eb2a490e5f566f279bde208ae8334194eff53338bf1b80854fbab95ebcdac63861b3607c8f074af73e77daee26"} 17:05:45 INFO - Running post-run listener: copy_logs_to_upload_dir 17:05:45 INFO - Copying logs to upload dir... 17:05:45 INFO - mkdir: /builds/slave/test/build/upload/logs 17:05:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.314384 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-27 17:05:45.978528) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 17:05:45.982984) ========= 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=fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa84724f43c4e812ec97bb9c31f2127956e2bf19a73c4ea78766ddcc34858939794e917db3c33b16b53e4c2e1f34778c8c28a95cf786d71f3cae2aaf14ae6ae4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c7fdac55b546416b6432e81f2e75f2557254bcdb62783b50a8088d741764b8e7f25a1e313c532d6512472bb11872ae47749dc5881f476b5c01dc33a0b42c02c1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4bcf5f328dfb5b3ed17b4650e2e6da9d819be0473c6eaa43dcc595da4949ed1ab0c6a795b63a21054b28e18f19d202103fef8c90e37a96e0beb667f0b75b6478", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5cdb6d0e186577af2fdb4d9ee5f1324eb96d91eb2a490e5f566f279bde208ae8334194eff53338bf1b80854fbab95ebcdac63861b3607c8f074af73e77daee26"} build_url:https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011456 build_url: 'https://queue.taskcluster.net/v1/task/dp8k4SKiS8qEBmLBTOvhFQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa84724f43c4e812ec97bb9c31f2127956e2bf19a73c4ea78766ddcc34858939794e917db3c33b16b53e4c2e1f34778c8c28a95cf786d71f3cae2aaf14ae6ae4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c7fdac55b546416b6432e81f2e75f2557254bcdb62783b50a8088d741764b8e7f25a1e313c532d6512472bb11872ae47749dc5881f476b5c01dc33a0b42c02c1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4bcf5f328dfb5b3ed17b4650e2e6da9d819be0473c6eaa43dcc595da4949ed1ab0c6a795b63a21054b28e18f19d202103fef8c90e37a96e0beb667f0b75b6478", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5cdb6d0e186577af2fdb4d9ee5f1324eb96d91eb2a490e5f566f279bde208ae8334194eff53338bf1b80854fbab95ebcdac63861b3607c8f074af73e77daee26"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 17:05:46.017358) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:05:46.021808) ========= 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=fcadb68e09dcf59683000ff6000001dc-1506555902.709872-1554159642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004484 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 17:05:46.071335) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 17:05:46.073698) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 17:05:46.074042) ========= ========= Total master_lag: 0.30 =========