builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-066 starttime: 1505066783.01 results: success (0) revision: fd87bb184e299fec695f69bd2977276c25719b98 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-10 11:06:23.006520) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-10 11:06:23.006977) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-10 11:06:23.007256) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-10 11:06:23.027956) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 11:06:23.028225) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-10 11:06:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 20.9M=0.001s 2017-09-10 11:06:23 (20.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.112547 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 11:06:23.168768) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 11:06:23.169427) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017589 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 11:06:23.231034) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 11:06:23.231365) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fd87bb184e299fec695f69bd2977276c25719b98 --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 fd87bb184e299fec695f69bd2977276c25719b98 --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=82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-10 11:06:23,290 truncating revision to first 12 chars 2017-09-10 11:06:23,291 Setting DEBUG logging. 2017-09-10 11:06:23,291 attempt 1/10 2017-09-10 11:06:23,291 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fd87bb184e29?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-10 11:06:23,543 unpacking tar archive at: mozilla-central-fd87bb184e29/testing/mozharness/ program finished with exit code 0 elapsedTime=0.553525 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 11:06:23.802187) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-10 11:06:23.802517) ========= script_repo_revision: fd87bb184e299fec695f69bd2977276c25719b98 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-10 11:06:23.802906) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-10 11:06:23.803170) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-10 11:06:23.819233) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-10 11:06:23.819528) ========= /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=82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:06:24 INFO - MultiFileLogger online at 20170910 11:06:24 in /builds/slave/test 11:06:24 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 11:06:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:06:24 INFO - {'append_to_log': False, 11:06:24 INFO - 'base_work_dir': '/builds/slave/test', 11:06:24 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:06:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:06:24 INFO - 'branch': 'Firefox-Non-PGO', 11:06:24 INFO - 'buildbot_json_path': 'buildprops.json', 11:06:24 INFO - 'code_coverage': False, 11:06:24 INFO - 'config_files': ('talos/linux_config.py',), 11:06:24 INFO - 'default_actions': ('clobber', 11:06:24 INFO - 'read-buildbot-config', 11:06:24 INFO - 'download-and-extract', 11:06:24 INFO - 'populate-webroot', 11:06:24 INFO - 'create-virtualenv', 11:06:24 INFO - 'install', 11:06:24 INFO - 'setup-mitmproxy', 11:06:24 INFO - 'run-tests'), 11:06:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:06:24 INFO - 'disable_ccov_upload': False, 11:06:24 INFO - 'disable_stylo': False, 11:06:24 INFO - 'download_minidump_stackwalk': True, 11:06:24 INFO - 'download_symbols': 'ondemand', 11:06:24 INFO - 'enable_stylo': False, 11:06:24 INFO - 'enable_webrender': False, 11:06:24 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:06:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:06:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:06:24 INFO - 'gecko_profile': False, 11:06:24 INFO - 'gecko_profile_interval': 0, 11:06:24 INFO - 'installer_path': 'installer.exe', 11:06:24 INFO - 'log_level': 'info', 11:06:24 INFO - 'log_name': 'talos', 11:06:24 INFO - 'log_to_console': True, 11:06:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:06:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:06:24 INFO - 'opt_config_files': (), 11:06:24 INFO - 'pip_index': False, 11:06:24 INFO - 'suite': 'dromaeojs-e10s', 11:06:24 INFO - 'system_bits': '32', 11:06:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:06:24 INFO - 'title': 'talos-linux64-ix-066', 11:06:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:06:24 INFO - 'use_talos_json': True, 11:06:24 INFO - 'verify': 'False', 11:06:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:06:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:06:24 INFO - 'work_dir': 'build'} 11:06:24 INFO - [mozharness: 2017-09-10 18:06:24.027290Z] Running clobber step. 11:06:24 INFO - Running pre-action listener: _resource_record_pre_action 11:06:24 INFO - Running main action method: clobber 11:06:24 INFO - rmtree: /builds/slave/test/build 11:06:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:06:25 INFO - Running post-action listener: _resource_record_post_action 11:06:25 INFO - [mozharness: 2017-09-10 18:06:25.560946Z] Finished clobber step (success) 11:06:25 INFO - [mozharness: 2017-09-10 18:06:25.561044Z] Running read-buildbot-config step. 11:06:25 INFO - Running pre-action listener: _resource_record_pre_action 11:06:25 INFO - Running main action method: read_buildbot_config 11:06:25 INFO - Using buildbot properties: 11:06:25 INFO - { 11:06:25 INFO - "project": "", 11:06:25 INFO - "product": "firefox", 11:06:25 INFO - "who": "ffxbld@noreply.mozilla.org", 11:06:25 INFO - "installer_path": "public/build/target.tar.bz2", 11:06:25 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:06:25 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:06:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 11:06:25 INFO - "stage_platform": "linux64", 11:06:25 INFO - "basedir": "/builds/slave/test", 11:06:25 INFO - "buildnumber": 1133, 11:06:25 INFO - "platform": "ubuntu64_hw", 11:06:25 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:06:25 INFO - "slavebuilddir": "test", 11:06:25 INFO - "taskId": "URHPHW6rSVOL28zIF0gkEA", 11:06:25 INFO - "branch": "mozilla-central", 11:06:25 INFO - "script_repo_revision": "production", 11:06:25 INFO - "revision": "fd87bb184e299fec695f69bd2977276c25719b98", 11:06:25 INFO - "slavename": "talos-linux64-ix-066", 11:06:25 INFO - "repo_path": "mozilla-central" 11:06:25 INFO - } 11:06:25 INFO - Finding installer, test and symbols from parent task. 11:06:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/URHPHW6rSVOL28zIF0gkEA'}, attempt #1 11:06:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/URHPHW6rSVOL28zIF0gkEA'}, attempt #1 11:06:26 INFO - Task dependencies: UZWMEdQaTLGG8H3YMvQkdw 11:06:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw'}, attempt #1 11:06:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.tar.bz2 11:06:26 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.test_packages.json 11:06:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.crashreporter-symbols.zip 11:06:26 INFO - Running post-action listener: _resource_record_post_action 11:06:26 INFO - [mozharness: 2017-09-10 18:06:26.951861Z] Finished read-buildbot-config step (success) 11:06:26 INFO - [mozharness: 2017-09-10 18:06:26.952078Z] Running download-and-extract step. 11:06:26 INFO - Running pre-action listener: _resource_record_pre_action 11:06:26 INFO - Running main action method: download_and_extract 11:06:26 INFO - mkdir: /builds/slave/test/build/tests 11:06: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')]} 11:06:26 INFO - trying https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.test_packages.json 11:06:26 INFO - Downloading https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:06:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:06:27 INFO - Downloaded 989 bytes. 11:06:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:06:27 INFO - Using the following test package requirements: 11:06:27 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:06:27 INFO - u'common': [u'target.common.tests.zip'], 11:06:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:06:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:06:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:06:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:06:27 INFO - u'mozbase': [u'target.common.tests.zip'], 11:06:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:06:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:06:27 INFO - u'web-platform': [u'target.common.tests.zip', 11:06:27 INFO - u'target.web-platform.tests.tar.gz'], 11:06:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:06:27 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:06:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.common.tests.zip 11:06:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:06:27 INFO - Fetch https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.common.tests.zip into memory 11:06:28 INFO - Content-Length response header: 55028530 11:06:28 INFO - Bytes received: 55028530 11:06:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.talos.tests.zip 11:06:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:06:39 INFO - Fetch https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.talos.tests.zip into memory 11:06:40 INFO - Content-Length response header: 14497963 11:06:40 INFO - Bytes received: 14497963 11:06:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:06:40 INFO - trying https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.tar.bz2 11:06:40 INFO - Downloading https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:06:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:06:43 INFO - Downloaded 62940438 bytes. 11:06:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.tar.bz2 11:06:43 INFO - mkdir: /builds/slave/test/properties 11:06:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:06:43 INFO - Writing to file /builds/slave/test/properties/build_url 11:06:43 INFO - Contents: 11:06:43 INFO - build_url:https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.tar.bz2 11:06:43 INFO - Running post-action listener: _resource_record_post_action 11:06:43 INFO - Running post-action listener: find_tests_for_verification 11:06:43 INFO - Running post-action listener: set_extra_try_arguments 11:06:43 INFO - [mozharness: 2017-09-10 18:06:43.500562Z] Finished download-and-extract step (success) 11:06:43 INFO - [mozharness: 2017-09-10 18:06:43.500794Z] Running populate-webroot step. 11:06:43 INFO - Running pre-action listener: _resource_record_pre_action 11:06:43 INFO - Running main action method: populate_webroot 11:06:43 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:06:43 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:06:43 INFO - 'tests': ['tresize', 11:06:43 INFO - 'tcanvasmark']}, 11:06:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:06:43 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:06:43 INFO - 'tests': ['dromaeo_css', 11:06:43 INFO - 'kraken']}, 11:06:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:06:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:06:43 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:06:43 INFO - 'talos_options': ['--disable-stylo'], 11:06:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:06:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:06:43 INFO - 'tests': ['damp', 'tps']}, 11:06:43 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:06:43 INFO - 'talos_options': ['--disable-stylo'], 11:06:43 INFO - 'tests': ['damp', 'tps']}, 11:06:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:06:43 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:06:43 INFO - 'tests': ['dromaeo_dom']}, 11:06:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:06:43 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:06:43 INFO - 'tests': ['basic_compositor_video', 11:06:43 INFO - 'glvideo']}, 11:06:43 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:06:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:06:43 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:06:43 INFO - 'talos_options': ['--disable-stylo'], 11:06:43 INFO - 'tests': ['ts_paint_webext', 11:06:43 INFO - 'tp5o_webext']}, 11:06:43 INFO - 'other-e10s': {'tests': ['a11yr', 11:06:43 INFO - 'ts_paint', 11:06:43 INFO - 'tpaint', 11:06:43 INFO - 'sessionrestore', 11:06:43 INFO - 'sessionrestore_many_windows', 11:06:43 INFO - 'sessionrestore_no_auto_restore', 11:06:43 INFO - 'tabpaint']}, 11:06:43 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:06:43 INFO - 'tests': ['a11yr', 11:06:43 INFO - 'ts_paint', 11:06:43 INFO - 'tpaint', 11:06:43 INFO - 'sessionrestore', 11:06:43 INFO - 'sessionrestore_many_windows', 11:06:43 INFO - 'sessionrestore_no_auto_restore', 11:06:43 INFO - 'tabpaint']}, 11:06:43 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:06:43 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:06:43 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:06:43 INFO - 'tests': ['bloom_basic_singleton']}, 11:06:43 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:06:43 INFO - 'tests': ['bloom_basic']}, 11:06:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:06:43 INFO - 'tsvgr_opacity', 11:06:43 INFO - 'tart', 11:06:43 INFO - 'tscrollx', 11:06:43 INFO - 'tsvg_static']}, 11:06:43 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:06:43 INFO - 'tests': ['tsvgx', 11:06:43 INFO - 'tsvgr_opacity', 11:06:43 INFO - 'tart', 11:06:43 INFO - 'tscrollx', 11:06:43 INFO - 'tsvg_static']}, 11:06:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:06:43 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:06:43 INFO - 'talos_options': ['--disable-stylo'], 11:06:43 INFO - 'tests': ['tp5o']}, 11:06:43 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:06:43 INFO - 'talos_options': ['--mitmproxy', 11:06:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:06:43 INFO - '--firstNonBlankPaint'], 11:06:43 INFO - 'tests': ['tp6_google', 11:06:43 INFO - 'tp6_youtube', 11:06:43 INFO - 'tp6_amazon', 11:06:43 INFO - 'tp6_facebook']}, 11:06:43 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:06:43 INFO - 'talos_options': ['--disable-stylo', 11:06:43 INFO - '--mitmproxy', 11:06:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:06:43 INFO - '--firstNonBlankPaint'], 11:06:43 INFO - 'tests': ['tp6_google', 11:06:43 INFO - 'tp6_youtube', 11:06:43 INFO - 'tp6_amazon', 11:06:43 INFO - 'tp6_facebook']}, 11:06:43 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:06:43 INFO - 'talos_options': ['--stylo-threads=1', 11:06:43 INFO - '--mitmproxy', 11:06:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:06:43 INFO - '--firstNonBlankPaint'], 11:06:43 INFO - 'tests': ['tp6_google', 11:06:43 INFO - 'tp6_youtube', 11:06:43 INFO - 'tp6_amazon', 11:06:43 INFO - 'tp6_facebook']}, 11:06:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:06:43 INFO - 'talos_options': ['--xperf_path', 11:06:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:06:43 INFO - 'tests': ['tp5n']}, 11:06:43 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:06:43 INFO - 'talos_options': ['--disable-stylo', 11:06:43 INFO - '--xperf_path', 11:06:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:06:43 INFO - 'tests': ['tp5n']}}} 11:06:43 INFO - Running post-action listener: _resource_record_post_action 11:06:43 INFO - [mozharness: 2017-09-10 18:06:43.521452Z] Finished populate-webroot step (success) 11:06:43 INFO - [mozharness: 2017-09-10 18:06:43.521544Z] Running create-virtualenv step. 11:06:43 INFO - Running pre-action listener: _resource_record_pre_action 11:06:43 INFO - Running main action method: create_virtualenv 11:06:43 INFO - Creating virtualenv /builds/slave/test/build/venv 11:06:43 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:06:43 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:06:43 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:06:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:06:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:06:44 INFO - Installing distribute.............................................................................................................................................................................................done. 11:06:44 INFO - Installing pip...............done. 11:06:44 INFO - Return code: 0 11:06:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:06:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:06:44 INFO - Reading from file tmpfile_stdout 11:06:44 INFO - Output received: 11:06:44 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:06:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:06: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')]} 11:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:06: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')]} 11:06:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:06: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e011f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2171090>, '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 0x2171d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', '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 11:06: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', 'pip>=1.5'] in /builds/slave/test/build 11:06: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 pip>=1.5 11:06:44 INFO - Using env: {'DISPLAY': ':0', 11:06:44 INFO - 'HOME': '/home/cltbld', 11:06:44 INFO - 'LANG': 'en_US.UTF-8', 11:06:44 INFO - 'LANGUAGE': 'en_US:en', 11:06:44 INFO - 'LOGNAME': 'cltbld', 11:06:44 INFO - 'MAIL': '/var/mail/cltbld', 11:06:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:44 INFO - 'MOZ_NO_REMOTE': '1', 11:06:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:06:44 INFO - 'NO_EM_RESTART': '1', 11:06:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:06:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:06:44 INFO - 'PWD': '/builds/slave/test', 11:06:44 INFO - 'SHELL': '/bin/bash', 11:06:44 INFO - 'SHLVL': '1', 11:06:44 INFO - 'TERM': 'linux', 11:06:44 INFO - 'TMOUT': '86400', 11:06:44 INFO - 'USER': 'cltbld', 11:06:44 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', 11:06:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:06:44 INFO - '_': '/tools/buildbot/bin/python'} 11:06:45 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:06:45 INFO - Downloading/unpacking pip>=1.5 11:06:45 INFO - Running setup.py egg_info for package pip 11:06:45 INFO - warning: no files found matching 'pip/cacert.pem' 11:06:45 INFO - warning: no files found matching '*.html' under directory 'docs' 11:06:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:06:45 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:06:45 INFO - Installing collected packages: pip 11:06:45 INFO - Found existing installation: pip 1.1 11:06:45 INFO - Uninstalling pip: 11:06:45 INFO - Successfully uninstalled pip 11:06:45 INFO - Running setup.py install for pip 11:06:45 INFO - warning: no files found matching 'pip/cacert.pem' 11:06:45 INFO - warning: no files found matching '*.html' under directory 'docs' 11:06:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:06:45 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:06:45 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:06:45 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:06:45 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:06:45 INFO - Successfully installed pip 11:06:45 INFO - Cleaning up... 11:06:45 INFO - Return code: 0 11:06:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:06:45 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')]} 11:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:06:45 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')]} 11:06:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:06:45 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 0x1e011f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2171090>, '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 0x2171d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', '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 11:06:45 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 11:06:45 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 11:06:45 INFO - Using env: {'DISPLAY': ':0', 11:06:45 INFO - 'HOME': '/home/cltbld', 11:06:45 INFO - 'LANG': 'en_US.UTF-8', 11:06:45 INFO - 'LANGUAGE': 'en_US:en', 11:06:45 INFO - 'LOGNAME': 'cltbld', 11:06:45 INFO - 'MAIL': '/var/mail/cltbld', 11:06:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:45 INFO - 'MOZ_NO_REMOTE': '1', 11:06:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:06:45 INFO - 'NO_EM_RESTART': '1', 11:06:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:06:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:06:45 INFO - 'PWD': '/builds/slave/test', 11:06:45 INFO - 'SHELL': '/bin/bash', 11:06:45 INFO - 'SHLVL': '1', 11:06:45 INFO - 'TERM': 'linux', 11:06:45 INFO - 'TMOUT': '86400', 11:06:45 INFO - 'USER': 'cltbld', 11:06:45 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', 11:06:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:06:45 INFO - '_': '/tools/buildbot/bin/python'} 11:06:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:06:45 INFO - Downloading/unpacking psutil>=3.1.1 11:06:45 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 11:06:45 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 11:06:45 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 11:06:45 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 11:06:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:06:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:06:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:06:47 INFO - Installing collected packages: psutil 11:06:47 INFO - Running setup.py install for psutil 11:06:47 INFO - building 'psutil._psutil_linux' extension 11:06:47 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 11:06:47 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 11:06:47 INFO - building 'psutil._psutil_posix' extension 11:06:47 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 11:06:47 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 11:06:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:06:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:06:47 INFO - Successfully installed psutil 11:06:47 INFO - Cleaning up... 11:06:47 INFO - Return code: 0 11:06:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:06:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e011f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2171090>, '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 0x2171d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', '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 11:06:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:06:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:06:47 INFO - Using env: {'DISPLAY': ':0', 11:06:47 INFO - 'HOME': '/home/cltbld', 11:06:47 INFO - 'LANG': 'en_US.UTF-8', 11:06:47 INFO - 'LANGUAGE': 'en_US:en', 11:06:47 INFO - 'LOGNAME': 'cltbld', 11:06:47 INFO - 'MAIL': '/var/mail/cltbld', 11:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:47 INFO - 'MOZ_NO_REMOTE': '1', 11:06:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:06:47 INFO - 'NO_EM_RESTART': '1', 11:06:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:06:47 INFO - 'PWD': '/builds/slave/test', 11:06:47 INFO - 'SHELL': '/bin/bash', 11:06:47 INFO - 'SHLVL': '1', 11:06:47 INFO - 'TERM': 'linux', 11:06:47 INFO - 'TMOUT': '86400', 11:06:47 INFO - 'USER': 'cltbld', 11:06:47 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', 11:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:06:47 INFO - '_': '/tools/buildbot/bin/python'} 11:06:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:06:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:06:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:06:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:06:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:06:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:06:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:06:50 INFO - Installing collected packages: mozsystemmonitor 11:06:50 INFO - Running setup.py install for mozsystemmonitor 11:06:51 INFO - Successfully installed mozsystemmonitor 11:06:51 INFO - Cleaning up... 11:06:52 INFO - Return code: 0 11:06:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:06:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:06:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:06:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:06:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e011f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2171090>, '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 0x2171d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', '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 11:06:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:06:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:06:52 INFO - Using env: {'DISPLAY': ':0', 11:06:52 INFO - 'HOME': '/home/cltbld', 11:06:52 INFO - 'LANG': 'en_US.UTF-8', 11:06:52 INFO - 'LANGUAGE': 'en_US:en', 11:06:52 INFO - 'LOGNAME': 'cltbld', 11:06:52 INFO - 'MAIL': '/var/mail/cltbld', 11:06:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:52 INFO - 'MOZ_NO_REMOTE': '1', 11:06:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:06:52 INFO - 'NO_EM_RESTART': '1', 11:06:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:06:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:06:52 INFO - 'PWD': '/builds/slave/test', 11:06:52 INFO - 'SHELL': '/bin/bash', 11:06:52 INFO - 'SHLVL': '1', 11:06:52 INFO - 'TERM': 'linux', 11:06:52 INFO - 'TMOUT': '86400', 11:06:52 INFO - 'USER': 'cltbld', 11:06:52 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', 11:06:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:06:52 INFO - '_': '/tools/buildbot/bin/python'} 11:06:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:06:52 INFO - Downloading/unpacking jsonschema==2.5.1 11:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:06:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:06:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:06:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:06:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:06:54 INFO - Downloading functools32-3.2.3-2.tar.gz 11:06:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:06:54 INFO - warning: no files found matching '*.txt' 11:06:54 INFO - no previously-included directories found matching 'build' 11:06:54 INFO - no previously-included directories found matching 'dist' 11:06:54 INFO - no previously-included directories found matching '.git*' 11:06:54 INFO - Installing collected packages: jsonschema, functools32 11:06:54 INFO - Running setup.py install for functools32 11:06:54 INFO - warning: no files found matching '*.txt' 11:06:54 INFO - no previously-included directories found matching 'build' 11:06:54 INFO - no previously-included directories found matching 'dist' 11:06:54 INFO - no previously-included directories found matching '.git*' 11:06:54 INFO - Successfully installed jsonschema functools32 11:06:54 INFO - Cleaning up... 11:06:54 INFO - Return code: 0 11:06:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:06:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1e011f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2171090>, '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 0x2171d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', '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 11:06:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:06:54 INFO - Using env: {'DISPLAY': ':0', 11:06:54 INFO - 'HOME': '/home/cltbld', 11:06:54 INFO - 'LANG': 'en_US.UTF-8', 11:06:54 INFO - 'LANGUAGE': 'en_US:en', 11:06:54 INFO - 'LOGNAME': 'cltbld', 11:06:54 INFO - 'MAIL': '/var/mail/cltbld', 11:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:54 INFO - 'MOZ_NO_REMOTE': '1', 11:06:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:06:54 INFO - 'NO_EM_RESTART': '1', 11:06:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:06:54 INFO - 'PWD': '/builds/slave/test', 11:06:54 INFO - 'SHELL': '/bin/bash', 11:06:54 INFO - 'SHLVL': '1', 11:06:54 INFO - 'TERM': 'linux', 11:06:54 INFO - 'TMOUT': '86400', 11:06:54 INFO - 'USER': 'cltbld', 11:06:54 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', 11:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:06:54 INFO - '_': '/tools/buildbot/bin/python'} 11:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:06:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:06:54 INFO - Cleaning up... 11:06:54 INFO - Return code: 0 11:06:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:06:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1e011f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2171090>, '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 0x2171d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', '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 11:06:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:06:54 INFO - Using env: {'DISPLAY': ':0', 11:06:54 INFO - 'HOME': '/home/cltbld', 11:06:54 INFO - 'LANG': 'en_US.UTF-8', 11:06:54 INFO - 'LANGUAGE': 'en_US:en', 11:06:54 INFO - 'LOGNAME': 'cltbld', 11:06:54 INFO - 'MAIL': '/var/mail/cltbld', 11:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:54 INFO - 'MOZ_NO_REMOTE': '1', 11:06:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:06:54 INFO - 'NO_EM_RESTART': '1', 11:06:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:06:54 INFO - 'PWD': '/builds/slave/test', 11:06:54 INFO - 'SHELL': '/bin/bash', 11:06:54 INFO - 'SHLVL': '1', 11:06:54 INFO - 'TERM': 'linux', 11:06:54 INFO - 'TMOUT': '86400', 11:06:54 INFO - 'USER': 'cltbld', 11:06:54 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', 11:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:06:54 INFO - '_': '/tools/buildbot/bin/python'} 11:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:06:54 INFO - Downloading/unpacking blobuploader==1.2.4 11:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:06:55 INFO - Downloading blobuploader-1.2.4.tar.gz 11:06:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:06:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:06:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:06:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:06:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:06:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:06:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:06:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:06:56 INFO - Downloading docopt-0.6.1.tar.gz 11:06:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:06:56 INFO - Installing collected packages: blobuploader, requests, docopt 11:06:56 INFO - Running setup.py install for blobuploader 11:06:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:06:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:06:56 INFO - Running setup.py install for requests 11:06:57 INFO - Running setup.py install for docopt 11:06:57 INFO - Successfully installed blobuploader requests docopt 11:06:57 INFO - Cleaning up... 11:06:57 INFO - Return code: 0 11:06:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:06:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:06:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:06:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:06:57 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 0x1e011f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2171090>, '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 0x2171d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', '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 11:06:57 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 11:06:57 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 11:06:57 INFO - Using env: {'DISPLAY': ':0', 11:06:57 INFO - 'HOME': '/home/cltbld', 11:06:57 INFO - 'LANG': 'en_US.UTF-8', 11:06:57 INFO - 'LANGUAGE': 'en_US:en', 11:06:57 INFO - 'LOGNAME': 'cltbld', 11:06:57 INFO - 'MAIL': '/var/mail/cltbld', 11:06:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:57 INFO - 'MOZ_NO_REMOTE': '1', 11:06:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:06:57 INFO - 'NO_EM_RESTART': '1', 11:06:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:06:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:06:57 INFO - 'PWD': '/builds/slave/test', 11:06:57 INFO - 'SHELL': '/bin/bash', 11:06:57 INFO - 'SHLVL': '1', 11:06:57 INFO - 'TERM': 'linux', 11:06:57 INFO - 'TMOUT': '86400', 11:06:57 INFO - 'USER': 'cltbld', 11:06:57 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', 11:06:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:06:57 INFO - '_': '/tools/buildbot/bin/python'} 11:06:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:06:57 INFO - Running setup.py (path:/tmp/pip-V_DTPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:06:57 INFO - Running setup.py (path:/tmp/pip-Eu2y00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:06:57 INFO - Running setup.py (path:/tmp/pip-eAQ3_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:06:57 INFO - Running setup.py (path:/tmp/pip-h0K4sF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:06:57 INFO - Running setup.py (path:/tmp/pip-UzMmVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:06:57 INFO - Running setup.py (path:/tmp/pip-Nw991S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:06:57 INFO - Running setup.py (path:/tmp/pip-WCIiDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:06:57 INFO - Running setup.py (path:/tmp/pip-X5GR_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:06:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:06:57 INFO - Running setup.py (path:/tmp/pip-DOr0b4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:06:58 INFO - Running setup.py (path:/tmp/pip-fD5Y59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:06:58 INFO - Running setup.py (path:/tmp/pip-Axs3AX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:06:58 INFO - Running setup.py (path:/tmp/pip-r4AEY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:06:58 INFO - Running setup.py (path:/tmp/pip-UE5F5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:06:58 INFO - Running setup.py (path:/tmp/pip-OP1s5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:06:58 INFO - Running setup.py (path:/tmp/pip-NMH8so-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:06:58 INFO - Running setup.py (path:/tmp/pip-_Yg3Ga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:06:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:06:58 INFO - Running setup.py (path:/tmp/pip-6uHiqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:06:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:06:58 INFO - Running setup.py install for manifestparser 11:06:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:06:58 INFO - Running setup.py install for mozcrash 11:06:58 INFO - Running setup.py install for mozdebug 11:06:58 INFO - Running setup.py install for mozdevice 11:06:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:06:58 INFO - Running setup.py install for mozfile 11:06:59 INFO - Running setup.py install for mozhttpd 11:06:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:06:59 INFO - Running setup.py install for mozinfo 11:06:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:06:59 INFO - Running setup.py install for mozInstall 11:06:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:06:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:06:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:06:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:06:59 INFO - Running setup.py install for mozleak 11:06:59 INFO - Running setup.py install for mozlog 11:06:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:06:59 INFO - Running setup.py install for moznetwork 11:06:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:06:59 INFO - Running setup.py install for mozprocess 11:06:59 INFO - Running setup.py install for mozprofile 11:06:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:06:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:06:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:06:59 INFO - Running setup.py install for mozrunner 11:07:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:07:00 INFO - Running setup.py install for mozscreenshot 11:07:00 INFO - Running setup.py install for moztest 11:07:00 INFO - Running setup.py install for mozversion 11:07:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:07:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:07:00 INFO - Cleaning up... 11:07:00 INFO - Return code: 0 11:07:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:07:00 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')]} 11:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:00 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')]} 11:07:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:00 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 0x1e011f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2171090>, '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 0x2171d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', '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 11:07:00 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 11:07:00 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 11:07:00 INFO - Using env: {'DISPLAY': ':0', 11:07:00 INFO - 'HOME': '/home/cltbld', 11:07:00 INFO - 'LANG': 'en_US.UTF-8', 11:07:00 INFO - 'LANGUAGE': 'en_US:en', 11:07:00 INFO - 'LOGNAME': 'cltbld', 11:07:00 INFO - 'MAIL': '/var/mail/cltbld', 11:07:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:00 INFO - 'MOZ_NO_REMOTE': '1', 11:07:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:00 INFO - 'NO_EM_RESTART': '1', 11:07:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:00 INFO - 'PWD': '/builds/slave/test', 11:07:00 INFO - 'SHELL': '/bin/bash', 11:07:00 INFO - 'SHLVL': '1', 11:07:00 INFO - 'TERM': 'linux', 11:07:00 INFO - 'TMOUT': '86400', 11:07:00 INFO - 'USER': 'cltbld', 11:07:00 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', 11:07:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:00 INFO - '_': '/tools/buildbot/bin/python'} 11:07:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:07:00 INFO - Running setup.py (path:/tmp/pip-pQLB7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:07:00 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)) 11:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:07:00 INFO - Running setup.py (path:/tmp/pip-nZlEXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:07:00 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)) 11:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:07:00 INFO - Running setup.py (path:/tmp/pip-XGApV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:07:00 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)) 11:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:07:00 INFO - Running setup.py (path:/tmp/pip-t_54DA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:07:00 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)) 11:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:07:00 INFO - Running setup.py (path:/tmp/pip-RpRi9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:07:00 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)) 11:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:07:00 INFO - Running setup.py (path:/tmp/pip-KTqs2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:07:00 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)) 11:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:07:00 INFO - Running setup.py (path:/tmp/pip-j9D3j0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:07:01 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)) 11:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:07:01 INFO - Running setup.py (path:/tmp/pip-vJAsFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:07:01 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)) 11:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:07:01 INFO - Running setup.py (path:/tmp/pip-sIiPJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:07:01 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)) 11:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:07:01 INFO - Running setup.py (path:/tmp/pip-9Edw0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:07:01 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)) 11:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:07:01 INFO - Running setup.py (path:/tmp/pip-qRs3re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:07:01 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)) 11:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:07:01 INFO - Running setup.py (path:/tmp/pip-OS6Zo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:07:01 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)) 11:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:07:01 INFO - Running setup.py (path:/tmp/pip-QxDMNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:07:01 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)) 11:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:07:01 INFO - Running setup.py (path:/tmp/pip-cO2UNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:07:01 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)) 11:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:07:01 INFO - Running setup.py (path:/tmp/pip-zsZkyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:07:01 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)) 11:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:07:01 INFO - Running setup.py (path:/tmp/pip-dRigv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:07:01 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)) 11:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:07:01 INFO - Running setup.py (path:/tmp/pip-C78gXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:07:01 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)) 11:07:01 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)) 11:07:01 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)) 11:07:01 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)) 11:07:01 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)) 11:07:01 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)) 11:07:01 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)) 11:07:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:07:01 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 11:07:01 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 11:07:01 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 11:07:01 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 11:07:02 INFO - Downloading blessings-1.6.tar.gz 11:07:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:07:02 INFO - Installing collected packages: blessings 11:07:02 INFO - Running setup.py install for blessings 11:07:03 INFO - Successfully installed blessings 11:07:03 INFO - Cleaning up... 11:07:03 INFO - Return code: 0 11:07:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:07:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:07:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:07:03 INFO - Reading from file tmpfile_stdout 11:07:03 INFO - Current package versions: 11:07:03 INFO - argparse == 1.2.1 11:07:03 INFO - blessings == 1.6 11:07:03 INFO - blobuploader == 1.2.4 11:07:03 INFO - docopt == 0.6.1 11:07:03 INFO - functools32 == 3.2.3-2 11:07:03 INFO - jsonschema == 2.5.1 11:07:03 INFO - manifestparser == 1.1 11:07:03 INFO - mozInstall == 1.13 11:07:03 INFO - mozcrash == 1.0 11:07:03 INFO - mozdebug == 0.1 11:07:03 INFO - mozdevice == 0.51 11:07:03 INFO - mozfile == 1.2 11:07:03 INFO - mozhttpd == 0.7 11:07:03 INFO - mozinfo == 0.10 11:07:03 INFO - mozleak == 0.1 11:07:03 INFO - mozlog == 3.5 11:07:03 INFO - moznetwork == 0.27 11:07:03 INFO - mozprocess == 0.25 11:07:03 INFO - mozprofile == 0.28 11:07:03 INFO - mozrunner == 6.13 11:07:03 INFO - mozscreenshot == 0.1 11:07:03 INFO - mozsystemmonitor == 0.3 11:07:03 INFO - moztest == 0.8 11:07:03 INFO - mozversion == 1.4 11:07:03 INFO - psutil == 3.1.1 11:07:03 INFO - requests == 1.2.3 11:07:03 INFO - wsgiref == 0.1.2 11:07:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:07:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:03 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 0x1e011f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2171090>, '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 0x2171d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', '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 11:07:03 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 11:07:03 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 11:07:03 INFO - Using env: {'DISPLAY': ':0', 11:07:03 INFO - 'HOME': '/home/cltbld', 11:07:03 INFO - 'LANG': 'en_US.UTF-8', 11:07:03 INFO - 'LANGUAGE': 'en_US:en', 11:07:03 INFO - 'LOGNAME': 'cltbld', 11:07:03 INFO - 'MAIL': '/var/mail/cltbld', 11:07:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:03 INFO - 'MOZ_NO_REMOTE': '1', 11:07:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:03 INFO - 'NO_EM_RESTART': '1', 11:07:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:03 INFO - 'PWD': '/builds/slave/test', 11:07:03 INFO - 'SHELL': '/bin/bash', 11:07:03 INFO - 'SHLVL': '1', 11:07:03 INFO - 'TERM': 'linux', 11:07:03 INFO - 'TMOUT': '86400', 11:07:03 INFO - 'USER': 'cltbld', 11:07:03 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', 11:07:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:03 INFO - '_': '/tools/buildbot/bin/python'} 11:07:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:03 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)) 11:07:03 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)) 11:07:03 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)) 11:07:03 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)) 11:07:03 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)) 11:07:03 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)) 11:07:03 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)) 11:07:03 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)) 11:07:03 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)) 11:07:03 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:07:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:07:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:07:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:07:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:07:04 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)) 11:07:04 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)) 11:07:04 INFO - Installing collected packages: simplejson 11:07:04 INFO - Running setup.py install for simplejson 11:07:04 INFO - building 'simplejson._speedups' extension 11:07:04 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 11:07:05 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 11:07:05 INFO - Successfully installed simplejson 11:07:05 INFO - Cleaning up... 11:07:05 INFO - Return code: 0 11:07:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:05 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 0x1e011f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fcaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2171090>, '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 0x2171d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, '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': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', '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 11:07:05 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 11:07:05 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 11:07:05 INFO - Using env: {'DISPLAY': ':0', 11:07:05 INFO - 'HOME': '/home/cltbld', 11:07:05 INFO - 'LANG': 'en_US.UTF-8', 11:07:05 INFO - 'LANGUAGE': 'en_US:en', 11:07:05 INFO - 'LOGNAME': 'cltbld', 11:07:05 INFO - 'MAIL': '/var/mail/cltbld', 11:07:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:05 INFO - 'MOZ_NO_REMOTE': '1', 11:07:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:05 INFO - 'NO_EM_RESTART': '1', 11:07:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:05 INFO - 'PWD': '/builds/slave/test', 11:07:05 INFO - 'SHELL': '/bin/bash', 11:07:05 INFO - 'SHLVL': '1', 11:07:05 INFO - 'TERM': 'linux', 11:07:05 INFO - 'TMOUT': '86400', 11:07:05 INFO - 'USER': 'cltbld', 11:07:05 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', 11:07:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:05 INFO - '_': '/tools/buildbot/bin/python'} 11:07:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:07:06 INFO - Cleaning up... 11:07:06 INFO - Return code: 0 11:07:06 INFO - Running post-action listener: _resource_record_post_action 11:07:06 INFO - Running post-action listener: _start_resource_monitoring 11:07:06 INFO - Starting resource monitoring. 11:07:06 INFO - [mozharness: 2017-09-10 18:07:06.071074Z] Finished create-virtualenv step (success) 11:07:06 INFO - [mozharness: 2017-09-10 18:07:06.071543Z] Running install step. 11:07:06 INFO - Running pre-action listener: _resource_record_pre_action 11:07:06 INFO - Running main action method: install 11:07:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:07:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:07:06 INFO - Reading from file tmpfile_stdout 11:07:06 INFO - Detecting whether we're running mozinstall >=1.0... 11:07:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:07:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:07:06 INFO - Reading from file tmpfile_stdout 11:07:06 INFO - Output received: 11:07:06 INFO - Usage: mozinstall [options] installer 11:07:06 INFO - Options: 11:07:06 INFO - -h, --help show this help message and exit 11:07:06 INFO - -d DEST, --destination=DEST 11:07:06 INFO - Directory to install application into. [default: 11:07:06 INFO - "/builds/slave/test"] 11:07:06 INFO - --app=APP Application being installed. [default: firefox] 11:07:06 INFO - mkdir: /builds/slave/test/build/application 11:07:06 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'] 11:07:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:07:22 INFO - Reading from file tmpfile_stdout 11:07:22 INFO - Output received: 11:07:22 INFO - /builds/slave/test/build/application/firefox/firefox 11:07:22 INFO - Running post-action listener: _resource_record_post_action 11:07:22 INFO - [mozharness: 2017-09-10 18:07:22.994252Z] Finished install step (success) 11:07:22 INFO - [mozharness: 2017-09-10 18:07:22.994506Z] Running setup-mitmproxy step. 11:07:22 INFO - Running pre-action listener: _resource_record_pre_action 11:07:22 INFO - Running main action method: setup_mitmproxy 11:07:22 INFO - Skipping: mitmproxy is not required 11:07:22 INFO - Running post-action listener: _resource_record_post_action 11:07:22 INFO - [mozharness: 2017-09-10 18:07:22.995637Z] Finished setup-mitmproxy step (success) 11:07:22 INFO - [mozharness: 2017-09-10 18:07:22.995858Z] Running run-tests step. 11:07:22 INFO - Running pre-action listener: _resource_record_pre_action 11:07:22 INFO - Running pre-action listener: _set_gcov_prefix 11:07:22 INFO - Running main action method: run_tests 11:07:22 WARNING - Try message not found. 11:07:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:07:23 INFO - Python 2.7.3 11:07:23 INFO - Return code: 0 11:07:23 INFO - grabbing minidump binary from tooltool 11:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:23 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 0x2171090>, '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 0x2171d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x216bf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:07:23 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 11:07:23 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 11:07:23 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 11:07:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:07:23 INFO - Return code: 0 11:07:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:07:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:07:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:07:23 INFO - ENV: RUST_BACKTRACE is now full 11:07:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:07:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:07:23 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/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 11:07:23 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/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-066 --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 11:07:23 INFO - Using env: {'DISPLAY': ':0', 11:07:23 INFO - 'HOME': '/home/cltbld', 11:07:23 INFO - 'LANG': 'en_US.UTF-8', 11:07:23 INFO - 'LANGUAGE': 'en_US:en', 11:07:23 INFO - 'LOGNAME': 'cltbld', 11:07:23 INFO - 'MAIL': '/var/mail/cltbld', 11:07:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:07:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:23 INFO - 'MOZ_NO_REMOTE': '1', 11:07:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:07:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:23 INFO - 'NO_EM_RESTART': '1', 11:07:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:23 INFO - 'PWD': '/builds/slave/test', 11:07:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:07:23 INFO - 'RUST_BACKTRACE': 'full', 11:07:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:07:23 INFO - 'SHELL': '/bin/bash', 11:07:23 INFO - 'SHLVL': '1', 11:07:23 INFO - 'TERM': 'linux', 11:07:23 INFO - 'TMOUT': '86400', 11:07:23 INFO - 'USER': 'cltbld', 11:07:23 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', 11:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:23 INFO - '_': '/tools/buildbot/bin/python'} 11:07:23 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/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 11:07:23 INFO - mozversion application_buildid: 20170910171456 11:07:23 INFO - mozversion application_changeset: fd87bb184e299fec695f69bd2977276c25719b98 11:07:23 INFO - mozversion application_display_name: Nightly 11:07:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:07:23 INFO - mozversion application_name: Firefox 11:07:23 INFO - mozversion application_remotingname: firefox 11:07:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:07:23 INFO - mozversion application_vendor: Mozilla 11:07:23 INFO - mozversion application_version: 57.0a1 11:07:23 INFO - mozversion platform_buildid: 20170910171456 11:07:23 INFO - mozversion platform_changeset: fd87bb184e299fec695f69bd2977276c25719b98 11:07:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:07:23 INFO - mozversion platform_version: 57.0a1 11:07:23 INFO - using testdate: 1505066843 11:07:23 INFO - actual date: 1505066843 11:07:23 INFO - starting webserver on 'localhost:55699' 11:07:23 INFO - SUITE-START | Running 2 tests 11:07:23 INFO - TEST-START | dromaeo_css 11:07:23 INFO - Initialising browser for dromaeo_css test... 11:07:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55699/getInfo.html -profile /tmp/tmpojRljx/profile 11:07:23 INFO - TEST-INFO | started process 19190 (/builds/slave/test/build/application/firefox/firefox http://localhost:55699/getInfo.html) 11:07:29 INFO - TEST-INFO | 19190: exit 0 11:07:29 INFO - Browser initialized. 11:07:29 INFO - Running cycle 1/1 for dromaeo_css test... 11:07:29 INFO - Using env: {'DISPLAY': ':0', 11:07:29 INFO - 'HOME': '/home/cltbld', 11:07:29 INFO - 'JSGC_DISABLE_POISONING': '1', 11:07:29 INFO - 'LANG': 'en_US.UTF-8', 11:07:29 INFO - 'LANGUAGE': 'en_US:en', 11:07:29 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:07:29 INFO - 'LOGNAME': 'cltbld', 11:07:29 INFO - 'MAIL': '/var/mail/cltbld', 11:07:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:07:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:07:29 INFO - 'MOZ_CRASHREPORTER': '1', 11:07:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:07:29 INFO - 'MOZ_NO_REMOTE': '1', 11:07:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:07:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:29 INFO - 'NO_EM_RESTART': '1', 11:07:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:29 INFO - 'PWD': '/builds/slave/test', 11:07:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:07:29 INFO - 'RUST_BACKTRACE': 'full', 11:07:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:07:29 INFO - 'SHELL': '/bin/bash', 11:07:29 INFO - 'SHLVL': '1', 11:07:29 INFO - 'TERM': 'linux', 11:07:29 INFO - 'TMOUT': '86400', 11:07:29 INFO - 'USER': 'cltbld', 11:07:29 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', 11:07:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:29 INFO - '_': '/tools/buildbot/bin/python'} 11:07:29 INFO - TEST-INFO | started process 19391 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpojRljx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:07:30 INFO - PID 19391 | 11:07:30 INFO - PID 19391 | (/builds/slave/test/build/application/firefox/firefox:19442): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:07:30 INFO - PID 19391 | 11:07:41 INFO - PID 19391 | RSS: Main: 171261952 11:07:41 INFO - PID 19391 | 11:10:38 INFO - PID 19391 | Cycle 1(1): loaded http://localhost:55699/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55699/tests/dromaeo/cssquery-ext.html) 11:10:39 INFO - PID 19391 | RSS: Main: 174141440 11:10:39 INFO - PID 19391 | 11:13:41 INFO - PID 19391 | Cycle 1(1): loaded http://localhost:55699/tests/dromaeo/cssquery-ext.html (next: http://localhost:55699/tests/dromaeo/cssquery-jquery.html) 11:13:42 INFO - PID 19391 | RSS: Main: 170065920 11:13:42 INFO - PID 19391 | 11:16:44 INFO - PID 19391 | Cycle 1(1): loaded http://localhost:55699/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55699/tests/dromaeo/cssquery-mootools.html) 11:16:44 INFO - PID 19391 | RSS: Main: 172630016 11:16:44 INFO - PID 19391 | 11:19:46 INFO - PID 19391 | Cycle 1(1): loaded http://localhost:55699/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55699/tests/dromaeo/cssquery-prototype.html) 11:19:46 INFO - PID 19391 | RSS: Main: 171053056 11:19:46 INFO - PID 19391 | 11:22:50 INFO - PID 19391 | Cycle 1(1): loaded http://localhost:55699/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55699/tests/dromaeo/cssquery-yui.html) 11:22:50 INFO - PID 19391 | RSS: Main: 172777472 11:22:50 INFO - PID 19391 | 11:25:52 INFO - PID 19391 | Cycle 1(1): loaded http://localhost:55699/tests/dromaeo/cssquery-yui.html (next: http://localhost:55699/tests/dromaeo/cssquery-dojo.html) 11:25:52 INFO - PID 19391 | RSS: Main: 175493120 11:25:52 INFO - PID 19391 | 11:25:52 INFO - PID 19391 | __start_tp_report 11:25:52 INFO - PID 19391 | _x_x_mozilla_page_load 11:25:52 INFO - PID 19391 | _x_x_mozilla_page_load_details 11:25:52 INFO - PID 19391 | |i|pagename|runs| 11:25:52 INFO - PID 19391 | |0;dojo.html;3127.8279694616795;3142.8428578571065;3166.82582457965;3170.5878235829337;3171.064535961891;6260.843478913018;6290.622562646245;6307.558470907038;6344.777932772354;6417.903731444027;6302.432781049704;6361.9363806362035;6383.074454204149;6398.744050237985;6413.486921046317;5648.43515648436;5785.8264252072295;5836.2996440427205;5856.736446859902;5866.530677545797;5721.971390143043;5769.538436925036;5834.2998840139035;5839.445252700986;5878.294604647448;5754.568407369443;5777.682227477487;5816.91274630883;5821.62159459638;5826.067829147316;5726.513246374063;5771.278590176228;5797.768089276424;5803.7968671096305;5843.2695913010875;5656.519195868356;5758.6544807311275;5773.336066352381;5842.036064049426;5854.5023672987845;6207.162033125561;6290.622562646256;6293.37066293367;6324.7470101195895;6475.611463312169;6745.527813053088;6807.353301436356;6814.829629259309;6817.5568588041615;6827.658617069127;6719.798406047827;6750.35871592198;6837;6850.177978642595;6861.279565645636;6120.234970628671;6174.135621013062;6183.288921773964;6211.782587609458;6270.78052268173;6807.761728339485;6860;6884.380405763405;6895.5173137879865;6949.44404447643;6687.832804179837;6745.628990405575;6746.662666866637;6749.392554670005;6749.730010799513;6779.220389655155;6792.864142717118;6856.211535673362;6860.5197636164985;6875.0718652982205;6529;6704.430123439464;6728.293529179464;6819.624920629314;6821.488388370892;6666.0667506549125;6666.500012499082;6755.898661520081;6759.222689390783;6764.526483146231;5344.091504444254;5346.4118946915805;5361.758720857571;5365.409804921455;5366.6779993200535;4612.815487380467;4645.187092258841;4662.067586482717;4675.275332323427;4677;5590.077637189899;5713.457221563945;5717.542596592262;5805;5878.29460464747;6644.966775166094;6645.568038077509;6737.629430381278;6809.216940051859;6813.1142951415995;5788.88422231553;5847.6783776891825;5853.9414605854245;5858.47273745365;5858.912116318259;5816.272965879265;5816.941830581724;5877.94122058774;5895;5899.321578018497;6484.773032943825;6508.219013718384;6532.183477065367;6612.305707900698;6617.636030018299;6564.310747371457;6601.24085730128;6680;6696.330366963265;6874.621895795779;6432;6468.320826313357;6480.125183100221;6480.805575832733;6549.24683661385;6677.966610167113;6694.430973367318;6772.627505487246;6786.151731033621;6787.762428314985;6719.529632925648;6746.089277947414;6755.054292399166;6844.1444819397575;6851.691673874788;6369.554131210771;6452.774152904626;6481;6536.477081833537;6620.04009418622;6596.934030659632;6601.108850305147;6609.306022867722;6637.900431493435;6642.767503137367;6475.125858009108;6483.189601299838;6483.384078512534;6572.605643661395;6575.342465753386;6531.738730450728;6590.044443555562;6609.041688955171;6643.30245324234;6711.094002309821;6208.844778880564;6251.624902505864;6317.684115794284;6349.746010159542;6355.7457701693265 11:25:52 INFO - PID 19391 | |1;ext.html;746.3730466408218;767.5202998126172;768.7347864986581;769.2730369800533;774.2219069834812;37674;41400.17199656005;41427.792861035654;41432.75701728949;41502.7924860376;37507.437388439124;37725.811370943105;37853.24293514128;37866.81066594663;37930.8103459483;7080.468964827633;7773.261540153676;7994.120646728884;8000;8011.0386753589655;7865.095514015876;7939.444238903279;7968.12350641429;7977.321927636158;7977.521348719067;7946.245106714881;7964.163762804909;7995.040595128593;8024.759257222264;8026;7900.920990790076;7964.721234756785;7965.123836377994;7984.880226796603;7984.960075199587;7852.254035866641;7913.6438860250855;7915.643796029192;7920.920790792076;7944.642491087915;37208.62791372079;37289;37379;37424.62575374239;37441.625583744084;3537.4340105582983;3580.5524309461316;3581.4090675038588;3582.9283414331567;3583.086312990197;31083.68916310853;31139.688603113904;31231.219219519466;31237.687623123933;31283.37433251345;5277.604179686539;5282.286891269668;5316.494932981361;5330.493603107699;5353.170258609922;181352;181687;182801;182823;183157;41468;42205;47904.76047619739;48128.27807582889;48235.75882120567;50785;51000.23499647434;51121.721956951376;51153;51167.48832511702;14200;14274;14328.785068223984;14341.067830590979;14379.568612941628;11465.484053217626;11485.0811935045;11518.251313664585;11553.36456494884;11554.248973816675;14192.574222773334;14204.573862784133;14244.07413518118;14383.496577619942;14393.568192954228;5698.572607054488;5772.792396128169;5792.594518383757;5827.88344233113;6032.487238584769;1025;1028.141501845967;1033.751899544119;1037.968860934173;1040.8282633365409;22450.438739031655;22613.208537701103;22782.886085569466;22868.4282892929;23197.188098416475;1110.1618278199908;1128.3342827731515;1128.6219116596033;1128.6614015795392;1130.6042884990188;11972.940135299268;12106.273623582612;12121.454534545966;12145.210561313486;12238.694032649255;210184;210217;210645;211304;212406;50676;50768.492315077114;50867.74566127146;50913.745431272604;50965.49034509608;1410.8377536583218;1426.721789251086;1455.1996401979081;1465.6702241995679;1470.43388295507;21762;21804.345869623936;22202.55594888061;22479.662805058266;22634.547309054054;36123.63876361203;36347.81826090958;36424.63575364318;36424.63575364318;36446.63553364432;181789;182761;182877;183339;183489;31650.841745791124;31869.681303187597;32067.839660801546;32106.67893321037;32218.35563288768;41047.17905641811;41075;41114.17771644491;41166.1766764657;41279.38080928849;30181.245468863453;30371.696283036887;30608.234794129436;30967.845160774054;31117;30593.23516912095;30594.388112237186;30871.2282192947;31178.22054448657;31459.685403145675;34566;34630;34700.82649586736;34717;34741;29928.952486663733;30140;30234.09297721072;30357.392852143275;30369.544456833606;22223.55486924203;22482.775172248068;22523.774762252822;22667.319980400614;22930.770692292863 11:25:52 INFO - PID 19391 | |2;jquery.html;552.7098273406461;568.8919105369977;571.1261769492677;574.7212601888081;584.3367777572454;21894.781052189493;22558.661620075713;22760.54478910421;22763.430914227112;22810.31569052929;22918.427039324026;23713.407164820845;23932.880335598296;24122.87938560309;24265.39336516588;6198.566100372976;6422.421982021617;6435.292117867031;6467.482601391901;6473.838154046163;6234.407731265523;6327;6332.430081292683;6339.524535659821;6343.809685709413;6246.312905580383;6314.179156709622;6512.479001679878;6514.543981921267;6528.967355163241;6459.224893012822;6494.0583615375745;6497.447716944065;6498;6503.902441463382;6018.759249630009;6022.066579680157;6741.157355330583;7021.929780702229;7058.188308344555;29641.25896852596;30054.24864378408;30145.547816782764;30153.396932061234;30181;2081.6253074446595;2109.430453777487;2114;2115.5134319106537;2124.925627603042;19146.329878454188;19690.50773730668;19804.900975495173;19955.201791928306;20028.399148025583;2517.2699917024042;2558.347621356561;2576.2915198320425;2586.2499875036215;2593.286846117314;114027.42986285016;120455.39772301083;125578;128285;132448.33775831157;19719.605607887763;19788.40634780959;20619.69070463944;20796;21391.967763062483;39719.20561588752;40200.195996079914;40294.59705402909;40299.59700403017;40357.394639080434;10290.279680422449;10417.895821041844;10559.63041293551;10684.51919663617;10698.358098514114;8577.142285771373;8590.570471476402;8625.568721563897;8643.30853531716;8758.2993360531;10842.674719758263;10894.564217431214;10913.353832092289;10992;11382;3765.5293088363956;3950.3188721365273;4091.97954010234;4150.211459822624;4386.539413361615;956.5360800011989;983.6557204978201;984.837501812193;985.4678473624163;994.7314225159166;18183.63632727338;18571.814281857278;18810.717839232424;18999;19006.23975040983;1034.7361422837128;1050.3382868792612;1054.541274545575;1056.181459368995;1056.3820165203288;10341.586336546454;10508.159347252355;10524.894751052545;10658.680239592824;10846;385231;396659;399119;402097;403533;104648.47675761572;105596.47201763942;106576.46711766547;107163.46418268015;107457.46271268594;1070.550368845087;1113.8273567597034;1173.1084375874398;1180.0913296761446;1231.8829711177423;19194;19444.611107778048;19527.804721952598;19716.90141549283;19735.60528789445;50904.74547627238;50942.490575093776;51614.22578661248;51852.74073629608;53229;66721;67367.3263267361;67411.66294168498;67531;67628.66185669237;18796.906015470384;19077;19199.712004320227;19345.129469174204;19416.320428784926;21735.23926662559;21832.67250991268;21981.560368792216;22042.228522001653;22204.88897555502;17766.644667106328;18106.36627718076;18127.456176314732;18128.90935545314;18151;14835.480758173411;15927.124008179662;15998.280077396312;16345.26446309895;16624.25190866438;17065.232064557375;17304;17412;17540.298388064846;17652.38216662462;18677.159527821008;18819.623607527497;18890.7166392507;18893.71659425137;18896.716549252047;17445.738313925554;17601.647967040844;17613.559661008578;17653.82346176522;18032.549186270448 11:25:52 INFO - PID 19391 | |3;mootools.html;1267.5183430296488;1323.3317174826718;1336.398620620721;1340.5040135149995;1346.6835293705976;14860.554183374503;15474.148921809296;15482.767758483618;15504.844951550509;15509.379624814994;15129.848701512981;15278.15970121643;15329.463468778595;15331.386744530206;15337.92331038346;2682.7317268273214;2751.477219328331;2754.2701184186208;2762.047093752662;2770.390514086898;2679.6650418697664;2704.9594256086175;2713.2131681812252;2717.429339838636;2717.714639962805;2570.190390027145;2671.252049426164;2761.4615150045747;2773.72262773722;2775.3339198592294;2711.4577084582966;2720.700722920487;2736.9726302736917;2749.1889892481668;2751.284665986848;2681.3430709476106;2686.5835795451735;2705.0126703753185;2707.323169207698;2710.0243912191595;14799.482018129314;15165.924170379078;15191.696166076727;15198.164100974442;15198.544043678707;7395.334419902235;7681.807954801119;7688.308052275322;7694.769156925301;7709.4988825726705;4956.851294461136;5075.796968121271;5107.9744601277125;5134.537891589737;5154.484551544853;1827.3604238516546;1853.2309091726918;1878.079740926936;1878.7557617509635;1910.2740958435845;26121.477570448485;26237.73762262372;26256.8687156563;26334.868325658248;26342.20973370802;6309.747610095636;6361.332060133712;6540.8364790880605;6609.339066093352;6658.267590565034;16476;17265.050422226646;17447.21487533064;17454.825451745575;17471.56321091983;7637.198094200029;7849.725259615886;7861.095973962953;7869.055713314439;7897.368210543258;7205.9551365051775;7263.745768898063;7288.3076107769675;7319.451041171933;7324.890126648104;4832.589229915427;4980.90038199234;5004.649674522821;5010.67430617016;5014.17266151081;1335.7194989052214;1364.5565191312862;1375.2711063136553;1386.729587730403;1399.7480453518465;859.7721603775004;887.4586502233632;899.5637115998735;902.7632143962682;904.5070436612062;7207.89188162178;7267.345938865422;7269.491135620456;7316.085489313836;7371.631418429057;684.8253695307765;692.9584224946519;707.900893874858;713.0872483221485;720.3300930135025;5081.18701007837;5114.769835357418;5234.057869583436;5378.489043540857;5381.0583147948955;21220.681689774665;21460;21691.132354705634;21713.56572868534;21775;20128.89935550342;21015.78984210169;21280;21311;21360.89319553392;10791.406472643923;10900.61847835306;10934.726631834268;10942.23404361687;10960.616378426717;7597.582132982518;8440.577971101298;8518.82962340762;8594.097619750086;8680.609372578123;18370.989595572373;19058.42824715261;19350.225990960203;19371.322003730365;19458.708119377938;19014.714779278045;19423.70864437063;19757.90121049443;19992.400227993185;20005.59988800245;10894.61868834587;11091.33451992883;11197.328160310408;11201.775964480501;11212.159088068265;13569.93215033958;13633.931830340785;13663.453461861813;13722.65693357674;13749.312534373441;11118.777624447304;11138.053265472525;11291.717707057389;11403.372814494951;11407.828882566933;10962.561497540328;10966.780664386828;11106.278091924;11225.270357426742;11245.156613253876;9510.334276600572;9634.7591310215;9690.660826871026;9718.076782705632;9742.07450292221;6984.4761642878;7031.296870313133;7045.647717613996;7093.900445430966;7110.1112360954885;6914.343137401939;6946.097007389007;6954.200266969363;6990.580565166107;7018.5788852669 11:25:52 INFO - PID 19391 | |4;prototype.html;490.2964246306548;503.3858692395281;516.4112911281139;520.6511637203075;523.2831021500544;6188.710177234424;6210.844728881781;6286.497080233582;6339.619622822628;6353.142325786018;6079.240094988127;6081.905257053729;6114.969425152868;6172.290186628539;6213.068039794033;4993.226049962243;5049.293098966147;5049.419316778581;5085.5423011928915;5102.132637451624;4730.881727956794;4979.701217926918;5082.449852794896;5085;5125.743712814363;4842.394700662418;4880.463149053601;4896.412430508344;4896.43690975537;4898.755062246892;4824.421069471686;4861.3923259592375;4893.412790465167;4923.212286034253;4955.504449555052;4385;4389.429374181368;4619.769011549409;4744.312074749142;4797.328374027639;6041.667708276043;6068.271807383098;6122.693865306762;6147.754089836401;6206.844828879269;5927.673977931212;5943.762249510014;5947.702614869239;5954.55340849438;5979.940200597982;6652.501062420338;6669.933300667029;6673.299303573152;6673.799786006428;6756.662166891636;5288.365396152486;5294.743732604459;5297.708626025567;5298.284731561228;5305.283786688786;6665.633390163539;6813.727450901958;6849.1780986281965;6866.725330986755;6936.514443988974;5425.376081750649;5426.375966763872;5427.3215848019;5452.018636645363;5478.890422191534;5468.48049435303;5510.641808282529;5518.23416506714;5544.91682624761;5549.1676248563035;5851.444112809277;5902.314446926638;5917.940820591825;5923.940760592425;5939.584229103922;6606.966965165239;6666.866662666719;6681.732730690718;6695.41795139598;6722.991551267349;1899.7150427435995;1915.1381878154607;1918.1368384227153;1918.3381733301987;1919.7312376267334;1351.7431687979251;1393.393873664959;1399.139529189541;1421.5451055662295;1449.14500444736;5624.578156638268;5679.290088738908;5684.289463817023;5713.8285851424525;5718.885622287531;6689;6730.259671436138;6732.764353247613;6732.764353247613;6761.8647627047185;5673.234113394721;5710.344000239967;5736.971315143398;5752.884942301131;5757.366689664133;3563.750537462353;3564.536610240652;3571.2143328468214;3586.264815712773;3590.479380489867;6402.903956440658;6427.775027874002;6459.677016149174;6481.319461456574;6546.803595892131;6882.208546017272;6915.928031155179;6936.653167341613;6952.801711212797;6959.512834101665;6375.872482550323;6434.034894765729;6457.967710161419;6517.315681853432;6526.24948130952;6660.933390666031;6703.195616526048;6731.865362692816;6744.156980377453;6798.966005169942;6607.735690572324;6616.768413105518;6644.235912870082;6710.362515561014;6751.459883209233;6409.871802563829;6436.967815160894;6437.227532696106;6463.127477790438;6482.643454610042;5391.29913111293;5436.3476382834315;5442.23808666779;5453.400125986218;5455.454454554513;6505.967470162619;6569.540132190701;6621.635810030494;6667.466602671871;6693.665316734241;6506.934930650633;6521.9673901630185;6557.540972131905;6566.4418524424045;6571.507136964651;6554.14796076507;6561.376669216417;6567.704453299709;6576.210854697467;6577.704003319766;6418.03729440569;6450.322716114741;6459.192600924885;6471.417572418505;6494.74021039172;6477.773277935438;6520.380563846427;6538.528831012984;6581.769638062645;6636.506785973117;6322.936770632235;6332.778352757631;6349.428551430394;6390.90413643805;6410.423061924449 11:25:52 INFO - PID 19391 | |5;yui.html;890.1009979920278;901.855703087506;918.0681608167711;920.1258804136072;932.748157997341;19080.42758717239;20004.799952000492;20025.899870500663;20037.298694545694;20041.99790010501;17666.47000589981;17967.371142010034;18058.909705451453;18086.81913180868;18410.539736506562;947.4031360243055;964.7732782796037;970.2819913264169;977.9070988256104;979.4612962870428;951.3245595627113;979.1970584121024;982.7346616413564;983.9065288797552;985.8176237396069;1045.028123844824;1051.75283808305;1055.3351388625156;1055.8257887448563;1059.2214722179192;991.4992928571025;1022.8158931392346;1052.4001319248032;1052.6263176572359;1052.9578816847318;754.5887491317245;755.6599530211427;756.0247281007494;756.1946526948782;756.3533179131873;19612.215511379527;19842.900785495982;20067.397978060537;20087.89956050225;20095.397138085882;18310.359137430125;18815.811841881543;19016.429507114808;19244.903775481172;19296;17134.31462741489;17309.826901730947;17437.38969136087;17473.825261747348;17483.650326993517;3615.620359862229;3647.74465787395;3653.799041052741;3656.5063716398213;3657.487951686766;129641.35179324137;132272.33863830715;132324.99999999904;133241.33379333137;134363;16386.83613163853;16396.83603163977;16597.83402165987;16617;16764.245608947625;30650.38699226003;30850.845745771578;31116.68883311138;31256.687433125833;31275.84362078175;7662.348700360531;7689.038870141232;7714.807129821799;7718.614069296513;7736.6518506667335;4022.7586344819406;4142.068034692231;4142.150859073943;4148.419221309019;4172.311568591209;5042.722650254272;5105.463926287761;5170.896582068338;5248.3701955765555;5254.763535640829;956.2254573795248;973.1290495007071;976.5556671714495;994.2990191914682;996.0288718499405;694.5173104692191;715.695829272557;716.8458781362032;735.4484136897327;738.4535443771678;12469.937650311691;12538.247705137721;12543.310117943418;12675.746485070431;12862.421191046426;579.3366595248417;583.9474447299764;588.1648059755072;588.4292236530644;592.8340064781868;5627.493525582717;5678.801241956594;5762.481376676119;5844.590878638454;5845.912311315334;117632;119716;121186;125078;126738.36630816972;102406;102559.487202565;104112;105672;109612.4519377398;91646;91940.54029729808;92049;94541;96715.032849672;10547.78904421892;10692.518836652214;10704.571817127227;10742;10924.071453926505;42963.785181073894;43092;43568;44214.336784947605;45775.313370298805;54177;56069.71965140148;56578;56742.14886776784;59059;14433.566992990227;14579.34392952341;14770.261486925825;14891.180985045927;15023.849761502683;17135.828641713422;17138.143092845556;17267.91366043162;17362.131893405025;17390.30438782485;15740.60648483797;16087.919560402122;16113.033218006956;16114.677706445571;16119.758203627189;15087.321070551632;15297.541073767805;15303.540893773205;15344.616384590474;15385.846141538888;14647.194404307866;14686.339114739649;14766.778498322748;14820;14873.405063797327;11083.61207357771;11156.609518666808;11341.659750207507;11357.716057098638;11518.53925842957;12245.142840001114;12288.938555307166;12474.313912734886;12511.99904007659;12676.922461590868 11:25:52 INFO - PID 19391 | __end_tp_report 11:25:52 INFO - PID 19391 | __start_cc_report 11:25:52 INFO - PID 19391 | _x_x_mozilla_cycle_collect,358 11:25:52 INFO - PID 19391 | __end_cc_report 11:25:52 INFO - PID 19391 | __startTimestamp1505067952586__endTimestamp 11:25:52 INFO - PID 19391 | 11:25:52 INFO - PID 19391 | ------- Summary: start ------- 11:25:52 INFO - PID 19391 | Number of tests: 6 11:25:52 INFO - PID 19391 | 11:25:52 INFO - PID 19391 | [#0] dojo.html Cycles:165 Average:6206.12 Median:6468.32 stddev:743.43 (11.5%) stddev-sans-first:705.40 11:25:52 INFO - PID 19391 | Values: 3127.8 3142.8 3166.8 3170.6 3171.1 6260.8 6290.6 6307.6 6344.8 6417.9 6302.4 6361.9 6383.1 6398.7 6413.5 5648.4 5785.8 5836.3 5856.7 5866.5 5722.0 5769.5 5834.3 5839.4 5878.3 5754.6 5777.7 5816.9 5821.6 5826.1 5726.5 5771.3 5797.8 5803.8 5843.3 5656.5 5758.7 5773.3 5842.0 5854.5 6207.2 6290.6 6293.4 6324.7 6475.6 6745.5 6807.4 6814.8 6817.6 6827.7 6719.8 6750.4 6837.0 6850.2 6861.3 6120.2 6174.1 6183.3 6211.8 6270.8 6807.8 6860.0 6884.4 6895.5 6949.4 6687.8 6745.6 6746.7 6749.4 6749.7 6779.2 6792.9 6856.2 6860.5 6875.1 6529.0 6704.4 6728.3 6819.6 6821.5 6666.1 6666.5 6755.9 6759.2 6764.5 5344.1 5346.4 5361.8 5365.4 5366.7 4612.8 4645.2 4662.1 4675.3 4677.0 5590.1 5713.5 5717.5 5805.0 5878.3 6645.0 6645.6 6737.6 6809.2 6813.1 5788.9 5847.7 5853.9 5858.5 5858.9 5816.3 5816.9 5877.9 5895.0 5899.3 6484.8 6508.2 6532.2 6612.3 6617.6 6564.3 6601.2 6680.0 6696.3 6874.6 6432.0 6468.3 6480.1 6480.8 6549.2 6678.0 6694.4 6772.6 6786.2 6787.8 6719.5 6746.1 6755.1 6844.1 6851.7 6369.6 6452.8 6481.0 6536.5 6620.0 6596.9 6601.1 6609.3 6637.9 6642.8 6475.1 6483.2 6483.4 6572.6 6575.3 6531.7 6590.0 6609.0 6643.3 6711.1 6208.8 6251.6 6317.7 6349.7 6355.7 11:25:52 INFO - PID 19391 | 11:25:52 INFO - PID 19391 | [#1] ext.html Cycles:180 Average:35694.09 Median:34598.00 stddev:49778.10 (143.9%) stddev-sans-first:49848.57 11:25:52 INFO - PID 19391 | Values: 746.4 767.5 768.7 769.3 774.2 37674.0 41400.2 41427.8 41432.8 41502.8 37507.4 37725.8 37853.2 37866.8 37930.8 7080.5 7773.3 7994.1 8000.0 8011.0 7865.1 7939.4 7968.1 7977.3 7977.5 7946.2 7964.2 7995.0 8024.8 8026.0 7900.9 7964.7 7965.1 7984.9 7985.0 7852.3 7913.6 7915.6 7920.9 7944.6 37208.6 37289.0 37379.0 37424.6 37441.6 3537.4 3580.6 3581.4 3582.9 3583.1 31083.7 31139.7 31231.2 31237.7 31283.4 5277.6 5282.3 5316.5 5330.5 5353.2 181352.0 181687.0 182801.0 182823.0 183157.0 41468.0 42205.0 47904.8 48128.3 48235.8 50785.0 51000.2 51121.7 51153.0 51167.5 14200.0 14274.0 14328.8 14341.1 14379.6 11465.5 11485.1 11518.3 11553.4 11554.2 14192.6 14204.6 14244.1 14383.5 14393.6 5698.6 5772.8 5792.6 5827.9 6032.5 1025.0 1028.1 1033.8 1038.0 1040.8 22450.4 22613.2 22782.9 22868.4 23197.2 1110.2 1128.3 1128.6 1128.7 1130.6 11972.9 12106.3 12121.5 12145.2 12238.7 210184.0 210217.0 210645.0 211304.0 212406.0 50676.0 50768.5 50867.7 50913.7 50965.5 1410.8 1426.7 1455.2 1465.7 1470.4 21762.0 21804.3 22202.6 22479.7 22634.5 36123.6 36347.8 36424.6 36424.6 36446.6 181789.0 182761.0 182877.0 183339.0 183489.0 31650.8 31869.7 32067.8 32106.7 32218.4 41047.2 41075.0 41114.2 41166.2 41279.4 30181.2 30371.7 30608.2 30967.8 31117.0 30593.2 30594.4 30871.2 31178.2 31459.7 34566.0 34630.0 34700.8 34717.0 34741.0 29929.0 30140.0 30234.1 30357.4 30369.5 22223.6 22482.8 22523.8 22667.3 22930.8 11:25:52 INFO - PID 19391 | 11:25:52 INFO - PID 19391 | [#2] jquery.html Cycles:180 Average:32118.86 Median:21783.96 stddev:67460.90 (309.7%) stddev-sans-first:67608.51 11:25:52 INFO - PID 19391 | Values: 552.7 568.9 571.1 574.7 584.3 21894.8 22558.7 22760.5 22763.4 22810.3 22918.4 23713.4 23932.9 24122.9 24265.4 6198.6 6422.4 6435.3 6467.5 6473.8 6234.4 6327.0 6332.4 6339.5 6343.8 6246.3 6314.2 6512.5 6514.5 6529.0 6459.2 6494.1 6497.4 6498.0 6503.9 6018.8 6022.1 6741.2 7021.9 7058.2 29641.3 30054.2 30145.5 30153.4 30181.0 2081.6 2109.4 2114.0 2115.5 2124.9 19146.3 19690.5 19804.9 19955.2 20028.4 2517.3 2558.3 2576.3 2586.2 2593.3 114027.4 120455.4 125578.0 128285.0 132448.3 19719.6 19788.4 20619.7 20796.0 21392.0 39719.2 40200.2 40294.6 40299.6 40357.4 10290.3 10417.9 10559.6 10684.5 10698.4 8577.1 8590.6 8625.6 8643.3 8758.3 10842.7 10894.6 10913.4 10992.0 11382.0 3765.5 3950.3 4092.0 4150.2 4386.5 956.5 983.7 984.8 985.5 994.7 18183.6 18571.8 18810.7 18999.0 19006.2 1034.7 1050.3 1054.5 1056.2 1056.4 10341.6 10508.2 10524.9 10658.7 10846.0 385231.0 396659.0 399119.0 402097.0 403533.0 104648.5 105596.5 106576.5 107163.5 107457.5 1070.6 1113.8 1173.1 1180.1 1231.9 19194.0 19444.6 19527.8 19716.9 19735.6 50904.7 50942.5 51614.2 51852.7 53229.0 66721.0 67367.3 67411.7 67531.0 67628.7 18796.9 19077.0 19199.7 19345.1 19416.3 21735.2 21832.7 21981.6 22042.2 22204.9 17766.6 18106.4 18127.5 18128.9 18151.0 14835.5 15927.1 15998.3 16345.3 16624.3 17065.2 17304.0 17412.0 17540.3 17652.4 18677.2 18819.6 18890.7 18893.7 18896.7 17445.7 17601.6 17613.6 17653.8 18032.5 11:25:52 INFO - PID 19391 | 11:25:52 INFO - PID 19391 | [#3] mootools.html Cycles:180 Average:9229.63 Median:2695.77 stddev:6704.86 (248.7%) stddev-sans-first:6696.98 11:25:52 INFO - PID 19391 | Values: 1267.5 1323.3 1336.4 1340.5 1346.7 14860.6 15474.1 15482.8 15504.8 15509.4 15129.8 15278.2 15329.5 15331.4 15337.9 2682.7 2751.5 2754.3 2762.0 2770.4 2679.7 2705.0 2713.2 2717.4 2717.7 2570.2 2671.3 2761.5 2773.7 2775.3 2711.5 2720.7 2737.0 2749.2 2751.3 2681.3 2686.6 2705.0 2707.3 2710.0 14799.5 15165.9 15191.7 15198.2 15198.5 7395.3 7681.8 7688.3 7694.8 7709.5 4956.9 5075.8 5108.0 5134.5 5154.5 1827.4 1853.2 1878.1 1878.8 1910.3 26121.5 26237.7 26256.9 26334.9 26342.2 6309.7 6361.3 6540.8 6609.3 6658.3 16476.0 17265.1 17447.2 17454.8 17471.6 7637.2 7849.7 7861.1 7869.1 7897.4 7206.0 7263.7 7288.3 7319.5 7324.9 4832.6 4980.9 5004.6 5010.7 5014.2 1335.7 1364.6 1375.3 1386.7 1399.7 859.8 887.5 899.6 902.8 904.5 7207.9 7267.3 7269.5 7316.1 7371.6 684.8 693.0 707.9 713.1 720.3 5081.2 5114.8 5234.1 5378.5 5381.1 21220.7 21460.0 21691.1 21713.6 21775.0 20128.9 21015.8 21280.0 21311.0 21360.9 10791.4 10900.6 10934.7 10942.2 10960.6 7597.6 8440.6 8518.8 8594.1 8680.6 18371.0 19058.4 19350.2 19371.3 19458.7 19014.7 19423.7 19757.9 19992.4 20005.6 10894.6 11091.3 11197.3 11201.8 11212.2 13569.9 13633.9 13663.5 13722.7 13749.3 11118.8 11138.1 11291.7 11403.4 11407.8 10962.6 10966.8 11106.3 11225.3 11245.2 9510.3 9634.8 9690.7 9718.1 9742.1 6984.5 7031.3 7045.6 7093.9 7110.1 6914.3 6946.1 6954.2 6990.6 7018.6 11:25:52 INFO - PID 19391 | 11:25:52 INFO - PID 19391 | [#4] prototype.html Cycles:180 Average:5583.73 Median:6135.22 stddev:1513.05 (24.7%) stddev-sans-first:1468.20 11:25:52 INFO - PID 19391 | Values: 490.3 503.4 516.4 520.7 523.3 6188.7 6210.8 6286.5 6339.6 6353.1 6079.2 6081.9 6115.0 6172.3 6213.1 4993.2 5049.3 5049.4 5085.5 5102.1 4730.9 4979.7 5082.4 5085.0 5125.7 4842.4 4880.5 4896.4 4896.4 4898.8 4824.4 4861.4 4893.4 4923.2 4955.5 4385.0 4389.4 4619.8 4744.3 4797.3 6041.7 6068.3 6122.7 6147.8 6206.8 5927.7 5943.8 5947.7 5954.6 5979.9 6652.5 6669.9 6673.3 6673.8 6756.7 5288.4 5294.7 5297.7 5298.3 5305.3 6665.6 6813.7 6849.2 6866.7 6936.5 5425.4 5426.4 5427.3 5452.0 5478.9 5468.5 5510.6 5518.2 5544.9 5549.2 5851.4 5902.3 5917.9 5923.9 5939.6 6607.0 6666.9 6681.7 6695.4 6723.0 1899.7 1915.1 1918.1 1918.3 1919.7 1351.7 1393.4 1399.1 1421.5 1449.1 5624.6 5679.3 5684.3 5713.8 5718.9 6689.0 6730.3 6732.8 6732.8 6761.9 5673.2 5710.3 5737.0 5752.9 5757.4 3563.8 3564.5 3571.2 3586.3 3590.5 6402.9 6427.8 6459.7 6481.3 6546.8 6882.2 6915.9 6936.7 6952.8 6959.5 6375.9 6434.0 6458.0 6517.3 6526.2 6660.9 6703.2 6731.9 6744.2 6799.0 6607.7 6616.8 6644.2 6710.4 6751.5 6409.9 6437.0 6437.2 6463.1 6482.6 5391.3 5436.3 5442.2 5453.4 5455.5 6506.0 6569.5 6621.6 6667.5 6693.7 6506.9 6522.0 6557.5 6566.4 6571.5 6554.1 6561.4 6567.7 6576.2 6577.7 6418.0 6450.3 6459.2 6471.4 6494.7 6477.8 6520.4 6538.5 6581.8 6636.5 6322.9 6332.8 6349.4 6390.9 6410.4 11:25:52 INFO - PID 19391 | 11:25:52 INFO - PID 19391 | [#5] yui.html Cycles:180 Average:23732.45 Median:19727.56 stddev:34238.58 (173.6%) stddev-sans-first:34291.67 11:25:52 INFO - PID 19391 | Values: 890.1 901.9 918.1 920.1 932.7 19080.4 20004.8 20025.9 20037.3 20042.0 17666.5 17967.4 18058.9 18086.8 18410.5 947.4 964.8 970.3 977.9 979.5 951.3 979.2 982.7 983.9 985.8 1045.0 1051.8 1055.3 1055.8 1059.2 991.5 1022.8 1052.4 1052.6 1053.0 754.6 755.7 756.0 756.2 756.4 19612.2 19842.9 20067.4 20087.9 20095.4 18310.4 18815.8 19016.4 19244.9 19296.0 17134.3 17309.8 17437.4 17473.8 17483.7 3615.6 3647.7 3653.8 3656.5 3657.5 129641.4 132272.3 132325.0 133241.3 134363.0 16386.8 16396.8 16597.8 16617.0 16764.2 30650.4 30850.8 31116.7 31256.7 31275.8 7662.3 7689.0 7714.8 7718.6 7736.7 4022.8 4142.1 4142.2 4148.4 4172.3 5042.7 5105.5 5170.9 5248.4 5254.8 956.2 973.1 976.6 994.3 996.0 694.5 715.7 716.8 735.4 738.5 12469.9 12538.2 12543.3 12675.7 12862.4 579.3 583.9 588.2 588.4 592.8 5627.5 5678.8 5762.5 5844.6 5845.9 117632.0 119716.0 121186.0 125078.0 126738.4 102406.0 102559.5 104112.0 105672.0 109612.5 91646.0 91940.5 92049.0 94541.0 96715.0 10547.8 10692.5 10704.6 10742.0 10924.1 42963.8 43092.0 43568.0 44214.3 45775.3 54177.0 56069.7 56578.0 56742.1 59059.0 14433.6 14579.3 14770.3 14891.2 15023.8 17135.8 17138.1 17267.9 17362.1 17390.3 15740.6 16087.9 16113.0 16114.7 16119.8 15087.3 15297.5 15303.5 15344.6 15385.8 14647.2 14686.3 14766.8 14820.0 14873.4 11083.6 11156.6 11341.7 11357.7 11518.5 12245.1 12288.9 12474.3 12512.0 12676.9 11:25:52 INFO - PID 19391 | -------- Summary: end -------- 11:25:52 INFO - PID 19391 | 11:25:53 INFO - TEST-INFO | 19391: exit 0 11:25:53 INFO - TEST-OK | dromaeo_css | took 1109947ms 11:25:53 INFO - TEST-START | kraken 11:25:53 INFO - Initialising browser for kraken test... 11:25:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55699/getInfo.html -profile /tmp/tmpvQcSQo/profile 11:25:53 INFO - TEST-INFO | started process 19774 (/builds/slave/test/build/application/firefox/firefox http://localhost:55699/getInfo.html) 11:25:59 INFO - TEST-INFO | 19774: exit 0 11:25:59 INFO - Browser initialized. 11:25:59 INFO - Running cycle 1/1 for kraken test... 11:25:59 INFO - Using env: {'DISPLAY': ':0', 11:25:59 INFO - 'HOME': '/home/cltbld', 11:25:59 INFO - 'JSGC_DISABLE_POISONING': '1', 11:25:59 INFO - 'LANG': 'en_US.UTF-8', 11:25:59 INFO - 'LANGUAGE': 'en_US:en', 11:25:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:25:59 INFO - 'LOGNAME': 'cltbld', 11:25:59 INFO - 'MAIL': '/var/mail/cltbld', 11:25:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:25:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:25:59 INFO - 'MOZ_CRASHREPORTER': '1', 11:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:25:59 INFO - 'MOZ_NO_REMOTE': '1', 11:25:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:25:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:59 INFO - 'NO_EM_RESTART': '1', 11:25:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:59 INFO - 'PWD': '/builds/slave/test', 11:25:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:25:59 INFO - 'RUST_BACKTRACE': 'full', 11:25:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:25:59 INFO - 'SHELL': '/bin/bash', 11:25:59 INFO - 'SHLVL': '1', 11:25:59 INFO - 'TERM': 'linux', 11:25:59 INFO - 'TMOUT': '86400', 11:25:59 INFO - 'USER': 'cltbld', 11:25:59 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013', 11:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:59 INFO - '_': '/tools/buildbot/bin/python'} 11:25:59 INFO - TEST-INFO | started process 19970 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvQcSQo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:26:00 INFO - PID 19970 | 11:26:00 INFO - PID 19970 | (/builds/slave/test/build/application/firefox/firefox:20021): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:26:00 INFO - PID 19970 | 11:26:11 INFO - PID 19970 | RSS: Main: 167133184 11:26:11 INFO - PID 19970 | 11:26:13 INFO - PID 19970 | Cycle 1(1): loaded http://localhost:55699/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55699/tests/kraken/driver.html?testName=audio-beat-detection) 11:26:14 INFO - PID 19970 | RSS: Main: 172163072 11:26:14 INFO - PID 19970 | 11:26:17 INFO - PID 19970 | Cycle 1(1): loaded http://localhost:55699/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55699/tests/kraken/driver.html?testName=audio-dft) 11:26:18 INFO - PID 19970 | RSS: Main: 172535808 11:26:18 INFO - PID 19970 | 11:26:21 INFO - PID 19970 | Cycle 1(1): loaded http://localhost:55699/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55699/tests/kraken/driver.html?testName=audio-fft) 11:26:21 INFO - PID 19970 | RSS: Main: 168407040 11:26:21 INFO - PID 19970 | 11:26:23 INFO - PID 19970 | Cycle 1(1): loaded http://localhost:55699/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55699/tests/kraken/driver.html?testName=audio-oscillator) 11:26:24 INFO - PID 19970 | RSS: Main: 168804352 11:26:24 INFO - PID 19970 | 11:26:26 INFO - PID 19970 | Cycle 1(1): loaded http://localhost:55699/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55699/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:26:27 INFO - PID 19970 | RSS: Main: 168706048 11:26:27 INFO - PID 19970 | 11:26:30 INFO - PID 19970 | Cycle 1(1): loaded http://localhost:55699/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55699/tests/kraken/driver.html?testName=imaging-darkroom) 11:26:31 INFO - PID 19970 | RSS: Main: 171511808 11:26:31 INFO - PID 19970 | 11:26:34 INFO - PID 19970 | Cycle 1(1): loaded http://localhost:55699/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55699/tests/kraken/driver.html?testName=imaging-desaturate) 11:26:35 INFO - PID 19970 | RSS: Main: 171352064 11:26:35 INFO - PID 19970 | 11:26:38 INFO - PID 19970 | Cycle 1(1): loaded http://localhost:55699/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55699/tests/kraken/driver.html?testName=json-parse-financial) 11:26:39 INFO - PID 19970 | RSS: Main: 171507712 11:26:39 INFO - PID 19970 | 11:26:40 INFO - PID 19970 | Cycle 1(1): loaded http://localhost:55699/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55699/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:26:41 INFO - PID 19970 | RSS: Main: 171900928 11:26:41 INFO - PID 19970 | 11:26:42 INFO - PID 19970 | Cycle 1(1): loaded http://localhost:55699/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55699/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:26:43 INFO - PID 19970 | RSS: Main: 172642304 11:26:43 INFO - PID 19970 | 11:26:45 INFO - PID 19970 | Cycle 1(1): loaded http://localhost:55699/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55699/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:26:46 INFO - PID 19970 | RSS: Main: 172740608 11:26:46 INFO - PID 19970 | 11:26:49 INFO - PID 19970 | Cycle 1(1): loaded http://localhost:55699/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55699/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:26:49 INFO - PID 19970 | RSS: Main: 173064192 11:26:49 INFO - PID 19970 | 11:26:52 INFO - PID 19970 | Cycle 1(1): loaded http://localhost:55699/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55699/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:26:53 INFO - PID 19970 | RSS: Main: 172883968 11:26:53 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | Cycle 1(1): loaded http://localhost:55699/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55699/tests/kraken/driver.html?testName=ai-astar) 11:26:55 INFO - PID 19970 | RSS: Main: 174747648 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | __start_tp_report 11:26:55 INFO - PID 19970 | _x_x_mozilla_page_load 11:26:55 INFO - PID 19970 | _x_x_mozilla_page_load_details 11:26:55 INFO - PID 19970 | |i|pagename|runs| 11:26:55 INFO - PID 19970 | |0;ai-astar;109;108;107;109;111;110;107;106;110;110 11:26:55 INFO - PID 19970 | |1;audio-beat-detection;117;117;118;151;119;118;116;117;127;118 11:26:55 INFO - PID 19970 | |2;audio-dft;166;164;167;164;164;162;170;170;169;165 11:26:55 INFO - PID 19970 | |3;audio-fft;75;75;77;80;75;73;74;73;74;75 11:26:55 INFO - PID 19970 | |4;audio-oscillator;79;80;81;79;82;78;79;79;79;80 11:26:55 INFO - PID 19970 | |5;imaging-gaussian-blur;113;113;114;110;114;113;114;114;115;115 11:26:55 INFO - PID 19970 | |6;imaging-darkroom;148;148;147;148;147;148;148;155;149;149 11:26:55 INFO - PID 19970 | |7;imaging-desaturate;105;95;94;94;94;94;95;94;94;93 11:26:55 INFO - PID 19970 | |8;json-parse-financial;53;56;54;52;51;52;59;53;54;54 11:26:55 INFO - PID 19970 | |9;json-stringify-tinderbox;38;38;38;38;38;38;38;38;38;38 11:26:55 INFO - PID 19970 | |10;stanford-crypto-aes;75;76;76;77;75;76;84;75;76;75 11:26:55 INFO - PID 19970 | |11;stanford-crypto-ccm;127;131;124;122;123;119;132;118;127;118 11:26:55 INFO - PID 19970 | |12;stanford-crypto-pbkdf2;182;174;171;172;174;184;177;172;175;177 11:26:55 INFO - PID 19970 | |13;stanford-crypto-sha256-iterative;65;65;65;66;67;67;68;69;73;66 11:26:55 INFO - PID 19970 | __end_tp_report 11:26:55 INFO - PID 19970 | __start_cc_report 11:26:55 INFO - PID 19970 | _x_x_mozilla_cycle_collect,906 11:26:55 INFO - PID 19970 | __end_cc_report 11:26:55 INFO - PID 19970 | __startTimestamp1505068015345__endTimestamp 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | ------- Summary: start ------- 11:26:55 INFO - PID 19970 | Number of tests: 14 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | [#0] ai-astar Cycles:10 Average:108.70 Median:109.50 stddev:1.64 (1.5%) stddev-sans-first:1.73 11:26:55 INFO - PID 19970 | Values: 109.0 108.0 107.0 109.0 111.0 110.0 107.0 106.0 110.0 110.0 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:118.00 stddev:10.72 (9.1%) stddev-sans-first:11.22 11:26:55 INFO - PID 19970 | Values: 117.0 117.0 118.0 151.0 119.0 118.0 116.0 117.0 127.0 118.0 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | [#2] audio-dft Cycles:10 Average:166.10 Median:166.50 stddev:2.81 (1.7%) stddev-sans-first:2.98 11:26:55 INFO - PID 19970 | Values: 166.0 164.0 167.0 164.0 164.0 162.0 170.0 170.0 169.0 165.0 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | [#3] audio-fft Cycles:10 Average:75.10 Median:75.00 stddev:2.08 (2.8%) stddev-sans-first:2.20 11:26:55 INFO - PID 19970 | Values: 75.0 75.0 77.0 80.0 75.0 73.0 74.0 73.0 74.0 75.0 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | [#4] audio-oscillator Cycles:10 Average:79.60 Median:79.50 stddev:1.17 (1.5%) stddev-sans-first:1.22 11:26:55 INFO - PID 19970 | Values: 79.0 80.0 81.0 79.0 82.0 78.0 79.0 79.0 79.0 80.0 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:114.00 stddev:1.43 (1.3%) stddev-sans-first:1.51 11:26:55 INFO - PID 19970 | Values: 113.0 113.0 114.0 110.0 114.0 113.0 114.0 114.0 115.0 115.0 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:148.00 stddev:2.31 (1.6%) stddev-sans-first:2.44 11:26:55 INFO - PID 19970 | Values: 148.0 148.0 147.0 148.0 147.0 148.0 148.0 155.0 149.0 149.0 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:94.00 stddev:3.49 (3.7%) stddev-sans-first:0.60 11:26:55 INFO - PID 19970 | Values: 105.0 95.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 93.0 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:2.30 (4.3%) stddev-sans-first:2.42 11:26:55 INFO - PID 19970 | Values: 53.0 56.0 54.0 52.0 51.0 52.0 59.0 53.0 54.0 54.0 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | [#9] json-stringify-tinderbox Cycles:10 Average:38.00 Median:38.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 11:26:55 INFO - PID 19970 | Values: 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | [#10] stanford-crypto-aes Cycles:10 Average:76.50 Median:76.00 stddev:2.72 (3.6%) stddev-sans-first:2.83 11:26:55 INFO - PID 19970 | Values: 75.0 76.0 76.0 77.0 75.0 76.0 84.0 75.0 76.0 75.0 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | [#11] stanford-crypto-ccm Cycles:10 Average:124.10 Median:125.50 stddev:5.09 (4.1%) stddev-sans-first:5.29 11:26:55 INFO - PID 19970 | Values: 127.0 131.0 124.0 122.0 123.0 119.0 132.0 118.0 127.0 118.0 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:176.00 stddev:4.32 (2.5%) stddev-sans-first:3.95 11:26:55 INFO - PID 19970 | Values: 182.0 174.0 171.0 172.0 174.0 184.0 177.0 172.0 175.0 177.0 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:2.47 (3.7%) stddev-sans-first:2.50 11:26:55 INFO - PID 19970 | Values: 65.0 65.0 65.0 66.0 67.0 67.0 68.0 69.0 73.0 66.0 11:26:55 INFO - PID 19970 | -------- Summary: end -------- 11:26:55 INFO - PID 19970 | 11:26:55 INFO - PID 19970 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:26:56 INFO - TEST-INFO | 19970: exit 0 11:26:56 INFO - TEST-OK | kraken | took 62761ms 11:26:56 INFO - SUITE-END | took 1172s 11:26:56 INFO - Completed test suite (00:19:33) 11:26:56 INFO - javascript benchmark 11:26:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8483.742479210017, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3127.8279694616795, 3142.8428578571065, 3166.82582457965, 3170.5878235829337, 3171.064535961891, 6260.843478913018, 6290.622562646245, 6307.558470907038, 6344.777932772354, 6417.903731444027, 6302.432781049704, 6361.9363806362035, 6383.074454204149, 6398.744050237985, 6413.486921046317, 5648.43515648436, 5785.8264252072295, 5836.2996440427205, 5856.736446859902, 5866.530677545797, 5721.971390143043, 5769.538436925036, 5834.2998840139035, 5839.445252700986, 5878.294604647448, 5754.568407369443, 5777.682227477487, 5816.91274630883, 5821.62159459638, 5826.067829147316, 5726.513246374063, 5771.278590176228, 5797.768089276424, 5803.7968671096305, 5843.2695913010875, 5656.519195868356, 5758.6544807311275, 5773.336066352381, 5842.036064049426, 5854.5023672987845, 6207.162033125561, 6290.622562646256, 6293.37066293367, 6324.7470101195895, 6475.611463312169, 6745.527813053088, 6807.353301436356, 6814.829629259309, 6817.5568588041615, 6827.658617069127, 6719.798406047827, 6750.35871592198, 6837.0, 6850.177978642595, 6861.279565645636, 6120.234970628671, 6174.135621013062, 6183.288921773964, 6211.782587609458, 6270.78052268173, 6807.761728339485, 6860.0, 6884.380405763405, 6895.5173137879865, 6949.44404447643, 6687.832804179837, 6745.628990405575, 6746.662666866637, 6749.392554670005, 6749.730010799513, 6779.220389655155, 6792.864142717118, 6856.211535673362, 6860.5197636164985, 6875.0718652982205, 6529.0, 6704.430123439464, 6728.293529179464, 6819.624920629314, 6821.488388370892, 6666.0667506549125, 6666.500012499082, 6755.898661520081, 6759.222689390783, 6764.526483146231, 5344.091504444254, 5346.4118946915805, 5361.758720857571, 5365.409804921455, 5366.6779993200535, 4612.815487380467, 4645.187092258841, 4662.067586482717, 4675.275332323427, 4677.0, 5590.077637189899, 5713.457221563945, 5717.542596592262, 5805.0, 5878.29460464747, 6644.966775166094, 6645.568038077509, 6737.629430381278, 6809.216940051859, 6813.1142951415995, 5788.88422231553, 5847.6783776891825, 5853.9414605854245, 5858.47273745365, 5858.912116318259, 5816.272965879265, 5816.941830581724, 5877.94122058774, 5895.0, 5899.321578018497, 6484.773032943825, 6508.219013718384, 6532.183477065367, 6612.305707900698, 6617.636030018299, 6564.310747371457, 6601.24085730128, 6680.0, 6696.330366963265, 6874.621895795779, 6432.0, 6468.320826313357, 6480.125183100221, 6480.805575832733, 6549.24683661385, 6677.966610167113, 6694.430973367318, 6772.627505487246, 6786.151731033621, 6787.762428314985, 6719.529632925648, 6746.089277947414, 6755.054292399166, 6844.1444819397575, 6851.691673874788, 6369.554131210771, 6452.774152904626, 6481.0, 6536.477081833537, 6620.04009418622, 6596.934030659632, 6601.108850305147, 6609.306022867722, 6637.900431493435, 6642.767503137367, 6475.125858009108, 6483.189601299838, 6483.384078512534, 6572.605643661395, 6575.342465753386, 6531.738730450728, 6590.044443555562, 6609.041688955171, 6643.30245324234, 6711.094002309821, 6208.844778880564, 6251.624902505864, 6317.684115794284, 6349.746010159542, 6355.7457701693265], "value": 6149.383696975101, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.3730466408218, 767.5202998126172, 768.7347864986581, 769.2730369800533, 774.2219069834812, 37674.0, 41400.17199656005, 41427.792861035654, 41432.75701728949, 41502.7924860376, 37507.437388439124, 37725.811370943105, 37853.24293514128, 37866.81066594663, 37930.8103459483, 7080.468964827633, 7773.261540153676, 7994.120646728884, 8000.0, 8011.0386753589655, 7865.095514015876, 7939.444238903279, 7968.12350641429, 7977.321927636158, 7977.521348719067, 7946.245106714881, 7964.163762804909, 7995.040595128593, 8024.759257222264, 8026.0, 7900.920990790076, 7964.721234756785, 7965.123836377994, 7984.880226796603, 7984.960075199587, 7852.254035866641, 7913.6438860250855, 7915.643796029192, 7920.920790792076, 7944.642491087915, 37208.62791372079, 37289.0, 37379.0, 37424.62575374239, 37441.625583744084, 3537.4340105582983, 3580.5524309461316, 3581.4090675038588, 3582.9283414331567, 3583.086312990197, 31083.68916310853, 31139.688603113904, 31231.219219519466, 31237.687623123933, 31283.37433251345, 5277.604179686539, 5282.286891269668, 5316.494932981361, 5330.493603107699, 5353.170258609922, 181352.0, 181687.0, 182801.0, 182823.0, 183157.0, 41468.0, 42205.0, 47904.76047619739, 48128.27807582889, 48235.75882120567, 50785.0, 51000.23499647434, 51121.721956951376, 51153.0, 51167.48832511702, 14200.0, 14274.0, 14328.785068223984, 14341.067830590979, 14379.568612941628, 11465.484053217626, 11485.0811935045, 11518.251313664585, 11553.36456494884, 11554.248973816675, 14192.574222773334, 14204.573862784133, 14244.07413518118, 14383.496577619942, 14393.568192954228, 5698.572607054488, 5772.792396128169, 5792.594518383757, 5827.88344233113, 6032.487238584769, 1025.0, 1028.141501845967, 1033.751899544119, 1037.968860934173, 1040.8282633365409, 22450.438739031655, 22613.208537701103, 22782.886085569466, 22868.4282892929, 23197.188098416475, 1110.1618278199908, 1128.3342827731515, 1128.6219116596033, 1128.6614015795392, 1130.6042884990188, 11972.940135299268, 12106.273623582612, 12121.454534545966, 12145.210561313486, 12238.694032649255, 210184.0, 210217.0, 210645.0, 211304.0, 212406.0, 50676.0, 50768.492315077114, 50867.74566127146, 50913.745431272604, 50965.49034509608, 1410.8377536583218, 1426.721789251086, 1455.1996401979081, 1465.6702241995679, 1470.43388295507, 21762.0, 21804.345869623936, 22202.55594888061, 22479.662805058266, 22634.547309054054, 36123.63876361203, 36347.81826090958, 36424.63575364318, 36424.63575364318, 36446.63553364432, 181789.0, 182761.0, 182877.0, 183339.0, 183489.0, 31650.841745791124, 31869.681303187597, 32067.839660801546, 32106.67893321037, 32218.35563288768, 41047.17905641811, 41075.0, 41114.17771644491, 41166.1766764657, 41279.38080928849, 30181.245468863453, 30371.696283036887, 30608.234794129436, 30967.845160774054, 31117.0, 30593.23516912095, 30594.388112237186, 30871.2282192947, 31178.22054448657, 31459.685403145675, 34566.0, 34630.0, 34700.82649586736, 34717.0, 34741.0, 29928.952486663733, 30140.0, 30234.09297721072, 30357.392852143275, 30369.544456833606, 22223.55486924203, 22482.775172248068, 22523.774762252822, 22667.319980400614, 22930.770692292863], "value": 16690.333119017872, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.7098273406461, 568.8919105369977, 571.1261769492677, 574.7212601888081, 584.3367777572454, 21894.781052189493, 22558.661620075713, 22760.54478910421, 22763.430914227112, 22810.31569052929, 22918.427039324026, 23713.407164820845, 23932.880335598296, 24122.87938560309, 24265.39336516588, 6198.566100372976, 6422.421982021617, 6435.292117867031, 6467.482601391901, 6473.838154046163, 6234.407731265523, 6327.0, 6332.430081292683, 6339.524535659821, 6343.809685709413, 6246.312905580383, 6314.179156709622, 6512.479001679878, 6514.543981921267, 6528.967355163241, 6459.224893012822, 6494.0583615375745, 6497.447716944065, 6498.0, 6503.902441463382, 6018.759249630009, 6022.066579680157, 6741.157355330583, 7021.929780702229, 7058.188308344555, 29641.25896852596, 30054.24864378408, 30145.547816782764, 30153.396932061234, 30181.0, 2081.6253074446595, 2109.430453777487, 2114.0, 2115.5134319106537, 2124.925627603042, 19146.329878454188, 19690.50773730668, 19804.900975495173, 19955.201791928306, 20028.399148025583, 2517.2699917024042, 2558.347621356561, 2576.2915198320425, 2586.2499875036215, 2593.286846117314, 114027.42986285016, 120455.39772301083, 125578.0, 128285.0, 132448.33775831157, 19719.605607887763, 19788.40634780959, 20619.69070463944, 20796.0, 21391.967763062483, 39719.20561588752, 40200.195996079914, 40294.59705402909, 40299.59700403017, 40357.394639080434, 10290.279680422449, 10417.895821041844, 10559.63041293551, 10684.51919663617, 10698.358098514114, 8577.142285771373, 8590.570471476402, 8625.568721563897, 8643.30853531716, 8758.2993360531, 10842.674719758263, 10894.564217431214, 10913.353832092289, 10992.0, 11382.0, 3765.5293088363956, 3950.3188721365273, 4091.97954010234, 4150.211459822624, 4386.539413361615, 956.5360800011989, 983.6557204978201, 984.837501812193, 985.4678473624163, 994.7314225159166, 18183.63632727338, 18571.814281857278, 18810.717839232424, 18999.0, 19006.23975040983, 1034.7361422837128, 1050.3382868792612, 1054.541274545575, 1056.181459368995, 1056.3820165203288, 10341.586336546454, 10508.159347252355, 10524.894751052545, 10658.680239592824, 10846.0, 385231.0, 396659.0, 399119.0, 402097.0, 403533.0, 104648.47675761572, 105596.47201763942, 106576.46711766547, 107163.46418268015, 107457.46271268594, 1070.550368845087, 1113.8273567597034, 1173.1084375874398, 1180.0913296761446, 1231.8829711177423, 19194.0, 19444.611107778048, 19527.804721952598, 19716.90141549283, 19735.60528789445, 50904.74547627238, 50942.490575093776, 51614.22578661248, 51852.74073629608, 53229.0, 66721.0, 67367.3263267361, 67411.66294168498, 67531.0, 67628.66185669237, 18796.906015470384, 19077.0, 19199.712004320227, 19345.129469174204, 19416.320428784926, 21735.23926662559, 21832.67250991268, 21981.560368792216, 22042.228522001653, 22204.88897555502, 17766.644667106328, 18106.36627718076, 18127.456176314732, 18128.90935545314, 18151.0, 14835.480758173411, 15927.124008179662, 15998.280077396312, 16345.26446309895, 16624.25190866438, 17065.232064557375, 17304.0, 17412.0, 17540.298388064846, 17652.38216662462, 18677.159527821008, 18819.623607527497, 18890.7166392507, 18893.71659425137, 18896.716549252047, 17445.738313925554, 17601.647967040844, 17613.559661008578, 17653.82346176522, 18032.549186270448], "value": 12376.004962282821, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1267.5183430296488, 1323.3317174826718, 1336.398620620721, 1340.5040135149995, 1346.6835293705976, 14860.554183374503, 15474.148921809296, 15482.767758483618, 15504.844951550509, 15509.379624814994, 15129.848701512981, 15278.15970121643, 15329.463468778595, 15331.386744530206, 15337.92331038346, 2682.7317268273214, 2751.477219328331, 2754.2701184186208, 2762.047093752662, 2770.390514086898, 2679.6650418697664, 2704.9594256086175, 2713.2131681812252, 2717.429339838636, 2717.714639962805, 2570.190390027145, 2671.252049426164, 2761.4615150045747, 2773.72262773722, 2775.3339198592294, 2711.4577084582966, 2720.700722920487, 2736.9726302736917, 2749.1889892481668, 2751.284665986848, 2681.3430709476106, 2686.5835795451735, 2705.0126703753185, 2707.323169207698, 2710.0243912191595, 14799.482018129314, 15165.924170379078, 15191.696166076727, 15198.164100974442, 15198.544043678707, 7395.334419902235, 7681.807954801119, 7688.308052275322, 7694.769156925301, 7709.4988825726705, 4956.851294461136, 5075.796968121271, 5107.9744601277125, 5134.537891589737, 5154.484551544853, 1827.3604238516546, 1853.2309091726918, 1878.079740926936, 1878.7557617509635, 1910.2740958435845, 26121.477570448485, 26237.73762262372, 26256.8687156563, 26334.868325658248, 26342.20973370802, 6309.747610095636, 6361.332060133712, 6540.8364790880605, 6609.339066093352, 6658.267590565034, 16476.0, 17265.050422226646, 17447.21487533064, 17454.825451745575, 17471.56321091983, 7637.198094200029, 7849.725259615886, 7861.095973962953, 7869.055713314439, 7897.368210543258, 7205.9551365051775, 7263.745768898063, 7288.3076107769675, 7319.451041171933, 7324.890126648104, 4832.589229915427, 4980.90038199234, 5004.649674522821, 5010.67430617016, 5014.17266151081, 1335.7194989052214, 1364.5565191312862, 1375.2711063136553, 1386.729587730403, 1399.7480453518465, 859.7721603775004, 887.4586502233632, 899.5637115998735, 902.7632143962682, 904.5070436612062, 7207.89188162178, 7267.345938865422, 7269.491135620456, 7316.085489313836, 7371.631418429057, 684.8253695307765, 692.9584224946519, 707.900893874858, 713.0872483221485, 720.3300930135025, 5081.18701007837, 5114.769835357418, 5234.057869583436, 5378.489043540857, 5381.0583147948955, 21220.681689774665, 21460.0, 21691.132354705634, 21713.56572868534, 21775.0, 20128.89935550342, 21015.78984210169, 21280.0, 21311.0, 21360.89319553392, 10791.406472643923, 10900.61847835306, 10934.726631834268, 10942.23404361687, 10960.616378426717, 7597.582132982518, 8440.577971101298, 8518.82962340762, 8594.097619750086, 8680.609372578123, 18370.989595572373, 19058.42824715261, 19350.225990960203, 19371.322003730365, 19458.708119377938, 19014.714779278045, 19423.70864437063, 19757.90121049443, 19992.400227993185, 20005.59988800245, 10894.61868834587, 11091.33451992883, 11197.328160310408, 11201.775964480501, 11212.159088068265, 13569.93215033958, 13633.931830340785, 13663.453461861813, 13722.65693357674, 13749.312534373441, 11118.777624447304, 11138.053265472525, 11291.717707057389, 11403.372814494951, 11407.828882566933, 10962.561497540328, 10966.780664386828, 11106.278091924, 11225.270357426742, 11245.156613253876, 9510.334276600572, 9634.7591310215, 9690.660826871026, 9718.076782705632, 9742.07450292221, 6984.4761642878, 7031.296870313133, 7045.647717613996, 7093.900445430966, 7110.1112360954885, 6914.343137401939, 6946.097007389007, 6954.200266969363, 6990.580565166107, 7018.5788852669], "value": 6509.85887055919, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.2964246306548, 503.3858692395281, 516.4112911281139, 520.6511637203075, 523.2831021500544, 6188.710177234424, 6210.844728881781, 6286.497080233582, 6339.619622822628, 6353.142325786018, 6079.240094988127, 6081.905257053729, 6114.969425152868, 6172.290186628539, 6213.068039794033, 4993.226049962243, 5049.293098966147, 5049.419316778581, 5085.5423011928915, 5102.132637451624, 4730.881727956794, 4979.701217926918, 5082.449852794896, 5085.0, 5125.743712814363, 4842.394700662418, 4880.463149053601, 4896.412430508344, 4896.43690975537, 4898.755062246892, 4824.421069471686, 4861.3923259592375, 4893.412790465167, 4923.212286034253, 4955.504449555052, 4385.0, 4389.429374181368, 4619.769011549409, 4744.312074749142, 4797.328374027639, 6041.667708276043, 6068.271807383098, 6122.693865306762, 6147.754089836401, 6206.844828879269, 5927.673977931212, 5943.762249510014, 5947.702614869239, 5954.55340849438, 5979.940200597982, 6652.501062420338, 6669.933300667029, 6673.299303573152, 6673.799786006428, 6756.662166891636, 5288.365396152486, 5294.743732604459, 5297.708626025567, 5298.284731561228, 5305.283786688786, 6665.633390163539, 6813.727450901958, 6849.1780986281965, 6866.725330986755, 6936.514443988974, 5425.376081750649, 5426.375966763872, 5427.3215848019, 5452.018636645363, 5478.890422191534, 5468.48049435303, 5510.641808282529, 5518.23416506714, 5544.91682624761, 5549.1676248563035, 5851.444112809277, 5902.314446926638, 5917.940820591825, 5923.940760592425, 5939.584229103922, 6606.966965165239, 6666.866662666719, 6681.732730690718, 6695.41795139598, 6722.991551267349, 1899.7150427435995, 1915.1381878154607, 1918.1368384227153, 1918.3381733301987, 1919.7312376267334, 1351.7431687979251, 1393.393873664959, 1399.139529189541, 1421.5451055662295, 1449.14500444736, 5624.578156638268, 5679.290088738908, 5684.289463817023, 5713.8285851424525, 5718.885622287531, 6689.0, 6730.259671436138, 6732.764353247613, 6732.764353247613, 6761.8647627047185, 5673.234113394721, 5710.344000239967, 5736.971315143398, 5752.884942301131, 5757.366689664133, 3563.750537462353, 3564.536610240652, 3571.2143328468214, 3586.264815712773, 3590.479380489867, 6402.903956440658, 6427.775027874002, 6459.677016149174, 6481.319461456574, 6546.803595892131, 6882.208546017272, 6915.928031155179, 6936.653167341613, 6952.801711212797, 6959.512834101665, 6375.872482550323, 6434.034894765729, 6457.967710161419, 6517.315681853432, 6526.24948130952, 6660.933390666031, 6703.195616526048, 6731.865362692816, 6744.156980377453, 6798.966005169942, 6607.735690572324, 6616.768413105518, 6644.235912870082, 6710.362515561014, 6751.459883209233, 6409.871802563829, 6436.967815160894, 6437.227532696106, 6463.127477790438, 6482.643454610042, 5391.29913111293, 5436.3476382834315, 5442.23808666779, 5453.400125986218, 5455.454454554513, 6505.967470162619, 6569.540132190701, 6621.635810030494, 6667.466602671871, 6693.665316734241, 6506.934930650633, 6521.9673901630185, 6557.540972131905, 6566.4418524424045, 6571.507136964651, 6554.14796076507, 6561.376669216417, 6567.704453299709, 6576.210854697467, 6577.704003319766, 6418.03729440569, 6450.322716114741, 6459.192600924885, 6471.417572418505, 6494.74021039172, 6477.773277935438, 6520.380563846427, 6538.528831012984, 6581.769638062645, 6636.506785973117, 6322.936770632235, 6332.778352757631, 6349.428551430394, 6390.90413643805, 6410.423061924449], "value": 5148.006983828547, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [890.1009979920278, 901.855703087506, 918.0681608167711, 920.1258804136072, 932.748157997341, 19080.42758717239, 20004.799952000492, 20025.899870500663, 20037.298694545694, 20041.99790010501, 17666.47000589981, 17967.371142010034, 18058.909705451453, 18086.81913180868, 18410.539736506562, 947.4031360243055, 964.7732782796037, 970.2819913264169, 977.9070988256104, 979.4612962870428, 951.3245595627113, 979.1970584121024, 982.7346616413564, 983.9065288797552, 985.8176237396069, 1045.028123844824, 1051.75283808305, 1055.3351388625156, 1055.8257887448563, 1059.2214722179192, 991.4992928571025, 1022.8158931392346, 1052.4001319248032, 1052.6263176572359, 1052.9578816847318, 754.5887491317245, 755.6599530211427, 756.0247281007494, 756.1946526948782, 756.3533179131873, 19612.215511379527, 19842.900785495982, 20067.397978060537, 20087.89956050225, 20095.397138085882, 18310.359137430125, 18815.811841881543, 19016.429507114808, 19244.903775481172, 19296.0, 17134.31462741489, 17309.826901730947, 17437.38969136087, 17473.825261747348, 17483.650326993517, 3615.620359862229, 3647.74465787395, 3653.799041052741, 3656.5063716398213, 3657.487951686766, 129641.35179324137, 132272.33863830715, 132324.99999999904, 133241.33379333137, 134363.0, 16386.83613163853, 16396.83603163977, 16597.83402165987, 16617.0, 16764.245608947625, 30650.38699226003, 30850.845745771578, 31116.68883311138, 31256.687433125833, 31275.84362078175, 7662.348700360531, 7689.038870141232, 7714.807129821799, 7718.614069296513, 7736.6518506667335, 4022.7586344819406, 4142.068034692231, 4142.150859073943, 4148.419221309019, 4172.311568591209, 5042.722650254272, 5105.463926287761, 5170.896582068338, 5248.3701955765555, 5254.763535640829, 956.2254573795248, 973.1290495007071, 976.5556671714495, 994.2990191914682, 996.0288718499405, 694.5173104692191, 715.695829272557, 716.8458781362032, 735.4484136897327, 738.4535443771678, 12469.937650311691, 12538.247705137721, 12543.310117943418, 12675.746485070431, 12862.421191046426, 579.3366595248417, 583.9474447299764, 588.1648059755072, 588.4292236530644, 592.8340064781868, 5627.493525582717, 5678.801241956594, 5762.481376676119, 5844.590878638454, 5845.912311315334, 117632.0, 119716.0, 121186.0, 125078.0, 126738.36630816972, 102406.0, 102559.487202565, 104112.0, 105672.0, 109612.4519377398, 91646.0, 91940.54029729808, 92049.0, 94541.0, 96715.032849672, 10547.78904421892, 10692.518836652214, 10704.571817127227, 10742.0, 10924.071453926505, 42963.785181073894, 43092.0, 43568.0, 44214.336784947605, 45775.313370298805, 54177.0, 56069.71965140148, 56578.0, 56742.14886776784, 59059.0, 14433.566992990227, 14579.34392952341, 14770.261486925825, 14891.180985045927, 15023.849761502683, 17135.828641713422, 17138.143092845556, 17267.91366043162, 17362.131893405025, 17390.30438782485, 15740.60648483797, 16087.919560402122, 16113.033218006956, 16114.677706445571, 16119.758203627189, 15087.321070551632, 15297.541073767805, 15303.540893773205, 15344.616384590474, 15385.846141538888, 14647.194404307866, 14686.339114739649, 14766.778498322748, 14820.0, 14873.405063797327, 11083.61207357771, 11156.609518666808, 11341.659750207507, 11357.716057098638, 11518.53925842957, 12245.142840001114, 12288.938555307166, 12474.313912734886, 12511.99904007659, 12676.922461590868], "value": 8758.139248522159, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1443.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 108.0, 107.0, 109.0, 111.0, 110.0, 107.0, 106.0, 110.0, 110.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 151.0, 119.0, 118.0, 116.0, 117.0, 127.0, 118.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 167.0, 164.0, 164.0, 162.0, 170.0, 170.0, 169.0, 165.0], "value": 166.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 80.0, 75.0, 73.0, 74.0, 73.0, 74.0, 75.0], "value": 75.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 81.0, 79.0, 82.0, 78.0, 79.0, 79.0, 79.0, 80.0], "value": 79.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 110.0, 114.0, 113.0, 114.0, 114.0, 115.0, 115.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 148.0, 147.0, 148.0, 148.0, 155.0, 149.0, 149.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 93.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 56.0, 54.0, 52.0, 51.0, 52.0, 59.0, 53.0, 54.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 38.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 77.0, 75.0, 76.0, 84.0, 75.0, 76.0, 75.0], "value": 76.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 131.0, 124.0, 122.0, 123.0, 119.0, 132.0, 118.0, 127.0, 118.0], "value": 124.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 174.0, 171.0, 172.0, 174.0, 184.0, 177.0, 172.0, 175.0, 177.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 67.0, 67.0, 68.0, 69.0, 73.0, 66.0], "value": 67.1, "unit": "score"}]}]} 11:26:56 INFO - Return code: 0 11:26:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:26:56 INFO - # TBPL SUCCESS # 11:26:56 INFO - Running post-action listener: _package_coverage_data 11:26:56 INFO - Running post-action listener: _resource_record_post_action 11:26:56 INFO - [mozharness: 2017-09-10 18:26:56.632401Z] Finished run-tests step (success) 11:26:56 INFO - Running post-run listener: _resource_record_post_run 11:26:56 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 298586112; Read time: 16; Write time: 171324 11:26:56 INFO - TinderboxPrint: CPU usage
13.1% 11:26:56 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 11:26:56 INFO - TinderboxPrint: I/O write bytes / time
298,586,112 / 171,324 11:26:56 INFO - TinderboxPrint: CPU idle
8,242.1 (86.9%) 11:26:56 INFO - TinderboxPrint: CPU user
1,194.2 (12.6%) 11:26:56 INFO - TinderboxPrint: Swap in / out
0 / 0 11:26:56 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 13148160; Read time: 0; Write time: 2144 11:26:56 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:26:56 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 285360128; Read time: 16; Write time: 169180 11:26:57 INFO - Running post-run listener: _upload_blobber_files 11:26:57 INFO - Blob upload gear active. 11:26:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:26:57 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 11:26:57 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'] 11:26:57 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 11:26:57 INFO - (blobuploader) - INFO - Open directory for files ... 11:26:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:26:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:26:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:26:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:26:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:26:58 INFO - (blobuploader) - INFO - Done attempting. 11:26:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:26:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:26:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:26:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:26:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:26:59 INFO - (blobuploader) - INFO - Done attempting. 11:26:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:26:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:26:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:27:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:27:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:27:06 INFO - (blobuploader) - INFO - Done attempting. 11:27:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:27:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:27:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:27:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:27:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:27:07 INFO - (blobuploader) - INFO - Done attempting. 11:27:07 INFO - (blobuploader) - INFO - Iteration through files over. 11:27:07 INFO - Return code: 0 11:27:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:27:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:27:07 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dce56a387584188e08aebab1cf7313298ec8f94ec53b68f952de00f08bd94110718a6b7b6cab1390df4c0ff7337aecd6432f89a6ff42ef89aaae2392f43cb9a9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/492d243d2f614b787734877b5c58e681c430a39f7bef478b7fe5fce9389a5ad8eef7dba5dd35727647891523ed34c8b7455d4c173c1a7dfcff9e113f087959ba", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/930c92e431fb57227016c7620ab51a727a9a9136353af4a04f38eaef47e2d52fb577e67060733a01110a14f64aac17f790d05de1447f1411911939cdf6e93387", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b07b04919e5ab8cd73d9a75446eec76aee2303fadc8d682a9a20307810257ebb655b74127f90ebaaeb131a1ae4e447ea49b0a8de675c0165b4c87b894a9cca66"} 11:27:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:27:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:27:07 INFO - Contents: 11:27:07 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dce56a387584188e08aebab1cf7313298ec8f94ec53b68f952de00f08bd94110718a6b7b6cab1390df4c0ff7337aecd6432f89a6ff42ef89aaae2392f43cb9a9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/492d243d2f614b787734877b5c58e681c430a39f7bef478b7fe5fce9389a5ad8eef7dba5dd35727647891523ed34c8b7455d4c173c1a7dfcff9e113f087959ba", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/930c92e431fb57227016c7620ab51a727a9a9136353af4a04f38eaef47e2d52fb577e67060733a01110a14f64aac17f790d05de1447f1411911939cdf6e93387", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b07b04919e5ab8cd73d9a75446eec76aee2303fadc8d682a9a20307810257ebb655b74127f90ebaaeb131a1ae4e447ea49b0a8de675c0165b4c87b894a9cca66"} 11:27:07 INFO - Running post-run listener: copy_logs_to_upload_dir 11:27:07 INFO - Copying logs to upload dir... 11:27:07 INFO - mkdir: /builds/slave/test/build/upload/logs 11:27:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.364496 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-10 11:27:07.214938) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-10 11:27:07.218956) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013 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/dce56a387584188e08aebab1cf7313298ec8f94ec53b68f952de00f08bd94110718a6b7b6cab1390df4c0ff7337aecd6432f89a6ff42ef89aaae2392f43cb9a9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/492d243d2f614b787734877b5c58e681c430a39f7bef478b7fe5fce9389a5ad8eef7dba5dd35727647891523ed34c8b7455d4c173c1a7dfcff9e113f087959ba", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/930c92e431fb57227016c7620ab51a727a9a9136353af4a04f38eaef47e2d52fb577e67060733a01110a14f64aac17f790d05de1447f1411911939cdf6e93387", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b07b04919e5ab8cd73d9a75446eec76aee2303fadc8d682a9a20307810257ebb655b74127f90ebaaeb131a1ae4e447ea49b0a8de675c0165b4c87b894a9cca66"} build_url:https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011377 build_url: 'https://queue.taskcluster.net/v1/task/UZWMEdQaTLGG8H3YMvQkdw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dce56a387584188e08aebab1cf7313298ec8f94ec53b68f952de00f08bd94110718a6b7b6cab1390df4c0ff7337aecd6432f89a6ff42ef89aaae2392f43cb9a9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/492d243d2f614b787734877b5c58e681c430a39f7bef478b7fe5fce9389a5ad8eef7dba5dd35727647891523ed34c8b7455d4c173c1a7dfcff9e113f087959ba", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/930c92e431fb57227016c7620ab51a727a9a9136353af4a04f38eaef47e2d52fb577e67060733a01110a14f64aac17f790d05de1447f1411911939cdf6e93387", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b07b04919e5ab8cd73d9a75446eec76aee2303fadc8d682a9a20307810257ebb655b74127f90ebaaeb131a1ae4e447ea49b0a8de675c0165b4c87b894a9cca66"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-10 11:27:07.247177) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 11:27:07.247552) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1505057342.397177-1449002013 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004540 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 11:27:07.330644) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-10 11:27:07.330982) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-10 11:27:07.331348) ========= ========= Total master_lag: 0.22 =========