builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-014 starttime: 1503971242.39 results: success (0) revision: 1b4c59eef820b46eb0037aca68f83a15088db45f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 18:47:22.394170) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-28 18:47:22.394672) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 18:47:22.394995) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-28 18:47:22.435796) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:47:22.436132) ========= 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=31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-28 18:47:22-- 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% 99.2M=0s 2017-08-28 18:47:22 (99.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.193581 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:47:22.686396) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:47:22.686747) ========= 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=31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017377 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 18:47:22.736692) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-28 18:47:22.737046) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1b4c59eef820b46eb0037aca68f83a15088db45f --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 1b4c59eef820b46eb0037aca68f83a15088db45f --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=31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-28 18:47:22,797 truncating revision to first 12 chars 2017-08-28 18:47:22,797 Setting DEBUG logging. 2017-08-28 18:47:22,797 attempt 1/10 2017-08-28 18:47:22,797 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1b4c59eef820?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-28 18:47:23,876 unpacking tar archive at: mozilla-central-1b4c59eef820/testing/mozharness/ program finished with exit code 0 elapsedTime=1.533830 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-28 18:47:24.288274) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 18:47:24.288719) ========= script_repo_revision: 1b4c59eef820b46eb0037aca68f83a15088db45f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-28 18:47:24.289161) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 18:47:24.289539) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-28 18:47:24.302120) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-28 18:47:24.302423) ========= /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=31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:47:24 INFO - MultiFileLogger online at 20170828 18:47:24 in /builds/slave/test 18:47:24 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:47:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:47:24 INFO - {'append_to_log': False, 18:47:24 INFO - 'base_work_dir': '/builds/slave/test', 18:47:24 INFO - 'blob_upload_branch': 'Firefox', 18:47:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:47:24 INFO - 'branch': 'Firefox', 18:47:24 INFO - 'buildbot_json_path': 'buildprops.json', 18:47:24 INFO - 'code_coverage': False, 18:47:24 INFO - 'config_files': ('talos/linux_config.py',), 18:47:24 INFO - 'default_actions': ('clobber', 18:47:24 INFO - 'read-buildbot-config', 18:47:24 INFO - 'download-and-extract', 18:47:24 INFO - 'populate-webroot', 18:47:24 INFO - 'create-virtualenv', 18:47:24 INFO - 'install', 18:47:24 INFO - 'setup-mitmproxy', 18:47:24 INFO - 'run-tests'), 18:47:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:47:24 INFO - 'disable_ccov_upload': False, 18:47:24 INFO - 'download_minidump_stackwalk': True, 18:47:24 INFO - 'download_symbols': 'ondemand', 18:47:24 INFO - 'enable_stylo': False, 18:47:24 INFO - 'enable_webrender': False, 18:47:24 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:47:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:47:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:47:24 INFO - 'gecko_profile': False, 18:47:24 INFO - 'gecko_profile_interval': 0, 18:47:24 INFO - 'installer_path': 'installer.exe', 18:47:24 INFO - 'log_level': 'info', 18:47:24 INFO - 'log_name': 'talos', 18:47:24 INFO - 'log_to_console': True, 18:47:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:47:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:47:24 INFO - 'opt_config_files': (), 18:47:24 INFO - 'pip_index': False, 18:47:24 INFO - 'suite': 'dromaeojs-e10s', 18:47:24 INFO - 'system_bits': '32', 18:47:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:47:24 INFO - 'title': 'talos-linux64-ix-014', 18:47:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:47:24 INFO - 'use_talos_json': True, 18:47:24 INFO - 'verify': 'False', 18:47:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:47:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:47:24 INFO - 'work_dir': 'build'} 18:47:24 INFO - [mozharness: 2017-08-29 01:47:24.589408Z] Running clobber step. 18:47:24 INFO - Running pre-action listener: _resource_record_pre_action 18:47:24 INFO - Running main action method: clobber 18:47:24 INFO - rmtree: /builds/slave/test/build 18:47:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:47:25 INFO - Running post-action listener: _resource_record_post_action 18:47:25 INFO - [mozharness: 2017-08-29 01:47:25.555679Z] Finished clobber step (success) 18:47:25 INFO - [mozharness: 2017-08-29 01:47:25.555776Z] Running read-buildbot-config step. 18:47:25 INFO - Running pre-action listener: _resource_record_pre_action 18:47:25 INFO - Running main action method: read_buildbot_config 18:47:25 INFO - Using buildbot properties: 18:47:25 INFO - { 18:47:25 INFO - "project": "", 18:47:25 INFO - "product": "firefox", 18:47:25 INFO - "who": "kwierso@gmail.com", 18:47:25 INFO - "installer_path": "public/build/target.tar.bz2", 18:47:25 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:47:25 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:47:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 18:47:25 INFO - "stage_platform": "linux64-pgo", 18:47:25 INFO - "basedir": "/builds/slave/test", 18:47:25 INFO - "buildnumber": 145, 18:47:25 INFO - "platform": "ubuntu64_hw", 18:47:25 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:47:25 INFO - "slavebuilddir": "test", 18:47:25 INFO - "taskId": "biHQi-7uTriEq4ws7wHCKg", 18:47:25 INFO - "branch": "mozilla-central", 18:47:25 INFO - "script_repo_revision": "production", 18:47:25 INFO - "revision": "1b4c59eef820b46eb0037aca68f83a15088db45f", 18:47:25 INFO - "slavename": "talos-linux64-ix-014", 18:47:25 INFO - "repo_path": "mozilla-central" 18:47:25 INFO - } 18:47:25 INFO - Finding installer, test and symbols from parent task. 18:47:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/biHQi-7uTriEq4ws7wHCKg'}, attempt #1 18:47:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/biHQi-7uTriEq4ws7wHCKg'}, attempt #1 18:47:26 INFO - Task dependencies: OviRUCxuR1uijxUodxp14A 18:47:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A'}, attempt #1 18:47:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.tar.bz2 18:47:26 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.test_packages.json 18:47:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.crashreporter-symbols.zip 18:47:26 INFO - Running post-action listener: _resource_record_post_action 18:47:26 INFO - [mozharness: 2017-08-29 01:47:26.896089Z] Finished read-buildbot-config step (success) 18:47:26 INFO - [mozharness: 2017-08-29 01:47:26.896306Z] Running download-and-extract step. 18:47:26 INFO - Running pre-action listener: _resource_record_pre_action 18:47:26 INFO - Running main action method: download_and_extract 18:47:26 INFO - mkdir: /builds/slave/test/build/tests 18:47: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:47:26 INFO - trying https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.test_packages.json 18:47:26 INFO - Downloading https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:47:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:47:27 INFO - Downloaded 989 bytes. 18:47:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:47:27 INFO - Using the following test package requirements: 18:47:27 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:47:27 INFO - u'common': [u'target.common.tests.zip'], 18:47:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:47:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:47:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:47:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:47:27 INFO - u'mozbase': [u'target.common.tests.zip'], 18:47:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:47:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:47:27 INFO - u'web-platform': [u'target.common.tests.zip', 18:47:27 INFO - u'target.web-platform.tests.tar.gz'], 18:47:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:47:27 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:47:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.common.tests.zip 18:47:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:47:27 INFO - Fetch https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.common.tests.zip into memory 18:47:28 INFO - Content-Length response header: 55718072 18:47:28 INFO - Bytes received: 55718072 18:47:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:47:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.common.tests.zip 18:47:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:47:39 INFO - Fetch https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.common.tests.zip into memory 18:47:40 INFO - Content-Length response header: 55718072 18:47:40 INFO - Bytes received: 55718072 18:47:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.talos.tests.zip 18:47:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:47:53 INFO - Fetch https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.talos.tests.zip into memory 18:47:54 INFO - Content-Length response header: 14496611 18:47:54 INFO - Bytes received: 14496611 18:47:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:54 INFO - trying https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.tar.bz2 18:47:54 INFO - Downloading https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:47:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:47:55 INFO - Downloaded 62454007 bytes. 18:47:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.tar.bz2 18:47:55 INFO - mkdir: /builds/slave/test/properties 18:47:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:47:55 INFO - Writing to file /builds/slave/test/properties/build_url 18:47:55 INFO - Contents: 18:47:55 INFO - build_url:https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.tar.bz2 18:47:55 INFO - Running post-action listener: _resource_record_post_action 18:47:55 INFO - Running post-action listener: find_tests_for_verification 18:47:55 INFO - Running post-action listener: set_extra_try_arguments 18:47:55 INFO - [mozharness: 2017-08-29 01:47:55.613389Z] Finished download-and-extract step (success) 18:47:55 INFO - [mozharness: 2017-08-29 01:47:55.613485Z] Running populate-webroot step. 18:47:55 INFO - Running pre-action listener: _resource_record_pre_action 18:47:55 INFO - Running main action method: populate_webroot 18:47:55 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:47:55 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 18:47:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:47:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:47:55 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 18:47:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:47:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:47:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:47:55 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:47:55 INFO - 'talos_options': ['--stylo'], 18:47:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:47:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:47:55 INFO - 'tests': ['damp', 'tps']}, 18:47:55 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:47:55 INFO - 'talos_options': ['--stylo'], 18:47:55 INFO - 'tests': ['damp', 'tps']}, 18:47:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:47:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:47:55 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 18:47:55 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:47:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:47:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:47:55 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:47:55 INFO - 'talos_options': ['--stylo'], 18:47:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:47:55 INFO - 'other-e10s': {'tests': ['a11yr', 18:47:55 INFO - 'ts_paint', 18:47:55 INFO - 'tpaint', 18:47:55 INFO - 'sessionrestore', 18:47:55 INFO - 'sessionrestore_many_windows', 18:47:55 INFO - 'sessionrestore_no_auto_restore', 18:47:55 INFO - 'tabpaint']}, 18:47:55 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 18:47:55 INFO - 'tests': ['a11yr', 18:47:55 INFO - 'ts_paint', 18:47:55 INFO - 'tpaint', 18:47:55 INFO - 'sessionrestore', 18:47:55 INFO - 'sessionrestore_many_windows', 18:47:55 INFO - 'sessionrestore_no_auto_restore', 18:47:55 INFO - 'tabpaint']}, 18:47:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:47:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:47:55 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 18:47:55 INFO - 'tests': ['bloom_basic_singleton']}, 18:47:55 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 18:47:55 INFO - 'tests': ['bloom_basic']}, 18:47:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:47:55 INFO - 'tsvgr_opacity', 18:47:55 INFO - 'tart', 18:47:55 INFO - 'tscrollx', 18:47:55 INFO - 'tsvg_static']}, 18:47:55 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 18:47:55 INFO - 'tests': ['tsvgx', 18:47:55 INFO - 'tsvgr_opacity', 18:47:55 INFO - 'tart', 18:47:55 INFO - 'tscrollx', 18:47:55 INFO - 'tsvg_static']}, 18:47:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:47:55 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:47:55 INFO - 'talos_options': ['--stylo'], 18:47:55 INFO - 'tests': ['tp5o']}, 18:47:55 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:47:55 INFO - 'talos_options': ['--mitmproxy', 18:47:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:47:55 INFO - '--firstNonBlankPaint'], 18:47:55 INFO - 'tests': ['tp6_google', 18:47:55 INFO - 'tp6_youtube', 18:47:55 INFO - 'tp6_amazon', 18:47:55 INFO - 'tp6_facebook']}, 18:47:55 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:47:55 INFO - 'talos_options': ['--stylo', 18:47:55 INFO - '--mitmproxy', 18:47:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:47:55 INFO - '--firstNonBlankPaint'], 18:47:55 INFO - 'tests': ['tp6_google', 18:47:55 INFO - 'tp6_youtube', 18:47:55 INFO - 'tp6_amazon', 18:47:55 INFO - 'tp6_facebook']}, 18:47:55 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:47:55 INFO - 'talos_options': ['--stylo', 18:47:55 INFO - '--stylo-threads=1', 18:47:55 INFO - '--mitmproxy', 18:47:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:47:55 INFO - '--firstNonBlankPaint'], 18:47:55 INFO - 'tests': ['tp6_google', 18:47:55 INFO - 'tp6_youtube', 18:47:55 INFO - 'tp6_amazon', 18:47:55 INFO - 'tp6_facebook']}, 18:47:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:47:55 INFO - 'talos_options': ['--xperf_path', 18:47:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:47:55 INFO - 'tests': ['tp5n']}, 18:47:55 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:47:55 INFO - 'talos_options': ['--stylo', 18:47:55 INFO - '--xperf_path', 18:47:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:47:55 INFO - 'tests': ['tp5n']}}} 18:47:55 INFO - Running post-action listener: _resource_record_post_action 18:47:55 INFO - [mozharness: 2017-08-29 01:47:55.627583Z] Finished populate-webroot step (success) 18:47:55 INFO - [mozharness: 2017-08-29 01:47:55.627672Z] Running create-virtualenv step. 18:47:55 INFO - Running pre-action listener: _resource_record_pre_action 18:47:55 INFO - Running main action method: create_virtualenv 18:47:55 INFO - Creating virtualenv /builds/slave/test/build/venv 18:47:55 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:47:55 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:47:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:47:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:47:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:47:56 INFO - Installing distribute.............................................................................................................................................................................................done. 18:47:56 INFO - Installing pip...............done. 18:47:56 INFO - Return code: 0 18:47:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:47:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:47:56 INFO - Reading from file tmpfile_stdout 18:47:56 INFO - Output received: 18:47:56 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:47:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:47:56 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:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:56 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:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:56 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 0x160d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979130>, '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 0x197a030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197a4c0>, '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': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', '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:47:56 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:47:56 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:47:56 INFO - Using env: {'DISPLAY': ':0', 18:47:56 INFO - 'HOME': '/home/cltbld', 18:47:56 INFO - 'LANG': 'en_US.UTF-8', 18:47:56 INFO - 'LANGUAGE': 'en_US:en', 18:47:56 INFO - 'LOGNAME': 'cltbld', 18:47:56 INFO - 'MAIL': '/var/mail/cltbld', 18:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:56 INFO - 'MOZ_NO_REMOTE': '1', 18:47:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:56 INFO - 'NO_EM_RESTART': '1', 18:47:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:56 INFO - 'PWD': '/builds/slave/test', 18:47:56 INFO - 'SHELL': '/bin/bash', 18:47:56 INFO - 'SHLVL': '1', 18:47:56 INFO - 'TERM': 'linux', 18:47:56 INFO - 'TMOUT': '86400', 18:47:56 INFO - 'USER': 'cltbld', 18:47:56 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', 18:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:56 INFO - '_': '/tools/buildbot/bin/python'} 18:47:58 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:47:58 INFO - Downloading/unpacking pip>=1.5 18:47:58 INFO - Running setup.py egg_info for package pip 18:47:58 INFO - warning: no files found matching 'pip/cacert.pem' 18:47:58 INFO - warning: no files found matching '*.html' under directory 'docs' 18:47:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:47:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:47:58 INFO - Installing collected packages: pip 18:47:58 INFO - Found existing installation: pip 1.1 18:47:58 INFO - Uninstalling pip: 18:47:58 INFO - Successfully uninstalled pip 18:47:58 INFO - Running setup.py install for pip 18:47:58 INFO - warning: no files found matching 'pip/cacert.pem' 18:47:58 INFO - warning: no files found matching '*.html' under directory 'docs' 18:47:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:47:58 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:47:58 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:47:58 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:47:58 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:47:58 INFO - Successfully installed pip 18:47:58 INFO - Cleaning up... 18:47:58 INFO - Return code: 0 18:47:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:47:58 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:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:58 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:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:58 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 0x160d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979130>, '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 0x197a030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197a4c0>, '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': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', '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:47:58 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:47:58 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:47:58 INFO - Using env: {'DISPLAY': ':0', 18:47:58 INFO - 'HOME': '/home/cltbld', 18:47:58 INFO - 'LANG': 'en_US.UTF-8', 18:47:58 INFO - 'LANGUAGE': 'en_US:en', 18:47:58 INFO - 'LOGNAME': 'cltbld', 18:47:58 INFO - 'MAIL': '/var/mail/cltbld', 18:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:58 INFO - 'MOZ_NO_REMOTE': '1', 18:47:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:58 INFO - 'NO_EM_RESTART': '1', 18:47:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:58 INFO - 'PWD': '/builds/slave/test', 18:47:58 INFO - 'SHELL': '/bin/bash', 18:47:58 INFO - 'SHLVL': '1', 18:47:58 INFO - 'TERM': 'linux', 18:47:58 INFO - 'TMOUT': '86400', 18:47:58 INFO - 'USER': 'cltbld', 18:47:58 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', 18:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:58 INFO - '_': '/tools/buildbot/bin/python'} 18:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:58 INFO - Downloading/unpacking psutil>=3.1.1 18:47:58 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:47:58 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:47:58 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:47:58 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:47:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:47:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:47:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:47:59 INFO - Installing collected packages: psutil 18:47:59 INFO - Running setup.py install for psutil 18:47:59 INFO - building 'psutil._psutil_linux' extension 18:47:59 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:48:00 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:48:00 INFO - building 'psutil._psutil_posix' extension 18:48:00 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:48:00 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:48:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:48:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:48:00 INFO - Successfully installed psutil 18:48:00 INFO - Cleaning up... 18:48:00 INFO - Return code: 0 18:48:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:48:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:00 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 0x160d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979130>, '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 0x197a030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197a4c0>, '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': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', '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:48:00 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:48:00 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:48:00 INFO - Using env: {'DISPLAY': ':0', 18:48:00 INFO - 'HOME': '/home/cltbld', 18:48:00 INFO - 'LANG': 'en_US.UTF-8', 18:48:00 INFO - 'LANGUAGE': 'en_US:en', 18:48:00 INFO - 'LOGNAME': 'cltbld', 18:48:00 INFO - 'MAIL': '/var/mail/cltbld', 18:48:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:00 INFO - 'MOZ_NO_REMOTE': '1', 18:48:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:00 INFO - 'NO_EM_RESTART': '1', 18:48:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:00 INFO - 'PWD': '/builds/slave/test', 18:48:00 INFO - 'SHELL': '/bin/bash', 18:48:00 INFO - 'SHLVL': '1', 18:48:00 INFO - 'TERM': 'linux', 18:48:00 INFO - 'TMOUT': '86400', 18:48:00 INFO - 'USER': 'cltbld', 18:48:00 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', 18:48:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:00 INFO - '_': '/tools/buildbot/bin/python'} 18:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:48:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:48:02 INFO - Installing collected packages: mozsystemmonitor 18:48:02 INFO - Running setup.py install for mozsystemmonitor 18:48:02 INFO - Successfully installed mozsystemmonitor 18:48:02 INFO - Cleaning up... 18:48:02 INFO - Return code: 0 18:48:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:02 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 0x160d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979130>, '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 0x197a030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197a4c0>, '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': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', '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:48:02 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:48:02 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:48:02 INFO - Using env: {'DISPLAY': ':0', 18:48:02 INFO - 'HOME': '/home/cltbld', 18:48:02 INFO - 'LANG': 'en_US.UTF-8', 18:48:02 INFO - 'LANGUAGE': 'en_US:en', 18:48:02 INFO - 'LOGNAME': 'cltbld', 18:48:02 INFO - 'MAIL': '/var/mail/cltbld', 18:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:02 INFO - 'MOZ_NO_REMOTE': '1', 18:48:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:02 INFO - 'NO_EM_RESTART': '1', 18:48:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:02 INFO - 'PWD': '/builds/slave/test', 18:48:02 INFO - 'SHELL': '/bin/bash', 18:48:02 INFO - 'SHLVL': '1', 18:48:02 INFO - 'TERM': 'linux', 18:48:02 INFO - 'TMOUT': '86400', 18:48:02 INFO - 'USER': 'cltbld', 18:48:02 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', 18:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:02 INFO - '_': '/tools/buildbot/bin/python'} 18:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:02 INFO - Downloading/unpacking jsonschema==2.5.1 18:48:02 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:48:02 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:48:02 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:48:02 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:48:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:48:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:48:03 INFO - Downloading functools32-3.2.3-2.tar.gz 18:48:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:48:03 INFO - warning: no files found matching '*.txt' 18:48:03 INFO - no previously-included directories found matching 'build' 18:48:03 INFO - no previously-included directories found matching 'dist' 18:48:03 INFO - no previously-included directories found matching '.git*' 18:48:03 INFO - Installing collected packages: jsonschema, functools32 18:48:03 INFO - Running setup.py install for functools32 18:48:04 INFO - warning: no files found matching '*.txt' 18:48:04 INFO - no previously-included directories found matching 'build' 18:48:04 INFO - no previously-included directories found matching 'dist' 18:48:04 INFO - no previously-included directories found matching '.git*' 18:48:04 INFO - Successfully installed jsonschema functools32 18:48:04 INFO - Cleaning up... 18:48:04 INFO - Return code: 0 18:48:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:48:04 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:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:04 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:48:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:04 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 0x160d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979130>, '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 0x197a030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197a4c0>, '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': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', '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:48:04 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:48:04 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:48:04 INFO - Using env: {'DISPLAY': ':0', 18:48:04 INFO - 'HOME': '/home/cltbld', 18:48:04 INFO - 'LANG': 'en_US.UTF-8', 18:48:04 INFO - 'LANGUAGE': 'en_US:en', 18:48:04 INFO - 'LOGNAME': 'cltbld', 18:48:04 INFO - 'MAIL': '/var/mail/cltbld', 18:48:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:04 INFO - 'MOZ_NO_REMOTE': '1', 18:48:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:04 INFO - 'NO_EM_RESTART': '1', 18:48:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:04 INFO - 'PWD': '/builds/slave/test', 18:48:04 INFO - 'SHELL': '/bin/bash', 18:48:04 INFO - 'SHLVL': '1', 18:48:04 INFO - 'TERM': 'linux', 18:48:04 INFO - 'TMOUT': '86400', 18:48:04 INFO - 'USER': 'cltbld', 18:48:04 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', 18:48:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:04 INFO - '_': '/tools/buildbot/bin/python'} 18:48:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:48:04 INFO - Cleaning up... 18:48:04 INFO - Return code: 0 18:48:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:48:04 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:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:04 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:48:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:04 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 0x160d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979130>, '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 0x197a030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197a4c0>, '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': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', '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:48:04 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:48:04 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:48:04 INFO - Using env: {'DISPLAY': ':0', 18:48:04 INFO - 'HOME': '/home/cltbld', 18:48:04 INFO - 'LANG': 'en_US.UTF-8', 18:48:04 INFO - 'LANGUAGE': 'en_US:en', 18:48:04 INFO - 'LOGNAME': 'cltbld', 18:48:04 INFO - 'MAIL': '/var/mail/cltbld', 18:48:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:04 INFO - 'MOZ_NO_REMOTE': '1', 18:48:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:04 INFO - 'NO_EM_RESTART': '1', 18:48:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:04 INFO - 'PWD': '/builds/slave/test', 18:48:04 INFO - 'SHELL': '/bin/bash', 18:48:04 INFO - 'SHLVL': '1', 18:48:04 INFO - 'TERM': 'linux', 18:48:04 INFO - 'TMOUT': '86400', 18:48:04 INFO - 'USER': 'cltbld', 18:48:04 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', 18:48:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:04 INFO - '_': '/tools/buildbot/bin/python'} 18:48:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:04 INFO - Downloading/unpacking blobuploader==1.2.4 18:48:04 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:48:04 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:48:04 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:48:04 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:48:05 INFO - Downloading blobuploader-1.2.4.tar.gz 18:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:48:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:48:05 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:48:05 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:48:05 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:48:05 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:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:48:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:48:05 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:48:05 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:48:05 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:48:05 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:48:06 INFO - Downloading docopt-0.6.1.tar.gz 18:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:48:06 INFO - Installing collected packages: blobuploader, requests, docopt 18:48:06 INFO - Running setup.py install for blobuploader 18:48:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:48:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:48:06 INFO - Running setup.py install for requests 18:48:06 INFO - Running setup.py install for docopt 18:48:06 INFO - Successfully installed blobuploader requests docopt 18:48:06 INFO - Cleaning up... 18:48:06 INFO - Return code: 0 18:48:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:48:06 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:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:06 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:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:06 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 0x160d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979130>, '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 0x197a030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197a4c0>, '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': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', '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:48:06 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:48:06 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:48:06 INFO - Using env: {'DISPLAY': ':0', 18:48:06 INFO - 'HOME': '/home/cltbld', 18:48:06 INFO - 'LANG': 'en_US.UTF-8', 18:48:06 INFO - 'LANGUAGE': 'en_US:en', 18:48:06 INFO - 'LOGNAME': 'cltbld', 18:48:06 INFO - 'MAIL': '/var/mail/cltbld', 18:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:06 INFO - 'MOZ_NO_REMOTE': '1', 18:48:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:06 INFO - 'NO_EM_RESTART': '1', 18:48:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:06 INFO - 'PWD': '/builds/slave/test', 18:48:06 INFO - 'SHELL': '/bin/bash', 18:48:06 INFO - 'SHLVL': '1', 18:48:06 INFO - 'TERM': 'linux', 18:48:06 INFO - 'TMOUT': '86400', 18:48:06 INFO - 'USER': 'cltbld', 18:48:06 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', 18:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:06 INFO - '_': '/tools/buildbot/bin/python'} 18:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:48:06 INFO - Running setup.py (path:/tmp/pip-Uwbzv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:48:07 INFO - Running setup.py (path:/tmp/pip-BfViCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:48:07 INFO - Running setup.py (path:/tmp/pip-Rp_B3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:48:07 INFO - Running setup.py (path:/tmp/pip-1mGMma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:48:07 INFO - Running setup.py (path:/tmp/pip-x4jyEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:48:07 INFO - Running setup.py (path:/tmp/pip-ZW4b2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:48:07 INFO - Running setup.py (path:/tmp/pip-lo9gjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:48:07 INFO - Running setup.py (path:/tmp/pip-ciBRdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:48:07 INFO - Running setup.py (path:/tmp/pip-saNhLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:48:07 INFO - Running setup.py (path:/tmp/pip-aiiWmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:48:07 INFO - Running setup.py (path:/tmp/pip-XubUy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:48:07 INFO - Running setup.py (path:/tmp/pip-ua9bJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:48:07 INFO - Running setup.py (path:/tmp/pip-fDsu2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:48:07 INFO - Running setup.py (path:/tmp/pip-j0SkcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:07 INFO - Running setup.py (path:/tmp/pip-nupbB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:48:08 INFO - Running setup.py (path:/tmp/pip-oVqaip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:48:08 INFO - Running setup.py (path:/tmp/pip-KHoVLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:48:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:48:08 INFO - Running setup.py install for manifestparser 18:48:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:48:08 INFO - Running setup.py install for mozcrash 18:48:08 INFO - Running setup.py install for mozdebug 18:48:08 INFO - Running setup.py install for mozdevice 18:48:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:48:08 INFO - Running setup.py install for mozfile 18:48:08 INFO - Running setup.py install for mozhttpd 18:48:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:48:08 INFO - Running setup.py install for mozinfo 18:48:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:48:08 INFO - Running setup.py install for mozInstall 18:48:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:48:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:48:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:48:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:48:08 INFO - Running setup.py install for mozleak 18:48:09 INFO - Running setup.py install for mozlog 18:48:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:48:09 INFO - Running setup.py install for moznetwork 18:48:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:48:09 INFO - Running setup.py install for mozprocess 18:48:09 INFO - Running setup.py install for mozprofile 18:48:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:48:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:48:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:48:09 INFO - Running setup.py install for mozrunner 18:48:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:48:09 INFO - Running setup.py install for mozscreenshot 18:48:09 INFO - Running setup.py install for moztest 18:48:09 INFO - Running setup.py install for mozversion 18:48:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:48:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:48:09 INFO - Cleaning up... 18:48:09 INFO - Return code: 0 18:48:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:09 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 0x160d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979130>, '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 0x197a030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197a4c0>, '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': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', '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:48:09 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:48:09 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:48:09 INFO - Using env: {'DISPLAY': ':0', 18:48:09 INFO - 'HOME': '/home/cltbld', 18:48:09 INFO - 'LANG': 'en_US.UTF-8', 18:48:09 INFO - 'LANGUAGE': 'en_US:en', 18:48:09 INFO - 'LOGNAME': 'cltbld', 18:48:09 INFO - 'MAIL': '/var/mail/cltbld', 18:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:09 INFO - 'MOZ_NO_REMOTE': '1', 18:48:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:09 INFO - 'NO_EM_RESTART': '1', 18:48:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:09 INFO - 'PWD': '/builds/slave/test', 18:48:09 INFO - 'SHELL': '/bin/bash', 18:48:09 INFO - 'SHLVL': '1', 18:48:09 INFO - 'TERM': 'linux', 18:48:09 INFO - 'TMOUT': '86400', 18:48:09 INFO - 'USER': 'cltbld', 18:48:09 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', 18:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:09 INFO - '_': '/tools/buildbot/bin/python'} 18:48:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:48:10 INFO - Running setup.py (path:/tmp/pip-_4XkvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:48:10 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:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:48:10 INFO - Running setup.py (path:/tmp/pip-WCVFr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:48:10 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:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:48:10 INFO - Running setup.py (path:/tmp/pip-nELKH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:48:10 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:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:48:10 INFO - Running setup.py (path:/tmp/pip-Dt0Smr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:48:10 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:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:48:10 INFO - Running setup.py (path:/tmp/pip-QOt4Ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:48:10 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:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:48:10 INFO - Running setup.py (path:/tmp/pip-tl2lIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:48:10 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:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:48:10 INFO - Running setup.py (path:/tmp/pip-YtLRk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:48:10 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:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:48:10 INFO - Running setup.py (path:/tmp/pip-_0j9Pj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:48:10 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:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:48:10 INFO - Running setup.py (path:/tmp/pip-OBAqu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:48:10 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:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:48:10 INFO - Running setup.py (path:/tmp/pip-eJO6qE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:48:10 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:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:48:10 INFO - Running setup.py (path:/tmp/pip-unuAT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:48:10 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:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:48:10 INFO - Running setup.py (path:/tmp/pip-fruJA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:48:11 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:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:48:11 INFO - Running setup.py (path:/tmp/pip-fhFnf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:48:11 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:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:48:11 INFO - Running setup.py (path:/tmp/pip-FBMmfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:48:11 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:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:11 INFO - Running setup.py (path:/tmp/pip-YLVCMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:48:11 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:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:48:11 INFO - Running setup.py (path:/tmp/pip-EmUh71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:48:11 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:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:48:11 INFO - Running setup.py (path:/tmp/pip-F7TrAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:48:11 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:48:11 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:48:11 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:48:11 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:48:11 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:48:11 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:48:11 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:48:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:48:11 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:48:11 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:48:11 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:48:11 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:48:12 INFO - Downloading blessings-1.6.tar.gz 18:48:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:48:12 INFO - Installing collected packages: blessings 18:48:12 INFO - Running setup.py install for blessings 18:48:12 INFO - Successfully installed blessings 18:48:12 INFO - Cleaning up... 18:48:12 INFO - Return code: 0 18:48:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:48:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:48:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:48:12 INFO - Reading from file tmpfile_stdout 18:48:12 INFO - Current package versions: 18:48:12 INFO - argparse == 1.2.1 18:48:12 INFO - blessings == 1.6 18:48:12 INFO - blobuploader == 1.2.4 18:48:12 INFO - docopt == 0.6.1 18:48:12 INFO - functools32 == 3.2.3-2 18:48:12 INFO - jsonschema == 2.5.1 18:48:12 INFO - manifestparser == 1.1 18:48:12 INFO - mozInstall == 1.13 18:48:12 INFO - mozcrash == 1.0 18:48:12 INFO - mozdebug == 0.1 18:48:12 INFO - mozdevice == 0.51 18:48:12 INFO - mozfile == 1.2 18:48:12 INFO - mozhttpd == 0.7 18:48:12 INFO - mozinfo == 0.10 18:48:12 INFO - mozleak == 0.1 18:48:12 INFO - mozlog == 3.5 18:48:12 INFO - moznetwork == 0.27 18:48:12 INFO - mozprocess == 0.25 18:48:12 INFO - mozprofile == 0.28 18:48:12 INFO - mozrunner == 6.13 18:48:12 INFO - mozscreenshot == 0.1 18:48:12 INFO - mozsystemmonitor == 0.3 18:48:12 INFO - moztest == 0.8 18:48:12 INFO - mozversion == 1.4 18:48:12 INFO - psutil == 3.1.1 18:48:12 INFO - requests == 1.2.3 18:48:12 INFO - wsgiref == 0.1.2 18:48:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:48:12 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:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:12 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:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:13 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 0x160d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979130>, '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 0x197a030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197a4c0>, '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': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', '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:48:13 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:48:13 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:48:13 INFO - Using env: {'DISPLAY': ':0', 18:48:13 INFO - 'HOME': '/home/cltbld', 18:48:13 INFO - 'LANG': 'en_US.UTF-8', 18:48:13 INFO - 'LANGUAGE': 'en_US:en', 18:48:13 INFO - 'LOGNAME': 'cltbld', 18:48:13 INFO - 'MAIL': '/var/mail/cltbld', 18:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:13 INFO - 'MOZ_NO_REMOTE': '1', 18:48:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:13 INFO - 'NO_EM_RESTART': '1', 18:48:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:13 INFO - 'PWD': '/builds/slave/test', 18:48:13 INFO - 'SHELL': '/bin/bash', 18:48:13 INFO - 'SHLVL': '1', 18:48:13 INFO - 'TERM': 'linux', 18:48:13 INFO - 'TMOUT': '86400', 18:48:13 INFO - 'USER': 'cltbld', 18:48:13 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', 18:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:13 INFO - '_': '/tools/buildbot/bin/python'} 18:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:13 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:48:13 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:48:13 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:48:13 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:48:13 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:48:13 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:48:13 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:48:13 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:48:13 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:48:13 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:48:13 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:48:13 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:48:13 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:48:13 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:48:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:48:14 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:48:14 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:48:14 INFO - Installing collected packages: simplejson 18:48:14 INFO - Running setup.py install for simplejson 18:48:14 INFO - building 'simplejson._speedups' extension 18:48:14 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:48:15 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:48:15 INFO - Successfully installed simplejson 18:48:15 INFO - Cleaning up... 18:48:15 INFO - Return code: 0 18:48:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:48:15 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:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:48:15 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:48:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:48:15 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 0x160d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x197bf90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1979130>, '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 0x197a030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197a4c0>, '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': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', '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:48:15 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:48:15 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:48:15 INFO - Using env: {'DISPLAY': ':0', 18:48:15 INFO - 'HOME': '/home/cltbld', 18:48:15 INFO - 'LANG': 'en_US.UTF-8', 18:48:15 INFO - 'LANGUAGE': 'en_US:en', 18:48:15 INFO - 'LOGNAME': 'cltbld', 18:48:15 INFO - 'MAIL': '/var/mail/cltbld', 18:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:15 INFO - 'MOZ_NO_REMOTE': '1', 18:48:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:15 INFO - 'NO_EM_RESTART': '1', 18:48:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:15 INFO - 'PWD': '/builds/slave/test', 18:48:15 INFO - 'SHELL': '/bin/bash', 18:48:15 INFO - 'SHLVL': '1', 18:48:15 INFO - 'TERM': 'linux', 18:48:15 INFO - 'TMOUT': '86400', 18:48:15 INFO - 'USER': 'cltbld', 18:48:15 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', 18:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:15 INFO - '_': '/tools/buildbot/bin/python'} 18:48:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:48:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:48:15 INFO - Cleaning up... 18:48:15 INFO - Return code: 0 18:48:15 INFO - Running post-action listener: _resource_record_post_action 18:48:15 INFO - Running post-action listener: _start_resource_monitoring 18:48:15 INFO - Starting resource monitoring. 18:48:15 INFO - [mozharness: 2017-08-29 01:48:15.714344Z] Finished create-virtualenv step (success) 18:48:15 INFO - [mozharness: 2017-08-29 01:48:15.715293Z] Running install step. 18:48:15 INFO - Running pre-action listener: _resource_record_pre_action 18:48:15 INFO - Running main action method: install 18:48:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:48:15 INFO - Reading from file tmpfile_stdout 18:48:15 INFO - Detecting whether we're running mozinstall >=1.0... 18:48:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:48:16 INFO - Reading from file tmpfile_stdout 18:48:16 INFO - Output received: 18:48:16 INFO - Usage: mozinstall [options] installer 18:48:16 INFO - Options: 18:48:16 INFO - -h, --help show this help message and exit 18:48:16 INFO - -d DEST, --destination=DEST 18:48:16 INFO - Directory to install application into. [default: 18:48:16 INFO - "/builds/slave/test"] 18:48:16 INFO - --app=APP Application being installed. [default: firefox] 18:48:16 INFO - mkdir: /builds/slave/test/build/application 18:48:16 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:48:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:48:33 INFO - Reading from file tmpfile_stdout 18:48:33 INFO - Output received: 18:48:33 INFO - /builds/slave/test/build/application/firefox/firefox 18:48:33 INFO - Running post-action listener: _resource_record_post_action 18:48:33 INFO - [mozharness: 2017-08-29 01:48:33.682983Z] Finished install step (success) 18:48:33 INFO - [mozharness: 2017-08-29 01:48:33.683130Z] Running setup-mitmproxy step. 18:48:33 INFO - Running pre-action listener: _resource_record_pre_action 18:48:33 INFO - Running main action method: setup_mitmproxy 18:48:33 INFO - Skipping: mitmproxy is not required 18:48:33 INFO - Running post-action listener: _resource_record_post_action 18:48:33 INFO - [mozharness: 2017-08-29 01:48:33.683656Z] Finished setup-mitmproxy step (success) 18:48:33 INFO - [mozharness: 2017-08-29 01:48:33.683758Z] Running run-tests step. 18:48:33 INFO - Running pre-action listener: _resource_record_pre_action 18:48:33 INFO - Running pre-action listener: _set_gcov_prefix 18:48:33 INFO - Running main action method: run_tests 18:48:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:48:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:48:33 INFO - Python 2.7.3 18:48:33 INFO - Return code: 0 18:48:33 INFO - grabbing minidump binary from tooltool 18:48: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:48:33 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 0x1979130>, '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 0x197a030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x197a4c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:48:33 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:48:33 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:48:33 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:48:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:48:33 INFO - Return code: 0 18:48:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:48:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:48:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:48:33 INFO - ENV: RUST_BACKTRACE is now full 18:48:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:48:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:48:33 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/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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:48:33 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/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-014 --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:48:33 INFO - Using env: {'DISPLAY': ':0', 18:48:33 INFO - 'HOME': '/home/cltbld', 18:48:33 INFO - 'LANG': 'en_US.UTF-8', 18:48:33 INFO - 'LANGUAGE': 'en_US:en', 18:48:33 INFO - 'LOGNAME': 'cltbld', 18:48:33 INFO - 'MAIL': '/var/mail/cltbld', 18:48:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:48:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:48:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:33 INFO - 'MOZ_NO_REMOTE': '1', 18:48:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:48:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:33 INFO - 'NO_EM_RESTART': '1', 18:48:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:33 INFO - 'PWD': '/builds/slave/test', 18:48:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:48:33 INFO - 'RUST_BACKTRACE': 'full', 18:48:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:48:33 INFO - 'SHELL': '/bin/bash', 18:48:33 INFO - 'SHLVL': '1', 18:48:33 INFO - 'TERM': 'linux', 18:48:33 INFO - 'TMOUT': '86400', 18:48:33 INFO - 'USER': 'cltbld', 18:48:33 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', 18:48:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:33 INFO - '_': '/tools/buildbot/bin/python'} 18:48:33 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/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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:48:34 INFO - mozversion application_buildid: 20170829003906 18:48:34 INFO - mozversion application_changeset: 1b4c59eef820b46eb0037aca68f83a15088db45f 18:48:34 INFO - mozversion application_display_name: Nightly 18:48:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:48:34 INFO - mozversion application_name: Firefox 18:48:34 INFO - mozversion application_remotingname: firefox 18:48:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:48:34 INFO - mozversion application_vendor: Mozilla 18:48:34 INFO - mozversion application_version: 57.0a1 18:48:34 INFO - mozversion platform_buildid: 20170829003906 18:48:34 INFO - mozversion platform_changeset: 1b4c59eef820b46eb0037aca68f83a15088db45f 18:48:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:48:34 INFO - mozversion platform_version: 57.0a1 18:48:34 INFO - using testdate: 1503971314 18:48:34 INFO - actual date: 1503971314 18:48:34 INFO - starting webserver on 'localhost:50425' 18:48:34 INFO - SUITE-START | Running 2 tests 18:48:34 INFO - TEST-START | dromaeo_css 18:48:34 INFO - Initialising browser for dromaeo_css test... 18:48:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50425/getInfo.html -profile /tmp/tmpg8gGNt/profile 18:48:34 INFO - TEST-INFO | started process 3451 (/builds/slave/test/build/application/firefox/firefox http://localhost:50425/getInfo.html) 18:48:42 INFO - TEST-INFO | 3451: exit 0 18:48:42 INFO - Browser initialized. 18:48:42 INFO - Running cycle 1/1 for dromaeo_css test... 18:48:42 INFO - Using env: {'DISPLAY': ':0', 18:48:42 INFO - 'HOME': '/home/cltbld', 18:48:42 INFO - 'JSGC_DISABLE_POISONING': '1', 18:48:42 INFO - 'LANG': 'en_US.UTF-8', 18:48:42 INFO - 'LANGUAGE': 'en_US:en', 18:48:42 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:48:42 INFO - 'LOGNAME': 'cltbld', 18:48:42 INFO - 'MAIL': '/var/mail/cltbld', 18:48:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:48:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:48:42 INFO - 'MOZ_CRASHREPORTER': '1', 18:48:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:42 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:48:42 INFO - 'MOZ_NO_REMOTE': '1', 18:48:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:48:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:48:42 INFO - 'NO_EM_RESTART': '1', 18:48:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:48:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:42 INFO - 'PWD': '/builds/slave/test', 18:48:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:48:42 INFO - 'RUST_BACKTRACE': 'full', 18:48:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:48:42 INFO - 'SHELL': '/bin/bash', 18:48:42 INFO - 'SHLVL': '1', 18:48:42 INFO - 'TERM': 'linux', 18:48:42 INFO - 'TMOUT': '86400', 18:48:42 INFO - 'USER': 'cltbld', 18:48:42 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', 18:48:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:42 INFO - '_': '/tools/buildbot/bin/python'} 18:48:42 INFO - TEST-INFO | started process 3627 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg8gGNt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:48:43 INFO - PID 3627 | 18:48:43 INFO - PID 3627 | (/builds/slave/test/build/application/firefox/firefox:3676): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:48:43 INFO - PID 3627 | 18:48:53 INFO - PID 3627 | RSS: Main: 163889152 18:48:53 INFO - PID 3627 | 18:51:51 INFO - PID 3627 | Cycle 1(1): loaded http://localhost:50425/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50425/tests/dromaeo/cssquery-ext.html) 18:51:53 INFO - PID 3627 | RSS: Main: 168599552 18:51:53 INFO - PID 3627 | 18:54:54 INFO - PID 3627 | Cycle 1(1): loaded http://localhost:50425/tests/dromaeo/cssquery-ext.html (next: http://localhost:50425/tests/dromaeo/cssquery-jquery.html) 18:54:55 INFO - PID 3627 | RSS: Main: 164257792 18:54:55 INFO - PID 3627 | 18:57:57 INFO - PID 3627 | Cycle 1(1): loaded http://localhost:50425/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50425/tests/dromaeo/cssquery-mootools.html) 18:57:57 INFO - PID 3627 | RSS: Main: 166600704 18:57:57 INFO - PID 3627 | 19:00:59 INFO - PID 3627 | Cycle 1(1): loaded http://localhost:50425/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50425/tests/dromaeo/cssquery-prototype.html) 19:00:59 INFO - PID 3627 | RSS: Main: 165523456 19:00:59 INFO - PID 3627 | 19:04:02 INFO - PID 3627 | Cycle 1(1): loaded http://localhost:50425/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50425/tests/dromaeo/cssquery-yui.html) 19:04:03 INFO - PID 3627 | RSS: Main: 166670336 19:04:03 INFO - PID 3627 | 19:07:05 INFO - PID 3627 | Cycle 1(1): loaded http://localhost:50425/tests/dromaeo/cssquery-yui.html (next: http://localhost:50425/tests/dromaeo/cssquery-dojo.html) 19:07:05 INFO - PID 3627 | RSS: Main: 168648704 19:07:05 INFO - PID 3627 | 19:07:05 INFO - PID 3627 | __start_tp_report 19:07:05 INFO - PID 3627 | _x_x_mozilla_page_load 19:07:05 INFO - PID 3627 | _x_x_mozilla_page_load_details 19:07:05 INFO - PID 3627 | |i|pagename|runs| 19:07:05 INFO - PID 3627 | |0;dojo.html;3824.9043773905664;3880.922381552371;3881.281962836875;3915.393114067319;3946.506686664167;6107.877842443148;6136.417040381162;6185.103160041791;6277.027060805571;6298.7165577549;6218.4092511211475;6225.097360882669;6284.7486100555925;6294.653794041325;6321.1150438938585;5423.050966080939;5596.552275817925;5654.547636189116;5664.518515926151;5667.433256674341;5540.085885828834;5570.972145139289;5662.207290979266;5679.801206957757;5681.886362272752;5558.082916318823;5577.609567330289;5601.411851755568;5602.551795856321;5620.185073164389;5453.863653408696;5552.583556233299;5607.383187849333;5618.15727640853;5666.490015898589;5498.257735205736;5513.2005859150195;5524.944750552483;5546.4453554644615;5592.161175823619;6205.3484384139465;6223.96888015557;6249.437550620466;6269.811905642838;6288.654124023177;6707.530472866902;6729.293424190439;6734.797956061277;6774.153230846145;6780.389764921131;6617.371349721769;6735.29279425663;6736.966315168393;6819.761308354234;6897.2757860424435;6237.500999919996;6271.278802937675;6283.937160628381;6287.402696743802;6298.87402251957;6756.121704178426;6837.692303846339;6892.172939247323;6929.861402772017;6944.51388402807;6561;6580.11168492257;6643.335666433415;6646.833829154213;6659.234188068434;6661.966690166615;6750.257471678112;6761.22245941723;6765.188177418741;6767.763128290486;6611.471082313404;6622.933770662328;6658.7336506539195;6699.263081061079;6710.429613482811;6593.505487088488;6656.73373065072;6663.145893281154;6750.35871592198;6771.559848609824;5023.949760502422;5024.748762561857;5094.719790411489;5147.6654017488745;5150.27896094547;4494.595486406239;4657.6273898088075;4677.391939047902;4725.07860967115;4733.881652958635;6185.8144255672405;6193.1329613854095;6276.1213430118905;6278.121063051085;6280;6523.934760652428;6562.212534495891;6595.406413422815;6656.134702488645;6744.123263975652;6189.226346706661;6226.875462490725;6268.153799237136;6270.59241149333;6276.497880169575;5534.806281780118;5556.055470570012;5617.662940223599;5625.803096891589;5646.6894320811925;6873.243943166248;6914.4468442525485;6950.3049695030095;6952.652367381611;7017.9649101755185;6581.703823327866;6615.933840661532;6623.4038936495945;6634.170728658918;6645.401913827779;6940.028396024662;7009.96495017542;7020.684069216795;7035.085438892911;7045.189803172496;6573.638449885302;6591.835204119935;6604.933950660432;6605.603663780189;6689.2307384651385;6567.835804104936;6595.043718660671;6690.632015239208;6700.430463410665;6754.8649027020165;6494.967525162344;6551.70517326731;6559.540832141704;6567.9014814776865;6569.737210511526;6224.408681175281;6366.6180029198395;6483.319251478715;6498.447631951337;6512.804615861532;6389.9680501597195;6392.105105285171;6394.392532709385;6398.584092034003;6409.9679501602195;6358.968205158944;6423.807285781434;6446.29090800021;6452.7418903245225;6492.610443373413;6394.424501794861;6398.104265402754;6411.102445657519;6411.4229719325485;6446.355364463516 19:07:05 INFO - PID 3627 | |1;ext.html;888.7778055486131;940.7083804020754;961.5480724059694;1233.9938300308497;1254.8117782332654;32842;41238;41838.37242441362;41934.16131677372;42124.78937605315;42265.57734422655;42556;42637.36043959335;42726.572734272646;42768.57231427685;8004.119546849842;8147.307478864303;8147.429679922408;8149.307308878752;8150.673973041071;8017.919820801776;8112.31045361145;8132.918670813276;8136.593170341489;8141.470804397695;8173.305269052138;8194.139615340393;8195.549244791535;8213.671453141867;8219.219174178474;8139.715109971152;8167.836643267131;8170.468919520212;8186.713465028755;8195.221453961864;8055;8061.677532898677;8119.512829230265;8139.226773456512;8142.267195952333;39743.205135897406;39940.20119597621;40068.59931400678;40074.59925400738;40159.59840401588;3537.2748586539697;3545.2377738786286;3560.181158333572;3562.251927095313;3567.6075631680496;32224.194395140075;32229.51655725166;32283.83858080718;32293.354132917444;32374.514382284284;5110.66780659256;5184.144616138333;5226.03318386096;5288.973555132238;5360.142377219626;209315;209530;210082;210969;211316;50436.74781626142;50703.49296507062;50728;50790.74604627027;50828.49171508312;53153;53492;53674.46325536694;53931;53938.73030634822;14896.925515372353;15040;16589.336426543043;16810.747838782427;16835.831641683428;13161.144525605805;13279.40242689062;13290.136141150795;13333.20000799955;13343.06598538093;14992.650661440523;15001.549953501195;15013.549593512213;15018.69962600742;15164.469243576643;5876.706164691748;5880.942389544988;5925.703714814242;5975.731092100866;6030.547708921848;1013.5388398278766;1018.0582960761266;1029.6190409548515;1034.8240799064176;1037.9481025948671;23758;23968.880155599458;24085.916133774022;24377.756222437547;30484.847575761978;1121.9046381057701;1132.428123797477;1136.4374634555948;1137.0505627800878;1141.8173092305192;13175.868241317465;13673.31633418345;13699.657508562366;13699.931500342635;17056.658866822592;299946;300542;301266;302495;302611;60414.69792651009;61276.69361653163;61384;61534.38465615286;61609.38390616126;1470.9632259193393;1473.6168596165073;1500.1524138861726;1521.9239038048054;1522.6345677037652;28575.714242857306;28825.71174288314;29491.55762663519;29733.851330743208;29739.405211896075;41168.1766364677;41225.587744123375;41238.38142427926;41467.58532414758;41468;241845;243488;244119;244124;244232;34024.14939626529;35325.470117947734;35379.46930795989;35433;35465.64534354623;38868.6113138865;39254.41118383284;39268.80365598268;39487.407688884115;39722;32649.836750816095;32745.34509309848;32953.34093318072;33827.83086084554;33836.830815845766;32963;33079;33152.50271245981;33364;33479.33041339208;36707.81646091752;36804.447933280484;36970;37058;37117.62882371142;31450.370992579563;32206.35587288288;32209;32267;32368.514472283405;24643.507129856946;27139;27450.45099098047;27517;27689.44621107607 19:07:05 INFO - PID 3627 | |2;jquery.html;563.2480638347805;574.5145352177411;579.9217105690731;584.7310237290848;591.9496842768365;21799.891000544976;23319.41701457462;24159;24175.516489670197;24219.394515137108;26037;26562.203133906012;26626.06808761694;26650.200493985212;26654;6160.907586386208;6298.46463050639;6300.5589608727405;6303.968480157593;6323.74705011799;5661.46216109469;6226.688665566726;6264.624122552661;6272.3100458949475;6275.309715931244;6246.531510136735;6251.468625166844;6252.3122456529745;6254.093156492306;6258.780942666985;6225.3463386344465;6230.9999999999545;6231.906521402183;6232.376762323776;6234.937650623527;5821.155932389822;5827.213326200951;5830.4461076197695;5838.9124163137585;5849.88300233993;29774.70225297741;30037.699623003708;30573.847130764425;31188.220294492592;31495.685043149504;2178.0852042142337;2216.1689366487567;2216.3794137641485;2217.6562632791943;2218.1681869299014;19859.205631774712;20433.080511377022;20455.693164602544;20468.488287792774;20474.590508189754;2425.478522117753;2465.815063870217;2492.8379655322346;2511.485145545159;2518.0934863449143;152033;154521;155003.2249838755;155248.22375888162;155528.2223588875;14828.406863725546;15538.844611553739;15615.141167235912;16135.919320403324;16368.427105051265;24860.37849053788;24882.37794055127;24924;24980.125695600567;25828.74171258301;10600.25798194119;10821.729456763644;10854.72863178411;10911.890881091087;10984.176186785864;9899.455529945923;9951.800963980824;9969.750756231153;10011.649592264235;10053.296269261082;9863.358881672812;9945.80108397828;10030.64892728751;10050.497475126214;10082.949585252027;2642.6036094585584;2723.360010397555;2728.563429851214;2739.5479745842113;2787.874545645451;999.3104757717152;1031.5254982707888;1034.5603118674533;1038.870141232346;1046.6912260883057;21062.368128956157;25200;26833.865830670722;27083;27473.450530989267;1030.2685093583639;1086.8206745886998;1088.6733979806027;1098.2257508456519;1098.9560417583207;11566.19036667442;11788.29270243788;12048.397580120958;13967.511137110356;15026.924865375604;666612;695081;698568;702148;742738;126307.3684631571;132184;135196.88818670713;135569;135994.3200284012;1286.3889652415028;1292.7285270093198;1326.1446367093363;1334.4795529743408;1338.953136640213;21963.890180548995;22087.558248835256;22525.887370563043;22576.20983265578;22580.548389032458;51893;61962;64638.676806615666;64747.676261620276;64839;82862;85541.1445885558;85623.5718821402;85860.57069714612;86001.56999214964;15195.696086077995;15825.287862046005;15898.920505397398;15907.602309942344;15911.920440398188;23181.07275708953;23198.420039499146;23207.419814504774;23344.53310933738;25563.977440902156;17799.377021804175;19984;20081.39755807328;20124.496887577927;20154;17041.147942603067;17097.829021709622;17157.970521768733;17310;18527.444176674722;20753.896230518752;20759.273425430038;20846.895765521076;20887.06008229603;20970.580588388453;15569.76645350298;15570.29933653011;15629.609259768597;15635.218239087773;15674.686506269583;17850;18060.458186254433;18113.909430452764;19015.42953711391;19897.801021989595 19:07:05 INFO - PID 3627 | |3;mootools.html;1411.5694713112503;1463.736527425063;1465.7874608181814;1468.126464753472;1475.7565001774708;15812.60468488287;15884.364625414999;16512.256948437305;16524.256408461617;16542.917285413587;16504.092274924875;16730.41443549476;16742.66514669709;16747.748783768253;16764.58088547787;2770.9445811083815;2808.9157325280275;2817.056286144134;2819.5347767618423;2820.097568777992;2719.4697034078363;2743.4238809849953;2775.4587855368213;2792.9581056284173;2811.2971757060736;2735.5623100303956;2759.6274502942147;2762.709915458878;2763.281546797827;2766.336079340954;2644.616530603071;2714.280715610365;2725.2505560970694;2727.386338073937;2736.1107640016876;2682.1685277563824;2708.6614173228345;2718.5242582547976;2720.795940304465;2724.087430710714;15556.922215388964;15647.608809779847;15805.68388632221;15845.92077039619;15847.366105355772;7799.766007019798;8037.718679846236;8095.23095305945;8101.716439924633;8108.918910810876;5232.659877107976;5470.835874923759;5620.971895140539;5625.662460252398;5629.80295689653;1964.673845154515;2006.7591888973416;2012.7785943546198;2057.9588408231753;2079.958400831975;26858.865705671542;26941;26953.191404257905;26970.5954410684;26993;6794.490413219029;6836.213835408892;6891.27641597635;6957.8956315655305;7029.261927497641;18394.17226224823;18397.90801045986;18466.815331846778;18475.445736627924;18504.259829606934;8085.83828323442;8252.917470825336;8258.958705206436;8299.792505187419;8305.211004954519;6361.427471527585;6500.902486462707;6774.796756097325;6780.864382712318;7707.113681926538;5346.117890548094;5547.195656629766;5553.944460555343;5638;5703.885922281531;1555.6033211530987;1574.157825563318;1592;1602.3510478256326;1621.9189040547926;925.3707478914268;953.6471505542994;960.3613596958085;961.485605201214;964.609333220047;8323.958380208182;8340.457870238537;8390.496570205807;8409.915900840913;8411.663533458715;722.1081963774734;733.1568695999537;754.1515794730927;763.6334559411403;769.769069279214;5594.944050559524;5732.713364331767;5743.54051675865;5816.854578635483;6063;23279.41801454977;23838.165664202017;24045.639315410284;24503.14239001662;24515.264542063767;23854.165104221614;23872;23878.88060559686;23936.04255829747;24150.275491735276;11611.129165312635;12178.512859485521;12442.191257568047;12444.81332780009;12459.750804983849;8784.560771961504;8951.462912225288;9385.108414700593;9490.810183796424;9715.125638692552;19672.606547869247;20016.69974950406;20220.19119235214;20677.966101694554;20950.47623809417;20578.58842823165;20680.379588612366;20892.47768805792;20990.790092099494;21019.159233630482;12749.93625031869;13037.413316400594;13052.282124482865;13059.869401305865;13060;14791;14882.851171488579;14955.401783928955;14998.400063997318;15081.24593770329;11468.254563453349;12193.69515762113;12200.328981905725;12255.509779608716;12301.753964920472;11037.944810276218;11949.342786146846;12039.217450865666;12079.818802718142;12195.573154939535;9591.9040809591;9744.366616169926;9746.951265243628;9758.51207439611;9763.707088787347;7685.730999414994;7717.768466946;7834.529928204326;7892.289693927574;8105.8784118239455;7798.37612990948;7804.6878124874365;7846.33306168982;7858.44990850635;7949.284564389232 19:07:05 INFO - PID 3627 | |4;prototype.html;532.5127508329879;543.65749577766;544.6405372454178;549.7498638119656;561.5311215135362;6229.532785041123;6249.468795152417;6252.593581417211;6271.278802937658;6322.186626951008;6121.745386956068;6163.845903852406;6164.229471316085;6172.969135154329;6178.96910515448;5080.2125670520945;5105.642605017651;5107.233914912774;5120.3343565336545;5154;4799.98400671717;4854.902901941959;5081.2886195932415;5089.440161582223;5089.669171503859;4890.73100979446;4900.240462728265;4924.113659541281;4965.155923492996;4972.229304457796;4885.923482519915;4890.877728056791;4895.429899302859;4922.089413458521;4942.481039490837;4397.2964325707735;4440.378347031418;4455.108978204372;4469.195544801934;4489.1021795641;5980.760769569212;6035.7887473938645;6037.339731573808;6088.677039302101;6122.510199184055;5723.7996670116345;5736.856578585569;5765.625234359795;5784.13238014297;5794.5074668653215;6563.868722625521;6589;6589.439897608709;6612.908870036486;6624.569402988838;4901.946081592475;4911.852644420673;4966.329545511346;4975.601951843843;4983.72589507577;6589.80230593083;6682.661068625991;6780;6814.1482314710665;6818.420434263094;5069.949300506948;5097.470758772353;5107.846764597068;5118.718470484085;5124.333836601292;5148.794048238104;5149.76826042829;5171.172612382001;5183.2743415921805;5184.300119483822;5715.514181294637;5729.169270455761;5769.711514424262;5785.4214578541805;5795.333536643256;6592.901106483407;6605.579800342949;6621.139251897319;6624.701888415033;6656.833579160463;1776.2362184260892;1777.8710518820642;1789.409494866691;1791.4267434421122;1798.7391828185102;1207.7524107557929;1234.672811704881;1252.555342853274;1269.2130878855169;1275.2157423184674;6112.724927378279;6193.84515387119;6211.16149319836;6212.285587157445;6220.626762394271;6639.369259920397;6670.733170673216;6730.461563074942;6746.494012949049;6772.762953296611;6091.44668109638;6177.227846519185;6183.134361189437;6183.474404675563;6205.28639206488;3405.3359594879257;3501.511857460569;3511.17487390463;3519.1905861651962;3520.4543295789194;6843.555168914005;6844.1748567259965;7008.334208250208;7028.613426261605;7045.92954070463;6882.242953275136;6945.305469453015;6954.582725036514;6970.302969702989;7034.6834392452465;7107.502474826816;7126.928730712627;7127.786166415016;7144.142702875584;7199.3520583147765;6703.095082163845;6722.2605513394465;6738.561993470409;6783.694733737092;6793.592384456751;6599.835004124935;6637.867242655217;6682.3317668232785;6698.363655452919;6758.763443279461;6944.4097251734165;7011.1586609607175;7041.929580704127;7112.751053713096;7120.643967801486;5004.649674522749;5052.8484145475695;5071.670341427795;5078.162103252922;5078.587670856314;6520.934790652223;6628.867422651617;6720.059191713066;6748;6809.127489940306;6445.323241059621;6447.742090316522;6457.61254324742;6553.574017688835;6559.901601476077;6418.550701450854;6446.226452825691;6507.349265073644;6564.573302735307;6587.604743715392;6484;6485.77299794505;6488.383603557654;6512.869742605216;6521.282658907611;6521.934780652133;6592.143021407186;6633.469322454289;6636.336366363518;6636.867262654623;6402.903956440564;6411.916127258566;6435.420812126931;6437.388448097423;6461.19235095613 19:07:05 INFO - PID 3627 | |5;yui.html;941.0542404883091;954.0602506531067;962.0273355332023;966.6665000574799;983.0464449483998;18574.814251857493;19447.124879380426;19541.902290488564;19545.706814397778;21337.786622133775;21307.68038479424;21798.673019904712;21846.016929238183;21860.672089918666;21864;1009.3943633819686;1016.761061150629;1018.6791160784367;1019.3017782818756;1019.5768755966266;1042.4006196437042;1085.6417382263874;1089.1559041742635;1092.814221582333;1093.8906109389077;1153.066016526612;1153.7692461507731;1158.0214718562843;1158.119828930015;1158.8435561199215;1137.3062431916524;1148.070063248767;1154.0479104738624;1154.2497376705126;1155.399192419937;837.5686521441462;839.9916000839975;840.1514470384894;840.3739214285397;840.9285210757032;19828.603427931506;19840.50398740043;20235.797642023685;20414.591708165903;20617.48456288605;21488.892555537277;21639.56720865574;22063.779362206333;22071.889640551854;22331.44171395712;17448.912755436268;17954.102294885204;18225.908870455696;18228.817711822845;18364.357247496406;3937.0551067743536;3945.782981935992;3961.425593288957;3973.5231772187235;3974.5230572331216;140910.29544852313;142722.28638856843;143454;145110;145409.2729536356;17008;17265.654686906193;17284.308627655006;17299.654006920042;17419.73870391945;31942.041738747874;32630.347393052005;33178.66821331756;33297.16757081044;33349.83325083359;8559.186877246777;8680.696175633822;8686.739397818075;8702.04277529484;8715.433496822812;3923.5684074752326;3975.5428125765907;3999.060220848098;4040.1111755413763;4056.350983842571;5501.944980550224;5514.5864060195645;5570.442955704478;5631.493165615032;5662.094064949588;1042.3797840285017;1056.915446764257;1067.3275836223286;1077.1167642533205;1078.1267173589417;703.4899697719113;724.3553237618523;736.120336198238;736.830528978338;743.4424181863602;11914.642560723192;12267;12828.743425131444;13396.732065358638;13406;699.7655785311978;705.7247673407376;708.1042481261106;711.1821405383849;728.8287252495659;6055.273367195877;6066.939330606726;6220.937790622036;6654.168228971379;6885.931140688629;123418;124545;127643.36178319048;131041.34479327542;132318;116267;117307;117772;118703;119354.40322798504;100022.4998875001;102026.48986755019;102923;104383.99999999849;106333;13561.38973746164;13817;14027.929860351041;14052.367643455864;14069.648258793612;49615.50384496109;49673.503264968334;49736.751316243186;49839;50750;63023.6848815753;63329.68335158295;63587;63988;65547.34452655603;18791.530211744815;18979.715304270172;18991.525211869815;19087.236510539424;19290.903545482182;21044.473888152304;21727;21814;23866.164684236664;23934.162304319267;20334.389968300973;20593.897030514752;21263.149474020865;21478.892605536872;21668.24161154352;20977.160913563286;21073.367798966057;21584.67622985625;21963.011664475456;22191.1123555056;20506.79493205049;20590;20731.4817129573;20788.896055519624;20789.480262993548;14364;14509.709805804036;14592;14621.122732636077;14668.486602968846;15859.640046561068;15980.920095399448;16163.68795738528;16181.190940453165;16505.83494165043 19:07:05 INFO - PID 3627 | __end_tp_report 19:07:05 INFO - PID 3627 | __start_cc_report 19:07:05 INFO - PID 3627 | _x_x_mozilla_cycle_collect,331 19:07:05 INFO - PID 3627 | __end_cc_report 19:07:05 INFO - PID 3627 | __startTimestamp1503972425357__endTimestamp 19:07:05 INFO - PID 3627 | 19:07:05 INFO - PID 3627 | ------- Summary: start ------- 19:07:05 INFO - PID 3627 | Number of tests: 6 19:07:05 INFO - PID 3627 | 19:07:05 INFO - PID 3627 | [#0] dojo.html Cycles:165 Average:6194.17 Median:6398.10 stddev:688.41 (10.8%) stddev-sans-first:664.96 19:07:05 INFO - PID 3627 | Values: 3824.9 3880.9 3881.3 3915.4 3946.5 6107.9 6136.4 6185.1 6277.0 6298.7 6218.4 6225.1 6284.7 6294.7 6321.1 5423.1 5596.6 5654.5 5664.5 5667.4 5540.1 5571.0 5662.2 5679.8 5681.9 5558.1 5577.6 5601.4 5602.6 5620.2 5453.9 5552.6 5607.4 5618.2 5666.5 5498.3 5513.2 5524.9 5546.4 5592.2 6205.3 6224.0 6249.4 6269.8 6288.7 6707.5 6729.3 6734.8 6774.2 6780.4 6617.4 6735.3 6737.0 6819.8 6897.3 6237.5 6271.3 6283.9 6287.4 6298.9 6756.1 6837.7 6892.2 6929.9 6944.5 6561.0 6580.1 6643.3 6646.8 6659.2 6662.0 6750.3 6761.2 6765.2 6767.8 6611.5 6622.9 6658.7 6699.3 6710.4 6593.5 6656.7 6663.1 6750.4 6771.6 5023.9 5024.7 5094.7 5147.7 5150.3 4494.6 4657.6 4677.4 4725.1 4733.9 6185.8 6193.1 6276.1 6278.1 6280.0 6523.9 6562.2 6595.4 6656.1 6744.1 6189.2 6226.9 6268.2 6270.6 6276.5 5534.8 5556.1 5617.7 5625.8 5646.7 6873.2 6914.4 6950.3 6952.7 7018.0 6581.7 6615.9 6623.4 6634.2 6645.4 6940.0 7010.0 7020.7 7035.1 7045.2 6573.6 6591.8 6604.9 6605.6 6689.2 6567.8 6595.0 6690.6 6700.4 6754.9 6495.0 6551.7 6559.5 6567.9 6569.7 6224.4 6366.6 6483.3 6498.4 6512.8 6390.0 6392.1 6394.4 6398.6 6410.0 6359.0 6423.8 6446.3 6452.7 6492.6 6394.4 6398.1 6411.1 6411.4 6446.4 19:07:05 INFO - PID 3627 | 19:07:05 INFO - PID 3627 | [#1] ext.html Cycles:180 Average:42418.79 Median:19458.37 stddev:66315.01 (340.8%) stddev-sans-first:66427.72 19:07:05 INFO - PID 3627 | Values: 888.8 940.7 961.5 1234.0 1254.8 32842.0 41238.0 41838.4 41934.2 42124.8 42265.6 42556.0 42637.4 42726.6 42768.6 8004.1 8147.3 8147.4 8149.3 8150.7 8017.9 8112.3 8132.9 8136.6 8141.5 8173.3 8194.1 8195.5 8213.7 8219.2 8139.7 8167.8 8170.5 8186.7 8195.2 8055.0 8061.7 8119.5 8139.2 8142.3 39743.2 39940.2 40068.6 40074.6 40159.6 3537.3 3545.2 3560.2 3562.3 3567.6 32224.2 32229.5 32283.8 32293.4 32374.5 5110.7 5184.1 5226.0 5289.0 5360.1 209315.0 209530.0 210082.0 210969.0 211316.0 50436.7 50703.5 50728.0 50790.7 50828.5 53153.0 53492.0 53674.5 53931.0 53938.7 14896.9 15040.0 16589.3 16810.7 16835.8 13161.1 13279.4 13290.1 13333.2 13343.1 14992.7 15001.5 15013.5 15018.7 15164.5 5876.7 5880.9 5925.7 5975.7 6030.5 1013.5 1018.1 1029.6 1034.8 1037.9 23758.0 23968.9 24085.9 24377.8 30484.8 1121.9 1132.4 1136.4 1137.1 1141.8 13175.9 13673.3 13699.7 13699.9 17056.7 299946.0 300542.0 301266.0 302495.0 302611.0 60414.7 61276.7 61384.0 61534.4 61609.4 1471.0 1473.6 1500.2 1521.9 1522.6 28575.7 28825.7 29491.6 29733.9 29739.4 41168.2 41225.6 41238.4 41467.6 41468.0 241845.0 243488.0 244119.0 244124.0 244232.0 34024.1 35325.5 35379.5 35433.0 35465.6 38868.6 39254.4 39268.8 39487.4 39722.0 32649.8 32745.3 32953.3 33827.8 33836.8 32963.0 33079.0 33152.5 33364.0 33479.3 36707.8 36804.4 36970.0 37058.0 37117.6 31450.4 32206.4 32209.0 32267.0 32368.5 24643.5 27139.0 27450.5 27517.0 27689.4 19:07:05 INFO - PID 3627 | 19:07:05 INFO - PID 3627 | [#2] jquery.html Cycles:180 Average:42844.30 Median:22880.81 stddev:116570.12 (509.5%) stddev-sans-first:116853.90 19:07:05 INFO - PID 3627 | Values: 563.2 574.5 579.9 584.7 591.9 21799.9 23319.4 24159.0 24175.5 24219.4 26037.0 26562.2 26626.1 26650.2 26654.0 6160.9 6298.5 6300.6 6304.0 6323.7 5661.5 6226.7 6264.6 6272.3 6275.3 6246.5 6251.5 6252.3 6254.1 6258.8 6225.3 6231.0 6231.9 6232.4 6234.9 5821.2 5827.2 5830.4 5838.9 5849.9 29774.7 30037.7 30573.8 31188.2 31495.7 2178.1 2216.2 2216.4 2217.7 2218.2 19859.2 20433.1 20455.7 20468.5 20474.6 2425.5 2465.8 2492.8 2511.5 2518.1 152033.0 154521.0 155003.2 155248.2 155528.2 14828.4 15538.8 15615.1 16135.9 16368.4 24860.4 24882.4 24924.0 24980.1 25828.7 10600.3 10821.7 10854.7 10911.9 10984.2 9899.5 9951.8 9969.8 10011.6 10053.3 9863.4 9945.8 10030.6 10050.5 10082.9 2642.6 2723.4 2728.6 2739.5 2787.9 999.3 1031.5 1034.6 1038.9 1046.7 21062.4 25200.0 26833.9 27083.0 27473.5 1030.3 1086.8 1088.7 1098.2 1099.0 11566.2 11788.3 12048.4 13967.5 15026.9 666612.0 695081.0 698568.0 702148.0 742738.0 126307.4 132184.0 135196.9 135569.0 135994.3 1286.4 1292.7 1326.1 1334.5 1339.0 21963.9 22087.6 22525.9 22576.2 22580.5 51893.0 61962.0 64638.7 64747.7 64839.0 82862.0 85541.1 85623.6 85860.6 86001.6 15195.7 15825.3 15898.9 15907.6 15911.9 23181.1 23198.4 23207.4 23344.5 25564.0 17799.4 19984.0 20081.4 20124.5 20154.0 17041.1 17097.8 17158.0 17310.0 18527.4 20753.9 20759.3 20846.9 20887.1 20970.6 15569.8 15570.3 15629.6 15635.2 15674.7 17850.0 18060.5 18113.9 19015.4 19897.8 19:07:05 INFO - PID 3627 | 19:07:05 INFO - PID 3627 | [#3] mootools.html Cycles:180 Average:9937.53 Median:2720.13 stddev:7231.21 (265.8%) stddev-sans-first:7223.12 19:07:05 INFO - PID 3627 | Values: 1411.6 1463.7 1465.8 1468.1 1475.8 15812.6 15884.4 16512.3 16524.3 16542.9 16504.1 16730.4 16742.7 16747.7 16764.6 2770.9 2808.9 2817.1 2819.5 2820.1 2719.5 2743.4 2775.5 2793.0 2811.3 2735.6 2759.6 2762.7 2763.3 2766.3 2644.6 2714.3 2725.3 2727.4 2736.1 2682.2 2708.7 2718.5 2720.8 2724.1 15556.9 15647.6 15805.7 15845.9 15847.4 7799.8 8037.7 8095.2 8101.7 8108.9 5232.7 5470.8 5621.0 5625.7 5629.8 1964.7 2006.8 2012.8 2058.0 2080.0 26858.9 26941.0 26953.2 26970.6 26993.0 6794.5 6836.2 6891.3 6957.9 7029.3 18394.2 18397.9 18466.8 18475.4 18504.3 8085.8 8252.9 8259.0 8299.8 8305.2 6361.4 6500.9 6774.8 6780.9 7707.1 5346.1 5547.2 5553.9 5638.0 5703.9 1555.6 1574.2 1592.0 1602.4 1621.9 925.4 953.6 960.4 961.5 964.6 8324.0 8340.5 8390.5 8409.9 8411.7 722.1 733.2 754.2 763.6 769.8 5594.9 5732.7 5743.5 5816.9 6063.0 23279.4 23838.2 24045.6 24503.1 24515.3 23854.2 23872.0 23878.9 23936.0 24150.3 11611.1 12178.5 12442.2 12444.8 12459.8 8784.6 8951.5 9385.1 9490.8 9715.1 19672.6 20016.7 20220.2 20678.0 20950.5 20578.6 20680.4 20892.5 20990.8 21019.2 12749.9 13037.4 13052.3 13059.9 13060.0 14791.0 14882.9 14955.4 14998.4 15081.2 11468.3 12193.7 12200.3 12255.5 12301.8 11037.9 11949.3 12039.2 12079.8 12195.6 9591.9 9744.4 9747.0 9758.5 9763.7 7685.7 7717.8 7834.5 7892.3 8105.9 7798.4 7804.7 7846.3 7858.4 7949.3 19:07:05 INFO - PID 3627 | 19:07:05 INFO - PID 3627 | [#4] prototype.html Cycles:180 Average:5597.03 Median:6199.57 stddev:1583.19 (25.5%) stddev-sans-first:1541.32 19:07:05 INFO - PID 3627 | Values: 532.5 543.7 544.6 549.7 561.5 6229.5 6249.5 6252.6 6271.3 6322.2 6121.7 6163.8 6164.2 6173.0 6179.0 5080.2 5105.6 5107.2 5120.3 5154.0 4800.0 4854.9 5081.3 5089.4 5089.7 4890.7 4900.2 4924.1 4965.2 4972.2 4885.9 4890.9 4895.4 4922.1 4942.5 4397.3 4440.4 4455.1 4469.2 4489.1 5980.8 6035.8 6037.3 6088.7 6122.5 5723.8 5736.9 5765.6 5784.1 5794.5 6563.9 6589.0 6589.4 6612.9 6624.6 4901.9 4911.9 4966.3 4975.6 4983.7 6589.8 6682.7 6780.0 6814.1 6818.4 5069.9 5097.5 5107.8 5118.7 5124.3 5148.8 5149.8 5171.2 5183.3 5184.3 5715.5 5729.2 5769.7 5785.4 5795.3 6592.9 6605.6 6621.1 6624.7 6656.8 1776.2 1777.9 1789.4 1791.4 1798.7 1207.8 1234.7 1252.6 1269.2 1275.2 6112.7 6193.8 6211.2 6212.3 6220.6 6639.4 6670.7 6730.5 6746.5 6772.8 6091.4 6177.2 6183.1 6183.5 6205.3 3405.3 3501.5 3511.2 3519.2 3520.5 6843.6 6844.2 7008.3 7028.6 7045.9 6882.2 6945.3 6954.6 6970.3 7034.7 7107.5 7126.9 7127.8 7144.1 7199.4 6703.1 6722.3 6738.6 6783.7 6793.6 6599.8 6637.9 6682.3 6698.4 6758.8 6944.4 7011.2 7041.9 7112.8 7120.6 5004.6 5052.8 5071.7 5078.2 5078.6 6520.9 6628.9 6720.1 6748.0 6809.1 6445.3 6447.7 6457.6 6553.6 6559.9 6418.6 6446.2 6507.3 6564.6 6587.6 6484.0 6485.8 6488.4 6512.9 6521.3 6521.9 6592.1 6633.5 6636.3 6636.9 6402.9 6411.9 6435.4 6437.4 6461.2 19:07:05 INFO - PID 3627 | 19:07:05 INFO - PID 3627 | [#5] yui.html Cycles:180 Average:26592.21 Median:20591.95 stddev:37100.80 (180.2%) stddev-sans-first:37154.88 19:07:05 INFO - PID 3627 | Values: 941.1 954.1 962.0 966.7 983.0 18574.8 19447.1 19541.9 19545.7 21337.8 21307.7 21798.7 21846.0 21860.7 21864.0 1009.4 1016.8 1018.7 1019.3 1019.6 1042.4 1085.6 1089.2 1092.8 1093.9 1153.1 1153.8 1158.0 1158.1 1158.8 1137.3 1148.1 1154.0 1154.2 1155.4 837.6 840.0 840.2 840.4 840.9 19828.6 19840.5 20235.8 20414.6 20617.5 21488.9 21639.6 22063.8 22071.9 22331.4 17448.9 17954.1 18225.9 18228.8 18364.4 3937.1 3945.8 3961.4 3973.5 3974.5 140910.3 142722.3 143454.0 145110.0 145409.3 17008.0 17265.7 17284.3 17299.7 17419.7 31942.0 32630.3 33178.7 33297.2 33349.8 8559.2 8680.7 8686.7 8702.0 8715.4 3923.6 3975.5 3999.1 4040.1 4056.4 5501.9 5514.6 5570.4 5631.5 5662.1 1042.4 1056.9 1067.3 1077.1 1078.1 703.5 724.4 736.1 736.8 743.4 11914.6 12267.0 12828.7 13396.7 13406.0 699.8 705.7 708.1 711.2 728.8 6055.3 6066.9 6220.9 6654.2 6885.9 123418.0 124545.0 127643.4 131041.3 132318.0 116267.0 117307.0 117772.0 118703.0 119354.4 100022.5 102026.5 102923.0 104384.0 106333.0 13561.4 13817.0 14027.9 14052.4 14069.6 49615.5 49673.5 49736.8 49839.0 50750.0 63023.7 63329.7 63587.0 63988.0 65547.3 18791.5 18979.7 18991.5 19087.2 19290.9 21044.5 21727.0 21814.0 23866.2 23934.2 20334.4 20593.9 21263.1 21478.9 21668.2 20977.2 21073.4 21584.7 21963.0 22191.1 20506.8 20590.0 20731.5 20788.9 20789.5 14364.0 14509.7 14592.0 14621.1 14668.5 15859.6 15980.9 16163.7 16181.2 16505.8 19:07:05 INFO - PID 3627 | -------- Summary: end -------- 19:07:05 INFO - PID 3627 | 19:07:06 INFO - TEST-INFO | 3627: exit 0 19:07:06 INFO - TEST-OK | dromaeo_css | took 1111828ms 19:07:06 INFO - TEST-START | kraken 19:07:06 INFO - Initialising browser for kraken test... 19:07:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50425/getInfo.html -profile /tmp/tmp8KOVWF/profile 19:07:06 INFO - TEST-INFO | started process 3992 (/builds/slave/test/build/application/firefox/firefox http://localhost:50425/getInfo.html) 19:07:11 INFO - TEST-INFO | 3992: exit 0 19:07:11 INFO - Browser initialized. 19:07:11 INFO - Running cycle 1/1 for kraken test... 19:07:11 INFO - Using env: {'DISPLAY': ':0', 19:07:11 INFO - 'HOME': '/home/cltbld', 19:07:11 INFO - 'JSGC_DISABLE_POISONING': '1', 19:07:11 INFO - 'LANG': 'en_US.UTF-8', 19:07:11 INFO - 'LANGUAGE': 'en_US:en', 19:07:11 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:07:11 INFO - 'LOGNAME': 'cltbld', 19:07:11 INFO - 'MAIL': '/var/mail/cltbld', 19:07:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:07:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:07:11 INFO - 'MOZ_CRASHREPORTER': '1', 19:07:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:11 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:07:11 INFO - 'MOZ_NO_REMOTE': '1', 19:07:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:07:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:11 INFO - 'NO_EM_RESTART': '1', 19:07:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:11 INFO - 'PWD': '/builds/slave/test', 19:07:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:07:11 INFO - 'RUST_BACKTRACE': 'full', 19:07:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:07:11 INFO - 'SHELL': '/bin/bash', 19:07:11 INFO - 'SHLVL': '1', 19:07:11 INFO - 'TERM': 'linux', 19:07:11 INFO - 'TMOUT': '86400', 19:07:11 INFO - 'USER': 'cltbld', 19:07:11 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767', 19:07:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:11 INFO - '_': '/tools/buildbot/bin/python'} 19:07:11 INFO - TEST-INFO | started process 4168 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8KOVWF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:07:12 INFO - PID 4168 | 19:07:12 INFO - PID 4168 | (/builds/slave/test/build/application/firefox/firefox:4217): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:07:12 INFO - PID 4168 | 19:07:22 INFO - PID 4168 | RSS: Main: 164511744 19:07:22 INFO - PID 4168 | 19:07:25 INFO - PID 4168 | Cycle 1(1): loaded http://localhost:50425/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50425/tests/kraken/driver.html?testName=audio-beat-detection) 19:07:26 INFO - PID 4168 | RSS: Main: 169517056 19:07:26 INFO - PID 4168 | 19:07:28 INFO - PID 4168 | Cycle 1(1): loaded http://localhost:50425/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50425/tests/kraken/driver.html?testName=audio-dft) 19:07:29 INFO - PID 4168 | RSS: Main: 173953024 19:07:29 INFO - PID 4168 | 19:07:32 INFO - PID 4168 | Cycle 1(1): loaded http://localhost:50425/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50425/tests/kraken/driver.html?testName=audio-fft) 19:07:32 INFO - PID 4168 | RSS: Main: 166637568 19:07:32 INFO - PID 4168 | 19:07:35 INFO - PID 4168 | Cycle 1(1): loaded http://localhost:50425/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50425/tests/kraken/driver.html?testName=audio-oscillator) 19:07:35 INFO - PID 4168 | RSS: Main: 167673856 19:07:35 INFO - PID 4168 | 19:07:37 INFO - PID 4168 | Cycle 1(1): loaded http://localhost:50425/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50425/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:07:38 INFO - PID 4168 | RSS: Main: 167714816 19:07:38 INFO - PID 4168 | 19:07:41 INFO - PID 4168 | Cycle 1(1): loaded http://localhost:50425/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50425/tests/kraken/driver.html?testName=imaging-darkroom) 19:07:42 INFO - PID 4168 | RSS: Main: 167538688 19:07:42 INFO - PID 4168 | 19:07:45 INFO - PID 4168 | Cycle 1(1): loaded http://localhost:50425/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50425/tests/kraken/driver.html?testName=imaging-desaturate) 19:07:46 INFO - PID 4168 | RSS: Main: 169385984 19:07:46 INFO - PID 4168 | 19:07:48 INFO - PID 4168 | Cycle 1(1): loaded http://localhost:50425/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50425/tests/kraken/driver.html?testName=json-parse-financial) 19:07:49 INFO - PID 4168 | RSS: Main: 169476096 19:07:49 INFO - PID 4168 | 19:07:50 INFO - PID 4168 | Cycle 1(1): loaded http://localhost:50425/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50425/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:07:51 INFO - PID 4168 | RSS: Main: 169541632 19:07:51 INFO - PID 4168 | 19:07:53 INFO - PID 4168 | Cycle 1(1): loaded http://localhost:50425/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50425/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:07:53 INFO - PID 4168 | RSS: Main: 170176512 19:07:53 INFO - PID 4168 | 19:07:55 INFO - PID 4168 | Cycle 1(1): loaded http://localhost:50425/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50425/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:07:56 INFO - PID 4168 | RSS: Main: 166875136 19:07:56 INFO - PID 4168 | 19:07:58 INFO - PID 4168 | Cycle 1(1): loaded http://localhost:50425/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50425/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:07:59 INFO - PID 4168 | RSS: Main: 166535168 19:07:59 INFO - PID 4168 | 19:08:02 INFO - PID 4168 | Cycle 1(1): loaded http://localhost:50425/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50425/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:08:03 INFO - PID 4168 | RSS: Main: 166752256 19:08:03 INFO - PID 4168 | 19:08:04 INFO - PID 4168 | Cycle 1(1): loaded http://localhost:50425/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50425/tests/kraken/driver.html?testName=ai-astar) 19:08:04 INFO - PID 4168 | RSS: Main: 168648704 19:08:04 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | __start_tp_report 19:08:05 INFO - PID 4168 | _x_x_mozilla_page_load 19:08:05 INFO - PID 4168 | _x_x_mozilla_page_load_details 19:08:05 INFO - PID 4168 | |i|pagename|runs| 19:08:05 INFO - PID 4168 | |0;ai-astar;96;107;99;105;107;108;110;97;109;106 19:08:05 INFO - PID 4168 | |1;audio-beat-detection;141;113;139;171;118;140;152;114;168;117 19:08:05 INFO - PID 4168 | |2;audio-dft;161;161;162;162;163;162;160;170;167;162 19:08:05 INFO - PID 4168 | |3;audio-fft;73;74;73;166;81;74;73;72;150;86 19:08:05 INFO - PID 4168 | |4;audio-oscillator;78;82;131;79;78;78;123;79;78;127 19:08:05 INFO - PID 4168 | |5;imaging-gaussian-blur;113;114;114;113;114;115;114;114;114;114 19:08:05 INFO - PID 4168 | |6;imaging-darkroom;149;150;149;150;150;150;149;149;151;150 19:08:05 INFO - PID 4168 | |7;imaging-desaturate;94;94;94;94;95;93;98;94;93;94 19:08:05 INFO - PID 4168 | |8;json-parse-financial;46;47;51;45;51;46;48;48;45;45 19:08:05 INFO - PID 4168 | |9;json-stringify-tinderbox;40;40;40;39;40;40;40;40;40;39 19:08:05 INFO - PID 4168 | |10;stanford-crypto-aes;73;73;73;75;73;74;73;74;74;77 19:08:05 INFO - PID 4168 | |11;stanford-crypto-ccm;116;120;124;120;114;118;112;136;115;146 19:08:05 INFO - PID 4168 | |12;stanford-crypto-pbkdf2;163;165;160;167;168;169;167;171;164;160 19:08:05 INFO - PID 4168 | |13;stanford-crypto-sha256-iterative;61;62;61;59;60;61;61;61;60;59 19:08:05 INFO - PID 4168 | __end_tp_report 19:08:05 INFO - PID 4168 | __start_cc_report 19:08:05 INFO - PID 4168 | _x_x_mozilla_cycle_collect,853 19:08:05 INFO - PID 4168 | __end_cc_report 19:08:05 INFO - PID 4168 | __startTimestamp1503972485000__endTimestamp 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | ------- Summary: start ------- 19:08:05 INFO - PID 4168 | Number of tests: 14 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | [#0] ai-astar Cycles:10 Average:104.40 Median:109.50 stddev:5.13 (4.7%) stddev-sans-first:4.44 19:08:05 INFO - PID 4168 | Values: 96.0 107.0 99.0 105.0 107.0 108.0 110.0 97.0 109.0 106.0 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | [#1] audio-beat-detection Cycles:10 Average:137.30 Median:140.50 stddev:21.70 (15.4%) stddev-sans-first:22.97 19:08:05 INFO - PID 4168 | Values: 141.0 113.0 139.0 171.0 118.0 140.0 152.0 114.0 168.0 117.0 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | [#2] audio-dft Cycles:10 Average:163.00 Median:162.00 stddev:3.09 (1.9%) stddev-sans-first:3.19 19:08:05 INFO - PID 4168 | Values: 161.0 161.0 162.0 162.0 163.0 162.0 160.0 170.0 167.0 162.0 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | [#3] audio-fft Cycles:10 Average:92.20 Median:73.50 stddev:35.16 (47.8%) stddev-sans-first:36.60 19:08:05 INFO - PID 4168 | Values: 73.0 74.0 73.0 166.0 81.0 74.0 73.0 72.0 150.0 86.0 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | [#4] audio-oscillator Cycles:10 Average:93.30 Median:78.00 stddev:23.36 (30.0%) stddev-sans-first:24.11 19:08:05 INFO - PID 4168 | Values: 78.0 82.0 131.0 79.0 78.0 78.0 123.0 79.0 78.0 127.0 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 19:08:05 INFO - PID 4168 | Values: 113.0 114.0 114.0 113.0 114.0 115.0 114.0 114.0 114.0 114.0 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:150.00 stddev:0.67 (0.4%) stddev-sans-first:0.67 19:08:05 INFO - PID 4168 | Values: 149.0 150.0 149.0 150.0 150.0 150.0 149.0 149.0 151.0 150.0 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:1.42 (1.5%) stddev-sans-first:1.50 19:08:05 INFO - PID 4168 | Values: 94.0 94.0 94.0 94.0 95.0 93.0 98.0 94.0 93.0 94.0 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | [#8] json-parse-financial Cycles:10 Average:47.20 Median:47.50 stddev:2.30 (4.8%) stddev-sans-first:2.40 19:08:05 INFO - PID 4168 | Values: 46.0 47.0 51.0 45.0 51.0 46.0 48.0 48.0 45.0 45.0 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | [#9] json-stringify-tinderbox Cycles:10 Average:39.80 Median:40.00 stddev:0.42 (1.1%) stddev-sans-first:0.44 19:08:05 INFO - PID 4168 | Values: 40.0 40.0 40.0 39.0 40.0 40.0 40.0 40.0 40.0 39.0 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | [#10] stanford-crypto-aes Cycles:10 Average:73.90 Median:74.00 stddev:1.29 (1.7%) stddev-sans-first:1.32 19:08:05 INFO - PID 4168 | Values: 73.0 73.0 73.0 75.0 73.0 74.0 73.0 74.0 74.0 77.0 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | [#11] stanford-crypto-ccm Cycles:10 Average:122.10 Median:120.00 stddev:10.80 (9.0%) stddev-sans-first:11.22 19:08:05 INFO - PID 4168 | Values: 116.0 120.0 124.0 120.0 114.0 118.0 112.0 136.0 115.0 146.0 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:167.00 stddev:3.69 (2.2%) stddev-sans-first:3.81 19:08:05 INFO - PID 4168 | Values: 163.0 165.0 160.0 167.0 168.0 169.0 167.0 171.0 164.0 160.0 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.50 Median:61.00 stddev:0.97 (1.6%) stddev-sans-first:1.01 19:08:05 INFO - PID 4168 | Values: 61.0 62.0 61.0 59.0 60.0 61.0 61.0 61.0 60.0 59.0 19:08:05 INFO - PID 4168 | -------- Summary: end -------- 19:08:05 INFO - PID 4168 | 19:08:05 INFO - PID 4168 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:08:05 INFO - TEST-INFO | 4168: exit 0 19:08:05 INFO - TEST-OK | kraken | took 59887ms 19:08:05 INFO - SUITE-END | took 1171s 19:08:06 INFO - Completed test suite (00:19:31) 19:08:06 INFO - javascript benchmark 19:08:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8948.702956526733, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3824.9043773905664, 3880.922381552371, 3881.281962836875, 3915.393114067319, 3946.506686664167, 6107.877842443148, 6136.417040381162, 6185.103160041791, 6277.027060805571, 6298.7165577549, 6218.4092511211475, 6225.097360882669, 6284.7486100555925, 6294.653794041325, 6321.1150438938585, 5423.050966080939, 5596.552275817925, 5654.547636189116, 5664.518515926151, 5667.433256674341, 5540.085885828834, 5570.972145139289, 5662.207290979266, 5679.801206957757, 5681.886362272752, 5558.082916318823, 5577.609567330289, 5601.411851755568, 5602.551795856321, 5620.185073164389, 5453.863653408696, 5552.583556233299, 5607.383187849333, 5618.15727640853, 5666.490015898589, 5498.257735205736, 5513.2005859150195, 5524.944750552483, 5546.4453554644615, 5592.161175823619, 6205.3484384139465, 6223.96888015557, 6249.437550620466, 6269.811905642838, 6288.654124023177, 6707.530472866902, 6729.293424190439, 6734.797956061277, 6774.153230846145, 6780.389764921131, 6617.371349721769, 6735.29279425663, 6736.966315168393, 6819.761308354234, 6897.2757860424435, 6237.500999919996, 6271.278802937675, 6283.937160628381, 6287.402696743802, 6298.87402251957, 6756.121704178426, 6837.692303846339, 6892.172939247323, 6929.861402772017, 6944.51388402807, 6561.0, 6580.11168492257, 6643.335666433415, 6646.833829154213, 6659.234188068434, 6661.966690166615, 6750.257471678112, 6761.22245941723, 6765.188177418741, 6767.763128290486, 6611.471082313404, 6622.933770662328, 6658.7336506539195, 6699.263081061079, 6710.429613482811, 6593.505487088488, 6656.73373065072, 6663.145893281154, 6750.35871592198, 6771.559848609824, 5023.949760502422, 5024.748762561857, 5094.719790411489, 5147.6654017488745, 5150.27896094547, 4494.595486406239, 4657.6273898088075, 4677.391939047902, 4725.07860967115, 4733.881652958635, 6185.8144255672405, 6193.1329613854095, 6276.1213430118905, 6278.121063051085, 6280.0, 6523.934760652428, 6562.212534495891, 6595.406413422815, 6656.134702488645, 6744.123263975652, 6189.226346706661, 6226.875462490725, 6268.153799237136, 6270.59241149333, 6276.497880169575, 5534.806281780118, 5556.055470570012, 5617.662940223599, 5625.803096891589, 5646.6894320811925, 6873.243943166248, 6914.4468442525485, 6950.3049695030095, 6952.652367381611, 7017.9649101755185, 6581.703823327866, 6615.933840661532, 6623.4038936495945, 6634.170728658918, 6645.401913827779, 6940.028396024662, 7009.96495017542, 7020.684069216795, 7035.085438892911, 7045.189803172496, 6573.638449885302, 6591.835204119935, 6604.933950660432, 6605.603663780189, 6689.2307384651385, 6567.835804104936, 6595.043718660671, 6690.632015239208, 6700.430463410665, 6754.8649027020165, 6494.967525162344, 6551.70517326731, 6559.540832141704, 6567.9014814776865, 6569.737210511526, 6224.408681175281, 6366.6180029198395, 6483.319251478715, 6498.447631951337, 6512.804615861532, 6389.9680501597195, 6392.105105285171, 6394.392532709385, 6398.584092034003, 6409.9679501602195, 6358.968205158944, 6423.807285781434, 6446.29090800021, 6452.7418903245225, 6492.610443373413, 6394.424501794861, 6398.104265402754, 6411.102445657519, 6411.4229719325485, 6446.355364463516], "value": 6150.689865874886, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [888.7778055486131, 940.7083804020754, 961.5480724059694, 1233.9938300308497, 1254.8117782332654, 32842.0, 41238.0, 41838.37242441362, 41934.16131677372, 42124.78937605315, 42265.57734422655, 42556.0, 42637.36043959335, 42726.572734272646, 42768.57231427685, 8004.119546849842, 8147.307478864303, 8147.429679922408, 8149.307308878752, 8150.673973041071, 8017.919820801776, 8112.31045361145, 8132.918670813276, 8136.593170341489, 8141.470804397695, 8173.305269052138, 8194.139615340393, 8195.549244791535, 8213.671453141867, 8219.219174178474, 8139.715109971152, 8167.836643267131, 8170.468919520212, 8186.713465028755, 8195.221453961864, 8055.0, 8061.677532898677, 8119.512829230265, 8139.226773456512, 8142.267195952333, 39743.205135897406, 39940.20119597621, 40068.59931400678, 40074.59925400738, 40159.59840401588, 3537.2748586539697, 3545.2377738786286, 3560.181158333572, 3562.251927095313, 3567.6075631680496, 32224.194395140075, 32229.51655725166, 32283.83858080718, 32293.354132917444, 32374.514382284284, 5110.66780659256, 5184.144616138333, 5226.03318386096, 5288.973555132238, 5360.142377219626, 209315.0, 209530.0, 210082.0, 210969.0, 211316.0, 50436.74781626142, 50703.49296507062, 50728.0, 50790.74604627027, 50828.49171508312, 53153.0, 53492.0, 53674.46325536694, 53931.0, 53938.73030634822, 14896.925515372353, 15040.0, 16589.336426543043, 16810.747838782427, 16835.831641683428, 13161.144525605805, 13279.40242689062, 13290.136141150795, 13333.20000799955, 13343.06598538093, 14992.650661440523, 15001.549953501195, 15013.549593512213, 15018.69962600742, 15164.469243576643, 5876.706164691748, 5880.942389544988, 5925.703714814242, 5975.731092100866, 6030.547708921848, 1013.5388398278766, 1018.0582960761266, 1029.6190409548515, 1034.8240799064176, 1037.9481025948671, 23758.0, 23968.880155599458, 24085.916133774022, 24377.756222437547, 30484.847575761978, 1121.9046381057701, 1132.428123797477, 1136.4374634555948, 1137.0505627800878, 1141.8173092305192, 13175.868241317465, 13673.31633418345, 13699.657508562366, 13699.931500342635, 17056.658866822592, 299946.0, 300542.0, 301266.0, 302495.0, 302611.0, 60414.69792651009, 61276.69361653163, 61384.0, 61534.38465615286, 61609.38390616126, 1470.9632259193393, 1473.6168596165073, 1500.1524138861726, 1521.9239038048054, 1522.6345677037652, 28575.714242857306, 28825.71174288314, 29491.55762663519, 29733.851330743208, 29739.405211896075, 41168.1766364677, 41225.587744123375, 41238.38142427926, 41467.58532414758, 41468.0, 241845.0, 243488.0, 244119.0, 244124.0, 244232.0, 34024.14939626529, 35325.470117947734, 35379.46930795989, 35433.0, 35465.64534354623, 38868.6113138865, 39254.41118383284, 39268.80365598268, 39487.407688884115, 39722.0, 32649.836750816095, 32745.34509309848, 32953.34093318072, 33827.83086084554, 33836.830815845766, 32963.0, 33079.0, 33152.50271245981, 33364.0, 33479.33041339208, 36707.81646091752, 36804.447933280484, 36970.0, 37058.0, 37117.62882371142, 31450.370992579563, 32206.35587288288, 32209.0, 32267.0, 32368.514472283405, 24643.507129856946, 27139.0, 27450.45099098047, 27517.0, 27689.44621107607], "value": 18299.146330041698, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.2480638347805, 574.5145352177411, 579.9217105690731, 584.7310237290848, 591.9496842768365, 21799.891000544976, 23319.41701457462, 24159.0, 24175.516489670197, 24219.394515137108, 26037.0, 26562.203133906012, 26626.06808761694, 26650.200493985212, 26654.0, 6160.907586386208, 6298.46463050639, 6300.5589608727405, 6303.968480157593, 6323.74705011799, 5661.46216109469, 6226.688665566726, 6264.624122552661, 6272.3100458949475, 6275.309715931244, 6246.531510136735, 6251.468625166844, 6252.3122456529745, 6254.093156492306, 6258.780942666985, 6225.3463386344465, 6230.9999999999545, 6231.906521402183, 6232.376762323776, 6234.937650623527, 5821.155932389822, 5827.213326200951, 5830.4461076197695, 5838.9124163137585, 5849.88300233993, 29774.70225297741, 30037.699623003708, 30573.847130764425, 31188.220294492592, 31495.685043149504, 2178.0852042142337, 2216.1689366487567, 2216.3794137641485, 2217.6562632791943, 2218.1681869299014, 19859.205631774712, 20433.080511377022, 20455.693164602544, 20468.488287792774, 20474.590508189754, 2425.478522117753, 2465.815063870217, 2492.8379655322346, 2511.485145545159, 2518.0934863449143, 152033.0, 154521.0, 155003.2249838755, 155248.22375888162, 155528.2223588875, 14828.406863725546, 15538.844611553739, 15615.141167235912, 16135.919320403324, 16368.427105051265, 24860.37849053788, 24882.37794055127, 24924.0, 24980.125695600567, 25828.74171258301, 10600.25798194119, 10821.729456763644, 10854.72863178411, 10911.890881091087, 10984.176186785864, 9899.455529945923, 9951.800963980824, 9969.750756231153, 10011.649592264235, 10053.296269261082, 9863.358881672812, 9945.80108397828, 10030.64892728751, 10050.497475126214, 10082.949585252027, 2642.6036094585584, 2723.360010397555, 2728.563429851214, 2739.5479745842113, 2787.874545645451, 999.3104757717152, 1031.5254982707888, 1034.5603118674533, 1038.870141232346, 1046.6912260883057, 21062.368128956157, 25200.0, 26833.865830670722, 27083.0, 27473.450530989267, 1030.2685093583639, 1086.8206745886998, 1088.6733979806027, 1098.2257508456519, 1098.9560417583207, 11566.19036667442, 11788.29270243788, 12048.397580120958, 13967.511137110356, 15026.924865375604, 666612.0, 695081.0, 698568.0, 702148.0, 742738.0, 126307.3684631571, 132184.0, 135196.88818670713, 135569.0, 135994.3200284012, 1286.3889652415028, 1292.7285270093198, 1326.1446367093363, 1334.4795529743408, 1338.953136640213, 21963.890180548995, 22087.558248835256, 22525.887370563043, 22576.20983265578, 22580.548389032458, 51893.0, 61962.0, 64638.676806615666, 64747.676261620276, 64839.0, 82862.0, 85541.1445885558, 85623.5718821402, 85860.57069714612, 86001.56999214964, 15195.696086077995, 15825.287862046005, 15898.920505397398, 15907.602309942344, 15911.920440398188, 23181.07275708953, 23198.420039499146, 23207.419814504774, 23344.53310933738, 25563.977440902156, 17799.377021804175, 19984.0, 20081.39755807328, 20124.496887577927, 20154.0, 17041.147942603067, 17097.829021709622, 17157.970521768733, 17310.0, 18527.444176674722, 20753.896230518752, 20759.273425430038, 20846.895765521076, 20887.06008229603, 20970.580588388453, 15569.76645350298, 15570.29933653011, 15629.609259768597, 15635.218239087773, 15674.686506269583, 17850.0, 18060.458186254433, 18113.909430452764, 19015.42953711391, 19897.801021989595], "value": 12831.726975714966, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1411.5694713112503, 1463.736527425063, 1465.7874608181814, 1468.126464753472, 1475.7565001774708, 15812.60468488287, 15884.364625414999, 16512.256948437305, 16524.256408461617, 16542.917285413587, 16504.092274924875, 16730.41443549476, 16742.66514669709, 16747.748783768253, 16764.58088547787, 2770.9445811083815, 2808.9157325280275, 2817.056286144134, 2819.5347767618423, 2820.097568777992, 2719.4697034078363, 2743.4238809849953, 2775.4587855368213, 2792.9581056284173, 2811.2971757060736, 2735.5623100303956, 2759.6274502942147, 2762.709915458878, 2763.281546797827, 2766.336079340954, 2644.616530603071, 2714.280715610365, 2725.2505560970694, 2727.386338073937, 2736.1107640016876, 2682.1685277563824, 2708.6614173228345, 2718.5242582547976, 2720.795940304465, 2724.087430710714, 15556.922215388964, 15647.608809779847, 15805.68388632221, 15845.92077039619, 15847.366105355772, 7799.766007019798, 8037.718679846236, 8095.23095305945, 8101.716439924633, 8108.918910810876, 5232.659877107976, 5470.835874923759, 5620.971895140539, 5625.662460252398, 5629.80295689653, 1964.673845154515, 2006.7591888973416, 2012.7785943546198, 2057.9588408231753, 2079.958400831975, 26858.865705671542, 26941.0, 26953.191404257905, 26970.5954410684, 26993.0, 6794.490413219029, 6836.213835408892, 6891.27641597635, 6957.8956315655305, 7029.261927497641, 18394.17226224823, 18397.90801045986, 18466.815331846778, 18475.445736627924, 18504.259829606934, 8085.83828323442, 8252.917470825336, 8258.958705206436, 8299.792505187419, 8305.211004954519, 6361.427471527585, 6500.902486462707, 6774.796756097325, 6780.864382712318, 7707.113681926538, 5346.117890548094, 5547.195656629766, 5553.944460555343, 5638.0, 5703.885922281531, 1555.6033211530987, 1574.157825563318, 1592.0, 1602.3510478256326, 1621.9189040547926, 925.3707478914268, 953.6471505542994, 960.3613596958085, 961.485605201214, 964.609333220047, 8323.958380208182, 8340.457870238537, 8390.496570205807, 8409.915900840913, 8411.663533458715, 722.1081963774734, 733.1568695999537, 754.1515794730927, 763.6334559411403, 769.769069279214, 5594.944050559524, 5732.713364331767, 5743.54051675865, 5816.854578635483, 6063.0, 23279.41801454977, 23838.165664202017, 24045.639315410284, 24503.14239001662, 24515.264542063767, 23854.165104221614, 23872.0, 23878.88060559686, 23936.04255829747, 24150.275491735276, 11611.129165312635, 12178.512859485521, 12442.191257568047, 12444.81332780009, 12459.750804983849, 8784.560771961504, 8951.462912225288, 9385.108414700593, 9490.810183796424, 9715.125638692552, 19672.606547869247, 20016.69974950406, 20220.19119235214, 20677.966101694554, 20950.47623809417, 20578.58842823165, 20680.379588612366, 20892.47768805792, 20990.790092099494, 21019.159233630482, 12749.93625031869, 13037.413316400594, 13052.282124482865, 13059.869401305865, 13060.0, 14791.0, 14882.851171488579, 14955.401783928955, 14998.400063997318, 15081.24593770329, 11468.254563453349, 12193.69515762113, 12200.328981905725, 12255.509779608716, 12301.753964920472, 11037.944810276218, 11949.342786146846, 12039.217450865666, 12079.818802718142, 12195.573154939535, 9591.9040809591, 9744.366616169926, 9746.951265243628, 9758.51207439611, 9763.707088787347, 7685.730999414994, 7717.768466946, 7834.529928204326, 7892.289693927574, 8105.8784118239455, 7798.37612990948, 7804.6878124874365, 7846.33306168982, 7858.44990850635, 7949.284564389232], "value": 6979.686966850086, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.5127508329879, 543.65749577766, 544.6405372454178, 549.7498638119656, 561.5311215135362, 6229.532785041123, 6249.468795152417, 6252.593581417211, 6271.278802937658, 6322.186626951008, 6121.745386956068, 6163.845903852406, 6164.229471316085, 6172.969135154329, 6178.96910515448, 5080.2125670520945, 5105.642605017651, 5107.233914912774, 5120.3343565336545, 5154.0, 4799.98400671717, 4854.902901941959, 5081.2886195932415, 5089.440161582223, 5089.669171503859, 4890.73100979446, 4900.240462728265, 4924.113659541281, 4965.155923492996, 4972.229304457796, 4885.923482519915, 4890.877728056791, 4895.429899302859, 4922.089413458521, 4942.481039490837, 4397.2964325707735, 4440.378347031418, 4455.108978204372, 4469.195544801934, 4489.1021795641, 5980.760769569212, 6035.7887473938645, 6037.339731573808, 6088.677039302101, 6122.510199184055, 5723.7996670116345, 5736.856578585569, 5765.625234359795, 5784.13238014297, 5794.5074668653215, 6563.868722625521, 6589.0, 6589.439897608709, 6612.908870036486, 6624.569402988838, 4901.946081592475, 4911.852644420673, 4966.329545511346, 4975.601951843843, 4983.72589507577, 6589.80230593083, 6682.661068625991, 6780.0, 6814.1482314710665, 6818.420434263094, 5069.949300506948, 5097.470758772353, 5107.846764597068, 5118.718470484085, 5124.333836601292, 5148.794048238104, 5149.76826042829, 5171.172612382001, 5183.2743415921805, 5184.300119483822, 5715.514181294637, 5729.169270455761, 5769.711514424262, 5785.4214578541805, 5795.333536643256, 6592.901106483407, 6605.579800342949, 6621.139251897319, 6624.701888415033, 6656.833579160463, 1776.2362184260892, 1777.8710518820642, 1789.409494866691, 1791.4267434421122, 1798.7391828185102, 1207.7524107557929, 1234.672811704881, 1252.555342853274, 1269.2130878855169, 1275.2157423184674, 6112.724927378279, 6193.84515387119, 6211.16149319836, 6212.285587157445, 6220.626762394271, 6639.369259920397, 6670.733170673216, 6730.461563074942, 6746.494012949049, 6772.762953296611, 6091.44668109638, 6177.227846519185, 6183.134361189437, 6183.474404675563, 6205.28639206488, 3405.3359594879257, 3501.511857460569, 3511.17487390463, 3519.1905861651962, 3520.4543295789194, 6843.555168914005, 6844.1748567259965, 7008.334208250208, 7028.613426261605, 7045.92954070463, 6882.242953275136, 6945.305469453015, 6954.582725036514, 6970.302969702989, 7034.6834392452465, 7107.502474826816, 7126.928730712627, 7127.786166415016, 7144.142702875584, 7199.3520583147765, 6703.095082163845, 6722.2605513394465, 6738.561993470409, 6783.694733737092, 6793.592384456751, 6599.835004124935, 6637.867242655217, 6682.3317668232785, 6698.363655452919, 6758.763443279461, 6944.4097251734165, 7011.1586609607175, 7041.929580704127, 7112.751053713096, 7120.643967801486, 5004.649674522749, 5052.8484145475695, 5071.670341427795, 5078.162103252922, 5078.587670856314, 6520.934790652223, 6628.867422651617, 6720.059191713066, 6748.0, 6809.127489940306, 6445.323241059621, 6447.742090316522, 6457.61254324742, 6553.574017688835, 6559.901601476077, 6418.550701450854, 6446.226452825691, 6507.349265073644, 6564.573302735307, 6587.604743715392, 6484.0, 6485.77299794505, 6488.383603557654, 6512.869742605216, 6521.282658907611, 6521.934780652133, 6592.143021407186, 6633.469322454289, 6636.336366363518, 6636.867262654623, 6402.903956440564, 6411.916127258566, 6435.420812126931, 6437.388448097423, 6461.19235095613], "value": 5137.070475798465, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [941.0542404883091, 954.0602506531067, 962.0273355332023, 966.6665000574799, 983.0464449483998, 18574.814251857493, 19447.124879380426, 19541.902290488564, 19545.706814397778, 21337.786622133775, 21307.68038479424, 21798.673019904712, 21846.016929238183, 21860.672089918666, 21864.0, 1009.3943633819686, 1016.761061150629, 1018.6791160784367, 1019.3017782818756, 1019.5768755966266, 1042.4006196437042, 1085.6417382263874, 1089.1559041742635, 1092.814221582333, 1093.8906109389077, 1153.066016526612, 1153.7692461507731, 1158.0214718562843, 1158.119828930015, 1158.8435561199215, 1137.3062431916524, 1148.070063248767, 1154.0479104738624, 1154.2497376705126, 1155.399192419937, 837.5686521441462, 839.9916000839975, 840.1514470384894, 840.3739214285397, 840.9285210757032, 19828.603427931506, 19840.50398740043, 20235.797642023685, 20414.591708165903, 20617.48456288605, 21488.892555537277, 21639.56720865574, 22063.779362206333, 22071.889640551854, 22331.44171395712, 17448.912755436268, 17954.102294885204, 18225.908870455696, 18228.817711822845, 18364.357247496406, 3937.0551067743536, 3945.782981935992, 3961.425593288957, 3973.5231772187235, 3974.5230572331216, 140910.29544852313, 142722.28638856843, 143454.0, 145110.0, 145409.2729536356, 17008.0, 17265.654686906193, 17284.308627655006, 17299.654006920042, 17419.73870391945, 31942.041738747874, 32630.347393052005, 33178.66821331756, 33297.16757081044, 33349.83325083359, 8559.186877246777, 8680.696175633822, 8686.739397818075, 8702.04277529484, 8715.433496822812, 3923.5684074752326, 3975.5428125765907, 3999.060220848098, 4040.1111755413763, 4056.350983842571, 5501.944980550224, 5514.5864060195645, 5570.442955704478, 5631.493165615032, 5662.094064949588, 1042.3797840285017, 1056.915446764257, 1067.3275836223286, 1077.1167642533205, 1078.1267173589417, 703.4899697719113, 724.3553237618523, 736.120336198238, 736.830528978338, 743.4424181863602, 11914.642560723192, 12267.0, 12828.743425131444, 13396.732065358638, 13406.0, 699.7655785311978, 705.7247673407376, 708.1042481261106, 711.1821405383849, 728.8287252495659, 6055.273367195877, 6066.939330606726, 6220.937790622036, 6654.168228971379, 6885.931140688629, 123418.0, 124545.0, 127643.36178319048, 131041.34479327542, 132318.0, 116267.0, 117307.0, 117772.0, 118703.0, 119354.40322798504, 100022.4998875001, 102026.48986755019, 102923.0, 104383.99999999849, 106333.0, 13561.38973746164, 13817.0, 14027.929860351041, 14052.367643455864, 14069.648258793612, 49615.50384496109, 49673.503264968334, 49736.751316243186, 49839.0, 50750.0, 63023.6848815753, 63329.68335158295, 63587.0, 63988.0, 65547.34452655603, 18791.530211744815, 18979.715304270172, 18991.525211869815, 19087.236510539424, 19290.903545482182, 21044.473888152304, 21727.0, 21814.0, 23866.164684236664, 23934.162304319267, 20334.389968300973, 20593.897030514752, 21263.149474020865, 21478.892605536872, 21668.24161154352, 20977.160913563286, 21073.367798966057, 21584.67622985625, 21963.011664475456, 22191.1123555056, 20506.79493205049, 20590.0, 20731.4817129573, 20788.896055519624, 20789.480262993548, 14364.0, 14509.709805804036, 14592.0, 14621.122732636077, 14668.486602968846, 15859.640046561068, 15980.920095399448, 16163.68795738528, 16181.190940453165, 16505.83494165043], "value": 9916.119744789008, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1457.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 107.0, 99.0, 105.0, 107.0, 108.0, 110.0, 97.0, 109.0, 106.0], "value": 104.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 113.0, 139.0, 171.0, 118.0, 140.0, 152.0, 114.0, 168.0, 117.0], "value": 137.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 162.0, 163.0, 162.0, 160.0, 170.0, 167.0, 162.0], "value": 163.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 166.0, 81.0, 74.0, 73.0, 72.0, 150.0, 86.0], "value": 92.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 82.0, 131.0, 79.0, 78.0, 78.0, 123.0, 79.0, 78.0, 127.0], "value": 93.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 113.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 149.0, 150.0, 150.0, 150.0, 149.0, 149.0, 151.0, 150.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 93.0, 98.0, 94.0, 93.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 47.0, 51.0, 45.0, 51.0, 46.0, 48.0, 48.0, 45.0, 45.0], "value": 47.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [40.0, 40.0, 40.0, 39.0, 40.0, 40.0, 40.0, 40.0, 40.0, 39.0], "value": 39.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 75.0, 73.0, 74.0, 73.0, 74.0, 74.0, 77.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 120.0, 124.0, 120.0, 114.0, 118.0, 112.0, 136.0, 115.0, 146.0], "value": 122.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 160.0, 167.0, 168.0, 169.0, 167.0, 171.0, 164.0, 160.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 59.0, 60.0, 61.0, 61.0, 61.0, 60.0, 59.0], "value": 60.5, "unit": "score"}]}]} 19:08:06 INFO - Return code: 0 19:08:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:08:06 INFO - # TBPL SUCCESS # 19:08:06 INFO - Running post-action listener: _package_coverage_data 19:08:06 INFO - Running post-action listener: _resource_record_post_action 19:08:06 INFO - [mozharness: 2017-08-29 02:08:06.309134Z] Finished run-tests step (success) 19:08:06 INFO - Running post-run listener: _resource_record_post_run 19:08:06 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 5488640; Write bytes: 433004544; Read time: 2880; Write time: 301040 19:08:06 INFO - TinderboxPrint: CPU usage
13.1% 19:08:06 INFO - TinderboxPrint: I/O read bytes / time
5,488,640 / 2,880 19:08:06 INFO - TinderboxPrint: I/O write bytes / time
433,004,544 / 301,040 19:08:06 INFO - TinderboxPrint: CPU idle
8,248.3 (86.9%) 19:08:06 INFO - TinderboxPrint: CPU user
1,188.5 (12.5%) 19:08:06 INFO - TinderboxPrint: Swap in / out
0 / 0 19:08:06 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 113655808; Read time: 84; Write time: 76952 19:08:06 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:08:06 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 5484544; Write bytes: 318955520; Read time: 2796; Write time: 224036 19:08:06 INFO - Running post-run listener: _upload_blobber_files 19:08:06 INFO - Blob upload gear active. 19:08:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:08:06 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 19:08:06 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'] 19:08:06 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 19:08:07 INFO - (blobuploader) - INFO - Open directory for files ... 19:08:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:08:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:08:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:14 INFO - (blobuploader) - INFO - Done attempting. 19:08:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:08:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:08:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:15 INFO - (blobuploader) - INFO - Done attempting. 19:08:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:08:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:08:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:16 INFO - (blobuploader) - INFO - Done attempting. 19:08:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:08:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:08:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:17 INFO - (blobuploader) - INFO - Done attempting. 19:08:17 INFO - (blobuploader) - INFO - Iteration through files over. 19:08:17 INFO - Return code: 0 19:08:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:08:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:08:17 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4085b71c418efb89ea09f5b4684877abbf8d3986f6fc2f3b5cf94bb7399f320e42fb994dc163ee0c9922b5265f505a438280001e972531fbaf161c4840af003", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae2a1284e3f20a6f58c11c93cff22ca0d2be754b2e1dff5b3afe7eb40000233fe76566bec81f26231a4b661befff2f10d3364fde9e82011337ddfc28bf8d7262", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4aa7da94d63923531968a0f44342c263b81aa64babb4d696cc9d1d7fb0589b22a77017fbe2d8856081653994c2ecc17e78cfde721855c52b12f7068d9f872ce", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30d3d44cdb2608bb579c548c31a47b453ddec74a6d4123e516f89b80d90ae5a7c5351cf28a90d81556ab89c839f8d1d4c03b1b73ae75e1defb72625e399f72da"} 19:08:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:08:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:08:17 INFO - Contents: 19:08:17 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4085b71c418efb89ea09f5b4684877abbf8d3986f6fc2f3b5cf94bb7399f320e42fb994dc163ee0c9922b5265f505a438280001e972531fbaf161c4840af003", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae2a1284e3f20a6f58c11c93cff22ca0d2be754b2e1dff5b3afe7eb40000233fe76566bec81f26231a4b661befff2f10d3364fde9e82011337ddfc28bf8d7262", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4aa7da94d63923531968a0f44342c263b81aa64babb4d696cc9d1d7fb0589b22a77017fbe2d8856081653994c2ecc17e78cfde721855c52b12f7068d9f872ce", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30d3d44cdb2608bb579c548c31a47b453ddec74a6d4123e516f89b80d90ae5a7c5351cf28a90d81556ab89c839f8d1d4c03b1b73ae75e1defb72625e399f72da"} 19:08:17 INFO - Running post-run listener: copy_logs_to_upload_dir 19:08:17 INFO - Copying logs to upload dir... 19:08:17 INFO - mkdir: /builds/slave/test/build/upload/logs 19:08:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.827003 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-28 19:08:17.162426) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 19:08:17.166182) ========= 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=31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4085b71c418efb89ea09f5b4684877abbf8d3986f6fc2f3b5cf94bb7399f320e42fb994dc163ee0c9922b5265f505a438280001e972531fbaf161c4840af003", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae2a1284e3f20a6f58c11c93cff22ca0d2be754b2e1dff5b3afe7eb40000233fe76566bec81f26231a4b661befff2f10d3364fde9e82011337ddfc28bf8d7262", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4aa7da94d63923531968a0f44342c263b81aa64babb4d696cc9d1d7fb0589b22a77017fbe2d8856081653994c2ecc17e78cfde721855c52b12f7068d9f872ce", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30d3d44cdb2608bb579c548c31a47b453ddec74a6d4123e516f89b80d90ae5a7c5351cf28a90d81556ab89c839f8d1d4c03b1b73ae75e1defb72625e399f72da"} build_url:https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011261 build_url: 'https://queue.taskcluster.net/v1/task/OviRUCxuR1uijxUodxp14A/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4085b71c418efb89ea09f5b4684877abbf8d3986f6fc2f3b5cf94bb7399f320e42fb994dc163ee0c9922b5265f505a438280001e972531fbaf161c4840af003", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae2a1284e3f20a6f58c11c93cff22ca0d2be754b2e1dff5b3afe7eb40000233fe76566bec81f26231a4b661befff2f10d3364fde9e82011337ddfc28bf8d7262", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4aa7da94d63923531968a0f44342c263b81aa64babb4d696cc9d1d7fb0589b22a77017fbe2d8856081653994c2ecc17e78cfde721855c52b12f7068d9f872ce", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30d3d44cdb2608bb579c548c31a47b453ddec74a6d4123e516f89b80d90ae5a7c5351cf28a90d81556ab89c839f8d1d4c03b1b73ae75e1defb72625e399f72da"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-28 19:08:17.192505) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 19:08:17.192836) ========= 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=31acecf42b6a79ec49471d4a000001bb-1503970145.997994-5334767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-28 19:08:17.276958) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 19:08:17.277269) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-28 19:08:17.277560) ========= ========= Total master_lag: 0.23 =========