builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-011 starttime: 1503623738.31 results: success (0) revision: 2306e153fba9ca55726ffcce889eaca7a479c29f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 18:15:38.310083) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-24 18:15:38.310538) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 18:15:38.310811) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-24 18:15:38.326649) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:15:38.326949) ========= 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=5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-24 18:15:38-- 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% 225M=0s 2017-08-24 18:15:38 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.186733 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:15:38.528550) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:15:38.528870) ========= 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=5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016052 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:15:38.578106) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-08-24 18:15:38.580532) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2306e153fba9ca55726ffcce889eaca7a479c29f --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 2306e153fba9ca55726ffcce889eaca7a479c29f --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=5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-24 18:15:38,762 truncating revision to first 12 chars 2017-08-24 18:15:38,762 Setting DEBUG logging. 2017-08-24 18:15:38,762 attempt 1/10 2017-08-24 18:15:38,762 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2306e153fba9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-24 18:15:41,060 unpacking tar archive at: mozilla-central-2306e153fba9/testing/mozharness/ program finished with exit code 0 elapsedTime=2.629429 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-08-24 18:15:41.224576) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 18:15:41.224939) ========= script_repo_revision: 2306e153fba9ca55726ffcce889eaca7a479c29f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-24 18:15:41.229373) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 18:15:41.229677) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-24 18:15:41.242885) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-08-24 18:15:41.243312) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:15:41 INFO - MultiFileLogger online at 20170824 18:15:41 in /builds/slave/test 18:15:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:15:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:15:41 INFO - {'append_to_log': False, 18:15:41 INFO - 'base_work_dir': '/builds/slave/test', 18:15:41 INFO - 'blob_upload_branch': 'Firefox', 18:15:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:15:41 INFO - 'branch': 'Firefox', 18:15:41 INFO - 'buildbot_json_path': 'buildprops.json', 18:15:41 INFO - 'code_coverage': False, 18:15:41 INFO - 'config_files': ('talos/linux_config.py',), 18:15:41 INFO - 'default_actions': ('clobber', 18:15:41 INFO - 'read-buildbot-config', 18:15:41 INFO - 'download-and-extract', 18:15:41 INFO - 'populate-webroot', 18:15:41 INFO - 'create-virtualenv', 18:15:41 INFO - 'install', 18:15:41 INFO - 'setup-mitmproxy', 18:15:41 INFO - 'run-tests'), 18:15:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:15:41 INFO - 'disable_ccov_upload': False, 18:15:41 INFO - 'download_minidump_stackwalk': True, 18:15:41 INFO - 'download_symbols': 'ondemand', 18:15:41 INFO - 'enable_stylo': False, 18:15:41 INFO - 'enable_webrender': False, 18:15:41 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:15:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:15:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:15:41 INFO - 'gecko_profile': False, 18:15:41 INFO - 'gecko_profile_interval': 0, 18:15:41 INFO - 'installer_path': 'installer.exe', 18:15:41 INFO - 'log_level': 'info', 18:15:41 INFO - 'log_name': 'talos', 18:15:41 INFO - 'log_to_console': True, 18:15:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:15:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:15:41 INFO - 'opt_config_files': (), 18:15:41 INFO - 'pip_index': False, 18:15:41 INFO - 'suite': 'dromaeojs-e10s', 18:15:41 INFO - 'system_bits': '32', 18:15:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:15:41 INFO - 'title': 'talos-linux64-ix-011', 18:15:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:15:41 INFO - 'use_talos_json': True, 18:15:41 INFO - 'verify': 'False', 18:15:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:15:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:15:41 INFO - 'work_dir': 'build'} 18:15:41 INFO - [mozharness: 2017-08-25 01:15:41.562873Z] Running clobber step. 18:15:41 INFO - Running pre-action listener: _resource_record_pre_action 18:15:41 INFO - Running main action method: clobber 18:15:41 INFO - rmtree: /builds/slave/test/build 18:15:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:15:42 INFO - Running post-action listener: _resource_record_post_action 18:15:42 INFO - [mozharness: 2017-08-25 01:15:42.587930Z] Finished clobber step (success) 18:15:42 INFO - [mozharness: 2017-08-25 01:15:42.588027Z] Running read-buildbot-config step. 18:15:42 INFO - Running pre-action listener: _resource_record_pre_action 18:15:42 INFO - Running main action method: read_buildbot_config 18:15:42 INFO - Using buildbot properties: 18:15:42 INFO - { 18:15:42 INFO - "project": "", 18:15:42 INFO - "product": "firefox", 18:15:42 INFO - "who": "ryanvm@gmail.com", 18:15:42 INFO - "installer_path": "public/build/target.tar.bz2", 18:15:42 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 18:15:42 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:15:42 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 18:15:42 INFO - "stage_platform": "linux64-stylosequential", 18:15:42 INFO - "basedir": "/builds/slave/test", 18:15:42 INFO - "buildnumber": 169, 18:15:42 INFO - "platform": "ubuntu64_hw_styloseq", 18:15:42 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:15:42 INFO - "slavebuilddir": "test", 18:15:42 INFO - "taskId": "RbUY8ZHtQvmdODADBYywcQ", 18:15:42 INFO - "branch": "mozilla-central", 18:15:42 INFO - "script_repo_revision": "production", 18:15:42 INFO - "revision": "2306e153fba9ca55726ffcce889eaca7a479c29f", 18:15:42 INFO - "slavename": "talos-linux64-ix-011", 18:15:42 INFO - "repo_path": "mozilla-central" 18:15:42 INFO - } 18:15:42 INFO - Finding installer, test and symbols from parent task. 18:15:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RbUY8ZHtQvmdODADBYywcQ'}, attempt #1 18:15:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RbUY8ZHtQvmdODADBYywcQ'}, attempt #1 18:15:43 INFO - Task dependencies: Y1BifvzVTmGQf0FseYcUxQ 18:15:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ'}, attempt #1 18:15:43 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2 18:15:43 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.test_packages.json 18:15:43 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.crashreporter-symbols.zip 18:15:43 INFO - Running post-action listener: _resource_record_post_action 18:15:43 INFO - [mozharness: 2017-08-25 01:15:43.970035Z] Finished read-buildbot-config step (success) 18:15:43 INFO - [mozharness: 2017-08-25 01:15:43.970252Z] Running download-and-extract step. 18:15:43 INFO - Running pre-action listener: _resource_record_pre_action 18:15:43 INFO - Running main action method: download_and_extract 18:15:43 INFO - mkdir: /builds/slave/test/build/tests 18:15:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:15:43 INFO - trying https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.test_packages.json 18:15:43 INFO - Downloading https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:15:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:15:47 INFO - Downloaded 989 bytes. 18:15:47 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:15:47 INFO - Using the following test package requirements: 18:15:47 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:15:47 INFO - u'common': [u'target.common.tests.zip'], 18:15:47 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:15:47 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:15:47 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:15:47 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:15:47 INFO - u'mozbase': [u'target.common.tests.zip'], 18:15:47 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:15:47 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:15:47 INFO - u'web-platform': [u'target.common.tests.zip', 18:15:47 INFO - u'target.web-platform.tests.tar.gz'], 18:15:47 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:15:47 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:15:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.common.tests.zip 18:15:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:15:47 INFO - Fetch https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.common.tests.zip into memory 18:15:48 INFO - Content-Length response header: 54979811 18:15:48 INFO - Bytes received: 54979811 18:15:59 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:15:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.common.tests.zip 18:15:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:15:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.common.tests.zip into memory 18:16:00 INFO - Content-Length response header: 54979811 18:16:00 INFO - Bytes received: 54979811 18:16:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.talos.tests.zip 18:16:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:16:13 INFO - Fetch https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.talos.tests.zip into memory 18:16:14 INFO - Content-Length response header: 14496615 18:16:14 INFO - Bytes received: 14496615 18:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:14 INFO - trying https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2 18:16:14 INFO - Downloading https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:16:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:16:15 INFO - Downloaded 61143859 bytes. 18:16:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2 18:16:15 INFO - mkdir: /builds/slave/test/properties 18:16:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:16:15 INFO - Writing to file /builds/slave/test/properties/build_url 18:16:15 INFO - Contents: 18:16:15 INFO - build_url:https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2 18:16:15 INFO - Running post-action listener: _resource_record_post_action 18:16:15 INFO - Running post-action listener: find_tests_for_verification 18:16:15 INFO - Running post-action listener: set_extra_try_arguments 18:16:15 INFO - [mozharness: 2017-08-25 01:16:15.930354Z] Finished download-and-extract step (success) 18:16:15 INFO - [mozharness: 2017-08-25 01:16:15.930450Z] Running populate-webroot step. 18:16:15 INFO - Running pre-action listener: _resource_record_pre_action 18:16:15 INFO - Running main action method: populate_webroot 18:16:15 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:16:15 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 18:16:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:16:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:16:15 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 18:16:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:16:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:16:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:16:15 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:16:15 INFO - 'talos_options': ['--stylo'], 18:16:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:16:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:16:15 INFO - 'tests': ['damp', 'tps']}, 18:16:15 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:16:15 INFO - 'talos_options': ['--stylo'], 18:16:15 INFO - 'tests': ['damp', 'tps']}, 18:16:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:16:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:16:15 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 18:16:15 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:16:15 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:16:15 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:16:15 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:16:15 INFO - 'talos_options': ['--stylo'], 18:16:15 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:16:15 INFO - 'other-e10s': {'tests': ['a11yr', 18:16:15 INFO - 'ts_paint', 18:16:15 INFO - 'tpaint', 18:16:15 INFO - 'sessionrestore', 18:16:15 INFO - 'sessionrestore_many_windows', 18:16:15 INFO - 'sessionrestore_no_auto_restore', 18:16:15 INFO - 'tabpaint']}, 18:16:15 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 18:16:15 INFO - 'tests': ['a11yr', 18:16:15 INFO - 'ts_paint', 18:16:15 INFO - 'tpaint', 18:16:15 INFO - 'sessionrestore', 18:16:15 INFO - 'sessionrestore_many_windows', 18:16:15 INFO - 'sessionrestore_no_auto_restore', 18:16:15 INFO - 'tabpaint']}, 18:16:15 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:16:15 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:16:15 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 18:16:15 INFO - 'tests': ['bloom_basic_singleton']}, 18:16:15 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 18:16:15 INFO - 'tests': ['bloom_basic']}, 18:16:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:16:15 INFO - 'tsvgr_opacity', 18:16:15 INFO - 'tart', 18:16:15 INFO - 'tscrollx', 18:16:15 INFO - 'tsvg_static']}, 18:16:15 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 18:16:15 INFO - 'tests': ['tsvgx', 18:16:15 INFO - 'tsvgr_opacity', 18:16:15 INFO - 'tart', 18:16:15 INFO - 'tscrollx', 18:16:15 INFO - 'tsvg_static']}, 18:16:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:16:15 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:16:15 INFO - 'talos_options': ['--stylo'], 18:16:15 INFO - 'tests': ['tp5o']}, 18:16:15 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:16:15 INFO - 'talos_options': ['--mitmproxy', 18:16:15 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:16:15 INFO - '--firstNonBlankPaint'], 18:16:15 INFO - 'tests': ['tp6_google', 18:16:15 INFO - 'tp6_youtube', 18:16:15 INFO - 'tp6_amazon', 18:16:15 INFO - 'tp6_facebook']}, 18:16:15 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:16:15 INFO - 'talos_options': ['--stylo', 18:16:15 INFO - '--mitmproxy', 18:16:15 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:16:15 INFO - '--firstNonBlankPaint'], 18:16:15 INFO - 'tests': ['tp6_google', 18:16:15 INFO - 'tp6_youtube', 18:16:15 INFO - 'tp6_amazon', 18:16:15 INFO - 'tp6_facebook']}, 18:16:15 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:16:15 INFO - 'talos_options': ['--stylo', 18:16:15 INFO - '--stylo-threads=1', 18:16:15 INFO - '--mitmproxy', 18:16:15 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:16:15 INFO - '--firstNonBlankPaint'], 18:16:15 INFO - 'tests': ['tp6_google', 18:16:15 INFO - 'tp6_youtube', 18:16:15 INFO - 'tp6_amazon', 18:16:15 INFO - 'tp6_facebook']}, 18:16:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:16:15 INFO - 'talos_options': ['--xperf_path', 18:16:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:16:15 INFO - 'tests': ['tp5n']}, 18:16:15 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:16:15 INFO - 'talos_options': ['--stylo', 18:16:15 INFO - '--xperf_path', 18:16:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:16:15 INFO - 'tests': ['tp5n']}}} 18:16:15 INFO - Running post-action listener: _resource_record_post_action 18:16:15 INFO - [mozharness: 2017-08-25 01:16:15.944691Z] Finished populate-webroot step (success) 18:16:15 INFO - [mozharness: 2017-08-25 01:16:15.944780Z] Running create-virtualenv step. 18:16:15 INFO - Running pre-action listener: _resource_record_pre_action 18:16:15 INFO - Running main action method: create_virtualenv 18:16:15 INFO - Creating virtualenv /builds/slave/test/build/venv 18:16:15 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:16:15 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:16:15 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:16:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:16:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:16:16 INFO - Installing distribute.............................................................................................................................................................................................done. 18:16:17 INFO - Installing pip...............done. 18:16:17 INFO - Return code: 0 18:16:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:16:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:16:17 INFO - Reading from file tmpfile_stdout 18:16:17 INFO - Output received: 18:16:17 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:16:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:16:17 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')]} 18:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:17 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')]} 18:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:17 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 0x26b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2883a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a26f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a24130>, '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 0x2a25030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a254c0>, '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': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', '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 18:16:17 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 18:16:17 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 18:16:17 INFO - Using env: {'DISPLAY': ':0', 18:16:17 INFO - 'HOME': '/home/cltbld', 18:16:17 INFO - 'LANG': 'en_US.UTF-8', 18:16:17 INFO - 'LANGUAGE': 'en_US:en', 18:16:17 INFO - 'LOGNAME': 'cltbld', 18:16:17 INFO - 'MAIL': '/var/mail/cltbld', 18:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:17 INFO - 'MOZ_NO_REMOTE': '1', 18:16:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:17 INFO - 'NO_EM_RESTART': '1', 18:16:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:17 INFO - 'PWD': '/builds/slave/test', 18:16:17 INFO - 'SHELL': '/bin/bash', 18:16:17 INFO - 'SHLVL': '1', 18:16:17 INFO - 'TERM': 'linux', 18:16:17 INFO - 'TMOUT': '86400', 18:16:17 INFO - 'USER': 'cltbld', 18:16:17 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', 18:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:17 INFO - '_': '/tools/buildbot/bin/python'} 18:16:18 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:16:18 INFO - Downloading/unpacking pip>=1.5 18:16:18 INFO - Running setup.py egg_info for package pip 18:16:18 INFO - warning: no files found matching 'pip/cacert.pem' 18:16:18 INFO - warning: no files found matching '*.html' under directory 'docs' 18:16:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:16:18 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:16:18 INFO - Installing collected packages: pip 18:16:18 INFO - Found existing installation: pip 1.1 18:16:18 INFO - Uninstalling pip: 18:16:18 INFO - Successfully uninstalled pip 18:16:18 INFO - Running setup.py install for pip 18:16:18 INFO - warning: no files found matching 'pip/cacert.pem' 18:16:18 INFO - warning: no files found matching '*.html' under directory 'docs' 18:16:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:16:18 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:16:18 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:16:18 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:16:18 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:16:18 INFO - Successfully installed pip 18:16:18 INFO - Cleaning up... 18:16:18 INFO - Return code: 0 18:16:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2883a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a26f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a24130>, '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 0x2a25030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a254c0>, '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': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', '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 18:16:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:16:18 INFO - Using env: {'DISPLAY': ':0', 18:16:18 INFO - 'HOME': '/home/cltbld', 18:16:18 INFO - 'LANG': 'en_US.UTF-8', 18:16:18 INFO - 'LANGUAGE': 'en_US:en', 18:16:18 INFO - 'LOGNAME': 'cltbld', 18:16:18 INFO - 'MAIL': '/var/mail/cltbld', 18:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:18 INFO - 'MOZ_NO_REMOTE': '1', 18:16:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:18 INFO - 'NO_EM_RESTART': '1', 18:16:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:18 INFO - 'PWD': '/builds/slave/test', 18:16:18 INFO - 'SHELL': '/bin/bash', 18:16:18 INFO - 'SHLVL': '1', 18:16:18 INFO - 'TERM': 'linux', 18:16:18 INFO - 'TMOUT': '86400', 18:16:18 INFO - 'USER': 'cltbld', 18:16:18 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', 18:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:18 INFO - '_': '/tools/buildbot/bin/python'} 18:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:18 INFO - Downloading/unpacking psutil>=3.1.1 18:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:16:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:16:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:16:19 INFO - Installing collected packages: psutil 18:16:19 INFO - Running setup.py install for psutil 18:16:19 INFO - building 'psutil._psutil_linux' extension 18:16:19 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 18:16:20 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 18:16:20 INFO - building 'psutil._psutil_posix' extension 18:16:20 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 18:16:20 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 18:16:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:16:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:16:20 INFO - Successfully installed psutil 18:16:20 INFO - Cleaning up... 18:16:20 INFO - Return code: 0 18:16:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:16:20 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')]} 18:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:20 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')]} 18:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:20 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 0x26b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2883a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a26f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a24130>, '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 0x2a25030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a254c0>, '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': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', '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 18:16:20 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 18:16:20 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 18:16:20 INFO - Using env: {'DISPLAY': ':0', 18:16:20 INFO - 'HOME': '/home/cltbld', 18:16:20 INFO - 'LANG': 'en_US.UTF-8', 18:16:20 INFO - 'LANGUAGE': 'en_US:en', 18:16:20 INFO - 'LOGNAME': 'cltbld', 18:16:20 INFO - 'MAIL': '/var/mail/cltbld', 18:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:20 INFO - 'MOZ_NO_REMOTE': '1', 18:16:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:20 INFO - 'NO_EM_RESTART': '1', 18:16:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:20 INFO - 'PWD': '/builds/slave/test', 18:16:20 INFO - 'SHELL': '/bin/bash', 18:16:20 INFO - 'SHLVL': '1', 18:16:20 INFO - 'TERM': 'linux', 18:16:20 INFO - 'TMOUT': '86400', 18:16:20 INFO - 'USER': 'cltbld', 18:16:20 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', 18:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:20 INFO - '_': '/tools/buildbot/bin/python'} 18:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:16:20 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 18:16:20 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 18:16:20 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 18:16:20 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 18:16:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:16:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:16:21 INFO - Installing collected packages: mozsystemmonitor 18:16:21 INFO - Running setup.py install for mozsystemmonitor 18:16:21 INFO - Successfully installed mozsystemmonitor 18:16:21 INFO - Cleaning up... 18:16:21 INFO - Return code: 0 18:16:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:16:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2883a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a26f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a24130>, '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 0x2a25030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a254c0>, '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': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', '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 18:16:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:16:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:16:21 INFO - Using env: {'DISPLAY': ':0', 18:16:21 INFO - 'HOME': '/home/cltbld', 18:16:21 INFO - 'LANG': 'en_US.UTF-8', 18:16:21 INFO - 'LANGUAGE': 'en_US:en', 18:16:21 INFO - 'LOGNAME': 'cltbld', 18:16:21 INFO - 'MAIL': '/var/mail/cltbld', 18:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:21 INFO - 'MOZ_NO_REMOTE': '1', 18:16:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:21 INFO - 'NO_EM_RESTART': '1', 18:16:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:21 INFO - 'PWD': '/builds/slave/test', 18:16:21 INFO - 'SHELL': '/bin/bash', 18:16:21 INFO - 'SHLVL': '1', 18:16:21 INFO - 'TERM': 'linux', 18:16:21 INFO - 'TMOUT': '86400', 18:16:21 INFO - 'USER': 'cltbld', 18:16:21 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', 18:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:21 INFO - '_': '/tools/buildbot/bin/python'} 18:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:22 INFO - Downloading/unpacking jsonschema==2.5.1 18:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:16:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:23 INFO - Downloading functools32-3.2.3-2.tar.gz 18:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:16:23 INFO - warning: no files found matching '*.txt' 18:16:23 INFO - no previously-included directories found matching 'build' 18:16:23 INFO - no previously-included directories found matching 'dist' 18:16:23 INFO - no previously-included directories found matching '.git*' 18:16:23 INFO - Installing collected packages: jsonschema, functools32 18:16:23 INFO - Running setup.py install for functools32 18:16:23 INFO - warning: no files found matching '*.txt' 18:16:23 INFO - no previously-included directories found matching 'build' 18:16:23 INFO - no previously-included directories found matching 'dist' 18:16:23 INFO - no previously-included directories found matching '.git*' 18:16:23 INFO - Successfully installed jsonschema functools32 18:16:23 INFO - Cleaning up... 18:16:23 INFO - Return code: 0 18:16:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:16: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')]} 18:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16: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')]} 18:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2883a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a26f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a24130>, '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 0x2a25030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a254c0>, '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': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', '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 18:16:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:16:23 INFO - Using env: {'DISPLAY': ':0', 18:16:23 INFO - 'HOME': '/home/cltbld', 18:16:23 INFO - 'LANG': 'en_US.UTF-8', 18:16:23 INFO - 'LANGUAGE': 'en_US:en', 18:16:23 INFO - 'LOGNAME': 'cltbld', 18:16:23 INFO - 'MAIL': '/var/mail/cltbld', 18:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:23 INFO - 'MOZ_NO_REMOTE': '1', 18:16:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:23 INFO - 'NO_EM_RESTART': '1', 18:16:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:23 INFO - 'PWD': '/builds/slave/test', 18:16:23 INFO - 'SHELL': '/bin/bash', 18:16:23 INFO - 'SHLVL': '1', 18:16:23 INFO - 'TERM': 'linux', 18:16:23 INFO - 'TMOUT': '86400', 18:16:23 INFO - 'USER': 'cltbld', 18:16:23 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', 18:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:23 INFO - '_': '/tools/buildbot/bin/python'} 18:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:16:23 INFO - Cleaning up... 18:16:23 INFO - Return code: 0 18:16:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:16: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')]} 18:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16: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')]} 18:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2883a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a26f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a24130>, '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 0x2a25030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a254c0>, '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': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', '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 18:16:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:16:23 INFO - Using env: {'DISPLAY': ':0', 18:16:23 INFO - 'HOME': '/home/cltbld', 18:16:23 INFO - 'LANG': 'en_US.UTF-8', 18:16:23 INFO - 'LANGUAGE': 'en_US:en', 18:16:23 INFO - 'LOGNAME': 'cltbld', 18:16:23 INFO - 'MAIL': '/var/mail/cltbld', 18:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:23 INFO - 'MOZ_NO_REMOTE': '1', 18:16:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:23 INFO - 'NO_EM_RESTART': '1', 18:16:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:23 INFO - 'PWD': '/builds/slave/test', 18:16:23 INFO - 'SHELL': '/bin/bash', 18:16:23 INFO - 'SHLVL': '1', 18:16:23 INFO - 'TERM': 'linux', 18:16:23 INFO - 'TMOUT': '86400', 18:16:23 INFO - 'USER': 'cltbld', 18:16:23 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', 18:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:23 INFO - '_': '/tools/buildbot/bin/python'} 18:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:23 INFO - Downloading/unpacking blobuploader==1.2.4 18:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:25 INFO - Downloading blobuploader-1.2.4.tar.gz 18:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:16:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:16:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:25 INFO - Downloading docopt-0.6.1.tar.gz 18:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:16:25 INFO - Installing collected packages: blobuploader, requests, docopt 18:16:25 INFO - Running setup.py install for blobuploader 18:16:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:16:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:16:25 INFO - Running setup.py install for requests 18:16:26 INFO - Running setup.py install for docopt 18:16:26 INFO - Successfully installed blobuploader requests docopt 18:16:26 INFO - Cleaning up... 18:16:26 INFO - Return code: 0 18:16:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16: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')]} 18:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16: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')]} 18:16:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2883a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a26f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a24130>, '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 0x2a25030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a254c0>, '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': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', '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 18:16:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:16:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:16:26 INFO - Using env: {'DISPLAY': ':0', 18:16:26 INFO - 'HOME': '/home/cltbld', 18:16:26 INFO - 'LANG': 'en_US.UTF-8', 18:16:26 INFO - 'LANGUAGE': 'en_US:en', 18:16:26 INFO - 'LOGNAME': 'cltbld', 18:16:26 INFO - 'MAIL': '/var/mail/cltbld', 18:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:26 INFO - 'MOZ_NO_REMOTE': '1', 18:16:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:26 INFO - 'NO_EM_RESTART': '1', 18:16:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:26 INFO - 'PWD': '/builds/slave/test', 18:16:26 INFO - 'SHELL': '/bin/bash', 18:16:26 INFO - 'SHLVL': '1', 18:16:26 INFO - 'TERM': 'linux', 18:16:26 INFO - 'TMOUT': '86400', 18:16:26 INFO - 'USER': 'cltbld', 18:16:26 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', 18:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:26 INFO - '_': '/tools/buildbot/bin/python'} 18:16:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:16:26 INFO - Running setup.py (path:/tmp/pip-h5q88c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:16:26 INFO - Running setup.py (path:/tmp/pip-557uk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:16:26 INFO - Running setup.py (path:/tmp/pip-ciWxkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:16:26 INFO - Running setup.py (path:/tmp/pip-taoDAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:16:26 INFO - Running setup.py (path:/tmp/pip-rOOc74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:16:26 INFO - Running setup.py (path:/tmp/pip-W1FAxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:16:26 INFO - Running setup.py (path:/tmp/pip-_QjlGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:16:26 INFO - Running setup.py (path:/tmp/pip-flHSh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:16:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:16:26 INFO - Running setup.py (path:/tmp/pip-FD9QjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:16:27 INFO - Running setup.py (path:/tmp/pip-7inZR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:16:27 INFO - Running setup.py (path:/tmp/pip-HWVQpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:16:27 INFO - Running setup.py (path:/tmp/pip-KDkokw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:16:27 INFO - Running setup.py (path:/tmp/pip-ZaH9fV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:16:27 INFO - Running setup.py (path:/tmp/pip-tkUGgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:27 INFO - Running setup.py (path:/tmp/pip-Fl7Bl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:16:27 INFO - Running setup.py (path:/tmp/pip-jAZ9p0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:16:27 INFO - Running setup.py (path:/tmp/pip-fFS4mN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:16:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:16:27 INFO - Running setup.py install for manifestparser 18:16:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:16:27 INFO - Running setup.py install for mozcrash 18:16:27 INFO - Running setup.py install for mozdebug 18:16:27 INFO - Running setup.py install for mozdevice 18:16:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:16:28 INFO - Running setup.py install for mozfile 18:16:28 INFO - Running setup.py install for mozhttpd 18:16:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:16:28 INFO - Running setup.py install for mozinfo 18:16:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:16:28 INFO - Running setup.py install for mozInstall 18:16:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:16:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:16:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:16:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:16:28 INFO - Running setup.py install for mozleak 18:16:28 INFO - Running setup.py install for mozlog 18:16:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:16:28 INFO - Running setup.py install for moznetwork 18:16:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:16:28 INFO - Running setup.py install for mozprocess 18:16:28 INFO - Running setup.py install for mozprofile 18:16:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:16:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:16:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:16:28 INFO - Running setup.py install for mozrunner 18:16:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:16:29 INFO - Running setup.py install for mozscreenshot 18:16:29 INFO - Running setup.py install for moztest 18:16:29 INFO - Running setup.py install for mozversion 18:16:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:16:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:16:29 INFO - Cleaning up... 18:16:29 INFO - Return code: 0 18:16:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2883a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a26f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a24130>, '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 0x2a25030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a254c0>, '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': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', '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 18:16:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:16:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:16:29 INFO - Using env: {'DISPLAY': ':0', 18:16:29 INFO - 'HOME': '/home/cltbld', 18:16:29 INFO - 'LANG': 'en_US.UTF-8', 18:16:29 INFO - 'LANGUAGE': 'en_US:en', 18:16:29 INFO - 'LOGNAME': 'cltbld', 18:16:29 INFO - 'MAIL': '/var/mail/cltbld', 18:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:29 INFO - 'MOZ_NO_REMOTE': '1', 18:16:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:29 INFO - 'NO_EM_RESTART': '1', 18:16:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:29 INFO - 'PWD': '/builds/slave/test', 18:16:29 INFO - 'SHELL': '/bin/bash', 18:16:29 INFO - 'SHLVL': '1', 18:16:29 INFO - 'TERM': 'linux', 18:16:29 INFO - 'TMOUT': '86400', 18:16:29 INFO - 'USER': 'cltbld', 18:16:29 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', 18:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:29 INFO - '_': '/tools/buildbot/bin/python'} 18:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:16:29 INFO - Running setup.py (path:/tmp/pip-1HUFqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:16:29 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)) 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:16:29 INFO - Running setup.py (path:/tmp/pip-6Gpl2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:16:29 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)) 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:16:29 INFO - Running setup.py (path:/tmp/pip-vlDzNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:16:29 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)) 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:16:29 INFO - Running setup.py (path:/tmp/pip-QRkaVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:16:29 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)) 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:16:29 INFO - Running setup.py (path:/tmp/pip-uYxD7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:16:29 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)) 18:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:16:29 INFO - Running setup.py (path:/tmp/pip-0RMZUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:16:30 INFO - Running setup.py (path:/tmp/pip-7ygynd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:16:30 INFO - Running setup.py (path:/tmp/pip-uQfE7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:16:30 INFO - Running setup.py (path:/tmp/pip-S0135F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:16:30 INFO - Running setup.py (path:/tmp/pip-6mpNAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:16:30 INFO - Running setup.py (path:/tmp/pip-Md4KH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:16:30 INFO - Running setup.py (path:/tmp/pip-pVYBrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:16:30 INFO - Running setup.py (path:/tmp/pip-evWhWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:16:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:16:30 INFO - Running setup.py (path:/tmp/pip-aLNNws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:16:30 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)) 18:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:30 INFO - Running setup.py (path:/tmp/pip-dnNWQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:30 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)) 18:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:16:30 INFO - Running setup.py (path:/tmp/pip-6kVLKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:16:30 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)) 18:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:16:30 INFO - Running setup.py (path:/tmp/pip-bb2Bge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:16:30 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)) 18:16:30 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)) 18:16:30 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)) 18:16:30 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)) 18:16:30 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)) 18:16:30 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)) 18:16:30 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)) 18:16:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:32 INFO - Downloading blessings-1.6.tar.gz 18:16:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:16:32 INFO - Installing collected packages: blessings 18:16:32 INFO - Running setup.py install for blessings 18:16:33 INFO - Successfully installed blessings 18:16:33 INFO - Cleaning up... 18:16:33 INFO - Return code: 0 18:16:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:16:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:16:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:16:33 INFO - Reading from file tmpfile_stdout 18:16:33 INFO - Current package versions: 18:16:33 INFO - argparse == 1.2.1 18:16:33 INFO - blessings == 1.6 18:16:33 INFO - blobuploader == 1.2.4 18:16:33 INFO - docopt == 0.6.1 18:16:33 INFO - functools32 == 3.2.3-2 18:16:33 INFO - jsonschema == 2.5.1 18:16:33 INFO - manifestparser == 1.1 18:16:33 INFO - mozInstall == 1.13 18:16:33 INFO - mozcrash == 1.0 18:16:33 INFO - mozdebug == 0.1 18:16:33 INFO - mozdevice == 0.51 18:16:33 INFO - mozfile == 1.2 18:16:33 INFO - mozhttpd == 0.7 18:16:33 INFO - mozinfo == 0.10 18:16:33 INFO - mozleak == 0.1 18:16:33 INFO - mozlog == 3.5 18:16:33 INFO - moznetwork == 0.27 18:16:33 INFO - mozprocess == 0.25 18:16:33 INFO - mozprofile == 0.28 18:16:33 INFO - mozrunner == 6.13 18:16:33 INFO - mozscreenshot == 0.1 18:16:33 INFO - mozsystemmonitor == 0.3 18:16:33 INFO - moztest == 0.8 18:16:33 INFO - mozversion == 1.4 18:16:33 INFO - psutil == 3.1.1 18:16:33 INFO - requests == 1.2.3 18:16:33 INFO - wsgiref == 0.1.2 18:16:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:33 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 0x26b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2883a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a26f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a24130>, '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 0x2a25030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a254c0>, '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': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', '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 18:16:33 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 18:16:33 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 18:16:33 INFO - Using env: {'DISPLAY': ':0', 18:16:33 INFO - 'HOME': '/home/cltbld', 18:16:33 INFO - 'LANG': 'en_US.UTF-8', 18:16:33 INFO - 'LANGUAGE': 'en_US:en', 18:16:33 INFO - 'LOGNAME': 'cltbld', 18:16:33 INFO - 'MAIL': '/var/mail/cltbld', 18:16:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:33 INFO - 'MOZ_NO_REMOTE': '1', 18:16:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:33 INFO - 'NO_EM_RESTART': '1', 18:16:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:33 INFO - 'PWD': '/builds/slave/test', 18:16:33 INFO - 'SHELL': '/bin/bash', 18:16:33 INFO - 'SHLVL': '1', 18:16:33 INFO - 'TERM': 'linux', 18:16:33 INFO - 'TMOUT': '86400', 18:16:33 INFO - 'USER': 'cltbld', 18:16:33 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', 18:16:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:33 INFO - '_': '/tools/buildbot/bin/python'} 18:16:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:16:33 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:16:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:16:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:16:35 INFO - Installing collected packages: simplejson 18:16:35 INFO - Running setup.py install for simplejson 18:16:35 INFO - building 'simplejson._speedups' extension 18:16:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:16:36 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:16:36 INFO - Successfully installed simplejson 18:16:36 INFO - Cleaning up... 18:16:36 INFO - Return code: 0 18:16:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26b81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2883a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a26f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a24130>, '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 0x2a25030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a254c0>, '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': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', '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 18:16:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:16:36 INFO - Using env: {'DISPLAY': ':0', 18:16:36 INFO - 'HOME': '/home/cltbld', 18:16:36 INFO - 'LANG': 'en_US.UTF-8', 18:16:36 INFO - 'LANGUAGE': 'en_US:en', 18:16:36 INFO - 'LOGNAME': 'cltbld', 18:16:36 INFO - 'MAIL': '/var/mail/cltbld', 18:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:36 INFO - 'MOZ_NO_REMOTE': '1', 18:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:36 INFO - 'NO_EM_RESTART': '1', 18:16:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:36 INFO - 'PWD': '/builds/slave/test', 18:16:36 INFO - 'SHELL': '/bin/bash', 18:16:36 INFO - 'SHLVL': '1', 18:16:36 INFO - 'TERM': 'linux', 18:16:36 INFO - 'TMOUT': '86400', 18:16:36 INFO - 'USER': 'cltbld', 18:16:36 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', 18:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:36 INFO - '_': '/tools/buildbot/bin/python'} 18:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:16:37 INFO - Cleaning up... 18:16:37 INFO - Return code: 0 18:16:37 INFO - Running post-action listener: _resource_record_post_action 18:16:37 INFO - Running post-action listener: _start_resource_monitoring 18:16:37 INFO - Starting resource monitoring. 18:16:37 INFO - [mozharness: 2017-08-25 01:16:37.074495Z] Finished create-virtualenv step (success) 18:16:37 INFO - [mozharness: 2017-08-25 01:16:37.074966Z] Running install step. 18:16:37 INFO - Running pre-action listener: _resource_record_pre_action 18:16:37 INFO - Running main action method: install 18:16:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:16:37 INFO - Reading from file tmpfile_stdout 18:16:37 INFO - Detecting whether we're running mozinstall >=1.0... 18:16:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:16:37 INFO - Reading from file tmpfile_stdout 18:16:37 INFO - Output received: 18:16:37 INFO - Usage: mozinstall [options] installer 18:16:37 INFO - Options: 18:16:37 INFO - -h, --help show this help message and exit 18:16:37 INFO - -d DEST, --destination=DEST 18:16:37 INFO - Directory to install application into. [default: 18:16:37 INFO - "/builds/slave/test"] 18:16:37 INFO - --app=APP Application being installed. [default: firefox] 18:16:37 INFO - mkdir: /builds/slave/test/build/application 18:16:37 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'] 18:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:16:55 INFO - Reading from file tmpfile_stdout 18:16:55 INFO - Output received: 18:16:55 INFO - /builds/slave/test/build/application/firefox/firefox 18:16:55 INFO - Running post-action listener: _resource_record_post_action 18:16:55 INFO - [mozharness: 2017-08-25 01:16:55.114566Z] Finished install step (success) 18:16:55 INFO - [mozharness: 2017-08-25 01:16:55.114821Z] Running setup-mitmproxy step. 18:16:55 INFO - Running pre-action listener: _resource_record_pre_action 18:16:55 INFO - Running main action method: setup_mitmproxy 18:16:55 INFO - Skipping: mitmproxy is not required 18:16:55 INFO - Running post-action listener: _resource_record_post_action 18:16:55 INFO - [mozharness: 2017-08-25 01:16:55.115939Z] Finished setup-mitmproxy step (success) 18:16:55 INFO - [mozharness: 2017-08-25 01:16:55.116158Z] Running run-tests step. 18:16:55 INFO - Running pre-action listener: _resource_record_pre_action 18:16:55 INFO - Running pre-action listener: _set_gcov_prefix 18:16:55 INFO - Running main action method: run_tests 18:16:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:16:55 INFO - Python 2.7.3 18:16:55 INFO - Return code: 0 18:16:55 INFO - grabbing minidump binary from tooltool 18:16:55 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')]} 18:16:55 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 0x2a24130>, '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 0x2a25030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a254c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:16:55 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 18:16:55 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 18:16:55 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 18:16:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:16:55 INFO - Return code: 0 18:16:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:16:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:16:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:16:55 INFO - ENV: RUST_BACKTRACE is now full 18:16:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:16:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:16:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 18:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 18:16:55 INFO - Using env: {'DISPLAY': ':0', 18:16:55 INFO - 'HOME': '/home/cltbld', 18:16:55 INFO - 'LANG': 'en_US.UTF-8', 18:16:55 INFO - 'LANGUAGE': 'en_US:en', 18:16:55 INFO - 'LOGNAME': 'cltbld', 18:16:55 INFO - 'MAIL': '/var/mail/cltbld', 18:16:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:16:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:55 INFO - 'MOZ_NO_REMOTE': '1', 18:16:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:16:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:55 INFO - 'NO_EM_RESTART': '1', 18:16:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:55 INFO - 'PWD': '/builds/slave/test', 18:16:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:16:55 INFO - 'RUST_BACKTRACE': 'full', 18:16:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:16:55 INFO - 'SHELL': '/bin/bash', 18:16:55 INFO - 'SHLVL': '1', 18:16:55 INFO - 'STYLO_FORCE_ENABLED': '1', 18:16:55 INFO - 'TERM': 'linux', 18:16:55 INFO - 'TMOUT': '86400', 18:16:55 INFO - 'USER': 'cltbld', 18:16:55 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', 18:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:55 INFO - '_': '/tools/buildbot/bin/python'} 18:16:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 18:16:55 INFO - mozversion application_buildid: 20170825002024 18:16:55 INFO - mozversion application_changeset: 2306e153fba9ca55726ffcce889eaca7a479c29f 18:16:55 INFO - mozversion application_display_name: Nightly 18:16:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:16:55 INFO - mozversion application_name: Firefox 18:16:55 INFO - mozversion application_remotingname: firefox 18:16:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:16:55 INFO - mozversion application_vendor: Mozilla 18:16:55 INFO - mozversion application_version: 57.0a1 18:16:55 INFO - mozversion platform_buildid: 20170825002024 18:16:55 INFO - mozversion platform_changeset: 2306e153fba9ca55726ffcce889eaca7a479c29f 18:16:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:16:55 INFO - mozversion platform_version: 57.0a1 18:16:55 INFO - using testdate: 1503623815 18:16:55 INFO - actual date: 1503623815 18:16:55 INFO - starting webserver on 'localhost:48416' 18:16:55 INFO - SUITE-START | Running 2 tests 18:16:55 INFO - TEST-START | dromaeo_css 18:16:55 INFO - Initialising browser for dromaeo_css test... 18:16:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48416/getInfo.html -profile /tmp/tmpQeVidR/profile 18:16:55 INFO - TEST-INFO | started process 13202 (/builds/slave/test/build/application/firefox/firefox http://localhost:48416/getInfo.html) 18:17:01 INFO - TEST-INFO | 13202: exit 0 18:17:01 INFO - Browser initialized. 18:17:01 INFO - Running cycle 1/1 for dromaeo_css test... 18:17:01 INFO - Using env: {'DISPLAY': ':0', 18:17:01 INFO - 'HOME': '/home/cltbld', 18:17:01 INFO - 'JSGC_DISABLE_POISONING': '1', 18:17:01 INFO - 'LANG': 'en_US.UTF-8', 18:17:01 INFO - 'LANGUAGE': 'en_US:en', 18:17:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:17:01 INFO - 'LOGNAME': 'cltbld', 18:17:01 INFO - 'MAIL': '/var/mail/cltbld', 18:17:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:17:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:17:01 INFO - 'MOZ_CRASHREPORTER': '1', 18:17:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:17:01 INFO - 'MOZ_NO_REMOTE': '1', 18:17:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:17:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:01 INFO - 'NO_EM_RESTART': '1', 18:17:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:01 INFO - 'PWD': '/builds/slave/test', 18:17:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:17:01 INFO - 'RUST_BACKTRACE': 'full', 18:17:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:17:01 INFO - 'SHELL': '/bin/bash', 18:17:01 INFO - 'SHLVL': '1', 18:17:01 INFO - 'STYLO_FORCE_ENABLED': '1', 18:17:01 INFO - 'TERM': 'linux', 18:17:01 INFO - 'TMOUT': '86400', 18:17:01 INFO - 'USER': 'cltbld', 18:17:01 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', 18:17:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:01 INFO - '_': '/tools/buildbot/bin/python'} 18:17:01 INFO - TEST-INFO | started process 13388 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQeVidR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:17:02 INFO - PID 13388 | 18:17:02 INFO - PID 13388 | (/builds/slave/test/build/application/firefox/firefox:13440): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:17:02 INFO - PID 13388 | 18:17:03 INFO - PID 13388 | 18:17:03 INFO - PID 13388 | (/builds/slave/test/build/application/firefox/firefox:13500): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:17:03 INFO - PID 13388 | 18:17:03 INFO - PID 13388 | __metrics Screen width/height:1600/1200 18:17:03 INFO - PID 13388 | colorDepth:24 18:17:03 INFO - PID 13388 | Browser inner width/height: 1024/768 18:17:03 INFO - PID 13388 | __metrics 18:17:13 INFO - PID 13388 | RSS: Main: 174460928 18:17:13 INFO - PID 13388 | 18:20:12 INFO - PID 13388 | Cycle 1(1): loaded http://localhost:48416/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48416/tests/dromaeo/cssquery-ext.html) 18:20:13 INFO - PID 13388 | RSS: Main: 178536448 18:20:13 INFO - PID 13388 | 18:23:15 INFO - PID 13388 | Cycle 1(1): loaded http://localhost:48416/tests/dromaeo/cssquery-ext.html (next: http://localhost:48416/tests/dromaeo/cssquery-jquery.html) 18:23:16 INFO - PID 13388 | RSS: Main: 174616576 18:23:16 INFO - PID 13388 | 18:26:18 INFO - PID 13388 | Cycle 1(1): loaded http://localhost:48416/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48416/tests/dromaeo/cssquery-mootools.html) 18:26:18 INFO - PID 13388 | RSS: Main: 177602560 18:26:18 INFO - PID 13388 | 18:29:20 INFO - PID 13388 | Cycle 1(1): loaded http://localhost:48416/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48416/tests/dromaeo/cssquery-prototype.html) 18:29:21 INFO - PID 13388 | RSS: Main: 175595520 18:29:21 INFO - PID 13388 | 18:32:25 INFO - PID 13388 | Cycle 1(1): loaded http://localhost:48416/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48416/tests/dromaeo/cssquery-yui.html) 18:32:25 INFO - PID 13388 | RSS: Main: 179068928 18:32:25 INFO - PID 13388 | 18:35:27 INFO - PID 13388 | Cycle 1(1): loaded http://localhost:48416/tests/dromaeo/cssquery-yui.html (next: http://localhost:48416/tests/dromaeo/cssquery-dojo.html) 18:35:27 INFO - PID 13388 | RSS: Main: 177287168 18:35:27 INFO - PID 13388 | 18:35:27 INFO - PID 13388 | __start_tp_report 18:35:27 INFO - PID 13388 | _x_x_mozilla_page_load 18:35:27 INFO - PID 13388 | _x_x_mozilla_page_load_details 18:35:27 INFO - PID 13388 | |i|pagename|runs| 18:35:27 INFO - PID 13388 | |0;dojo.html;3240.141362538926;3275.2958114005482;3277.754168437367;3317.999999999999;3324.484704870746;6202.100695399165;6260.46786023188;6323.747050117995;6388.456981156595;6427.5179361547835;6307.716152773136;6328.810135695925;6397.3602639736;6417.486601071914;6449.129367535381;5599.804006859761;5759.337676167232;5831;5862.32583252925;5890.64656120632;5722.456366645162;5742.79900203493;5855.297364316288;5865.413458654143;5879.088741245114;5756.3092428908585;5841.766329346842;5852.67810270435;5871.647701137946;5900.52795776339;5706.115552089433;5766.971165144189;5816.592838501307;5845.707714614252;5877.500412464946;5721.256236689245;5780.161876527922;5814.883702325929;5828.2131912191735;5844.912326315109;6211.068339749028;6280.277768056686;6307.463865571432;6355.713992870332;6375.872482550323;6777.118974533329;6780.99782609767;6824.0446337512785;6868.7252509900045;6872.965635171792;6585.473162147065;6692.297308782605;6711.463082953352;6723.462123030145;6723.529352945296;6251.718672659741;6260.593061450991;6271.404216599416;6283.183186185813;6287.49700023997;6913.930860691429;6920.653967301615;6939.167299923941;6953.79138625842;6963.4777391695825;6652.767153149616;6706.195256569145;6729.49528785343;6730.0577919091365;6742.6291553965;6756.324367563205;6816.897746533806;6860.3139686031;6880.965595172092;6882.4149947254045;6528.673566321665;6629.7679581214425;6659.167604049494;6665.166854143232;6693.364130407604;6676.332366763382;6701.564398314094;6712.060311556386;6754.324567543206;6840.555363901331;5404.675719456766;5407.729613519308;5408.594355423359;5409.459054094559;5420.566354691615;4680.695754775928;4745.1221524018165;4761.047790441856;4790.8323208687525;4803.711777293373;5941.821745347646;5941.940580594225;6004.909926351108;6036.849078772978;6046.576739628272;6650.667466626649;6741.730330786713;6745.460363170934;6750.9873518972545;6798.150231221098;5814.098814683731;5852.239208902815;5861.501772349313;5874.471297583238;5888.411158884077;5778.133280008033;5786.855328616734;5791.797287094932;5799.246098007232;5807.796727114531;6507.284198738135;6570;6581.2760596334365;6650.9667451662435;6670.266270710218;6605.042268871083;6721.563098398588;6819.659017049128;6849.520533562602;6941.062956500808;6474.514411419068;6483.708233129426;6484.416402523795;6485.383888530582;6491.642959637265;6577.408033277028;6586.769463068769;6598.637074960923;6610.305917878553;6616.073749675145;6185.907211391743;6737.090492783412;6738.460923126237;6738.494612903954;6750.797476075725;6434.06706027614;6478.935210647834;6500.317466666121;6507.479401647762;6511.3163117871945;6630.7679231226675;6631.734730610721;6639.601623902581;6655.334466553306;6681.164854393201;6490.091387205701;6545.738170473128;6561.835954101186;6575.4410875076155;6581.473482121514;6617.8014659560295;6625.569337993015;6641;6670.499712521483;6718.563293385914;6252.874942501216;6285.277193122849;6359.713812878525;6375.872482550416;6499.285078641251 18:35:27 INFO - PID 13388 | |1;ext.html;749.6401727170958;829.4732844643652;847.0809172048325;850.7447765670297;1114.9609763658273;28986.420271594554;29592.556111658316;29593.40813183735;38584.614153858456;39162.60837391625;38996;39376.409353859715;39391.803040984756;39408.408873866916;39436.21127577454;7213.855722885565;7509.924900750991;7832.56920869352;7837.608119594026;7839.568823714693;7710.151883292833;7777.727779527718;7814.687412503493;7815.25755053269;7819.218078192163;7751.22487751226;7847.215278472164;7848.13670496245;7851.136374998746;7853.175416581263;7795.961020194919;7826.72606458774;7828.491148075385;7837.294643482057;7838.804029899241;7667.118281397655;7753.806154846117;7760.340371068466;7764.3788496920115;7769.145394006655;35465.645343546494;35594.64405355965;35666.108347291265;35766.642333576594;35884.82057589722;3422.2299982503987;3424.5890493140982;3461;3470.444728843373;3477.7565570410084;29741.702582974325;29762.553561696594;29767.958121465646;29828.8508557458;29837.10488685343;5013.774380152902;5090.669106508065;5143.434222235551;5205.973970130163;5217.921731174036;180044.9999999987;180287;181667;181691;182467;44578.77710611426;44706;44795.55204447979;44837.77581112139;44840.77579612081;50109.498905010485;50477.24284135741;50531.74734126306;50777.238341424905;50810.491895081315;12684.556040538722;14018.579442616738;14021.719565608631;14194.361253743606;14212.218327991854;10493.9475302623;11382.487788049557;11487.54049837997;11493.138014648768;11505.194636375541;13404.530841420503;13422.463101476027;13491.730165396637;13558.11872228302;13615.931920340336;5430.15832545956;5495.862603434866;5499.697516636544;5653.568965344941;5690.658560486384;972.4116909269932;1007.3049595779036;1009.8485227215976;1021.7241344836852;1022.6062965758236;22669.659955100688;27381;27807.165785026482;27910;28783.71216287852;1060.6022741471947;1068.8076146293743;1080.8702955645215;1087.6573879228017;1087.7226307291749;11904.761904761855;12110;12232.327222002697;12318.75362492745;12446.937765311297;226768;226987;226993;227504;228660;46653.5334646656;46673.29990050152;46980.53019469762;47002.76498617485;47128.29307560389;1380.758367285721;1403.1440821099122;1406.0368647476512;1406.1984668738721;1413.4770135050098;21701.457463563536;21826.236081737065;21988;22165;22331.44171395728;35396.646033539335;35456.11359716028;35561.466578000836;35650;35791.46312805362;186815;187612;204477;207298;208964;29340.413191736472;30339.393212135194;30863.537046944763;31001;34292.48561271633;37365.81317093397;42329.15341693087;42527.3620895693;42726.78636606797;43144.56855431406;31068.53397199089;31576.368472629958;31739.52390714095;31756.364872701954;31768.205794855312;30907.69092309138;31198.532022019233;32263.193420164684;32667.836660816545;32696.673033269362;35698.643013569534;35703.28593428065;36035.27929441344;36071.639283606826;36098.09754756047;30575.694243057285;31035.68964310328;31084.37831243317;31343.84328078345;31772.205694857814;23082.192123275603;23156.652650209922;23449.648255276526;23599.764002359756;23707.52584948326 18:35:27 INFO - PID 13388 | |2;jquery.html;550.705372625645;554.6173140533032;562.977480900764;563.1355868741481;564.9689267090309;20861.895690521545;21875;21903.890480547616;21906.561868762656;22554.21060262891;23990.520189596198;24348;24350;24370.512589748196;24444.266671999867;6369.267534233564;6472.320406357336;6476.384743449365;6526.967365163167;6527.673616319165;5919.940800591982;6423.0686550450155;6437.420632143107;6473.093766872642;6501.609903405811;6454.22549294083;6455.031745238229;6458.2895881453005;6459.9354006460035;6460.644664543448;6440.742370305182;6445;6447.838804029913;6448.322926092763;6453.290138084807;5986.311574168983;5997.610155339889;5998.190244317005;5998.220231369937;5999.340072592055;27789.861050694617;28341.858290708413;28564.714352856412;29216.561751573743;29430.558541621678;2021.9595608087757;2073.150008496509;2075.398134540981;2077.8337732981327;2078.428432181147;18878.716819247722;19243;20017;20230.494237644176;20242.291519796883;2349.4009027698;2377.583922813518;2415.2271273192587;2425.563398588253;2432.270318904339;127322;129017;140296;145372;145516.27241863724;14524.844472802562;14686.192259425621;14785.334659940328;14816;14877.776833347509;22260;22513.662295065587;22573;22583.774162258494;22593.774062259166;8986.281097512183;9045.412048216846;9135.314851386172;9303.023032629493;9486.288528360401;8242.216989386;8328;8331.35085841167;8369.707060252984;8412.537310447984;9028.909710902806;9044.68852016454;9055.003949565542;9185;9309.255259579217;2632.6959665619524;2699.6625421822273;2735.794815388814;2752.6146339512484;2810.971890281112;919.8942121656095;960.7357976556364;970.5680971967548;973.3186769261546;973.3867663372172;19323.227070917288;19486.317978870673;19560.804391956182;19777.703334449994;20008.2997095101;959.4483172176027;1034.5499707627207;1036.1917704190744;1036.5801850250662;1042.166266986408;10643.042126208678;10670.626528071636;10759.3544387337;10773.461326933779;10789.352638841694;564415;565278;567882;589054;592039;113310;114544;115748;117615;117627;1163.3078318400537;1222.2238878312335;1227.5887577661406;1228.5700004998357;1231.132051903406;20593;21217.893910530966;21352.14591416326;21370.145194192057;21514.569708605428;58517.4148258529;58555.70722146362;58593.12110318263;59879.401205987386;61747.38252617596;67166.66416668081;67466.32533674796;67811.66094169498;68175.65912170407;68226;14393.568192954228;14503.564893053226;14594.343254553358;14641.048331858396;14667;22847.771522285228;22912.4271893204;22963;23289;23311.650325244795;17972.370967016093;18492.075396230404;18686.06569671538;18692.813071869106;18706.438806835817;16030;16678.916605416896;16998.57503562421;17425.39011134604;17793.199306031;19381.61236775285;19590.90204548968;19787.40637780869;20026.499337516678;20026.599468010267;14079;14422.495212667507;14439.205843678279;14468.27658617086;14530;17766.289348426435;18107.637847242717;18116.90941545284;18122.27510899549;18232.17955192046 18:35:27 INFO - PID 13388 | |3;mootools.html;1303.6414985878882;1335.1455068755997;1360.5170164591573;1376.0298989212606;1378.9448422063117;14525.346359413825;15144;15155.696886062273;15162.39350425983;15186.392544298242;14500.202488863108;15537.22313884307;15544.76682849758;15560.221988900537;15606.609834754137;2654.8938042478326;2717.1305182341657;2717.184844546638;2717.279920820984;2727.154582079552;2179.2481593850175;2515.4843257132334;2585.2115104893;2592.857392843393;2772.209920172751;2672.786177105827;2708.756211940925;2709.6612923384578;2710.5256580098503;2713.6472258606354;2584.638150658909;2608.5826267797156;2624.422627022052;2627.461370419069;2632.631431599568;2510.5104504621604;2559.667243258383;2611.4124322027583;2631.723669014745;2641.9207423777316;14524.636884077983;14531.636709082251;14620.70758584833;14636;14638.92680536601;7402.074740657418;7585.962070189614;7605.923940760577;7621.695132194705;7650.579218143;5222.947770522284;5412.621116521845;5516.531094856902;5548.250986116863;5647.3505546862225;1727.9827201728074;1773.122304459301;1804.1520485371855;1834.4404956488265;1837.693694413516;25221.495570088497;25246.242612721464;25418.491630167293;25422.491550169078;25424.618630720553;6617.139771829728;6658.500612454085;6741.727918551779;6787;6820.31796820324;16959.406420775213;16984.150792460576;17004.489865304062;17006.829931700773;17020.65958680844;7362.411007119417;7456.217097204824;7488.887666685004;7503.474756767083;7505.62471876404;6859.451243900476;6958.060661810692;7003.159620845429;7007.124109486314;7010.75462358815;5072.54347108762;5189.42916279209;5232.529072383504;5249.790008399621;5269.077911365573;1401.3833913078213;1418.666613345863;1466.2595389328321;1493.3802471974227;1540.2683725230427;863.6890719341033;872.821071680304;890.3900827932887;893.4594570285014;909.6679711905107;8001.519908805491;8192.34461243099;8193.426460147852;8272.958635206785;8286.668533258602;941.3928016429364;952.552300418802;955.4888134847823;960.6109525642127;964.6720115160879;5607.4112218217315;5622.128570071645;5725.599208055396;5748.626339287933;5779.017567013618;20564;21175.15299388038;21240.25659101924;21273.36179914605;21283;19890.30383936555;20676.689849652266;21010.579788404146;21012;21022.474438138863;10357.223208259411;10776;11128.387938663289;11161.107111431065;11220.214584978972;8297.336213102817;8310.293625041939;8323.6670533178;8489.702860399857;8744.125587441205;17900.104994750473;17941.730874037163;17970.730439043688;18211;18278.81721182771;18154.364597239033;18553.25786968506;18591.814081859007;18778.967156806506;18944.431667050012;11973.940130299641;12124.09069319787;12167.81748273759;12185.817212741993;12235.571754988532;13254.734905302033;13511.256880871646;13601.931990340381;13615.52345667897;13622.93188534051;11261.042811361127;11303.208775385645;11355.261907975955;11571.537138514703;11806.409679515818;10873.565057397616;10953.288036277616;11093.168012399265;11110.61112861046;11422;9764.9023509764;10031.147352475122;10034.147097496503;10037.247206459399;10040.046195611405;6925;6954.79135625932;6977.372036516738;6995.195552511526;7001.789946301619;7330.743423980135;7332.816679583053;7360.190379058192;7363.595002274927;7446.925530744623 18:35:27 INFO - PID 13388 | |4;prototype.html;494.88681397281994;498.9269584874845;518.2278405176288;522.8300802239272;526.699781124759;6401.807945761635;6406.2953075161695;6419.390157935;6431.935680643192;6472.644004579746;6036.2152920120325;6275.838969790592;6302.747890084403;6327.335629758878;6353;5025.4872563718145;5117.334746482961;5128.71792051437;5128.769205385748;5149;5059.2158215476475;5060;5082.745862706868;5084.246229775172;5119.744012799364;4856.611471082322;4947.893248603668;4969.527894849984;5021.961713163539;5088.949110508903;5010.398752149747;5020.97489512552;5059.721715305656;5078.781092537802;5109.514596113363;4484.551544845522;4514.187446259672;4515.006698526319;4516.932246016312;4539.886502837422;6215.658138802364;6253.592941588152;6265.968670156665;6279.8744025119695;6341.87316253677;5783;5816.8947899898885;5841.240638716982;5846.21076154718;5860.736266868002;6604.735810567571;6638.634875081869;6681.231658359254;6720.899186512206;6724.462043036546;4903.921137349777;4932.654714170009;4968.310774336743;4997.475265097185;5001.324821149135;7076.292370762934;7109.040279562297;7151.7854464366155;7155.749548765768;7155.928440715578;5054.396985552444;5089.8218562350885;5116.923246151311;5120.155174396183;5132.589392848563;5038.3198268234055;5099.252049743435;5139.843535204624;5181.5077567631015;5181.922271165936;5786.71066446676;5803.274590676166;5803.709814509258;5814.854628634318;5859.2090067841145;6618.63597502142;6748.426383757435;6763.391294783493;6775.288594697585;6816.420604248694;1693.2549678141825;1716.519374575121;1727.6285598596112;1732.7487514310355;1759.3490408548919;1201.4593432955205;1213.8604060533153;1234.734532075608;1236.511577926714;1236.8824961628632;5744.368119506851;5873.148393482921;5875.706214689249;5888.499477544457;5934.584579079423;6778.93221067783;6785.253622101565;6824.283450237753;6852.383285504328;6865.897011544831;5799.623024503394;5837.3287071986415;5894.587378883437;5901.616394934315;5932.377100404396;3579.8544465771224;3594.406922857699;3595.856165753393;3635.618260082706;3635.8545658173375;6249.562530622813;6566.573172743756;6639.336066393419;6671.3995740382625;6671.766488172891;6970.79087627372;6989.860202795916;7022.719091236293;7069.434445244368;7086.574805511583;6561;6568.573042752206;6603.306652801579;6659;6661.600303981777;6860.725570977105;6888.655567221719;6890.44876409896;6941.583504989717;6957.234704182435;6812.591244525344;6825.624590647562;6843.657817109224;6863.759768408082;6888.931110688829;6645.767398141042;6665.133532640726;6688.531802773954;6694.899576506454;6732.528722989344;5076.441591425014;5076.974615127048;5097.796088156432;5120.820771272987;5138.434772175133;6889;6929.549579277331;6936.2023367311385;6944.583325000516;6958.130233720785;6372.58578192416;6557.540972132096;6577.967110164419;6589.374009469093;6670.566413183127;6531.869362612816;6576.725128666405;6613.735450581923;6644.60132392058;6733.192016957801;6600.240972288057;6607.900881486686;6647.734090636513;6650.567713098633;6655.401013908578;6648.335166483313;6681.866362672622;6719.966400167968;6722.462203023844;6767;6159.599626024294;6367.108604795239;6411.4229719325485;6431.967840160769;6444.065610486547 18:35:27 INFO - PID 13388 | |5;yui.html;883.8099808541165;891.9018907920128;898.6495266845931;914.9222316103131;915.3729695158817;17467.912660436697;18878;19662;19690;19778.307759228446;20188.19247230109;20953.266635667755;21006.684899726515;21042;21130.471738206554;881.8544940084878;908.8636704494313;909.3770767024577;911.2846415563783;913.4245425382034;892.8437523433406;931.0456781798677;948.1040416806121;948.4025064209538;953.8473844184932;958.1568219966455;1006.1598565198068;1010.3432768700367;1011.5448048378222;1012.5190534496093;958.1663952361455;1000.9949950250202;1003.8845532763753;1004.5429329655026;1007.078523151323;699.1191099214975;715.5635062611802;730.8684436801374;731.1007460823164;731.3637135691982;18237;18371.173297201658;18584.44246672602;18609.06954652262;19677.606447871105;20460.488487787774;20514.794852051586;20982;21057.368278951504;21198.5760284795;16705.164741762863;16946.745798813026;17048.403305884236;17224.741628875578;17267.568310792205;3682.8710995115043;3720.572134204574;3736.4768932349493;3741.5884252732176;3742.3825068863603;113997;119979.40010299893;122025.38987305095;123872;129334;16277.348906043862;16551.172441378123;16709.749353759704;16731.749023764653;16754.246058927376;22849.200277990192;28863.855680721463;29786.10641680753;29793.851030744707;29799.55300670492;8043.235892590312;8677.56612169389;8686.52224127679;8716.956415217883;8759.781005474913;4069.633732963988;4158.293090174678;4158.563350848118;4160.895977600584;4164.208800327928;4815.614750819926;4955.578775804025;4976.452590215146;4982.177940639754;5009.574186194214;953.113604347963;976.2482888176206;988.0761488008735;988.1650005745134;989.9752506187403;681.7750142453078;699.2133849419403;709.4395427612232;711.8932160176015;719.3058698356111;10999.505022274017;11701.180917335874;11753.4710938008;12222.449989750483;12695.682607934876;683.4873844616537;683.5830143612393;685.4036987820529;687.3126873126873;707.6249587718518;5517.124685226276;5539.19681646159;5593.440655934374;5678.914816277759;5684.488396044376;114107.42946285216;115151;116431;117481.41259293648;118072.40963795298;97682.51158744161;101388.49305753571;102278.48860755649;103230.48384758178;106549.93450065555;87822.56088719516;89674.55162724145;92132.53933730288;94133;94328.52835735778;10045.899541004497;10208.285420020824;10221.284510084222;10284.742881428025;10485.16118710486;41204.58795412008;41582.79208604059;42206.366904497074;42581;42607.1478570433;68475.31524684888;68727;68831;72115.63942180255;72962.16916712398;16449.917750411172;17797.46607601774;17812.46562603124;18115.184816683017;18126.18432170582;20932.267370641955;21132.260370886946;21286.297096231956;21344.7865521349;21548.245811396526;18003.459896203134;19708.21167153298;19884.90057549703;19984.70022949686;20062.197512099352;17796.55508612295;18300.633987319914;18342.63314733671;18422.815771842645;18436.81563184351;18656.253749849853;18858.24567017359;18908.14913328876;19099.23603055918;19189.520261993563;12420.378981051092;12785.61643150707;13489.662758430724;13762.518311859038;14130.646733831736;14029.719405611626;14110.576682699535;14417.42330306776;14504;14715.190664513555 18:35:27 INFO - PID 13388 | __end_tp_report 18:35:27 INFO - PID 13388 | __start_cc_report 18:35:27 INFO - PID 13388 | _x_x_mozilla_cycle_collect,365 18:35:27 INFO - PID 13388 | __end_cc_report 18:35:27 INFO - PID 13388 | __startTimestamp1503624927841__endTimestamp 18:35:27 INFO - PID 13388 | 18:35:27 INFO - PID 13388 | ------- Summary: start ------- 18:35:27 INFO - PID 13388 | Number of tests: 6 18:35:27 INFO - PID 13388 | 18:35:27 INFO - PID 13388 | [#0] dojo.html Cycles:165 Average:6221.94 Median:6478.94 stddev:712.23 (11.0%) stddev-sans-first:674.91 18:35:27 INFO - PID 13388 | Values: 3240.1 3275.3 3277.8 3318.0 3324.5 6202.1 6260.5 6323.7 6388.5 6427.5 6307.7 6328.8 6397.4 6417.5 6449.1 5599.8 5759.3 5831.0 5862.3 5890.6 5722.5 5742.8 5855.3 5865.4 5879.1 5756.3 5841.8 5852.7 5871.6 5900.5 5706.1 5767.0 5816.6 5845.7 5877.5 5721.3 5780.2 5814.9 5828.2 5844.9 6211.1 6280.3 6307.5 6355.7 6375.9 6777.1 6781.0 6824.0 6868.7 6873.0 6585.5 6692.3 6711.5 6723.5 6723.5 6251.7 6260.6 6271.4 6283.2 6287.5 6913.9 6920.7 6939.2 6953.8 6963.5 6652.8 6706.2 6729.5 6730.1 6742.6 6756.3 6816.9 6860.3 6881.0 6882.4 6528.7 6629.8 6659.2 6665.2 6693.4 6676.3 6701.6 6712.1 6754.3 6840.6 5404.7 5407.7 5408.6 5409.5 5420.6 4680.7 4745.1 4761.0 4790.8 4803.7 5941.8 5941.9 6004.9 6036.8 6046.6 6650.7 6741.7 6745.5 6751.0 6798.2 5814.1 5852.2 5861.5 5874.5 5888.4 5778.1 5786.9 5791.8 5799.2 5807.8 6507.3 6570.0 6581.3 6651.0 6670.3 6605.0 6721.6 6819.7 6849.5 6941.1 6474.5 6483.7 6484.4 6485.4 6491.6 6577.4 6586.8 6598.6 6610.3 6616.1 6185.9 6737.1 6738.5 6738.5 6750.8 6434.1 6478.9 6500.3 6507.5 6511.3 6630.8 6631.7 6639.6 6655.3 6681.2 6490.1 6545.7 6561.8 6575.4 6581.5 6617.8 6625.6 6641.0 6670.5 6718.6 6252.9 6285.3 6359.7 6375.9 6499.3 18:35:27 INFO - PID 13388 | 18:35:27 INFO - PID 13388 | [#1] ext.html Cycles:180 Average:36311.51 Median:32682.25 stddev:52697.88 (161.2%) stddev-sans-first:52778.06 18:35:27 INFO - PID 13388 | Values: 749.6 829.5 847.1 850.7 1115.0 28986.4 29592.6 29593.4 38584.6 39162.6 38996.0 39376.4 39391.8 39408.4 39436.2 7213.9 7509.9 7832.6 7837.6 7839.6 7710.2 7777.7 7814.7 7815.3 7819.2 7751.2 7847.2 7848.1 7851.1 7853.2 7796.0 7826.7 7828.5 7837.3 7838.8 7667.1 7753.8 7760.3 7764.4 7769.1 35465.6 35594.6 35666.1 35766.6 35884.8 3422.2 3424.6 3461.0 3470.4 3477.8 29741.7 29762.6 29768.0 29828.9 29837.1 5013.8 5090.7 5143.4 5206.0 5217.9 180045.0 180287.0 181667.0 181691.0 182467.0 44578.8 44706.0 44795.6 44837.8 44840.8 50109.5 50477.2 50531.7 50777.2 50810.5 12684.6 14018.6 14021.7 14194.4 14212.2 10493.9 11382.5 11487.5 11493.1 11505.2 13404.5 13422.5 13491.7 13558.1 13615.9 5430.2 5495.9 5499.7 5653.6 5690.7 972.4 1007.3 1009.8 1021.7 1022.6 22669.7 27381.0 27807.2 27910.0 28783.7 1060.6 1068.8 1080.9 1087.7 1087.7 11904.8 12110.0 12232.3 12318.8 12446.9 226768.0 226987.0 226993.0 227504.0 228660.0 46653.5 46673.3 46980.5 47002.8 47128.3 1380.8 1403.1 1406.0 1406.2 1413.5 21701.5 21826.2 21988.0 22165.0 22331.4 35396.6 35456.1 35561.5 35650.0 35791.5 186815.0 187612.0 204477.0 207298.0 208964.0 29340.4 30339.4 30863.5 31001.0 34292.5 37365.8 42329.2 42527.4 42726.8 43144.6 31068.5 31576.4 31739.5 31756.4 31768.2 30907.7 31198.5 32263.2 32667.8 32696.7 35698.6 35703.3 36035.3 36071.6 36098.1 30575.7 31035.7 31084.4 31343.8 31772.2 23082.2 23156.7 23449.6 23599.8 23707.5 18:35:27 INFO - PID 13388 | 18:35:27 INFO - PID 13388 | [#2] jquery.html Cycles:180 Average:37061.74 Median:22937.71 stddev:95920.41 (418.2%) stddev-sans-first:96150.32 18:35:27 INFO - PID 13388 | Values: 550.7 554.6 563.0 563.1 565.0 20861.9 21875.0 21903.9 21906.6 22554.2 23990.5 24348.0 24350.0 24370.5 24444.3 6369.3 6472.3 6476.4 6527.0 6527.7 5919.9 6423.1 6437.4 6473.1 6501.6 6454.2 6455.0 6458.3 6459.9 6460.6 6440.7 6445.0 6447.8 6448.3 6453.3 5986.3 5997.6 5998.2 5998.2 5999.3 27789.9 28341.9 28564.7 29216.6 29430.6 2022.0 2073.2 2075.4 2077.8 2078.4 18878.7 19243.0 20017.0 20230.5 20242.3 2349.4 2377.6 2415.2 2425.6 2432.3 127322.0 129017.0 140296.0 145372.0 145516.3 14524.8 14686.2 14785.3 14816.0 14877.8 22260.0 22513.7 22573.0 22583.8 22593.8 8986.3 9045.4 9135.3 9303.0 9486.3 8242.2 8328.0 8331.4 8369.7 8412.5 9028.9 9044.7 9055.0 9185.0 9309.3 2632.7 2699.7 2735.8 2752.6 2811.0 919.9 960.7 970.6 973.3 973.4 19323.2 19486.3 19560.8 19777.7 20008.3 959.4 1034.5 1036.2 1036.6 1042.2 10643.0 10670.6 10759.4 10773.5 10789.4 564415.0 565278.0 567882.0 589054.0 592039.0 113310.0 114544.0 115748.0 117615.0 117627.0 1163.3 1222.2 1227.6 1228.6 1231.1 20593.0 21217.9 21352.1 21370.1 21514.6 58517.4 58555.7 58593.1 59879.4 61747.4 67166.7 67466.3 67811.7 68175.7 68226.0 14393.6 14503.6 14594.3 14641.0 14667.0 22847.8 22912.4 22963.0 23289.0 23311.7 17972.4 18492.1 18686.1 18692.8 18706.4 16030.0 16678.9 16998.6 17425.4 17793.2 19381.6 19590.9 19787.4 20026.5 20026.6 14079.0 14422.5 14439.2 14468.3 14530.0 17766.3 18107.6 18116.9 18122.3 18232.2 18:35:27 INFO - PID 13388 | 18:35:27 INFO - PID 13388 | [#3] mootools.html Cycles:180 Average:9182.91 Median:25423.56 stddev:6478.63 (25.5%) stddev-sans-first:6469.75 18:35:27 INFO - PID 13388 | Values: 1303.6 1335.1 1360.5 1376.0 1378.9 14525.3 15144.0 15155.7 15162.4 15186.4 14500.2 15537.2 15544.8 15560.2 15606.6 2654.9 2717.1 2717.2 2717.3 2727.2 2179.2 2515.5 2585.2 2592.9 2772.2 2672.8 2708.8 2709.7 2710.5 2713.6 2584.6 2608.6 2624.4 2627.5 2632.6 2510.5 2559.7 2611.4 2631.7 2641.9 14524.6 14531.6 14620.7 14636.0 14638.9 7402.1 7586.0 7605.9 7621.7 7650.6 5222.9 5412.6 5516.5 5548.3 5647.4 1728.0 1773.1 1804.2 1834.4 1837.7 25221.5 25246.2 25418.5 25422.5 25424.6 6617.1 6658.5 6741.7 6787.0 6820.3 16959.4 16984.2 17004.5 17006.8 17020.7 7362.4 7456.2 7488.9 7503.5 7505.6 6859.5 6958.1 7003.2 7007.1 7010.8 5072.5 5189.4 5232.5 5249.8 5269.1 1401.4 1418.7 1466.3 1493.4 1540.3 863.7 872.8 890.4 893.5 909.7 8001.5 8192.3 8193.4 8273.0 8286.7 941.4 952.6 955.5 960.6 964.7 5607.4 5622.1 5725.6 5748.6 5779.0 20564.0 21175.2 21240.3 21273.4 21283.0 19890.3 20676.7 21010.6 21012.0 21022.5 10357.2 10776.0 11128.4 11161.1 11220.2 8297.3 8310.3 8323.7 8489.7 8744.1 17900.1 17941.7 17970.7 18211.0 18278.8 18154.4 18553.3 18591.8 18779.0 18944.4 11973.9 12124.1 12167.8 12185.8 12235.6 13254.7 13511.3 13601.9 13615.5 13622.9 11261.0 11303.2 11355.3 11571.5 11806.4 10873.6 10953.3 11093.2 11110.6 11422.0 9764.9 10031.1 10034.1 10037.2 10040.0 6925.0 6954.8 6977.4 6995.2 7001.8 7330.7 7332.8 7360.2 7363.6 7446.9 18:35:27 INFO - PID 13388 | 18:35:27 INFO - PID 13388 | [#4] prototype.html Cycles:180 Average:5618.21 Median:6270.90 stddev:1591.94 (25.4%) stddev-sans-first:1549.27 18:35:27 INFO - PID 13388 | Values: 494.9 498.9 518.2 522.8 526.7 6401.8 6406.3 6419.4 6431.9 6472.6 6036.2 6275.8 6302.7 6327.3 6353.0 5025.5 5117.3 5128.7 5128.8 5149.0 5059.2 5060.0 5082.7 5084.2 5119.7 4856.6 4947.9 4969.5 5022.0 5088.9 5010.4 5021.0 5059.7 5078.8 5109.5 4484.6 4514.2 4515.0 4516.9 4539.9 6215.7 6253.6 6266.0 6279.9 6341.9 5783.0 5816.9 5841.2 5846.2 5860.7 6604.7 6638.6 6681.2 6720.9 6724.5 4903.9 4932.7 4968.3 4997.5 5001.3 7076.3 7109.0 7151.8 7155.7 7155.9 5054.4 5089.8 5116.9 5120.2 5132.6 5038.3 5099.3 5139.8 5181.5 5181.9 5786.7 5803.3 5803.7 5814.9 5859.2 6618.6 6748.4 6763.4 6775.3 6816.4 1693.3 1716.5 1727.6 1732.7 1759.3 1201.5 1213.9 1234.7 1236.5 1236.9 5744.4 5873.1 5875.7 5888.5 5934.6 6778.9 6785.3 6824.3 6852.4 6865.9 5799.6 5837.3 5894.6 5901.6 5932.4 3579.9 3594.4 3595.9 3635.6 3635.9 6249.6 6566.6 6639.3 6671.4 6671.8 6970.8 6989.9 7022.7 7069.4 7086.6 6561.0 6568.6 6603.3 6659.0 6661.6 6860.7 6888.7 6890.4 6941.6 6957.2 6812.6 6825.6 6843.7 6863.8 6888.9 6645.8 6665.1 6688.5 6694.9 6732.5 5076.4 5077.0 5097.8 5120.8 5138.4 6889.0 6929.5 6936.2 6944.6 6958.1 6372.6 6557.5 6578.0 6589.4 6670.6 6531.9 6576.7 6613.7 6644.6 6733.2 6600.2 6607.9 6647.7 6650.6 6655.4 6648.3 6681.9 6720.0 6722.5 6767.0 6159.6 6367.1 6411.4 6432.0 6444.1 18:35:27 INFO - PID 13388 | 18:35:27 INFO - PID 13388 | [#5] yui.html Cycles:180 Average:24050.38 Median:21093.92 stddev:32884.93 (155.9%) stddev-sans-first:32931.17 18:35:27 INFO - PID 13388 | Values: 883.8 891.9 898.6 914.9 915.4 17467.9 18878.0 19662.0 19690.0 19778.3 20188.2 20953.3 21006.7 21042.0 21130.5 881.9 908.9 909.4 911.3 913.4 892.8 931.0 948.1 948.4 953.8 958.2 1006.2 1010.3 1011.5 1012.5 958.2 1001.0 1003.9 1004.5 1007.1 699.1 715.6 730.9 731.1 731.4 18237.0 18371.2 18584.4 18609.1 19677.6 20460.5 20514.8 20982.0 21057.4 21198.6 16705.2 16946.7 17048.4 17224.7 17267.6 3682.9 3720.6 3736.5 3741.6 3742.4 113997.0 119979.4 122025.4 123872.0 129334.0 16277.3 16551.2 16709.7 16731.7 16754.2 22849.2 28863.9 29786.1 29793.9 29799.6 8043.2 8677.6 8686.5 8717.0 8759.8 4069.6 4158.3 4158.6 4160.9 4164.2 4815.6 4955.6 4976.5 4982.2 5009.6 953.1 976.2 988.1 988.2 990.0 681.8 699.2 709.4 711.9 719.3 10999.5 11701.2 11753.5 12222.4 12695.7 683.5 683.6 685.4 687.3 707.6 5517.1 5539.2 5593.4 5678.9 5684.5 114107.4 115151.0 116431.0 117481.4 118072.4 97682.5 101388.5 102278.5 103230.5 106549.9 87822.6 89674.6 92132.5 94133.0 94328.5 10045.9 10208.3 10221.3 10284.7 10485.2 41204.6 41582.8 42206.4 42581.0 42607.1 68475.3 68727.0 68831.0 72115.6 72962.2 16449.9 17797.5 17812.5 18115.2 18126.2 20932.3 21132.3 21286.3 21344.8 21548.2 18003.5 19708.2 19884.9 19984.7 20062.2 17796.6 18300.6 18342.6 18422.8 18436.8 18656.3 18858.2 18908.1 19099.2 19189.5 12420.4 12785.6 13489.7 13762.5 14130.6 14029.7 14110.6 14417.4 14504.0 14715.2 18:35:27 INFO - PID 13388 | -------- Summary: end -------- 18:35:27 INFO - PID 13388 | 18:35:28 INFO - TEST-INFO | 13388: exit 0 18:35:28 INFO - TEST-OK | dromaeo_css | took 1113163ms 18:35:28 INFO - TEST-START | kraken 18:35:28 INFO - Initialising browser for kraken test... 18:35:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48416/getInfo.html -profile /tmp/tmprLKanj/profile 18:35:28 INFO - TEST-INFO | started process 13810 (/builds/slave/test/build/application/firefox/firefox http://localhost:48416/getInfo.html) 18:35:35 INFO - TEST-INFO | 13810: exit 0 18:35:35 INFO - Browser initialized. 18:35:35 INFO - Running cycle 1/1 for kraken test... 18:35:35 INFO - Using env: {'DISPLAY': ':0', 18:35:35 INFO - 'HOME': '/home/cltbld', 18:35:35 INFO - 'JSGC_DISABLE_POISONING': '1', 18:35:35 INFO - 'LANG': 'en_US.UTF-8', 18:35:35 INFO - 'LANGUAGE': 'en_US:en', 18:35:35 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:35:35 INFO - 'LOGNAME': 'cltbld', 18:35:35 INFO - 'MAIL': '/var/mail/cltbld', 18:35:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:35:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:35:35 INFO - 'MOZ_CRASHREPORTER': '1', 18:35:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:35:35 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:35:35 INFO - 'MOZ_NO_REMOTE': '1', 18:35:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:35:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:35:35 INFO - 'NO_EM_RESTART': '1', 18:35:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:35:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:35:35 INFO - 'PWD': '/builds/slave/test', 18:35:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:35:35 INFO - 'RUST_BACKTRACE': 'full', 18:35:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:35:35 INFO - 'SHELL': '/bin/bash', 18:35:35 INFO - 'SHLVL': '1', 18:35:35 INFO - 'STYLO_FORCE_ENABLED': '1', 18:35:35 INFO - 'TERM': 'linux', 18:35:35 INFO - 'TMOUT': '86400', 18:35:35 INFO - 'USER': 'cltbld', 18:35:35 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486', 18:35:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:35:35 INFO - '_': '/tools/buildbot/bin/python'} 18:35:35 INFO - TEST-INFO | started process 13996 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprLKanj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:35:36 INFO - PID 13996 | 18:35:36 INFO - PID 13996 | (/builds/slave/test/build/application/firefox/firefox:14046): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:35:36 INFO - PID 13996 | 18:35:36 INFO - PID 13996 | 18:35:36 INFO - PID 13996 | (/builds/slave/test/build/application/firefox/firefox:14106): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:35:36 INFO - PID 13996 | 18:35:36 INFO - PID 13996 | __metrics Screen width/height:1600/1200 18:35:36 INFO - PID 13996 | colorDepth:24 18:35:36 INFO - PID 13996 | Browser inner width/height: 1024/768 18:35:36 INFO - PID 13996 | __metrics 18:35:46 INFO - PID 13996 | RSS: Main: 174759936 18:35:46 INFO - PID 13996 | 18:35:49 INFO - PID 13996 | Cycle 1(1): loaded http://localhost:48416/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48416/tests/kraken/driver.html?testName=audio-beat-detection) 18:35:50 INFO - PID 13996 | RSS: Main: 178999296 18:35:50 INFO - PID 13996 | 18:35:52 INFO - PID 13996 | Cycle 1(1): loaded http://localhost:48416/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48416/tests/kraken/driver.html?testName=audio-dft) 18:35:53 INFO - PID 13996 | RSS: Main: 181137408 18:35:53 INFO - PID 13996 | 18:35:56 INFO - PID 13996 | Cycle 1(1): loaded http://localhost:48416/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48416/tests/kraken/driver.html?testName=audio-fft) 18:35:57 INFO - PID 13996 | RSS: Main: 174989312 18:35:57 INFO - PID 13996 | 18:35:59 INFO - PID 13996 | Cycle 1(1): loaded http://localhost:48416/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48416/tests/kraken/driver.html?testName=audio-oscillator) 18:36:00 INFO - PID 13996 | RSS: Main: 176107520 18:36:00 INFO - PID 13996 | 18:36:02 INFO - PID 13996 | Cycle 1(1): loaded http://localhost:48416/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48416/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:36:02 INFO - PID 13996 | RSS: Main: 175894528 18:36:02 INFO - PID 13996 | 18:36:06 INFO - PID 13996 | Cycle 1(1): loaded http://localhost:48416/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48416/tests/kraken/driver.html?testName=imaging-darkroom) 18:36:06 INFO - PID 13996 | RSS: Main: 177418240 18:36:06 INFO - PID 13996 | 18:36:10 INFO - PID 13996 | Cycle 1(1): loaded http://localhost:48416/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48416/tests/kraken/driver.html?testName=imaging-desaturate) 18:36:10 INFO - PID 13996 | RSS: Main: 177205248 18:36:10 INFO - PID 13996 | 18:36:13 INFO - PID 13996 | Cycle 1(1): loaded http://localhost:48416/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48416/tests/kraken/driver.html?testName=json-parse-financial) 18:36:14 INFO - PID 13996 | RSS: Main: 177733632 18:36:14 INFO - PID 13996 | 18:36:16 INFO - PID 13996 | Cycle 1(1): loaded http://localhost:48416/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48416/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:36:16 INFO - PID 13996 | RSS: Main: 177868800 18:36:16 INFO - PID 13996 | 18:36:18 INFO - PID 13996 | Cycle 1(1): loaded http://localhost:48416/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48416/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:36:19 INFO - PID 13996 | RSS: Main: 177700864 18:36:19 INFO - PID 13996 | 18:36:21 INFO - PID 13996 | Cycle 1(1): loaded http://localhost:48416/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48416/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:36:21 INFO - PID 13996 | RSS: Main: 177520640 18:36:21 INFO - PID 13996 | 18:36:24 INFO - PID 13996 | Cycle 1(1): loaded http://localhost:48416/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48416/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:36:25 INFO - PID 13996 | RSS: Main: 177815552 18:36:25 INFO - PID 13996 | 18:36:28 INFO - PID 13996 | Cycle 1(1): loaded http://localhost:48416/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48416/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:36:29 INFO - PID 13996 | RSS: Main: 177954816 18:36:29 INFO - PID 13996 | 18:36:30 INFO - PID 13996 | Cycle 1(1): loaded http://localhost:48416/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48416/tests/kraken/driver.html?testName=ai-astar) 18:36:31 INFO - PID 13996 | RSS: Main: 179736576 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | __start_tp_report 18:36:31 INFO - PID 13996 | _x_x_mozilla_page_load 18:36:31 INFO - PID 13996 | _x_x_mozilla_page_load_details 18:36:31 INFO - PID 13996 | |i|pagename|runs| 18:36:31 INFO - PID 13996 | |0;ai-astar;97;108;107;106;106;109;110;98;110;113 18:36:31 INFO - PID 13996 | |1;audio-beat-detection;154;121;154;171;118;146;148;123;182;118 18:36:31 INFO - PID 13996 | |2;audio-dft;162;162;162;162;161;163;163;170;164;169 18:36:31 INFO - PID 13996 | |3;audio-fft;75;74;72;162;74;74;74;83;96;74 18:36:31 INFO - PID 13996 | |4;audio-oscillator;79;79;133;79;78;80;134;85;79;135 18:36:31 INFO - PID 13996 | |5;imaging-gaussian-blur;117;116;116;117;114;114;115;119;115;114 18:36:31 INFO - PID 13996 | |6;imaging-darkroom;150;150;149;151;149;149;150;153;148;148 18:36:31 INFO - PID 13996 | |7;imaging-desaturate;95;98;97;94;99;98;99;94;93;93 18:36:31 INFO - PID 13996 | |8;json-parse-financial;60;63;52;51;55;57;51;55;51;53 18:36:31 INFO - PID 13996 | |9;json-stringify-tinderbox;55;58;62;61;56;56;60;57;56;56 18:36:31 INFO - PID 13996 | |10;stanford-crypto-aes;83;78;73;74;73;73;75;72;79;74 18:36:31 INFO - PID 13996 | |11;stanford-crypto-ccm;128;121;114;125;120;121;118;116;131;171 18:36:31 INFO - PID 13996 | |12;stanford-crypto-pbkdf2;184;176;170;169;170;172;171;170;170;170 18:36:31 INFO - PID 13996 | |13;stanford-crypto-sha256-iterative;69;64;68;63;64;64;67;63;67;64 18:36:31 INFO - PID 13996 | __end_tp_report 18:36:31 INFO - PID 13996 | __start_cc_report 18:36:31 INFO - PID 13996 | _x_x_mozilla_cycle_collect,973 18:36:31 INFO - PID 13996 | __end_cc_report 18:36:31 INFO - PID 13996 | __startTimestamp1503624991104__endTimestamp 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | ------- Summary: start ------- 18:36:31 INFO - PID 13996 | Number of tests: 14 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | [#0] ai-astar Cycles:10 Average:106.40 Median:110.00 stddev:5.15 (4.7%) stddev-sans-first:4.19 18:36:31 INFO - PID 13996 | Values: 97.0 108.0 107.0 106.0 106.0 109.0 110.0 98.0 110.0 113.0 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:151.00 stddev:22.88 (15.2%) stddev-sans-first:23.95 18:36:31 INFO - PID 13996 | Values: 154.0 121.0 154.0 171.0 118.0 146.0 148.0 123.0 182.0 118.0 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.00 stddev:3.12 (1.9%) stddev-sans-first:3.24 18:36:31 INFO - PID 13996 | Values: 162.0 162.0 162.0 162.0 161.0 163.0 163.0 170.0 164.0 169.0 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | [#3] audio-fft Cycles:10 Average:85.80 Median:74.00 stddev:27.73 (37.5%) stddev-sans-first:29.14 18:36:31 INFO - PID 13996 | Values: 75.0 74.0 72.0 162.0 74.0 74.0 74.0 83.0 96.0 74.0 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:79.00 stddev:26.23 (33.2%) stddev-sans-first:27.08 18:36:31 INFO - PID 13996 | Values: 79.0 79.0 133.0 79.0 78.0 80.0 134.0 85.0 79.0 135.0 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | [#5] imaging-gaussian-blur Cycles:10 Average:115.70 Median:116.00 stddev:1.64 (1.4%) stddev-sans-first:1.67 18:36:31 INFO - PID 13996 | Values: 117.0 116.0 116.0 117.0 114.0 114.0 115.0 119.0 115.0 114.0 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:150.00 stddev:1.49 (1.0%) stddev-sans-first:1.58 18:36:31 INFO - PID 13996 | Values: 150.0 150.0 149.0 151.0 149.0 149.0 150.0 153.0 148.0 148.0 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:97.50 stddev:2.45 (2.5%) stddev-sans-first:2.57 18:36:31 INFO - PID 13996 | Values: 95.0 98.0 97.0 94.0 99.0 98.0 99.0 94.0 93.0 93.0 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:4.13 (7.5%) stddev-sans-first:3.93 18:36:31 INFO - PID 13996 | Values: 60.0 63.0 52.0 51.0 55.0 57.0 51.0 55.0 51.0 53.0 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | [#9] json-stringify-tinderbox Cycles:10 Average:57.70 Median:57.50 stddev:2.45 (4.3%) stddev-sans-first:2.40 18:36:31 INFO - PID 13996 | Values: 55.0 58.0 62.0 61.0 56.0 56.0 60.0 57.0 56.0 56.0 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:74.50 stddev:3.50 (4.7%) stddev-sans-first:2.40 18:36:31 INFO - PID 13996 | Values: 83.0 78.0 73.0 74.0 73.0 73.0 75.0 72.0 79.0 74.0 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:123.00 stddev:16.49 (13.4%) stddev-sans-first:17.48 18:36:31 INFO - PID 13996 | Values: 128.0 121.0 114.0 125.0 120.0 121.0 118.0 116.0 131.0 171.0 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:170.50 stddev:4.59 (2.7%) stddev-sans-first:2.09 18:36:31 INFO - PID 13996 | Values: 184.0 176.0 170.0 169.0 170.0 172.0 171.0 170.0 170.0 170.0 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.50 stddev:2.21 (3.4%) stddev-sans-first:1.90 18:36:31 INFO - PID 13996 | Values: 69.0 64.0 68.0 63.0 64.0 64.0 67.0 63.0 67.0 64.0 18:36:31 INFO - PID 13996 | -------- Summary: end -------- 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150081,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 18:36:31 INFO - PID 13996 | 18:36:31 INFO - PID 13996 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:36:31 INFO - TEST-INFO | 13996: exit 0 18:36:31 INFO - TEST-OK | kraken | took 63253ms 18:36:31 INFO - SUITE-END | took 1176s 18:36:32 INFO - Completed test suite (00:19:36) 18:36:32 INFO - javascript benchmark 18:36:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8488.553193787788, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3240.141362538926, 3275.2958114005482, 3277.754168437367, 3317.999999999999, 3324.484704870746, 6202.100695399165, 6260.46786023188, 6323.747050117995, 6388.456981156595, 6427.5179361547835, 6307.716152773136, 6328.810135695925, 6397.3602639736, 6417.486601071914, 6449.129367535381, 5599.804006859761, 5759.337676167232, 5831.0, 5862.32583252925, 5890.64656120632, 5722.456366645162, 5742.79900203493, 5855.297364316288, 5865.413458654143, 5879.088741245114, 5756.3092428908585, 5841.766329346842, 5852.67810270435, 5871.647701137946, 5900.52795776339, 5706.115552089433, 5766.971165144189, 5816.592838501307, 5845.707714614252, 5877.500412464946, 5721.256236689245, 5780.161876527922, 5814.883702325929, 5828.2131912191735, 5844.912326315109, 6211.068339749028, 6280.277768056686, 6307.463865571432, 6355.713992870332, 6375.872482550323, 6777.118974533329, 6780.99782609767, 6824.0446337512785, 6868.7252509900045, 6872.965635171792, 6585.473162147065, 6692.297308782605, 6711.463082953352, 6723.462123030145, 6723.529352945296, 6251.718672659741, 6260.593061450991, 6271.404216599416, 6283.183186185813, 6287.49700023997, 6913.930860691429, 6920.653967301615, 6939.167299923941, 6953.79138625842, 6963.4777391695825, 6652.767153149616, 6706.195256569145, 6729.49528785343, 6730.0577919091365, 6742.6291553965, 6756.324367563205, 6816.897746533806, 6860.3139686031, 6880.965595172092, 6882.4149947254045, 6528.673566321665, 6629.7679581214425, 6659.167604049494, 6665.166854143232, 6693.364130407604, 6676.332366763382, 6701.564398314094, 6712.060311556386, 6754.324567543206, 6840.555363901331, 5404.675719456766, 5407.729613519308, 5408.594355423359, 5409.459054094559, 5420.566354691615, 4680.695754775928, 4745.1221524018165, 4761.047790441856, 4790.8323208687525, 4803.711777293373, 5941.821745347646, 5941.940580594225, 6004.909926351108, 6036.849078772978, 6046.576739628272, 6650.667466626649, 6741.730330786713, 6745.460363170934, 6750.9873518972545, 6798.150231221098, 5814.098814683731, 5852.239208902815, 5861.501772349313, 5874.471297583238, 5888.411158884077, 5778.133280008033, 5786.855328616734, 5791.797287094932, 5799.246098007232, 5807.796727114531, 6507.284198738135, 6570.0, 6581.2760596334365, 6650.9667451662435, 6670.266270710218, 6605.042268871083, 6721.563098398588, 6819.659017049128, 6849.520533562602, 6941.062956500808, 6474.514411419068, 6483.708233129426, 6484.416402523795, 6485.383888530582, 6491.642959637265, 6577.408033277028, 6586.769463068769, 6598.637074960923, 6610.305917878553, 6616.073749675145, 6185.907211391743, 6737.090492783412, 6738.460923126237, 6738.494612903954, 6750.797476075725, 6434.06706027614, 6478.935210647834, 6500.317466666121, 6507.479401647762, 6511.3163117871945, 6630.7679231226675, 6631.734730610721, 6639.601623902581, 6655.334466553306, 6681.164854393201, 6490.091387205701, 6545.738170473128, 6561.835954101186, 6575.4410875076155, 6581.473482121514, 6617.8014659560295, 6625.569337993015, 6641.0, 6670.499712521483, 6718.563293385914, 6252.874942501216, 6285.277193122849, 6359.713812878525, 6375.872482550416, 6499.285078641251], "value": 6170.779158876794, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.6401727170958, 829.4732844643652, 847.0809172048325, 850.7447765670297, 1114.9609763658273, 28986.420271594554, 29592.556111658316, 29593.40813183735, 38584.614153858456, 39162.60837391625, 38996.0, 39376.409353859715, 39391.803040984756, 39408.408873866916, 39436.21127577454, 7213.855722885565, 7509.924900750991, 7832.56920869352, 7837.608119594026, 7839.568823714693, 7710.151883292833, 7777.727779527718, 7814.687412503493, 7815.25755053269, 7819.218078192163, 7751.22487751226, 7847.215278472164, 7848.13670496245, 7851.136374998746, 7853.175416581263, 7795.961020194919, 7826.72606458774, 7828.491148075385, 7837.294643482057, 7838.804029899241, 7667.118281397655, 7753.806154846117, 7760.340371068466, 7764.3788496920115, 7769.145394006655, 35465.645343546494, 35594.64405355965, 35666.108347291265, 35766.642333576594, 35884.82057589722, 3422.2299982503987, 3424.5890493140982, 3461.0, 3470.444728843373, 3477.7565570410084, 29741.702582974325, 29762.553561696594, 29767.958121465646, 29828.8508557458, 29837.10488685343, 5013.774380152902, 5090.669106508065, 5143.434222235551, 5205.973970130163, 5217.921731174036, 180044.9999999987, 180287.0, 181667.0, 181691.0, 182467.0, 44578.77710611426, 44706.0, 44795.55204447979, 44837.77581112139, 44840.77579612081, 50109.498905010485, 50477.24284135741, 50531.74734126306, 50777.238341424905, 50810.491895081315, 12684.556040538722, 14018.579442616738, 14021.719565608631, 14194.361253743606, 14212.218327991854, 10493.9475302623, 11382.487788049557, 11487.54049837997, 11493.138014648768, 11505.194636375541, 13404.530841420503, 13422.463101476027, 13491.730165396637, 13558.11872228302, 13615.931920340336, 5430.15832545956, 5495.862603434866, 5499.697516636544, 5653.568965344941, 5690.658560486384, 972.4116909269932, 1007.3049595779036, 1009.8485227215976, 1021.7241344836852, 1022.6062965758236, 22669.659955100688, 27381.0, 27807.165785026482, 27910.0, 28783.71216287852, 1060.6022741471947, 1068.8076146293743, 1080.8702955645215, 1087.6573879228017, 1087.7226307291749, 11904.761904761855, 12110.0, 12232.327222002697, 12318.75362492745, 12446.937765311297, 226768.0, 226987.0, 226993.0, 227504.0, 228660.0, 46653.5334646656, 46673.29990050152, 46980.53019469762, 47002.76498617485, 47128.29307560389, 1380.758367285721, 1403.1440821099122, 1406.0368647476512, 1406.1984668738721, 1413.4770135050098, 21701.457463563536, 21826.236081737065, 21988.0, 22165.0, 22331.44171395728, 35396.646033539335, 35456.11359716028, 35561.466578000836, 35650.0, 35791.46312805362, 186815.0, 187612.0, 204477.0, 207298.0, 208964.0, 29340.413191736472, 30339.393212135194, 30863.537046944763, 31001.0, 34292.48561271633, 37365.81317093397, 42329.15341693087, 42527.3620895693, 42726.78636606797, 43144.56855431406, 31068.53397199089, 31576.368472629958, 31739.52390714095, 31756.364872701954, 31768.205794855312, 30907.69092309138, 31198.532022019233, 32263.193420164684, 32667.836660816545, 32696.673033269362, 35698.643013569534, 35703.28593428065, 36035.27929441344, 36071.639283606826, 36098.09754756047, 30575.694243057285, 31035.68964310328, 31084.37831243317, 31343.84328078345, 31772.205694857814, 23082.192123275603, 23156.652650209922, 23449.648255276526, 23599.764002359756, 23707.52584948326], "value": 16596.65729418039, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.705372625645, 554.6173140533032, 562.977480900764, 563.1355868741481, 564.9689267090309, 20861.895690521545, 21875.0, 21903.890480547616, 21906.561868762656, 22554.21060262891, 23990.520189596198, 24348.0, 24350.0, 24370.512589748196, 24444.266671999867, 6369.267534233564, 6472.320406357336, 6476.384743449365, 6526.967365163167, 6527.673616319165, 5919.940800591982, 6423.0686550450155, 6437.420632143107, 6473.093766872642, 6501.609903405811, 6454.22549294083, 6455.031745238229, 6458.2895881453005, 6459.9354006460035, 6460.644664543448, 6440.742370305182, 6445.0, 6447.838804029913, 6448.322926092763, 6453.290138084807, 5986.311574168983, 5997.610155339889, 5998.190244317005, 5998.220231369937, 5999.340072592055, 27789.861050694617, 28341.858290708413, 28564.714352856412, 29216.561751573743, 29430.558541621678, 2021.9595608087757, 2073.150008496509, 2075.398134540981, 2077.8337732981327, 2078.428432181147, 18878.716819247722, 19243.0, 20017.0, 20230.494237644176, 20242.291519796883, 2349.4009027698, 2377.583922813518, 2415.2271273192587, 2425.563398588253, 2432.270318904339, 127322.0, 129017.0, 140296.0, 145372.0, 145516.27241863724, 14524.844472802562, 14686.192259425621, 14785.334659940328, 14816.0, 14877.776833347509, 22260.0, 22513.662295065587, 22573.0, 22583.774162258494, 22593.774062259166, 8986.281097512183, 9045.412048216846, 9135.314851386172, 9303.023032629493, 9486.288528360401, 8242.216989386, 8328.0, 8331.35085841167, 8369.707060252984, 8412.537310447984, 9028.909710902806, 9044.68852016454, 9055.003949565542, 9185.0, 9309.255259579217, 2632.6959665619524, 2699.6625421822273, 2735.794815388814, 2752.6146339512484, 2810.971890281112, 919.8942121656095, 960.7357976556364, 970.5680971967548, 973.3186769261546, 973.3867663372172, 19323.227070917288, 19486.317978870673, 19560.804391956182, 19777.703334449994, 20008.2997095101, 959.4483172176027, 1034.5499707627207, 1036.1917704190744, 1036.5801850250662, 1042.166266986408, 10643.042126208678, 10670.626528071636, 10759.3544387337, 10773.461326933779, 10789.352638841694, 564415.0, 565278.0, 567882.0, 589054.0, 592039.0, 113310.0, 114544.0, 115748.0, 117615.0, 117627.0, 1163.3078318400537, 1222.2238878312335, 1227.5887577661406, 1228.5700004998357, 1231.132051903406, 20593.0, 21217.893910530966, 21352.14591416326, 21370.145194192057, 21514.569708605428, 58517.4148258529, 58555.70722146362, 58593.12110318263, 59879.401205987386, 61747.38252617596, 67166.66416668081, 67466.32533674796, 67811.66094169498, 68175.65912170407, 68226.0, 14393.568192954228, 14503.564893053226, 14594.343254553358, 14641.048331858396, 14667.0, 22847.771522285228, 22912.4271893204, 22963.0, 23289.0, 23311.650325244795, 17972.370967016093, 18492.075396230404, 18686.06569671538, 18692.813071869106, 18706.438806835817, 16030.0, 16678.916605416896, 16998.57503562421, 17425.39011134604, 17793.199306031, 19381.61236775285, 19590.90204548968, 19787.40637780869, 20026.499337516678, 20026.599468010267, 14079.0, 14422.495212667507, 14439.205843678279, 14468.27658617086, 14530.0, 17766.289348426435, 18107.637847242717, 18116.90941545284, 18122.27510899549, 18232.17955192046], "value": 11931.949964692789, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1303.6414985878882, 1335.1455068755997, 1360.5170164591573, 1376.0298989212606, 1378.9448422063117, 14525.346359413825, 15144.0, 15155.696886062273, 15162.39350425983, 15186.392544298242, 14500.202488863108, 15537.22313884307, 15544.76682849758, 15560.221988900537, 15606.609834754137, 2654.8938042478326, 2717.1305182341657, 2717.184844546638, 2717.279920820984, 2727.154582079552, 2179.2481593850175, 2515.4843257132334, 2585.2115104893, 2592.857392843393, 2772.209920172751, 2672.786177105827, 2708.756211940925, 2709.6612923384578, 2710.5256580098503, 2713.6472258606354, 2584.638150658909, 2608.5826267797156, 2624.422627022052, 2627.461370419069, 2632.631431599568, 2510.5104504621604, 2559.667243258383, 2611.4124322027583, 2631.723669014745, 2641.9207423777316, 14524.636884077983, 14531.636709082251, 14620.70758584833, 14636.0, 14638.92680536601, 7402.074740657418, 7585.962070189614, 7605.923940760577, 7621.695132194705, 7650.579218143, 5222.947770522284, 5412.621116521845, 5516.531094856902, 5548.250986116863, 5647.3505546862225, 1727.9827201728074, 1773.122304459301, 1804.1520485371855, 1834.4404956488265, 1837.693694413516, 25221.495570088497, 25246.242612721464, 25418.491630167293, 25422.491550169078, 25424.618630720553, 6617.139771829728, 6658.500612454085, 6741.727918551779, 6787.0, 6820.31796820324, 16959.406420775213, 16984.150792460576, 17004.489865304062, 17006.829931700773, 17020.65958680844, 7362.411007119417, 7456.217097204824, 7488.887666685004, 7503.474756767083, 7505.62471876404, 6859.451243900476, 6958.060661810692, 7003.159620845429, 7007.124109486314, 7010.75462358815, 5072.54347108762, 5189.42916279209, 5232.529072383504, 5249.790008399621, 5269.077911365573, 1401.3833913078213, 1418.666613345863, 1466.2595389328321, 1493.3802471974227, 1540.2683725230427, 863.6890719341033, 872.821071680304, 890.3900827932887, 893.4594570285014, 909.6679711905107, 8001.519908805491, 8192.34461243099, 8193.426460147852, 8272.958635206785, 8286.668533258602, 941.3928016429364, 952.552300418802, 955.4888134847823, 960.6109525642127, 964.6720115160879, 5607.4112218217315, 5622.128570071645, 5725.599208055396, 5748.626339287933, 5779.017567013618, 20564.0, 21175.15299388038, 21240.25659101924, 21273.36179914605, 21283.0, 19890.30383936555, 20676.689849652266, 21010.579788404146, 21012.0, 21022.474438138863, 10357.223208259411, 10776.0, 11128.387938663289, 11161.107111431065, 11220.214584978972, 8297.336213102817, 8310.293625041939, 8323.6670533178, 8489.702860399857, 8744.125587441205, 17900.104994750473, 17941.730874037163, 17970.730439043688, 18211.0, 18278.81721182771, 18154.364597239033, 18553.25786968506, 18591.814081859007, 18778.967156806506, 18944.431667050012, 11973.940130299641, 12124.09069319787, 12167.81748273759, 12185.817212741993, 12235.571754988532, 13254.734905302033, 13511.256880871646, 13601.931990340381, 13615.52345667897, 13622.93188534051, 11261.042811361127, 11303.208775385645, 11355.261907975955, 11571.537138514703, 11806.409679515818, 10873.565057397616, 10953.288036277616, 11093.168012399265, 11110.61112861046, 11422.0, 9764.9023509764, 10031.147352475122, 10034.147097496503, 10037.247206459399, 10040.046195611405, 6925.0, 6954.79135625932, 6977.372036516738, 6995.195552511526, 7001.789946301619, 7330.743423980135, 7332.816679583053, 7360.190379058192, 7363.595002274927, 7446.925530744623], "value": 6575.642282068204, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.88681397281994, 498.9269584874845, 518.2278405176288, 522.8300802239272, 526.699781124759, 6401.807945761635, 6406.2953075161695, 6419.390157935, 6431.935680643192, 6472.644004579746, 6036.2152920120325, 6275.838969790592, 6302.747890084403, 6327.335629758878, 6353.0, 5025.4872563718145, 5117.334746482961, 5128.71792051437, 5128.769205385748, 5149.0, 5059.2158215476475, 5060.0, 5082.745862706868, 5084.246229775172, 5119.744012799364, 4856.611471082322, 4947.893248603668, 4969.527894849984, 5021.961713163539, 5088.949110508903, 5010.398752149747, 5020.97489512552, 5059.721715305656, 5078.781092537802, 5109.514596113363, 4484.551544845522, 4514.187446259672, 4515.006698526319, 4516.932246016312, 4539.886502837422, 6215.658138802364, 6253.592941588152, 6265.968670156665, 6279.8744025119695, 6341.87316253677, 5783.0, 5816.8947899898885, 5841.240638716982, 5846.21076154718, 5860.736266868002, 6604.735810567571, 6638.634875081869, 6681.231658359254, 6720.899186512206, 6724.462043036546, 4903.921137349777, 4932.654714170009, 4968.310774336743, 4997.475265097185, 5001.324821149135, 7076.292370762934, 7109.040279562297, 7151.7854464366155, 7155.749548765768, 7155.928440715578, 5054.396985552444, 5089.8218562350885, 5116.923246151311, 5120.155174396183, 5132.589392848563, 5038.3198268234055, 5099.252049743435, 5139.843535204624, 5181.5077567631015, 5181.922271165936, 5786.71066446676, 5803.274590676166, 5803.709814509258, 5814.854628634318, 5859.2090067841145, 6618.63597502142, 6748.426383757435, 6763.391294783493, 6775.288594697585, 6816.420604248694, 1693.2549678141825, 1716.519374575121, 1727.6285598596112, 1732.7487514310355, 1759.3490408548919, 1201.4593432955205, 1213.8604060533153, 1234.734532075608, 1236.511577926714, 1236.8824961628632, 5744.368119506851, 5873.148393482921, 5875.706214689249, 5888.499477544457, 5934.584579079423, 6778.93221067783, 6785.253622101565, 6824.283450237753, 6852.383285504328, 6865.897011544831, 5799.623024503394, 5837.3287071986415, 5894.587378883437, 5901.616394934315, 5932.377100404396, 3579.8544465771224, 3594.406922857699, 3595.856165753393, 3635.618260082706, 3635.8545658173375, 6249.562530622813, 6566.573172743756, 6639.336066393419, 6671.3995740382625, 6671.766488172891, 6970.79087627372, 6989.860202795916, 7022.719091236293, 7069.434445244368, 7086.574805511583, 6561.0, 6568.573042752206, 6603.306652801579, 6659.0, 6661.600303981777, 6860.725570977105, 6888.655567221719, 6890.44876409896, 6941.583504989717, 6957.234704182435, 6812.591244525344, 6825.624590647562, 6843.657817109224, 6863.759768408082, 6888.931110688829, 6645.767398141042, 6665.133532640726, 6688.531802773954, 6694.899576506454, 6732.528722989344, 5076.441591425014, 5076.974615127048, 5097.796088156432, 5120.820771272987, 5138.434772175133, 6889.0, 6929.549579277331, 6936.2023367311385, 6944.583325000516, 6958.130233720785, 6372.58578192416, 6557.540972132096, 6577.967110164419, 6589.374009469093, 6670.566413183127, 6531.869362612816, 6576.725128666405, 6613.735450581923, 6644.60132392058, 6733.192016957801, 6600.240972288057, 6607.900881486686, 6647.734090636513, 6650.567713098633, 6655.401013908578, 6648.335166483313, 6681.866362672622, 6719.966400167968, 6722.462203023844, 6767.0, 6159.599626024294, 6367.108604795239, 6411.4229719325485, 6431.967840160769, 6444.065610486547], "value": 5143.955825022577, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [883.8099808541165, 891.9018907920128, 898.6495266845931, 914.9222316103131, 915.3729695158817, 17467.912660436697, 18878.0, 19662.0, 19690.0, 19778.307759228446, 20188.19247230109, 20953.266635667755, 21006.684899726515, 21042.0, 21130.471738206554, 881.8544940084878, 908.8636704494313, 909.3770767024577, 911.2846415563783, 913.4245425382034, 892.8437523433406, 931.0456781798677, 948.1040416806121, 948.4025064209538, 953.8473844184932, 958.1568219966455, 1006.1598565198068, 1010.3432768700367, 1011.5448048378222, 1012.5190534496093, 958.1663952361455, 1000.9949950250202, 1003.8845532763753, 1004.5429329655026, 1007.078523151323, 699.1191099214975, 715.5635062611802, 730.8684436801374, 731.1007460823164, 731.3637135691982, 18237.0, 18371.173297201658, 18584.44246672602, 18609.06954652262, 19677.606447871105, 20460.488487787774, 20514.794852051586, 20982.0, 21057.368278951504, 21198.5760284795, 16705.164741762863, 16946.745798813026, 17048.403305884236, 17224.741628875578, 17267.568310792205, 3682.8710995115043, 3720.572134204574, 3736.4768932349493, 3741.5884252732176, 3742.3825068863603, 113997.0, 119979.40010299893, 122025.38987305095, 123872.0, 129334.0, 16277.348906043862, 16551.172441378123, 16709.749353759704, 16731.749023764653, 16754.246058927376, 22849.200277990192, 28863.855680721463, 29786.10641680753, 29793.851030744707, 29799.55300670492, 8043.235892590312, 8677.56612169389, 8686.52224127679, 8716.956415217883, 8759.781005474913, 4069.633732963988, 4158.293090174678, 4158.563350848118, 4160.895977600584, 4164.208800327928, 4815.614750819926, 4955.578775804025, 4976.452590215146, 4982.177940639754, 5009.574186194214, 953.113604347963, 976.2482888176206, 988.0761488008735, 988.1650005745134, 989.9752506187403, 681.7750142453078, 699.2133849419403, 709.4395427612232, 711.8932160176015, 719.3058698356111, 10999.505022274017, 11701.180917335874, 11753.4710938008, 12222.449989750483, 12695.682607934876, 683.4873844616537, 683.5830143612393, 685.4036987820529, 687.3126873126873, 707.6249587718518, 5517.124685226276, 5539.19681646159, 5593.440655934374, 5678.914816277759, 5684.488396044376, 114107.42946285216, 115151.0, 116431.0, 117481.41259293648, 118072.40963795298, 97682.51158744161, 101388.49305753571, 102278.48860755649, 103230.48384758178, 106549.93450065555, 87822.56088719516, 89674.55162724145, 92132.53933730288, 94133.0, 94328.52835735778, 10045.899541004497, 10208.285420020824, 10221.284510084222, 10284.742881428025, 10485.16118710486, 41204.58795412008, 41582.79208604059, 42206.366904497074, 42581.0, 42607.1478570433, 68475.31524684888, 68727.0, 68831.0, 72115.63942180255, 72962.16916712398, 16449.917750411172, 17797.46607601774, 17812.46562603124, 18115.184816683017, 18126.18432170582, 20932.267370641955, 21132.260370886946, 21286.297096231956, 21344.7865521349, 21548.245811396526, 18003.459896203134, 19708.21167153298, 19884.90057549703, 19984.70022949686, 20062.197512099352, 17796.55508612295, 18300.633987319914, 18342.63314733671, 18422.815771842645, 18436.81563184351, 18656.253749849853, 18858.24567017359, 18908.14913328876, 19099.23603055918, 19189.520261993563, 12420.378981051092, 12785.61643150707, 13489.662758430724, 13762.518311859038, 14130.646733831736, 14029.719405611626, 14110.576682699535, 14417.42330306776, 14504.0, 14715.190664513555], "value": 9050.449702656686, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1508.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 108.0, 107.0, 106.0, 106.0, 109.0, 110.0, 98.0, 110.0, 113.0], "value": 106.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 121.0, 154.0, 171.0, 118.0, 146.0, 148.0, 123.0, 182.0, 118.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 162.0, 161.0, 163.0, 163.0, 170.0, 164.0, 169.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 72.0, 162.0, 74.0, 74.0, 74.0, 83.0, 96.0, 74.0], "value": 85.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 133.0, 79.0, 78.0, 80.0, 134.0, 85.0, 79.0, 135.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 114.0, 114.0, 115.0, 119.0, 115.0, 114.0], "value": 115.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 149.0, 151.0, 149.0, 149.0, 150.0, 153.0, 148.0, 148.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 97.0, 94.0, 99.0, 98.0, 99.0, 94.0, 93.0, 93.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 52.0, 51.0, 55.0, 57.0, 51.0, 55.0, 51.0, 53.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 62.0, 61.0, 56.0, 56.0, 60.0, 57.0, 56.0, 56.0], "value": 57.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 78.0, 73.0, 74.0, 73.0, 73.0, 75.0, 72.0, 79.0, 74.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 121.0, 114.0, 125.0, 120.0, 121.0, 118.0, 116.0, 131.0, 171.0], "value": 126.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 176.0, 170.0, 169.0, 170.0, 172.0, 171.0, 170.0, 170.0, 170.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 64.0, 68.0, 63.0, 64.0, 64.0, 67.0, 63.0, 67.0, 64.0], "value": 65.3, "unit": "score"}]}]} 18:36:32 INFO - Return code: 0 18:36:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:36:32 INFO - # TBPL SUCCESS # 18:36:32 INFO - Running post-action listener: _package_coverage_data 18:36:32 INFO - Running post-action listener: _resource_record_post_action 18:36:32 INFO - [mozharness: 2017-08-25 01:36:32.386220Z] Finished run-tests step (success) 18:36:32 INFO - Running post-run listener: _resource_record_post_run 18:36:32 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 12288; Write bytes: 431366144; Read time: 340; Write time: 496648 18:36:32 INFO - TinderboxPrint: CPU usage
13.2% 18:36:32 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 340 18:36:32 INFO - TinderboxPrint: I/O write bytes / time
431,366,144 / 496,648 18:36:32 INFO - TinderboxPrint: CPU idle
8,273.9 (86.8%) 18:36:32 INFO - TinderboxPrint: CPU user
1,201.1 (12.6%) 18:36:32 INFO - TinderboxPrint: Swap in / out
0 / 0 18:36:32 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 112644096; Read time: 0; Write time: 203792 18:36:32 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:36:32 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 8192; Write bytes: 318722048; Read time: 324; Write time: 292856 18:36:33 INFO - Running post-run listener: _upload_blobber_files 18:36:33 INFO - Blob upload gear active. 18:36:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:36:33 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 18:36:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:36:33 INFO - (blobuploader) - INFO - Open directory for files ... 18:36:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:36:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:36:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:34 INFO - (blobuploader) - INFO - Done attempting. 18:36:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:36:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:36:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:35 INFO - (blobuploader) - INFO - Done attempting. 18:36:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:36:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:36:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:38 INFO - (blobuploader) - INFO - Done attempting. 18:36:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:36:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:36:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:39 INFO - (blobuploader) - INFO - Done attempting. 18:36:39 INFO - (blobuploader) - INFO - Iteration through files over. 18:36:39 INFO - Return code: 0 18:36:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:36:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:36:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c5d7d89625a8b0370ddaeda3438065de879109b78d76232709eda3c3291b147629ece190cd52e529db3ec3a4cee8f0f5e1dd6e2c735cbc306b19e40ecbd9000", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e848f926a799566bf114f5bcbcd55b404c4b462ef4f2f2a7bad5e7a8c4741ece2d6280931f4d9e4535b128521000c422cca3ebeceb124b73fe8e5b2b08940f9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26604325aa2d2916a92f883966bc695ca7eda6ca33f9645cfcfac1388b0a8f1087d77cb81c95f62548480ec7486f4a55ff4e3895498df320f9faa79d7c4548c1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01a465ca31278254d6c3d85fe6463e7cd6f0700239d800c694f41640f5b3144f3dd1bc051d6551dad9d02217718037611572be562f3a870172f9856524affa37"} 18:36:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:36:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:36:39 INFO - Contents: 18:36:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c5d7d89625a8b0370ddaeda3438065de879109b78d76232709eda3c3291b147629ece190cd52e529db3ec3a4cee8f0f5e1dd6e2c735cbc306b19e40ecbd9000", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e848f926a799566bf114f5bcbcd55b404c4b462ef4f2f2a7bad5e7a8c4741ece2d6280931f4d9e4535b128521000c422cca3ebeceb124b73fe8e5b2b08940f9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26604325aa2d2916a92f883966bc695ca7eda6ca33f9645cfcfac1388b0a8f1087d77cb81c95f62548480ec7486f4a55ff4e3895498df320f9faa79d7c4548c1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01a465ca31278254d6c3d85fe6463e7cd6f0700239d800c694f41640f5b3144f3dd1bc051d6551dad9d02217718037611572be562f3a870172f9856524affa37"} 18:36:39 INFO - Running post-run listener: copy_logs_to_upload_dir 18:36:39 INFO - Copying logs to upload dir... 18:36:39 INFO - mkdir: /builds/slave/test/build/upload/logs 18:36:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.470692 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-08-24 18:36:39.782865) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 18:36:39.787609) ========= 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=5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c5d7d89625a8b0370ddaeda3438065de879109b78d76232709eda3c3291b147629ece190cd52e529db3ec3a4cee8f0f5e1dd6e2c735cbc306b19e40ecbd9000", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e848f926a799566bf114f5bcbcd55b404c4b462ef4f2f2a7bad5e7a8c4741ece2d6280931f4d9e4535b128521000c422cca3ebeceb124b73fe8e5b2b08940f9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26604325aa2d2916a92f883966bc695ca7eda6ca33f9645cfcfac1388b0a8f1087d77cb81c95f62548480ec7486f4a55ff4e3895498df320f9faa79d7c4548c1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01a465ca31278254d6c3d85fe6463e7cd6f0700239d800c694f41640f5b3144f3dd1bc051d6551dad9d02217718037611572be562f3a870172f9856524affa37"} build_url:https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011362 build_url: 'https://queue.taskcluster.net/v1/task/Y1BifvzVTmGQf0FseYcUxQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4c5d7d89625a8b0370ddaeda3438065de879109b78d76232709eda3c3291b147629ece190cd52e529db3ec3a4cee8f0f5e1dd6e2c735cbc306b19e40ecbd9000", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e848f926a799566bf114f5bcbcd55b404c4b462ef4f2f2a7bad5e7a8c4741ece2d6280931f4d9e4535b128521000c422cca3ebeceb124b73fe8e5b2b08940f9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26604325aa2d2916a92f883966bc695ca7eda6ca33f9645cfcfac1388b0a8f1087d77cb81c95f62548480ec7486f4a55ff4e3895498df320f9faa79d7c4548c1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01a465ca31278254d6c3d85fe6463e7cd6f0700239d800c694f41640f5b3144f3dd1bc051d6551dad9d02217718037611572be562f3a870172f9856524affa37"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-24 18:36:39.816506) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:36:39.816801) ========= 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=5cf47e208febbcd81da23b2e0000026f-1503623737.492437-52351486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004509 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-24 18:36:39.899196) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 18:36:39.899527) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-24 18:36:39.899809) ========= ========= Total master_lag: 0.23 =========