builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-038 starttime: 1506509003.65 results: success (0) revision: 35fbf14b96a633c3f66ea13c1a163a3f3a4219b9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 03:43:23.645645) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 03:43:23.646664) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 03:43:23.646949) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 03:43:23.695401) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 03:43:23.695672) ========= 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=8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-27 03:43:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.1M=0s 2017-09-27 03:43:23 (37.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.163559 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 03:43:23.876236) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 03:43:23.876543) ========= 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=8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014346 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 03:43:23.930754) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 03:43:23.934093) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 35fbf14b96a633c3f66ea13c1a163a3f3a4219b9 --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 35fbf14b96a633c3f66ea13c1a163a3f3a4219b9 --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=8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-27 03:43:24,012 truncating revision to first 12 chars 2017-09-27 03:43:24,012 Setting DEBUG logging. 2017-09-27 03:43:24,012 attempt 1/10 2017-09-27 03:43:24,012 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/35fbf14b96a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-27 03:43:25,030 unpacking tar archive at: mozilla-central-35fbf14b96a6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.317196 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 03:43:25.268695) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 03:43:25.269074) ========= script_repo_revision: 35fbf14b96a633c3f66ea13c1a163a3f3a4219b9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 03:43:25.269459) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 03:43:25.269719) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 03:43:25.295711) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-09-27 03:43:25.296031) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:43:25 INFO - MultiFileLogger online at 20170927 03:43:25 in /builds/slave/test 03:43:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 03:43:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:43:25 INFO - {'append_to_log': False, 03:43:25 INFO - 'base_work_dir': '/builds/slave/test', 03:43:25 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:43:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:43:25 INFO - 'branch': 'Firefox-Non-PGO', 03:43:25 INFO - 'buildbot_json_path': 'buildprops.json', 03:43:25 INFO - 'code_coverage': False, 03:43:25 INFO - 'config_files': ('talos/linux_config.py',), 03:43:25 INFO - 'default_actions': ('clobber', 03:43:25 INFO - 'read-buildbot-config', 03:43:25 INFO - 'download-and-extract', 03:43:25 INFO - 'populate-webroot', 03:43:25 INFO - 'create-virtualenv', 03:43:25 INFO - 'install', 03:43:25 INFO - 'setup-mitmproxy', 03:43:25 INFO - 'run-tests'), 03:43:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:43:25 INFO - 'disable_ccov_upload': False, 03:43:25 INFO - 'disable_stylo': False, 03:43:25 INFO - 'download_minidump_stackwalk': True, 03:43:25 INFO - 'download_symbols': 'ondemand', 03:43:25 INFO - 'enable_stylo': False, 03:43:25 INFO - 'enable_webrender': False, 03:43:25 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:43:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:43:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:43:25 INFO - 'gecko_profile': False, 03:43:25 INFO - 'gecko_profile_interval': 0, 03:43:25 INFO - 'installer_path': 'installer.exe', 03:43:25 INFO - 'log_level': 'info', 03:43:25 INFO - 'log_name': 'talos', 03:43:25 INFO - 'log_to_console': True, 03:43:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:43:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:43:25 INFO - 'opt_config_files': (), 03:43:25 INFO - 'pip_index': False, 03:43:25 INFO - 'suite': 'dromaeojs-e10s', 03:43:25 INFO - 'system_bits': '32', 03:43:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:43:25 INFO - 'title': 'talos-linux64-ix-038', 03:43:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:43:25 INFO - 'use_talos_json': True, 03:43:25 INFO - 'verify': 'False', 03:43:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:43:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:43:25 INFO - 'work_dir': 'build'} 03:43:25 INFO - [mozharness: 2017-09-27 10:43:25.657546Z] Running clobber step. 03:43:25 INFO - Running pre-action listener: _resource_record_pre_action 03:43:25 INFO - Running main action method: clobber 03:43:25 INFO - rmtree: /builds/slave/test/build 03:43:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:43:26 INFO - Running post-action listener: _resource_record_post_action 03:43:26 INFO - [mozharness: 2017-09-27 10:43:26.612353Z] Finished clobber step (success) 03:43:26 INFO - [mozharness: 2017-09-27 10:43:26.612450Z] Running read-buildbot-config step. 03:43:26 INFO - Running pre-action listener: _resource_record_pre_action 03:43:26 INFO - Running main action method: read_buildbot_config 03:43:26 INFO - Using buildbot properties: 03:43:26 INFO - { 03:43:26 INFO - "project": "", 03:43:26 INFO - "product": "firefox", 03:43:26 INFO - "who": "archaeopteryx@coole-files.de", 03:43:26 INFO - "installer_path": "public/build/target.tar.bz2", 03:43:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:43:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:43:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:43:26 INFO - "stage_platform": "linux64", 03:43:26 INFO - "basedir": "/builds/slave/test", 03:43:26 INFO - "buildnumber": 872, 03:43:26 INFO - "platform": "ubuntu64_hw", 03:43:26 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:43:26 INFO - "slavebuilddir": "test", 03:43:26 INFO - "taskId": "cxK8N5iLQ-KLPM9L7QQTwA", 03:43:26 INFO - "branch": "mozilla-central", 03:43:26 INFO - "script_repo_revision": "production", 03:43:26 INFO - "revision": "35fbf14b96a633c3f66ea13c1a163a3f3a4219b9", 03:43:26 INFO - "slavename": "talos-linux64-ix-038", 03:43:26 INFO - "repo_path": "mozilla-central" 03:43:26 INFO - } 03:43:26 INFO - Finding installer, test and symbols from parent task. 03:43:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cxK8N5iLQ-KLPM9L7QQTwA'}, attempt #1 03:43:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cxK8N5iLQ-KLPM9L7QQTwA'}, attempt #1 03:43:27 INFO - Task dependencies: FadbKXGRQEiHgoCzlzPmQA 03:43:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA'}, attempt #1 03:43:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.tar.bz2 03:43:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.test_packages.json 03:43:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.crashreporter-symbols.zip 03:43:28 INFO - Running post-action listener: _resource_record_post_action 03:43:28 INFO - [mozharness: 2017-09-27 10:43:28.044742Z] Finished read-buildbot-config step (success) 03:43:28 INFO - [mozharness: 2017-09-27 10:43:28.044962Z] Running download-and-extract step. 03:43:28 INFO - Running pre-action listener: _resource_record_pre_action 03:43:28 INFO - Running main action method: download_and_extract 03:43:28 INFO - mkdir: /builds/slave/test/build/tests 03:43:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:43:28 INFO - trying https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.test_packages.json 03:43:28 INFO - Downloading https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:43:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:43:28 INFO - Downloaded 989 bytes. 03:43:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:43:28 INFO - Using the following test package requirements: 03:43:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:43:28 INFO - u'common': [u'target.common.tests.zip'], 03:43:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:43:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:43:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:43:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:43:28 INFO - u'mozbase': [u'target.common.tests.zip'], 03:43:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:43:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:43:28 INFO - u'web-platform': [u'target.common.tests.zip', 03:43:28 INFO - u'target.web-platform.tests.tar.gz'], 03:43:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:43:28 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:43:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.common.tests.zip 03:43:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:43:28 INFO - Fetch https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.common.tests.zip into memory 03:43:29 INFO - Content-Length response header: 54946111 03:43:29 INFO - Bytes received: 54946111 03:43:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.talos.tests.zip 03:43:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:43:41 INFO - Fetch https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.talos.tests.zip into memory 03:43:42 INFO - Content-Length response header: 14513665 03:43:42 INFO - Bytes received: 14513665 03:43:42 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')]} 03:43:42 INFO - trying https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.tar.bz2 03:43:42 INFO - Downloading https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:43:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:43:43 INFO - Downloaded 61763156 bytes. 03:43:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.tar.bz2 03:43:43 INFO - mkdir: /builds/slave/test/properties 03:43:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:43:43 INFO - Writing to file /builds/slave/test/properties/build_url 03:43:43 INFO - Contents: 03:43:43 INFO - build_url:https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.tar.bz2 03:43:43 INFO - Running post-action listener: _resource_record_post_action 03:43:43 INFO - Running post-action listener: find_tests_for_verification 03:43:43 INFO - Running post-action listener: set_extra_try_arguments 03:43:43 INFO - [mozharness: 2017-09-27 10:43:43.952709Z] Finished download-and-extract step (success) 03:43:43 INFO - [mozharness: 2017-09-27 10:43:43.952808Z] Running populate-webroot step. 03:43:43 INFO - Running pre-action listener: _resource_record_pre_action 03:43:43 INFO - Running main action method: populate_webroot 03:43:43 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:43:43 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:43 INFO - 'tests': ['tresize', 03:43:43 INFO - 'tcanvasmark']}, 03:43:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:43:43 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:43 INFO - 'tests': ['dromaeo_css', 03:43:43 INFO - 'kraken']}, 03:43:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:43:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:43:43 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:43:43 INFO - 'talos_options': ['--disable-stylo'], 03:43:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:43:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:43:43 INFO - 'tests': ['damp', 'tps']}, 03:43:43 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:43:43 INFO - 'talos_options': ['--disable-stylo'], 03:43:43 INFO - 'tests': ['damp', 'tps']}, 03:43:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:43:43 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:43 INFO - 'tests': ['dromaeo_dom']}, 03:43:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:43:43 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:43 INFO - 'tests': ['basic_compositor_video', 03:43:43 INFO - 'glvideo']}, 03:43:43 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:43:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:43:43 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:43:43 INFO - 'talos_options': ['--disable-stylo'], 03:43:43 INFO - 'tests': ['ts_paint_webext', 03:43:43 INFO - 'tp5o_webext']}, 03:43:43 INFO - 'other-e10s': {'tests': ['a11yr', 03:43:43 INFO - 'ts_paint', 03:43:43 INFO - 'tpaint', 03:43:43 INFO - 'sessionrestore', 03:43:43 INFO - 'sessionrestore_many_windows', 03:43:43 INFO - 'sessionrestore_no_auto_restore', 03:43:43 INFO - 'tabpaint']}, 03:43:43 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:43 INFO - 'tests': ['a11yr', 03:43:43 INFO - 'ts_paint', 03:43:43 INFO - 'tpaint', 03:43:43 INFO - 'sessionrestore', 03:43:43 INFO - 'sessionrestore_many_windows', 03:43:43 INFO - 'sessionrestore_no_auto_restore', 03:43:43 INFO - 'tabpaint']}, 03:43:43 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 03:43:43 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 03:43:43 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:43 INFO - 'tests': ['perf_reftest_singletons']}, 03:43:43 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:43 INFO - 'tests': ['perf_reftest']}, 03:43:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:43:43 INFO - 'tsvgr_opacity', 03:43:43 INFO - 'tart', 03:43:43 INFO - 'tscrollx', 03:43:43 INFO - 'tsvg_static']}, 03:43:43 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:43:43 INFO - 'tests': ['tsvgx', 03:43:43 INFO - 'tsvgr_opacity', 03:43:43 INFO - 'tart', 03:43:43 INFO - 'tscrollx', 03:43:43 INFO - 'tsvg_static']}, 03:43:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:43:43 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:43:43 INFO - 'talos_options': ['--disable-stylo'], 03:43:43 INFO - 'tests': ['tp5o']}, 03:43:43 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:43:43 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:43:43 INFO - 'talos_options': ['--mitmproxy', 03:43:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:43:43 INFO - '--firstNonBlankPaint'], 03:43:43 INFO - 'tests': ['tp6_google', 03:43:43 INFO - 'tp6_youtube', 03:43:43 INFO - 'tp6_amazon', 03:43:43 INFO - 'tp6_facebook']}, 03:43:43 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:43:43 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:43:43 INFO - 'talos_options': ['--disable-stylo', 03:43:43 INFO - '--mitmproxy', 03:43:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:43:43 INFO - '--firstNonBlankPaint'], 03:43:43 INFO - 'tests': ['tp6_google', 03:43:43 INFO - 'tp6_youtube', 03:43:43 INFO - 'tp6_amazon', 03:43:43 INFO - 'tp6_facebook']}, 03:43:43 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:43:43 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 03:43:43 INFO - 'talos_options': ['--stylo-threads=1', 03:43:43 INFO - '--mitmproxy', 03:43:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:43:43 INFO - '--firstNonBlankPaint'], 03:43:43 INFO - 'tests': ['tp6_google', 03:43:43 INFO - 'tp6_youtube', 03:43:43 INFO - 'tp6_amazon', 03:43:43 INFO - 'tp6_facebook']}, 03:43:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:43:43 INFO - 'talos_options': ['--xperf_path', 03:43:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:43:43 INFO - 'tests': ['tp5n']}, 03:43:43 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:43:43 INFO - 'talos_options': ['--disable-stylo', 03:43:43 INFO - '--xperf_path', 03:43:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:43:43 INFO - 'tests': ['tp5n']}}} 03:43:43 INFO - Running post-action listener: _resource_record_post_action 03:43:43 INFO - [mozharness: 2017-09-27 10:43:43.968300Z] Finished populate-webroot step (success) 03:43:43 INFO - [mozharness: 2017-09-27 10:43:43.968390Z] Running create-virtualenv step. 03:43:43 INFO - Running pre-action listener: _resource_record_pre_action 03:43:43 INFO - Running main action method: create_virtualenv 03:43:43 INFO - Creating virtualenv /builds/slave/test/build/venv 03:43:43 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:43:43 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:43:43 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:43:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:43:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:43:48 INFO - Installing distribute.............................................................................................................................................................................................done. 03:43:49 INFO - Installing pip...............done. 03:43:49 INFO - Return code: 0 03:43:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:43:49 INFO - Reading from file tmpfile_stdout 03:43:49 INFO - Output received: 03:43:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:43:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:43:49 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')]} 03:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:49 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')]} 03:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:49 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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a78b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f440>, '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 0x1c20130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1a320>, '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': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', '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 03:43:49 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 03:43:49 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 03:43:49 INFO - Using env: {'DISPLAY': ':0', 03:43:49 INFO - 'HOME': '/home/cltbld', 03:43:49 INFO - 'LANG': 'en_US.UTF-8', 03:43:49 INFO - 'LANGUAGE': 'en_US:en', 03:43:49 INFO - 'LOGNAME': 'cltbld', 03:43:49 INFO - 'MAIL': '/var/mail/cltbld', 03:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:49 INFO - 'MOZ_NO_REMOTE': '1', 03:43:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:49 INFO - 'NO_EM_RESTART': '1', 03:43:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:49 INFO - 'PWD': '/builds/slave/test', 03:43:49 INFO - 'SHELL': '/bin/bash', 03:43:49 INFO - 'SHLVL': '1', 03:43:49 INFO - 'TERM': 'linux', 03:43:49 INFO - 'TMOUT': '86400', 03:43:49 INFO - 'USER': 'cltbld', 03:43:49 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', 03:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:49 INFO - '_': '/tools/buildbot/bin/python'} 03:43:53 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:43:53 INFO - Downloading/unpacking pip>=1.5 03:43:53 INFO - Running setup.py egg_info for package pip 03:43:53 INFO - warning: no files found matching 'pip/cacert.pem' 03:43:53 INFO - warning: no files found matching '*.html' under directory 'docs' 03:43:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:43:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:43:53 INFO - Installing collected packages: pip 03:43:53 INFO - Found existing installation: pip 1.1 03:43:53 INFO - Uninstalling pip: 03:43:53 INFO - Successfully uninstalled pip 03:43:53 INFO - Running setup.py install for pip 03:43:53 INFO - warning: no files found matching 'pip/cacert.pem' 03:43:53 INFO - warning: no files found matching '*.html' under directory 'docs' 03:43:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:43:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:43:53 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:43:53 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:43:53 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:43:53 INFO - Successfully installed pip 03:43:53 INFO - Cleaning up... 03:43:53 INFO - Return code: 0 03:43:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:43:53 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')]} 03:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:43:53 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')]} 03:43:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:43:53 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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a78b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f440>, '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 0x1c20130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1a320>, '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': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', '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 03:43:53 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 03:43:53 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 03:43:53 INFO - Using env: {'DISPLAY': ':0', 03:43:53 INFO - 'HOME': '/home/cltbld', 03:43:53 INFO - 'LANG': 'en_US.UTF-8', 03:43:53 INFO - 'LANGUAGE': 'en_US:en', 03:43:53 INFO - 'LOGNAME': 'cltbld', 03:43:53 INFO - 'MAIL': '/var/mail/cltbld', 03:43:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:43:53 INFO - 'MOZ_NO_REMOTE': '1', 03:43:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:43:53 INFO - 'NO_EM_RESTART': '1', 03:43:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:43:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:43:53 INFO - 'PWD': '/builds/slave/test', 03:43:53 INFO - 'SHELL': '/bin/bash', 03:43:53 INFO - 'SHLVL': '1', 03:43:53 INFO - 'TERM': 'linux', 03:43:53 INFO - 'TMOUT': '86400', 03:43:53 INFO - 'USER': 'cltbld', 03:43:53 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', 03:43:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:43:53 INFO - '_': '/tools/buildbot/bin/python'} 03:43:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:43:56 INFO - Downloading/unpacking psutil>=3.1.1 03:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:43:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:43:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:43:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:43:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:43:57 INFO - Installing collected packages: psutil 03:43:57 INFO - Running setup.py install for psutil 03:43:57 INFO - building 'psutil._psutil_linux' extension 03:43:57 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 03:43:59 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 03:43:59 INFO - building 'psutil._psutil_posix' extension 03:43:59 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 03:44: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 03:44:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:44:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:44:00 INFO - Successfully installed psutil 03:44:00 INFO - Cleaning up... 03:44:00 INFO - Return code: 0 03:44:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:44: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')]} 03:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44: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')]} 03:44:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44: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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a78b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f440>, '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 0x1c20130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1a320>, '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': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', '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 03:44: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 03:44: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 03:44:00 INFO - Using env: {'DISPLAY': ':0', 03:44:00 INFO - 'HOME': '/home/cltbld', 03:44:00 INFO - 'LANG': 'en_US.UTF-8', 03:44:00 INFO - 'LANGUAGE': 'en_US:en', 03:44:00 INFO - 'LOGNAME': 'cltbld', 03:44:00 INFO - 'MAIL': '/var/mail/cltbld', 03:44:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:00 INFO - 'MOZ_NO_REMOTE': '1', 03:44:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:00 INFO - 'NO_EM_RESTART': '1', 03:44:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:00 INFO - 'PWD': '/builds/slave/test', 03:44:00 INFO - 'SHELL': '/bin/bash', 03:44:00 INFO - 'SHLVL': '1', 03:44:00 INFO - 'TERM': 'linux', 03:44:00 INFO - 'TMOUT': '86400', 03:44:00 INFO - 'USER': 'cltbld', 03:44:00 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', 03:44:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:00 INFO - '_': '/tools/buildbot/bin/python'} 03:44:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:44:00 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 03:44:00 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 03:44:00 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 03:44:00 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 03:44:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:44:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:44:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:44:01 INFO - Installing collected packages: mozsystemmonitor 03:44:01 INFO - Running setup.py install for mozsystemmonitor 03:44:01 INFO - Successfully installed mozsystemmonitor 03:44:01 INFO - Cleaning up... 03:44:01 INFO - Return code: 0 03:44:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:44:01 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')]} 03:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:01 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')]} 03:44:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:01 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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a78b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f440>, '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 0x1c20130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1a320>, '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': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', '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 03:44:01 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 03:44:01 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 03:44:01 INFO - Using env: {'DISPLAY': ':0', 03:44:01 INFO - 'HOME': '/home/cltbld', 03:44:01 INFO - 'LANG': 'en_US.UTF-8', 03:44:01 INFO - 'LANGUAGE': 'en_US:en', 03:44:01 INFO - 'LOGNAME': 'cltbld', 03:44:01 INFO - 'MAIL': '/var/mail/cltbld', 03:44:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:01 INFO - 'MOZ_NO_REMOTE': '1', 03:44:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:01 INFO - 'NO_EM_RESTART': '1', 03:44:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:01 INFO - 'PWD': '/builds/slave/test', 03:44:01 INFO - 'SHELL': '/bin/bash', 03:44:01 INFO - 'SHLVL': '1', 03:44:01 INFO - 'TERM': 'linux', 03:44:01 INFO - 'TMOUT': '86400', 03:44:01 INFO - 'USER': 'cltbld', 03:44:01 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', 03:44:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:01 INFO - '_': '/tools/buildbot/bin/python'} 03:44:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:01 INFO - Downloading/unpacking jsonschema==2.5.1 03:44: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 03:44: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 03:44: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 03:44: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 03:44:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:44:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:44: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 03:44: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 03:44: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 03:44: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 03:44:03 INFO - Downloading functools32-3.2.3-2.tar.gz 03:44:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:44:03 INFO - warning: no files found matching '*.txt' 03:44:03 INFO - no previously-included directories found matching 'build' 03:44:03 INFO - no previously-included directories found matching 'dist' 03:44:03 INFO - no previously-included directories found matching '.git*' 03:44:03 INFO - Installing collected packages: jsonschema, functools32 03:44:03 INFO - Running setup.py install for functools32 03:44:03 INFO - warning: no files found matching '*.txt' 03:44:03 INFO - no previously-included directories found matching 'build' 03:44:03 INFO - no previously-included directories found matching 'dist' 03:44:03 INFO - no previously-included directories found matching '.git*' 03:44:03 INFO - Successfully installed jsonschema functools32 03:44:03 INFO - Cleaning up... 03:44:03 INFO - Return code: 0 03:44:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:44:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:03 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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a78b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f440>, '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 0x1c20130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1a320>, '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': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', '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 03:44:03 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 03:44:03 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 03:44:03 INFO - Using env: {'DISPLAY': ':0', 03:44:03 INFO - 'HOME': '/home/cltbld', 03:44:03 INFO - 'LANG': 'en_US.UTF-8', 03:44:03 INFO - 'LANGUAGE': 'en_US:en', 03:44:03 INFO - 'LOGNAME': 'cltbld', 03:44:03 INFO - 'MAIL': '/var/mail/cltbld', 03:44:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:03 INFO - 'MOZ_NO_REMOTE': '1', 03:44:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:03 INFO - 'NO_EM_RESTART': '1', 03:44:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:03 INFO - 'PWD': '/builds/slave/test', 03:44:03 INFO - 'SHELL': '/bin/bash', 03:44:03 INFO - 'SHLVL': '1', 03:44:03 INFO - 'TERM': 'linux', 03:44:03 INFO - 'TMOUT': '86400', 03:44:03 INFO - 'USER': 'cltbld', 03:44:03 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', 03:44:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:03 INFO - '_': '/tools/buildbot/bin/python'} 03:44:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:44:03 INFO - Cleaning up... 03:44:03 INFO - Return code: 0 03:44:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:44:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:03 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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a78b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f440>, '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 0x1c20130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1a320>, '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': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', '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 03:44:03 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 03:44:03 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 03:44:03 INFO - Using env: {'DISPLAY': ':0', 03:44:03 INFO - 'HOME': '/home/cltbld', 03:44:03 INFO - 'LANG': 'en_US.UTF-8', 03:44:03 INFO - 'LANGUAGE': 'en_US:en', 03:44:03 INFO - 'LOGNAME': 'cltbld', 03:44:03 INFO - 'MAIL': '/var/mail/cltbld', 03:44:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:03 INFO - 'MOZ_NO_REMOTE': '1', 03:44:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:03 INFO - 'NO_EM_RESTART': '1', 03:44:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:03 INFO - 'PWD': '/builds/slave/test', 03:44:03 INFO - 'SHELL': '/bin/bash', 03:44:03 INFO - 'SHLVL': '1', 03:44:03 INFO - 'TERM': 'linux', 03:44:03 INFO - 'TMOUT': '86400', 03:44:03 INFO - 'USER': 'cltbld', 03:44:03 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', 03:44:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:03 INFO - '_': '/tools/buildbot/bin/python'} 03:44:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:03 INFO - Downloading/unpacking blobuploader==1.2.4 03:44: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 03:44: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 03:44: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 03:44: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 03:44:04 INFO - Downloading blobuploader-1.2.4.tar.gz 03:44:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:44:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:44: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 03:44: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 03:44: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 03:44: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 03:44:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:44:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:44: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 03:44: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 03:44: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 03:44: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 03:44:05 INFO - Downloading docopt-0.6.1.tar.gz 03:44:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:44:05 INFO - Installing collected packages: blobuploader, requests, docopt 03:44:05 INFO - Running setup.py install for blobuploader 03:44:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:44:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:44:05 INFO - Running setup.py install for requests 03:44:05 INFO - Running setup.py install for docopt 03:44:06 INFO - Successfully installed blobuploader requests docopt 03:44:06 INFO - Cleaning up... 03:44:06 INFO - Return code: 0 03:44:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:44: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')]} 03:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44: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')]} 03:44:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44: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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a78b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f440>, '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 0x1c20130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1a320>, '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': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', '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 03:44: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 03:44: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 03:44:06 INFO - Using env: {'DISPLAY': ':0', 03:44:06 INFO - 'HOME': '/home/cltbld', 03:44:06 INFO - 'LANG': 'en_US.UTF-8', 03:44:06 INFO - 'LANGUAGE': 'en_US:en', 03:44:06 INFO - 'LOGNAME': 'cltbld', 03:44:06 INFO - 'MAIL': '/var/mail/cltbld', 03:44:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:06 INFO - 'MOZ_NO_REMOTE': '1', 03:44:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:06 INFO - 'NO_EM_RESTART': '1', 03:44:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:06 INFO - 'PWD': '/builds/slave/test', 03:44:06 INFO - 'SHELL': '/bin/bash', 03:44:06 INFO - 'SHLVL': '1', 03:44:06 INFO - 'TERM': 'linux', 03:44:06 INFO - 'TMOUT': '86400', 03:44:06 INFO - 'USER': 'cltbld', 03:44:06 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', 03:44:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:06 INFO - '_': '/tools/buildbot/bin/python'} 03:44:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:44:06 INFO - Running setup.py (path:/tmp/pip-pSV2Gs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:44:06 INFO - Running setup.py (path:/tmp/pip-FkKUap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:44:06 INFO - Running setup.py (path:/tmp/pip-UtXrLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:44:06 INFO - Running setup.py (path:/tmp/pip-UYIzSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:44:06 INFO - Running setup.py (path:/tmp/pip-85tlJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:44:06 INFO - Running setup.py (path:/tmp/pip-RZyB0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:44:06 INFO - Running setup.py (path:/tmp/pip-Ya8EHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:44:06 INFO - Running setup.py (path:/tmp/pip-Wz70EK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:44:06 INFO - Running setup.py (path:/tmp/pip-1cT62o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:44:06 INFO - Running setup.py (path:/tmp/pip-esAHf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:44:07 INFO - Running setup.py (path:/tmp/pip-bCWwwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:44:07 INFO - Running setup.py (path:/tmp/pip-A9TxCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:44:07 INFO - Running setup.py (path:/tmp/pip-4buCbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:44:07 INFO - Running setup.py (path:/tmp/pip-VhPODi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:07 INFO - Running setup.py (path:/tmp/pip-JinafR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:44:07 INFO - Running setup.py (path:/tmp/pip-JLY3N7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:44:07 INFO - Running setup.py (path:/tmp/pip-lJonBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:44:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:44:07 INFO - Running setup.py install for manifestparser 03:44:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:44:07 INFO - Running setup.py install for mozcrash 03:44:07 INFO - Running setup.py install for mozdebug 03:44:07 INFO - Running setup.py install for mozdevice 03:44:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:44:07 INFO - Running setup.py install for mozfile 03:44:08 INFO - Running setup.py install for mozhttpd 03:44:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:44:08 INFO - Running setup.py install for mozinfo 03:44:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:44:08 INFO - Running setup.py install for mozInstall 03:44:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:44:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:44:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:44:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:44:08 INFO - Running setup.py install for mozleak 03:44:08 INFO - Running setup.py install for mozlog 03:44:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:44:08 INFO - Running setup.py install for moznetwork 03:44:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:44:08 INFO - Running setup.py install for mozprocess 03:44:08 INFO - Running setup.py install for mozprofile 03:44:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:44:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:44:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:44:08 INFO - Running setup.py install for mozrunner 03:44:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:44:08 INFO - Running setup.py install for mozscreenshot 03:44:09 INFO - Running setup.py install for moztest 03:44:09 INFO - Running setup.py install for mozversion 03:44:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:44:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:44:09 INFO - Cleaning up... 03:44:09 INFO - Return code: 0 03:44:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:44: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')]} 03:44:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44: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')]} 03:44:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44: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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a78b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f440>, '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 0x1c20130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1a320>, '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': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', '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 03:44: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 03:44: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 03:44:09 INFO - Using env: {'DISPLAY': ':0', 03:44:09 INFO - 'HOME': '/home/cltbld', 03:44:09 INFO - 'LANG': 'en_US.UTF-8', 03:44:09 INFO - 'LANGUAGE': 'en_US:en', 03:44:09 INFO - 'LOGNAME': 'cltbld', 03:44:09 INFO - 'MAIL': '/var/mail/cltbld', 03:44:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:09 INFO - 'MOZ_NO_REMOTE': '1', 03:44:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:09 INFO - 'NO_EM_RESTART': '1', 03:44:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:09 INFO - 'PWD': '/builds/slave/test', 03:44:09 INFO - 'SHELL': '/bin/bash', 03:44:09 INFO - 'SHLVL': '1', 03:44:09 INFO - 'TERM': 'linux', 03:44:09 INFO - 'TMOUT': '86400', 03:44:09 INFO - 'USER': 'cltbld', 03:44:09 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', 03:44:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:09 INFO - '_': '/tools/buildbot/bin/python'} 03:44:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:44:09 INFO - Running setup.py (path:/tmp/pip-OJa86L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:44:09 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)) 03:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:44:09 INFO - Running setup.py (path:/tmp/pip-9Lw7jf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:44:09 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)) 03:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:44:09 INFO - Running setup.py (path:/tmp/pip-zZBGLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:44:09 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)) 03:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:44:09 INFO - Running setup.py (path:/tmp/pip-jPNIWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:44:09 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)) 03:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:44:09 INFO - Running setup.py (path:/tmp/pip-WfvjM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:44:09 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)) 03:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:44:09 INFO - Running setup.py (path:/tmp/pip-d0YO36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:44:09 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)) 03:44:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:44:09 INFO - Running setup.py (path:/tmp/pip-CQZkVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:44: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)) 03:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:44:10 INFO - Running setup.py (path:/tmp/pip-4U5_WG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:44: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)) 03:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:44:10 INFO - Running setup.py (path:/tmp/pip-tlVrXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:44: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)) 03:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:44:10 INFO - Running setup.py (path:/tmp/pip-vj6Xny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:44: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)) 03:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:44:10 INFO - Running setup.py (path:/tmp/pip-J4FXRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:44: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)) 03:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:44:10 INFO - Running setup.py (path:/tmp/pip-2wMqYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:44:10 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)) 03:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:44:10 INFO - Running setup.py (path:/tmp/pip-fpGl38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:44:10 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)) 03:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:44:10 INFO - Running setup.py (path:/tmp/pip-5LYdrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:44:10 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)) 03:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:10 INFO - Running setup.py (path:/tmp/pip-14uekz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:44:10 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)) 03:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:44:10 INFO - Running setup.py (path:/tmp/pip-E2fuW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:44:10 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)) 03:44:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:44:10 INFO - Running setup.py (path:/tmp/pip-RoSjCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:44:10 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)) 03:44:10 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)) 03:44:10 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)) 03:44:10 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)) 03:44:10 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)) 03:44:10 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)) 03:44:10 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)) 03:44:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:44:10 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 03:44:10 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 03:44:10 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 03:44:10 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 03:44:11 INFO - Downloading blessings-1.6.tar.gz 03:44:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:44:11 INFO - Installing collected packages: blessings 03:44:11 INFO - Running setup.py install for blessings 03:44:12 INFO - Successfully installed blessings 03:44:12 INFO - Cleaning up... 03:44:12 INFO - Return code: 0 03:44:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:44:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:44:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:44:12 INFO - Reading from file tmpfile_stdout 03:44:12 INFO - Current package versions: 03:44:12 INFO - argparse == 1.2.1 03:44:12 INFO - blessings == 1.6 03:44:12 INFO - blobuploader == 1.2.4 03:44:12 INFO - docopt == 0.6.1 03:44:12 INFO - functools32 == 3.2.3-2 03:44:12 INFO - jsonschema == 2.5.1 03:44:12 INFO - manifestparser == 1.1 03:44:12 INFO - mozInstall == 1.13 03:44:12 INFO - mozcrash == 1.0 03:44:12 INFO - mozdebug == 0.1 03:44:12 INFO - mozdevice == 0.51 03:44:12 INFO - mozfile == 1.2 03:44:12 INFO - mozhttpd == 0.7 03:44:12 INFO - mozinfo == 0.10 03:44:12 INFO - mozleak == 0.1 03:44:12 INFO - mozlog == 3.5 03:44:12 INFO - moznetwork == 0.27 03:44:12 INFO - mozprocess == 0.25 03:44:12 INFO - mozprofile == 0.28 03:44:12 INFO - mozrunner == 6.13 03:44:12 INFO - mozscreenshot == 0.1 03:44:12 INFO - mozsystemmonitor == 0.3 03:44:12 INFO - moztest == 0.8 03:44:12 INFO - mozversion == 1.4 03:44:12 INFO - psutil == 3.1.1 03:44:12 INFO - requests == 1.2.3 03:44:12 INFO - wsgiref == 0.1.2 03:44:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:44: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')]} 03:44:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44: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')]} 03:44:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:12 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 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a78b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f440>, '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 0x1c20130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1a320>, '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': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', '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 03:44:12 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 03:44:12 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 03:44:12 INFO - Using env: {'DISPLAY': ':0', 03:44:12 INFO - 'HOME': '/home/cltbld', 03:44:12 INFO - 'LANG': 'en_US.UTF-8', 03:44:12 INFO - 'LANGUAGE': 'en_US:en', 03:44:12 INFO - 'LOGNAME': 'cltbld', 03:44:12 INFO - 'MAIL': '/var/mail/cltbld', 03:44:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:12 INFO - 'MOZ_NO_REMOTE': '1', 03:44:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:12 INFO - 'NO_EM_RESTART': '1', 03:44:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:12 INFO - 'PWD': '/builds/slave/test', 03:44:12 INFO - 'SHELL': '/bin/bash', 03:44:12 INFO - 'SHLVL': '1', 03:44:12 INFO - 'TERM': 'linux', 03:44:12 INFO - 'TMOUT': '86400', 03:44:12 INFO - 'USER': 'cltbld', 03:44:12 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', 03:44:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:12 INFO - '_': '/tools/buildbot/bin/python'} 03:44:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:12 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)) 03:44:12 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)) 03:44:12 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)) 03:44:12 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)) 03:44:12 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)) 03:44:12 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)) 03:44:12 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)) 03:44:12 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)) 03:44:12 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)) 03:44:12 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:44:12 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 03:44:12 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 03:44:12 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 03:44:12 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 03:44:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:44:13 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)) 03:44:13 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)) 03:44:13 INFO - Installing collected packages: simplejson 03:44:13 INFO - Running setup.py install for simplejson 03:44:13 INFO - building 'simplejson._speedups' extension 03:44:13 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 03:44:14 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 03:44:14 INFO - Successfully installed simplejson 03:44:14 INFO - Cleaning up... 03:44:14 INFO - Return code: 0 03:44:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:44:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:44:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18b01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a78b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c1bdf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c1f440>, '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 0x1c20130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1a320>, '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': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', '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 03:44:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:44:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:44:14 INFO - Using env: {'DISPLAY': ':0', 03:44:14 INFO - 'HOME': '/home/cltbld', 03:44:14 INFO - 'LANG': 'en_US.UTF-8', 03:44:14 INFO - 'LANGUAGE': 'en_US:en', 03:44:14 INFO - 'LOGNAME': 'cltbld', 03:44:14 INFO - 'MAIL': '/var/mail/cltbld', 03:44:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:14 INFO - 'MOZ_NO_REMOTE': '1', 03:44:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:14 INFO - 'NO_EM_RESTART': '1', 03:44:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:14 INFO - 'PWD': '/builds/slave/test', 03:44:14 INFO - 'SHELL': '/bin/bash', 03:44:14 INFO - 'SHLVL': '1', 03:44:14 INFO - 'TERM': 'linux', 03:44:14 INFO - 'TMOUT': '86400', 03:44:14 INFO - 'USER': 'cltbld', 03:44:14 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', 03:44:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:14 INFO - '_': '/tools/buildbot/bin/python'} 03:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:44:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:44:15 INFO - Cleaning up... 03:44:15 INFO - Return code: 0 03:44:15 INFO - Running post-action listener: _resource_record_post_action 03:44:15 INFO - Running post-action listener: _start_resource_monitoring 03:44:15 INFO - Starting resource monitoring. 03:44:15 INFO - [mozharness: 2017-09-27 10:44:15.078950Z] Finished create-virtualenv step (success) 03:44:15 INFO - [mozharness: 2017-09-27 10:44:15.079944Z] Running install step. 03:44:15 INFO - Running pre-action listener: _resource_record_pre_action 03:44:15 INFO - Running main action method: install 03:44:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:44:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:44:15 INFO - Reading from file tmpfile_stdout 03:44:15 INFO - Detecting whether we're running mozinstall >=1.0... 03:44:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:44:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:44:15 INFO - Reading from file tmpfile_stdout 03:44:15 INFO - Output received: 03:44:15 INFO - Usage: mozinstall [options] installer 03:44:15 INFO - Options: 03:44:15 INFO - -h, --help show this help message and exit 03:44:15 INFO - -d DEST, --destination=DEST 03:44:15 INFO - Directory to install application into. [default: 03:44:15 INFO - "/builds/slave/test"] 03:44:15 INFO - --app=APP Application being installed. [default: firefox] 03:44:15 INFO - mkdir: /builds/slave/test/build/application 03:44:15 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'] 03:44:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:44:33 INFO - Reading from file tmpfile_stdout 03:44:33 INFO - Output received: 03:44:33 INFO - /builds/slave/test/build/application/firefox/firefox 03:44:33 INFO - Running post-action listener: _resource_record_post_action 03:44:33 INFO - [mozharness: 2017-09-27 10:44:33.224800Z] Finished install step (success) 03:44:33 INFO - [mozharness: 2017-09-27 10:44:33.224945Z] Running setup-mitmproxy step. 03:44:33 INFO - Running pre-action listener: _resource_record_pre_action 03:44:33 INFO - Running main action method: setup_mitmproxy 03:44:33 INFO - Skipping: mitmproxy is not required 03:44:33 INFO - Running post-action listener: _resource_record_post_action 03:44:33 INFO - [mozharness: 2017-09-27 10:44:33.225773Z] Finished setup-mitmproxy step (success) 03:44:33 INFO - [mozharness: 2017-09-27 10:44:33.225995Z] Running run-tests step. 03:44:33 INFO - Running pre-action listener: _resource_record_pre_action 03:44:33 INFO - Running pre-action listener: _set_gcov_prefix 03:44:33 INFO - Running main action method: run_tests 03:44:33 WARNING - Try message not found. 03:44:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:44:33 INFO - Python 2.7.3 03:44:33 INFO - Return code: 0 03:44:33 INFO - grabbing minidump binary from tooltool 03:44: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')]} 03:44: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 0x1c1f440>, '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 0x1c20130>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c1a320>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:44: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 03:44: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 03:44: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 03:44:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:44:33 INFO - Return code: 0 03:44:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:44:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:44:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:44:33 INFO - ENV: RUST_BACKTRACE is now full 03:44:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:44:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:44:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 03:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 03:44:33 INFO - Using env: {'DISPLAY': ':0', 03:44:33 INFO - 'HOME': '/home/cltbld', 03:44:33 INFO - 'LANG': 'en_US.UTF-8', 03:44:33 INFO - 'LANGUAGE': 'en_US:en', 03:44:33 INFO - 'LOGNAME': 'cltbld', 03:44:33 INFO - 'MAIL': '/var/mail/cltbld', 03:44:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:44:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:44:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:33 INFO - 'MOZ_NO_REMOTE': '1', 03:44:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:44:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:33 INFO - 'NO_EM_RESTART': '1', 03:44:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:33 INFO - 'PWD': '/builds/slave/test', 03:44:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:44:33 INFO - 'RUST_BACKTRACE': 'full', 03:44:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:44:33 INFO - 'SHELL': '/bin/bash', 03:44:33 INFO - 'SHLVL': '1', 03:44:33 INFO - 'TERM': 'linux', 03:44:33 INFO - 'TMOUT': '86400', 03:44:33 INFO - 'USER': 'cltbld', 03:44:33 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', 03:44:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:33 INFO - '_': '/tools/buildbot/bin/python'} 03:44:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 03:44:34 INFO - mozversion application_buildid: 20170927094813 03:44:34 INFO - mozversion application_changeset: 35fbf14b96a633c3f66ea13c1a163a3f3a4219b9 03:44:34 INFO - mozversion application_display_name: Nightly 03:44:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:44:34 INFO - mozversion application_name: Firefox 03:44:34 INFO - mozversion application_remotingname: firefox 03:44:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:44:34 INFO - mozversion application_vendor: Mozilla 03:44:34 INFO - mozversion application_version: 58.0a1 03:44:34 INFO - mozversion platform_buildid: 20170927094813 03:44:34 INFO - mozversion platform_changeset: 35fbf14b96a633c3f66ea13c1a163a3f3a4219b9 03:44:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:44:34 INFO - mozversion platform_version: 58.0a1 03:44:34 INFO - using testdate: 1506509074 03:44:34 INFO - actual date: 1506509074 03:44:34 INFO - starting webserver on 'localhost:49708' 03:44:34 INFO - SUITE-START | Running 2 tests 03:44:34 INFO - TEST-START | dromaeo_css 03:44:34 INFO - Initialising browser for dromaeo_css test... 03:44:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49708/getInfo.html -profile /tmp/tmpY5uxB_/profile 03:44:34 INFO - TEST-INFO | started process 3588 (/builds/slave/test/build/application/firefox/firefox http://localhost:49708/getInfo.html) 03:44:44 INFO - TEST-INFO | 3588: exit 0 03:44:44 INFO - Browser initialized. 03:44:44 INFO - Running cycle 1/1 for dromaeo_css test... 03:44:44 INFO - Using env: {'DISPLAY': ':0', 03:44:44 INFO - 'HOME': '/home/cltbld', 03:44:44 INFO - 'JSGC_DISABLE_POISONING': '1', 03:44:44 INFO - 'LANG': 'en_US.UTF-8', 03:44:44 INFO - 'LANGUAGE': 'en_US:en', 03:44:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:44:44 INFO - 'LOGNAME': 'cltbld', 03:44:44 INFO - 'MAIL': '/var/mail/cltbld', 03:44:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:44:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:44:44 INFO - 'MOZ_CRASHREPORTER': '1', 03:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:44:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:44:44 INFO - 'MOZ_NO_REMOTE': '1', 03:44:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:44:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:44:44 INFO - 'NO_EM_RESTART': '1', 03:44:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:44:44 INFO - 'PWD': '/builds/slave/test', 03:44:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:44:44 INFO - 'RUST_BACKTRACE': 'full', 03:44:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:44:44 INFO - 'SHELL': '/bin/bash', 03:44:44 INFO - 'SHLVL': '1', 03:44:44 INFO - 'TERM': 'linux', 03:44:44 INFO - 'TMOUT': '86400', 03:44:44 INFO - 'USER': 'cltbld', 03:44:44 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', 03:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:44:44 INFO - '_': '/tools/buildbot/bin/python'} 03:44:44 INFO - TEST-INFO | started process 3822 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY5uxB_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:44:45 INFO - PID 3822 | 03:44:45 INFO - PID 3822 | (/builds/slave/test/build/application/firefox/firefox:3871): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:44:45 INFO - PID 3822 | 03:44:55 INFO - PID 3822 | RSS: Main: 148250624 03:44:55 INFO - PID 3822 | 03:47:53 INFO - PID 3822 | Cycle 1(1): loaded http://localhost:49708/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49708/tests/dromaeo/cssquery-ext.html) 03:47:54 INFO - PID 3822 | RSS: Main: 152354816 03:47:54 INFO - PID 3822 | 03:50:56 INFO - PID 3822 | Cycle 1(1): loaded http://localhost:49708/tests/dromaeo/cssquery-ext.html (next: http://localhost:49708/tests/dromaeo/cssquery-jquery.html) 03:50:57 INFO - PID 3822 | RSS: Main: 152760320 03:50:57 INFO - PID 3822 | 03:53:58 INFO - PID 3822 | Cycle 1(1): loaded http://localhost:49708/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49708/tests/dromaeo/cssquery-mootools.html) 03:53:59 INFO - PID 3822 | RSS: Main: 155258880 03:53:59 INFO - PID 3822 | 03:57:01 INFO - PID 3822 | Cycle 1(1): loaded http://localhost:49708/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49708/tests/dromaeo/cssquery-prototype.html) 03:57:01 INFO - PID 3822 | RSS: Main: 153612288 03:57:01 INFO - PID 3822 | 04:00:05 INFO - PID 3822 | Cycle 1(1): loaded http://localhost:49708/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49708/tests/dromaeo/cssquery-yui.html) 04:00:05 INFO - PID 3822 | RSS: Main: 154292224 04:00:05 INFO - PID 3822 | 04:03:07 INFO - PID 3822 | Cycle 1(1): loaded http://localhost:49708/tests/dromaeo/cssquery-yui.html (next: http://localhost:49708/tests/dromaeo/cssquery-dojo.html) 04:03:07 INFO - PID 3822 | RSS: Main: 156938240 04:03:07 INFO - PID 3822 | 04:03:07 INFO - PID 3822 | __start_tp_report 04:03:07 INFO - PID 3822 | _x_x_mozilla_page_load 04:03:07 INFO - PID 3822 | _x_x_mozilla_page_load_details 04:03:07 INFO - PID 3822 | |i|pagename|runs| 04:03:07 INFO - PID 3822 | |0;dojo.html;3175.079227024163;3181.125190572593;3205.807651540906;3243.8864639737612;3253.446914024617;6235.0959110929;6343.460805831504;6365.108884756138;6383.968080159599;6451.903221451682;6261.3425590313045;6302.054691796233;6313.747450101991;6326.905096423558;6370.394812492806;5808.041673123929;5874.647521148723;5874.911876321869;5897.793577224798;5912.17229587858;5693.77224911003;5865.530757539386;5877.617954832922;5953.970230148865;5964.2246507953905;5728.541716662678;5763.855903602402;5831.125331200312;5837.6497410155525;5843.795467158671;5731.971340143273;5756.769729210869;5802.564807639402;5813.883722325572;5824.796132135355;5692.089265717506;5764.538836893039;5766.769329226826;5780.971095144498;5795.88408231833;6240.469560087353;6245.406686364833;6260.029695397221;6276.340984196685;6319.74721011159;6701.530892837503;6719.93280067198;6900.723971041152;6908.7236510539515;6927.307269273083;6896;6898.448124150106;6968.965155174192;6995.125609298838;6997.160340759141;6195.628262304276;6288.465480434214;6305;6380.649064301461;6445.162128923257;6807.65961701913;6880.8967865482055;6928.44572434204;6933.37599616037;6958.79123626292;6631.038499417557;6733.0237115617965;6744.190697116377;6764.763233286812;6778.423833974069;6832.931670683329;6835.418989385858;6871.587704737732;6924.99587559801;6937.826554336081;6802.523823332319;6804.523683342119;6869.072675188786;6889.931100688929;6895.655217239118;6647.634380109141;6725.9663701681175;6751.392374686204;6754.864902701918;6772.322767723287;5247.632665713363;5253.50091741284;5267.130923397596;5269.947300526946;5272.947270527246;4617.261238201939;4618.676692631483;4672.322513235562;4704.882377940579;4717.8348757794;5870.73581688825;5872.265966754156;5936.821895343147;5949.10763385487;5959.046552551657;6863.862722745517;6866.313368663094;6930.93069069313;6950.617716025566;6957.965210173917;5679.545636349082;5810.070388737781;5811.041178205549;5819.156222347736;5918.644881307136;5725.227094342294;5817.534597232211;5914.497267732205;5916.674582897982;5926.199963005025;6642.169728783902;6677.098591690156;6678.3989440950545;6715.832104197434;6736.6631668416385;6868.828279293058;6869.793906182923;6952.270011648704;6955.269696681877;6983.127109111361;6426.550141490051;6543.901841472477;6571.671416429063;6617.569857959217;6722.1261236039;6766.391024807791;6800.693968771318;6814;6822.079019332326;6822.3859852613505;6775;6877.037214790033;6883.965580172267;6889.483288753263;6907.930920690729;6581.80254592363;6649.03588979605;6658.933410665832;6673.366030227121;6684.097646817618;6522.184726909137;6594.934050659432;6597.406233439014;6666.466682665278;6703.363180497845;6402.903956440564;6413.294537600954;6468.773592924225;6488.610683359014;6499.38255865692;6564.671766411756;6613.801585952237;6613.867722645617;6677.866442671217;6707.865842683216;6316.305206427382;6406.23125224976;6429.164208652845;6462.192225971754;6464.385883341075 04:03:07 INFO - PID 3822 | |1;ext.html;852.4459101583972;877.793718476158;881.6473410635745;885.1281487734584;886.6586364249762;41427.37858932118;42731.359029614505;42803.35794963074;42843.35734963978;43152.568474315245;39533.60466395335;39887.800560997224;39965.80017099918;39992.40011399824;40058;7210.495265331442;7330.8900366494545;7334.1565719942355;7374.299441553044;7374.889376659355;7285.380742636882;7362.66867990939;7381.741639042634;7406.481546291735;7407.333339999399;7357.632118394059;7936.246056624612;8168.060673022589;8191.508509489449;8207.302379297767;8096.959515202416;8143.918560814375;8148.266656000958;8152.38857085721;8155.34757219421;7954.9602251988945;7998.64006119726;8086.070101938235;8094.231048050426;8097.35221182304;37897.62102378969;38263.234735305414;38315;38327.61672383268;38379.424308635396;3235.7573182011206;3258.5112233164978;3259.1363288728508;3265.967340326614;3289.9835500822583;31407.685923140703;31420.371592568248;31550.526742098886;31628.05115846528;31655.683443165504;5275.683458992473;5295.020421222048;5323.680579165262;5327.946720532784;5394.05604019299;187914;189359;189478.05260973744;189626;189757;41855.58144418578;43051.78474107672;48993.26510102351;49288;50080.499195007586;50576.49423505718;50639.24041139386;50663;50709.74645126751;50807.74596126996;15982.680346393006;16014.519564413085;16073.67852642964;16157.838421615868;16160.111193884213;13027.348632568333;13035.674108147372;13056.477740890448;13070.738585228242;13205.53780617693;14099.718005640036;14290.857091429161;14319.498817541336;14335.283235838375;14335.713285734435;5789.363170051291;5858.47273745365;5934.673592952343;5973.283206015219;6021.819345419644;1014.4065721552827;1027.0961553832578;1028.6759670703705;1036.549101141006;1039.8856125826153;22808.543829123657;23083;23154.884225578764;23349.299521014396;23526.176583819826;1116.4752566293828;1116.659418877234;1123.1744667669257;1128.7968165730083;1129.390129330169;12317.260964342167;12365.814512782315;12366.381680915918;12377.009839212842;12383.814242786366;228187;229054;229394;229842;229967;52141.47858521442;52164;52409.2138617921;52425.2136217957;52588.21117683161;1434.12518363798;1465.992670036643;1468.0685164859945;1483.2806089046803;1496.5884381794876;22977.195798146986;22999.88500057489;23055.538889221785;23091.653625195973;23161.76838231596;37631.62368376281;37717.245655087296;37734;37740.62259377371;37837;190460;191777;192261;192263;192748;33868.661313386554;34889.302213955074;36739.63260367362;36750.8162459186;36849.447258291686;45668.54331456643;45835;45836.770816145705;46021.53978460172;46022.53977460317;33420.331593368486;33517.994460166236;33549.329013419105;33559.66440335663;33816.83091584526;32718.50922236121;32932.83533582317;32972.1756956078;33389.49915751314;33918.321633567684;37389;37405.2518949614;37436.812815935744;37731.81134094312;37798.43302350412;31993.200169995936;32009.67990320067;32350.029499115066;32444.83777581097;32642.51036234411;23846.449980751197;24296.785160742245;24350.87824560866;24442.51114977726;24821 04:03:07 INFO - PID 3822 | |2;jquery.html;578.1356871477142;600.5705920267009;606.390577469643;606.4572207873953;611.4558043341425;22190.778092219072;23144.65283020756;23152.65271020936;23179;23190;23313.883430582864;24417.389565260877;25267.241982740547;25436;25502;6538.6076835389795;6694.698738556753;6712.127423434947;6726.899096513532;6727.394534491895;6630.336966303355;6733.764318248862;6753.527253092286;7144.535605185646;7368.521046131984;7331.340179383855;7397.22329155439;7414.814629634248;7434.368078713316;7435.330820226179;7352.154502232258;7357.558546487201;7387.852242955165;7395.8151046224;7444.776656700254;7215.927840721631;7218.133823941161;7266.600336981464;7273.199948005715;7275.636218189123;30103.096907092822;30773.53839692384;31267;31392.21519462009;31474.842625786954;2055.2806517718827;2073.502359433725;2099.5066159452517;2102.9474263143543;2112.0601332407086;20098.799012009986;20666.380008599765;20766.48083797902;20861.79138208629;20882.58234835295;2352.3704389408263;2407.3620490570015;2413.4931664350506;2422.212780846232;2483.962740558893;123577.38211308976;130042;139690;147253.99999999892;147332;21184.89407552983;21464.57070858574;22384.216552420912;22435.87820608963;22444.551108978056;43172.568274317484;43404.56595434069;43446.56553434425;43455.56544434515;43720;11422.885771142182;11901.583444579399;11985.460654270579;12013.459394327276;12099;9860.704178874501;9890.230735394101;9910.702678919644;9982.750431239132;10012.749681258027;11584.884151158549;11825.226216067658;11861.644150675493;12042.518299268106;12066.336351500577;4388.912221755547;4403.647708183337;4435.401220835211;4468.3074123510905;4486.5962063414445;981.2052237687496;984.6996666016934;993.7614972406715;994.7662299359645;1004.6483730694345;19345.709814352795;19871.900640496995;19872.90063549702;20027.09878055491;20094;1050.6270274052754;1053.9631112911165;1058.1111866032415;1060.6234786650782;1065.541817018674;10685.786284274427;10891.83662245067;10914.181436392302;10991.780164396667;11023.44882755859;431586;438223;439980;445712;446310;113179;117229.4138529319;118222.40888795501;122754;124003;1185.2769810415812;1204.6325870609548;1255.5354518828092;1284.6595652152187;1290.161395093197;20860.37418877436;21306.36080917575;21503.892480537495;21557.46106347354;21623.2431864884;62114.68942655258;62488.37511625008;62834.37165628285;64037;64775.35224647882;73112;73331.63334183295;73596;74233.2576674248;77473;14758.040727352687;14997.400103995718;15068.246587670354;15095.924520377328;15118.168500732565;22990.195343162908;23064.308070757903;23165.536689266457;23375.948082336676;23479.530409391376;18637.347692830685;18664.720029199845;18756.249750009847;18814;18947.526311842314;16346.509604711877;16366.672666546841;16581.17094145312;17002.319907203575;17188.14059297055;18915.243390264237;18960.43118706441;19082;19245.326413575458;19361.903190483958;14876;14900.403983840959;14927.328270227648;14953.401863925756;15002.924985375003;18663.160157793205;18698.906505467385;18708.438746837073;18822.341218057303;18868.905655471634 04:03:07 INFO - PID 3822 | |3;mootools.html;1332.300542215337;1421.3461807568517;1434.5337765226304;1436.5762100180445;1441.8702316791487;15588.454404095844;16198.514044578667;16220.513384598466;16221.351145954148;16223.756643650326;15798.210089495537;15874.920625396886;15879;15895.046297222174;16011.519654410356;2773.9167824965284;2791.302174456391;2834.1072562142963;2834.3764371838265;2843.445528122017;2747.1346525844297;2779.138467075203;2794.1058861164433;2795.65054368204;2805.5230610796216;2674.371522692166;2685;2686.570148776196;2837.3757773289944;2838.6593608766975;2789.288731373497;2803.088996076263;2825.3642930340716;2829.4906916754976;2833.9574906376524;2618.2407101940416;2664.267326485213;2665.1338315047688;2680.0351873325585;2683.5840444731098;14926;15241.466548670744;15285.082895026335;15286.694266114615;15287.923560382238;7998.480098793618;8313.792155196108;8406.705765298246;8408;8420.4947703138;5224.216367544861;5413.593980451482;5546.334439867242;5548.72256387179;5555.527780138693;1932.2657390191637;1975.4073777866583;1981.6730239510464;2030.9187632494682;2054.7020682001025;27142.72857271422;27180.728192718016;27272.727272727414;27289.454210915672;27363.17910462689;6854.931450685529;6855.862882742417;6892.104026476526;7074.646267686595;7077.858442831218;17762.37831675911;17778.555536111442;17784.932904025798;17839.643207135785;17858.46424607264;7907;7986.241307075818;7996.840063198703;8061.758147255592;8069.435139540294;7065.964670176616;7237.638118094074;7262.854742905112;7266.237045110187;7420.220876807965;4937.23472861707;5053.5957123430035;5108.259302401206;5182.97408512955;5203.14148165556;1481.8814494840387;1481.9259037048105;1494.118470102623;1510.546835949233;1520.0879472316742;879.2438502887512;898.7483504618717;902.702108304258;906.6871929184421;909.2862103248918;7457.85084298311;7464.85070298591;7471.066116735408;7473.476856620093;7596.08846938371;690.6374153569316;738.9704411823575;739.3973911262304;742.8365759532894;755.0825746717765;4142.668586513072;5041.319421878;5286.788528458896;5350.983313170485;5428;20744;21435.464113396978;21464.14143434245;22000;22031.449213769785;21847.563048739255;22132.446688832584;22373.888130559244;22414.103435862384;22437.21469748551;9601.47191904438;10108.696739097839;10279.434631095211;10300.175985921109;10311.535980880879;7808.84382312362;7862.921370786447;8013.799655008672;8031.116577176389;8041.798955026171;18586.721199182295;18716.719249211546;18721.25114995385;18736.53158671044;19035.714464283323;15860.52418427449;19153.233870645017;19295.51761205981;19385.903070484557;19437.708434373777;10991.120710342993;11250;11360.602378916697;11361.147913906325;11389.658310250707;13866.930665346608;13933.512327068504;14011;14089.15465072099;14093.788593171315;10827.24209305341;11022.338659680445;11068.335899845713;11133.888661113286;11322.32066076038;10579.206559507913;10662.413567253876;10668.679939601825;10795.244332896622;11029.338239705643;9835.508224588884;10189.439580823126;10214.489275536342;10216.335938163995;10220.233482488991;7622.9237707622215;7632.389408847168;7662.540247585162;7674.270944260286;7781.221877812173;7541.208173141741;7581.886271705818;7619.69521219145;7644.885326719992;7686.346660533917 04:03:07 INFO - PID 3822 | |4;prototype.html;518.1812735877313;521.9321488206533;532.9227262047006;544.9863753406165;559.1138046196778;6169.514224463175;6238.407351301625;6238.906416403757;6257.71840267188;6264.154339164212;6127.855250662266;6129.356417576158;6152.323244443119;6206.844828879281;6213.130161777355;4900.142475066867;5096.770645320969;5186.118359878829;5191.688498690071;5204.297419848329;5005.899882002358;5044.571211447032;5044.596432285427;5053.216751403538;5067.619928505358;4987.301777751118;4997.750112494361;5001.7499125043605;5005.47442518534;5038.59691224703;4992.650514463989;5006.349174607296;5019.469061936108;5029.622778291643;5047.974760126176;4553.567411095941;4557;4565.223911934979;4568.314752787075;4599.264117741179;6137.324894261627;6153.1693221415435;6204.596701214452;6242.438180563771;6245;5696.658200507983;5701.942980570183;5743.5118014968775;5778.682172480512;5791.305043394819;6535.967320163416;6546.803595892131;6565.179352580927;6566.93433065668;6615.702293396761;4876.829310974099;4929.556339929423;4931.926021109651;4947.169547267498;4959.817680220692;6878.65606719662;6926.203486599055;6943.201531823855;6946.235914049451;6991.545549539263;5029.195328747456;5034.471380504994;5040.798368065236;5050.242463630485;5056.544910958031;5064.518870707277;5125.4874512549195;5136.357955255593;5148.9742551287;5161.193582246259;5646.6047376684055;5667.716614169275;5696;5730.484256416942;5743.501576814099;6256;6280.748770049238;6436.452901503425;6572.375624315682;6580.934190658032;1735.7136072547887;1758.2791055667371;1761.9119044047745;1767.2842498788011;1770.9468715938542;1033.9586416543405;1199.6341115959717;1240.7034648792035;1244.4213440750095;1247.5758242197514;5605.5515558755205;5719.7426115824055;5754.223179870748;5762.2509073820975;5805.6806875621405;6783.796486105425;6784.932150678529;6809.353111454404;6812.3187681232475;6848.623325717033;5766.769329226784;5776.451237132465;5809.157672137517;5829.912551311733;5839.824805255849;3532.8410221540094;3538.327717733622;3539.6460353964912;3545.503629491873;3549.5917969433845;6378.840528986812;6422.9036564450635;6467.773627923;6478.481721462272;6488.351164883568;6682.828080873735;6715.764948226789;6763.004913550514;6836.658167091725;6863.828404289833;6433.903491447632;6477.611343319416;6491.578047426902;6493.188351456068;6530.118434011348;6509.902351464637;6586.8353291168105;6625.933740662725;6646.634435105922;6697.162854643169;6428;6482.384173503509;6511.902321465276;6523.967380163069;6555.934440655532;6445.16212892321;6459.515536334888;6460.547761656639;6479.125318081999;6481.481481481376;4985.376827896513;5018.573421259234;5025;5035.29505869186;5078.339815824067;6565.540412171103;6633.6019838809825;6667.399934005963;6689.96305572637;6692.464602831859;6414.743410263537;6447.323031081856;6485.675716214265;6507.316731743288;6535.44448721864;6377.808665740035;6433.260175079926;6449.54917131487;6455.193100862392;6535.183102112236;6367.840803979938;6411.326810684811;6448.580842245239;6486.189226346707;6499;6096.695165241809;6427.325130861379;6520.771772987922;6539.182602174728;6544.6727663615675;6180.721867516063;6222.253329600477;6265.997440409513;6267;6335.420487424591 04:03:07 INFO - PID 3822 | |5;yui.html;834.9666013359466;875.8861348024756;904.0159358630693;915.8534634458488;926.2497377124525;17499.912500437513;18107.54731131721;18232.90883545582;18323.083845807723;18326.90836545819;18009.36967206148;18110.4566862994;18177.182026808794;18185.7272140918;18189.18148683309;941.5245301122941;961.2262128986164;965.4207475514705;965.5510187762688;966.2270183852925;965.1169180200144;970.7039352997333;970.912617864392;973.8880028796708;977.1254727019314;999.2855108597352;1027.5016616940777;1037.0148359058887;1040.2562168049842;1043.8799538053145;1019.9031092046243;1026.497016461932;1026.5483187397522;1027.193652982409;1027.6249169053317;718.1992078832117;755.8715018446823;756.6330329790047;759.3431681595436;760.095486371222;20384.796152038438;20592.073356698984;20599.5880082399;20618.072186751633;20631.587368252553;21245.15019399222;21528.784712152836;21955.780442195533;22087.226947056937;22101;17302.740458893128;17404;17458.738118928228;17494.825051749573;17608.823911760846;3772.5472943246714;3791.639794219545;3806.8286927088348;3808.371618682914;3815.236952609489;108886;132940.33529832386;135262;135921;138473.99999999898;17572.297108115534;17969.281228750704;18192.363267285848;18195;18203.635927281644;31358.37283254322;32635.18412039718;32774.3445131096;32814.8359258207;32915.50626740601;9065.184133428023;9238;9274.814503710022;9317.813643727088;9336.346455748033;4252.936205956913;4346.67399944999;4354.042110735633;4354.10740798142;4358.6295164911335;5617.775288988395;5724.742386592613;5737.741801618937;5741.282339707536;5747.195392645033;1119.468252580018;1145.032447581792;1148.6439203846835;1148.9487119285927;1157.0916830288352;618.8298217990089;631.5011141198402;634.5875181132209;643.4241353988155;647.5758378262245;11634.592789252336;12313.753724925451;12423.937880310721;13372.531961381304;13519.526816561372;682.9112215411965;684.1755684400285;686.7287421468492;697.3340459860821;701.2636731431976;5541.695206763667;5630.183623374588;5631.408702086222;5675.460831221028;5727.656340619493;124796;126635;128336.35831820968;129116;129959.35020324838;102810.48594756979;107496.70013199384;108282;110940;111482;92340.53829730809;95149;97705;97987.51006244923;100325.99999999854;10649.877174412508;10700.197485188797;10789.62236321725;10863.619773307897;11012.944935275273;44252.77873610611;44450;45224.32163517484;45471.77264113658;45717.771411142734;58489;58729.4127058724;59589.70205148947;59759;59946.400535995825;15081.095134291978;15512.301946412214;15558.066516009076;15634;15688.764668529753;17352.47942561725;17713;17765.555861103578;17852;17975.820241797413;15559.766603501183;15801.841981580497;15855;15872.206389680701;15883.920580397024;15623.843761562239;15745.291461884472;15841;15880.285387157375;16102.67794644124;14483.855161448251;14688.339024743698;14710.779338310149;14728.55814325572;14738;11993.700157496132;12025.819612705642;12107.636770896886;12125.27248365101;12372.257664540157;12034.157608967289;12047.457864396298;12119.515219391125;12153.270803751802;12225.449854756735 04:03:07 INFO - PID 3822 | __end_tp_report 04:03:07 INFO - PID 3822 | __start_cc_report 04:03:07 INFO - PID 3822 | _x_x_mozilla_cycle_collect,289 04:03:07 INFO - PID 3822 | __end_cc_report 04:03:07 INFO - PID 3822 | __startTimestamp1506510187461__endTimestamp 04:03:07 INFO - PID 3822 | 04:03:07 INFO - PID 3822 | ------- Summary: start ------- 04:03:07 INFO - PID 3822 | Number of tests: 6 04:03:07 INFO - PID 3822 | 04:03:07 INFO - PID 3822 | [#0] dojo.html Cycles:165 Average:6257.81 Median:6464.39 stddev:763.24 (11.8%) stddev-sans-first:726.26 04:03:07 INFO - PID 3822 | Values: 3175.1 3181.1 3205.8 3243.9 3253.4 6235.1 6343.5 6365.1 6384.0 6451.9 6261.3 6302.1 6313.7 6326.9 6370.4 5808.0 5874.6 5874.9 5897.8 5912.2 5693.8 5865.5 5877.6 5954.0 5964.2 5728.5 5763.9 5831.1 5837.6 5843.8 5732.0 5756.8 5802.6 5813.9 5824.8 5692.1 5764.5 5766.8 5781.0 5795.9 6240.5 6245.4 6260.0 6276.3 6319.7 6701.5 6719.9 6900.7 6908.7 6927.3 6896.0 6898.4 6969.0 6995.1 6997.2 6195.6 6288.5 6305.0 6380.6 6445.2 6807.7 6880.9 6928.4 6933.4 6958.8 6631.0 6733.0 6744.2 6764.8 6778.4 6832.9 6835.4 6871.6 6925.0 6937.8 6802.5 6804.5 6869.1 6889.9 6895.7 6647.6 6726.0 6751.4 6754.9 6772.3 5247.6 5253.5 5267.1 5269.9 5272.9 4617.3 4618.7 4672.3 4704.9 4717.8 5870.7 5872.3 5936.8 5949.1 5959.0 6863.9 6866.3 6930.9 6950.6 6958.0 5679.5 5810.1 5811.0 5819.2 5918.6 5725.2 5817.5 5914.5 5916.7 5926.2 6642.2 6677.1 6678.4 6715.8 6736.7 6868.8 6869.8 6952.3 6955.3 6983.1 6426.6 6543.9 6571.7 6617.6 6722.1 6766.4 6800.7 6814.0 6822.1 6822.4 6775.0 6877.0 6884.0 6889.5 6907.9 6581.8 6649.0 6658.9 6673.4 6684.1 6522.2 6594.9 6597.4 6666.5 6703.4 6402.9 6413.3 6468.8 6488.6 6499.4 6564.7 6613.8 6613.9 6677.9 6707.9 6316.3 6406.2 6429.2 6462.2 6464.4 04:03:07 INFO - PID 3822 | 04:03:07 INFO - PID 3822 | [#1] ext.html Cycles:180 Average:37572.53 Median:34403.81 stddev:52870.28 (153.7%) stddev-sans-first:52946.70 04:03:07 INFO - PID 3822 | Values: 852.4 877.8 881.6 885.1 886.7 41427.4 42731.4 42803.4 42843.4 43152.6 39533.6 39887.8 39965.8 39992.4 40058.0 7210.5 7330.9 7334.2 7374.3 7374.9 7285.4 7362.7 7381.7 7406.5 7407.3 7357.6 7936.2 8168.1 8191.5 8207.3 8097.0 8143.9 8148.3 8152.4 8155.3 7955.0 7998.6 8086.1 8094.2 8097.4 37897.6 38263.2 38315.0 38327.6 38379.4 3235.8 3258.5 3259.1 3266.0 3290.0 31407.7 31420.4 31550.5 31628.1 31655.7 5275.7 5295.0 5323.7 5327.9 5394.1 187914.0 189359.0 189478.1 189626.0 189757.0 41855.6 43051.8 48993.3 49288.0 50080.5 50576.5 50639.2 50663.0 50709.7 50807.7 15982.7 16014.5 16073.7 16157.8 16160.1 13027.3 13035.7 13056.5 13070.7 13205.5 14099.7 14290.9 14319.5 14335.3 14335.7 5789.4 5858.5 5934.7 5973.3 6021.8 1014.4 1027.1 1028.7 1036.5 1039.9 22808.5 23083.0 23154.9 23349.3 23526.2 1116.5 1116.7 1123.2 1128.8 1129.4 12317.3 12365.8 12366.4 12377.0 12383.8 228187.0 229054.0 229394.0 229842.0 229967.0 52141.5 52164.0 52409.2 52425.2 52588.2 1434.1 1466.0 1468.1 1483.3 1496.6 22977.2 22999.9 23055.5 23091.7 23161.8 37631.6 37717.2 37734.0 37740.6 37837.0 190460.0 191777.0 192261.0 192263.0 192748.0 33868.7 34889.3 36739.6 36750.8 36849.4 45668.5 45835.0 45836.8 46021.5 46022.5 33420.3 33518.0 33549.3 33559.7 33816.8 32718.5 32932.8 32972.2 33389.5 33918.3 37389.0 37405.3 37436.8 37731.8 37798.4 31993.2 32009.7 32350.0 32444.8 32642.5 23846.4 24296.8 24350.9 24442.5 24821.0 04:03:07 INFO - PID 3822 | 04:03:07 INFO - PID 3822 | [#2] jquery.html Cycles:180 Average:35006.26 Median:22410.05 stddev:75014.33 (334.7%) stddev-sans-first:75180.23 04:03:07 INFO - PID 3822 | Values: 578.1 600.6 606.4 606.5 611.5 22190.8 23144.7 23152.7 23179.0 23190.0 23313.9 24417.4 25267.2 25436.0 25502.0 6538.6 6694.7 6712.1 6726.9 6727.4 6630.3 6733.8 6753.5 7144.5 7368.5 7331.3 7397.2 7414.8 7434.4 7435.3 7352.2 7357.6 7387.9 7395.8 7444.8 7215.9 7218.1 7266.6 7273.2 7275.6 30103.1 30773.5 31267.0 31392.2 31474.8 2055.3 2073.5 2099.5 2102.9 2112.1 20098.8 20666.4 20766.5 20861.8 20882.6 2352.4 2407.4 2413.5 2422.2 2484.0 123577.4 130042.0 139690.0 147254.0 147332.0 21184.9 21464.6 22384.2 22435.9 22444.6 43172.6 43404.6 43446.6 43455.6 43720.0 11422.9 11901.6 11985.5 12013.5 12099.0 9860.7 9890.2 9910.7 9982.8 10012.7 11584.9 11825.2 11861.6 12042.5 12066.3 4388.9 4403.6 4435.4 4468.3 4486.6 981.2 984.7 993.8 994.8 1004.6 19345.7 19871.9 19872.9 20027.1 20094.0 1050.6 1054.0 1058.1 1060.6 1065.5 10685.8 10891.8 10914.2 10991.8 11023.4 431586.0 438223.0 439980.0 445712.0 446310.0 113179.0 117229.4 118222.4 122754.0 124003.0 1185.3 1204.6 1255.5 1284.7 1290.2 20860.4 21306.4 21503.9 21557.5 21623.2 62114.7 62488.4 62834.4 64037.0 64775.4 73112.0 73331.6 73596.0 74233.3 77473.0 14758.0 14997.4 15068.2 15095.9 15118.2 22990.2 23064.3 23165.5 23375.9 23479.5 18637.3 18664.7 18756.2 18814.0 18947.5 16346.5 16366.7 16581.2 17002.3 17188.1 18915.2 18960.4 19082.0 19245.3 19361.9 14876.0 14900.4 14927.3 14953.4 15002.9 18663.2 18698.9 18708.4 18822.3 18868.9 04:03:07 INFO - PID 3822 | 04:03:07 INFO - PID 3822 | [#3] mootools.html Cycles:180 Average:9387.95 Median:14914.65 stddev:6799.44 (45.6%) stddev-sans-first:6791.57 04:03:07 INFO - PID 3822 | Values: 1332.3 1421.3 1434.5 1436.6 1441.9 15588.5 16198.5 16220.5 16221.4 16223.8 15798.2 15874.9 15879.0 15895.0 16011.5 2773.9 2791.3 2834.1 2834.4 2843.4 2747.1 2779.1 2794.1 2795.7 2805.5 2674.4 2685.0 2686.6 2837.4 2838.7 2789.3 2803.1 2825.4 2829.5 2834.0 2618.2 2664.3 2665.1 2680.0 2683.6 14926.0 15241.5 15285.1 15286.7 15287.9 7998.5 8313.8 8406.7 8408.0 8420.5 5224.2 5413.6 5546.3 5548.7 5555.5 1932.3 1975.4 1981.7 2030.9 2054.7 27142.7 27180.7 27272.7 27289.5 27363.2 6854.9 6855.9 6892.1 7074.6 7077.9 17762.4 17778.6 17784.9 17839.6 17858.5 7907.0 7986.2 7996.8 8061.8 8069.4 7066.0 7237.6 7262.9 7266.2 7420.2 4937.2 5053.6 5108.3 5183.0 5203.1 1481.9 1481.9 1494.1 1510.5 1520.1 879.2 898.7 902.7 906.7 909.3 7457.9 7464.9 7471.1 7473.5 7596.1 690.6 739.0 739.4 742.8 755.1 4142.7 5041.3 5286.8 5351.0 5428.0 20744.0 21435.5 21464.1 22000.0 22031.4 21847.6 22132.4 22373.9 22414.1 22437.2 9601.5 10108.7 10279.4 10300.2 10311.5 7808.8 7862.9 8013.8 8031.1 8041.8 18586.7 18716.7 18721.3 18736.5 19035.7 15860.5 19153.2 19295.5 19385.9 19437.7 10991.1 11250.0 11360.6 11361.1 11389.7 13866.9 13933.5 14011.0 14089.2 14093.8 10827.2 11022.3 11068.3 11133.9 11322.3 10579.2 10662.4 10668.7 10795.2 11029.3 9835.5 10189.4 10214.5 10216.3 10220.2 7622.9 7632.4 7662.5 7674.3 7781.2 7541.2 7581.9 7619.7 7644.9 7686.3 04:03:07 INFO - PID 3822 | 04:03:07 INFO - PID 3822 | [#4] prototype.html Cycles:180 Average:5505.53 Median:6161.34 stddev:1526.02 (24.8%) stddev-sans-first:1483.68 04:03:07 INFO - PID 3822 | Values: 518.2 521.9 532.9 545.0 559.1 6169.5 6238.4 6238.9 6257.7 6264.2 6127.9 6129.4 6152.3 6206.8 6213.1 4900.1 5096.8 5186.1 5191.7 5204.3 5005.9 5044.6 5044.6 5053.2 5067.6 4987.3 4997.8 5001.7 5005.5 5038.6 4992.7 5006.3 5019.5 5029.6 5048.0 4553.6 4557.0 4565.2 4568.3 4599.3 6137.3 6153.2 6204.6 6242.4 6245.0 5696.7 5701.9 5743.5 5778.7 5791.3 6536.0 6546.8 6565.2 6566.9 6615.7 4876.8 4929.6 4931.9 4947.2 4959.8 6878.7 6926.2 6943.2 6946.2 6991.5 5029.2 5034.5 5040.8 5050.2 5056.5 5064.5 5125.5 5136.4 5149.0 5161.2 5646.6 5667.7 5696.0 5730.5 5743.5 6256.0 6280.7 6436.5 6572.4 6580.9 1735.7 1758.3 1761.9 1767.3 1770.9 1034.0 1199.6 1240.7 1244.4 1247.6 5605.6 5719.7 5754.2 5762.3 5805.7 6783.8 6784.9 6809.4 6812.3 6848.6 5766.8 5776.5 5809.2 5829.9 5839.8 3532.8 3538.3 3539.6 3545.5 3549.6 6378.8 6422.9 6467.8 6478.5 6488.4 6682.8 6715.8 6763.0 6836.7 6863.8 6433.9 6477.6 6491.6 6493.2 6530.1 6509.9 6586.8 6625.9 6646.6 6697.2 6428.0 6482.4 6511.9 6524.0 6555.9 6445.2 6459.5 6460.5 6479.1 6481.5 4985.4 5018.6 5025.0 5035.3 5078.3 6565.5 6633.6 6667.4 6690.0 6692.5 6414.7 6447.3 6485.7 6507.3 6535.4 6377.8 6433.3 6449.5 6455.2 6535.2 6367.8 6411.3 6448.6 6486.2 6499.0 6096.7 6427.3 6520.8 6539.2 6544.7 6180.7 6222.3 6266.0 6267.0 6335.4 04:03:07 INFO - PID 3822 | 04:03:07 INFO - PID 3822 | [#5] yui.html Cycles:180 Average:24386.51 Median:18199.32 stddev:35020.64 (192.4%) stddev-sans-first:35074.23 04:03:07 INFO - PID 3822 | Values: 835.0 875.9 904.0 915.9 926.2 17499.9 18107.5 18232.9 18323.1 18326.9 18009.4 18110.5 18177.2 18185.7 18189.2 941.5 961.2 965.4 965.6 966.2 965.1 970.7 970.9 973.9 977.1 999.3 1027.5 1037.0 1040.3 1043.9 1019.9 1026.5 1026.5 1027.2 1027.6 718.2 755.9 756.6 759.3 760.1 20384.8 20592.1 20599.6 20618.1 20631.6 21245.2 21528.8 21955.8 22087.2 22101.0 17302.7 17404.0 17458.7 17494.8 17608.8 3772.5 3791.6 3806.8 3808.4 3815.2 108886.0 132940.3 135262.0 135921.0 138474.0 17572.3 17969.3 18192.4 18195.0 18203.6 31358.4 32635.2 32774.3 32814.8 32915.5 9065.2 9238.0 9274.8 9317.8 9336.3 4252.9 4346.7 4354.0 4354.1 4358.6 5617.8 5724.7 5737.7 5741.3 5747.2 1119.5 1145.0 1148.6 1148.9 1157.1 618.8 631.5 634.6 643.4 647.6 11634.6 12313.8 12423.9 13372.5 13519.5 682.9 684.2 686.7 697.3 701.3 5541.7 5630.2 5631.4 5675.5 5727.7 124796.0 126635.0 128336.4 129116.0 129959.4 102810.5 107496.7 108282.0 110940.0 111482.0 92340.5 95149.0 97705.0 97987.5 100326.0 10649.9 10700.2 10789.6 10863.6 11012.9 44252.8 44450.0 45224.3 45471.8 45717.8 58489.0 58729.4 59589.7 59759.0 59946.4 15081.1 15512.3 15558.1 15634.0 15688.8 17352.5 17713.0 17765.6 17852.0 17975.8 15559.8 15801.8 15855.0 15872.2 15883.9 15623.8 15745.3 15841.0 15880.3 16102.7 14483.9 14688.3 14710.8 14728.6 14738.0 11993.7 12025.8 12107.6 12125.3 12372.3 12034.2 12047.5 12119.5 12153.3 12225.4 04:03:07 INFO - PID 3822 | -------- Summary: end -------- 04:03:07 INFO - PID 3822 | 04:03:08 INFO - TEST-INFO | 3822: exit 0 04:03:08 INFO - TEST-OK | dromaeo_css | took 1113727ms 04:03:08 INFO - TEST-START | kraken 04:03:08 INFO - Initialising browser for kraken test... 04:03:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49708/getInfo.html -profile /tmp/tmpIgIdAd/profile 04:03:08 INFO - TEST-INFO | started process 4229 (/builds/slave/test/build/application/firefox/firefox http://localhost:49708/getInfo.html) 04:03:15 INFO - TEST-INFO | 4229: exit 0 04:03:15 INFO - Browser initialized. 04:03:15 INFO - Running cycle 1/1 for kraken test... 04:03:15 INFO - Using env: {'DISPLAY': ':0', 04:03:15 INFO - 'HOME': '/home/cltbld', 04:03:15 INFO - 'JSGC_DISABLE_POISONING': '1', 04:03:15 INFO - 'LANG': 'en_US.UTF-8', 04:03:15 INFO - 'LANGUAGE': 'en_US:en', 04:03:15 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:03:15 INFO - 'LOGNAME': 'cltbld', 04:03:15 INFO - 'MAIL': '/var/mail/cltbld', 04:03:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:03:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:03:15 INFO - 'MOZ_CRASHREPORTER': '1', 04:03:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:15 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:03:15 INFO - 'MOZ_NO_REMOTE': '1', 04:03:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:03:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:15 INFO - 'NO_EM_RESTART': '1', 04:03:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:15 INFO - 'PWD': '/builds/slave/test', 04:03:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:03:15 INFO - 'RUST_BACKTRACE': 'full', 04:03:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:03:15 INFO - 'SHELL': '/bin/bash', 04:03:15 INFO - 'SHLVL': '1', 04:03:15 INFO - 'TERM': 'linux', 04:03:15 INFO - 'TMOUT': '86400', 04:03:15 INFO - 'USER': 'cltbld', 04:03:15 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409', 04:03:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:15 INFO - '_': '/tools/buildbot/bin/python'} 04:03:15 INFO - TEST-INFO | started process 4463 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIgIdAd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:03:16 INFO - PID 4463 | 04:03:16 INFO - PID 4463 | (/builds/slave/test/build/application/firefox/firefox:4511): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:03:16 INFO - PID 4463 | 04:03:26 INFO - PID 4463 | RSS: Main: 147750912 04:03:26 INFO - PID 4463 | 04:03:29 INFO - PID 4463 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49708/tests/kraken/driver.html?testName=audio-beat-detection) 04:03:30 INFO - PID 4463 | RSS: Main: 153624576 04:03:30 INFO - PID 4463 | 04:03:33 INFO - PID 4463 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49708/tests/kraken/driver.html?testName=audio-dft) 04:03:33 INFO - PID 4463 | RSS: Main: 153620480 04:03:33 INFO - PID 4463 | 04:03:36 INFO - PID 4463 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49708/tests/kraken/driver.html?testName=audio-fft) 04:03:37 INFO - PID 4463 | RSS: Main: 151064576 04:03:37 INFO - PID 4463 | 04:03:39 INFO - PID 4463 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49708/tests/kraken/driver.html?testName=audio-oscillator) 04:03:39 INFO - PID 4463 | RSS: Main: 151310336 04:03:39 INFO - PID 4463 | 04:03:41 INFO - PID 4463 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49708/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:03:42 INFO - PID 4463 | RSS: Main: 151085056 04:03:42 INFO - PID 4463 | 04:03:45 INFO - PID 4463 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49708/tests/kraken/driver.html?testName=imaging-darkroom) 04:03:46 INFO - PID 4463 | RSS: Main: 153432064 04:03:46 INFO - PID 4463 | 04:03:50 INFO - PID 4463 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49708/tests/kraken/driver.html?testName=imaging-desaturate) 04:03:50 INFO - PID 4463 | RSS: Main: 153935872 04:03:50 INFO - PID 4463 | 04:03:53 INFO - PID 4463 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49708/tests/kraken/driver.html?testName=json-parse-financial) 04:03:54 INFO - PID 4463 | RSS: Main: 154062848 04:03:54 INFO - PID 4463 | 04:03:55 INFO - PID 4463 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49708/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:03:56 INFO - PID 4463 | RSS: Main: 153976832 04:03:56 INFO - PID 4463 | 04:03:58 INFO - PID 4463 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:03:58 INFO - PID 4463 | RSS: Main: 153956352 04:03:58 INFO - PID 4463 | 04:04:00 INFO - PID 4463 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:04:01 INFO - PID 4463 | RSS: Main: 154144768 04:04:01 INFO - PID 4463 | 04:04:04 INFO - PID 4463 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:04:04 INFO - PID 4463 | RSS: Main: 154509312 04:04:04 INFO - PID 4463 | 04:04:08 INFO - PID 4463 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:04:08 INFO - PID 4463 | RSS: Main: 154578944 04:04:08 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | Cycle 1(1): loaded http://localhost:49708/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49708/tests/kraken/driver.html?testName=ai-astar) 04:04:10 INFO - PID 4463 | RSS: Main: 155676672 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | __start_tp_report 04:04:10 INFO - PID 4463 | _x_x_mozilla_page_load 04:04:10 INFO - PID 4463 | _x_x_mozilla_page_load_details 04:04:10 INFO - PID 4463 | |i|pagename|runs| 04:04:10 INFO - PID 4463 | |0;ai-astar;108;108;109;109;109;95;107;107;108;107 04:04:10 INFO - PID 4463 | |1;audio-beat-detection;116;120;117;118;118;151;118;117;117;123 04:04:10 INFO - PID 4463 | |2;audio-dft;163;161;161;163;162;161;162;164;165;166 04:04:10 INFO - PID 4463 | |3;audio-fft;75;75;81;73;75;75;73;74;74;73 04:04:10 INFO - PID 4463 | |4;audio-oscillator;79;79;79;79;80;78;79;80;80;79 04:04:10 INFO - PID 4463 | |5;imaging-gaussian-blur;113;116;114;115;114;114;114;113;116;114 04:04:10 INFO - PID 4463 | |6;imaging-darkroom;154;147;148;148;148;146;150;149;150;151 04:04:10 INFO - PID 4463 | |7;imaging-desaturate;94;94;94;94;94;94;93;94;95;95 04:04:10 INFO - PID 4463 | |8;json-parse-financial;52;53;54;58;58;54;55;53;54;53 04:04:10 INFO - PID 4463 | |9;json-stringify-tinderbox;38;38;39;39;38;39;38;39;39;39 04:04:10 INFO - PID 4463 | |10;stanford-crypto-aes;71;72;73;74;76;80;82;72;73;76 04:04:10 INFO - PID 4463 | |11;stanford-crypto-ccm;114;119;110;112;114;117;118;111;114;117 04:04:10 INFO - PID 4463 | |12;stanford-crypto-pbkdf2;180;166;166;163;178;181;181;169;164;166 04:04:10 INFO - PID 4463 | |13;stanford-crypto-sha256-iterative;63;63;62;69;66;84;65;63;65;66 04:04:10 INFO - PID 4463 | __end_tp_report 04:04:10 INFO - PID 4463 | __start_cc_report 04:04:10 INFO - PID 4463 | _x_x_mozilla_cycle_collect,753 04:04:10 INFO - PID 4463 | __end_cc_report 04:04:10 INFO - PID 4463 | __startTimestamp1506510250296__endTimestamp 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | ------- Summary: start ------- 04:04:10 INFO - PID 4463 | Number of tests: 14 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | [#0] ai-astar Cycles:10 Average:106.70 Median:108.50 stddev:4.19 (3.9%) stddev-sans-first:4.42 04:04:10 INFO - PID 4463 | Values: 108.0 108.0 109.0 109.0 109.0 95.0 107.0 107.0 108.0 107.0 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | [#1] audio-beat-detection Cycles:10 Average:121.50 Median:118.00 stddev:10.55 (8.9%) stddev-sans-first:11.01 04:04:10 INFO - PID 4463 | Values: 116.0 120.0 117.0 118.0 118.0 151.0 118.0 117.0 117.0 123.0 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | [#2] audio-dft Cycles:10 Average:162.80 Median:163.00 stddev:1.75 (1.1%) stddev-sans-first:1.86 04:04:10 INFO - PID 4463 | Values: 163.0 161.0 161.0 163.0 162.0 161.0 162.0 164.0 165.0 166.0 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | [#3] audio-fft Cycles:10 Average:74.80 Median:75.00 stddev:2.35 (3.1%) stddev-sans-first:2.49 04:04:10 INFO - PID 4463 | Values: 75.0 75.0 81.0 73.0 75.0 75.0 73.0 74.0 74.0 73.0 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | [#4] audio-oscillator Cycles:10 Average:79.20 Median:79.00 stddev:0.63 (0.8%) stddev-sans-first:0.67 04:04:10 INFO - PID 4463 | Values: 79.0 79.0 79.0 79.0 80.0 78.0 79.0 80.0 80.0 79.0 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:1.06 (0.9%) stddev-sans-first:1.01 04:04:10 INFO - PID 4463 | Values: 113.0 116.0 114.0 115.0 114.0 114.0 114.0 113.0 116.0 114.0 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | [#6] imaging-darkroom Cycles:10 Average:149.10 Median:149.50 stddev:2.28 (1.5%) stddev-sans-first:1.59 04:04:10 INFO - PID 4463 | Values: 154.0 147.0 148.0 148.0 148.0 146.0 150.0 149.0 150.0 151.0 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 04:04:10 INFO - PID 4463 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 95.0 95.0 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:2.07 (3.8%) stddev-sans-first:2.00 04:04:10 INFO - PID 4463 | Values: 52.0 53.0 54.0 58.0 58.0 54.0 55.0 53.0 54.0 53.0 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | [#9] json-stringify-tinderbox Cycles:10 Average:38.60 Median:39.00 stddev:0.52 (1.3%) stddev-sans-first:0.50 04:04:10 INFO - PID 4463 | Values: 38.0 38.0 39.0 39.0 38.0 39.0 38.0 39.0 39.0 39.0 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | [#10] stanford-crypto-aes Cycles:10 Average:74.90 Median:75.00 stddev:3.63 (4.8%) stddev-sans-first:3.57 04:04:10 INFO - PID 4463 | Values: 71.0 72.0 73.0 74.0 76.0 80.0 82.0 72.0 73.0 76.0 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | [#11] stanford-crypto-ccm Cycles:10 Average:114.60 Median:115.50 stddev:3.06 (2.7%) stddev-sans-first:3.24 04:04:10 INFO - PID 4463 | Values: 114.0 119.0 110.0 112.0 114.0 117.0 118.0 111.0 114.0 117.0 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.40 Median:173.50 stddev:7.60 (4.4%) stddev-sans-first:7.40 04:04:10 INFO - PID 4463 | Values: 180.0 166.0 166.0 163.0 178.0 181.0 181.0 169.0 164.0 166.0 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:65.50 stddev:6.45 (9.8%) stddev-sans-first:6.71 04:04:10 INFO - PID 4463 | Values: 63.0 63.0 62.0 69.0 66.0 84.0 65.0 63.0 65.0 66.0 04:04:10 INFO - PID 4463 | -------- Summary: end -------- 04:04:10 INFO - PID 4463 | 04:04:10 INFO - PID 4463 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:04:11 INFO - TEST-INFO | 4463: exit 0 04:04:11 INFO - TEST-OK | kraken | took 62843ms 04:04:11 INFO - SUITE-END | took 1176s 04:04:11 INFO - Completed test suite (00:19:36) 04:04:11 INFO - javascript benchmark 04:04:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8670.249848182593, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3175.079227024163, 3181.125190572593, 3205.807651540906, 3243.8864639737612, 3253.446914024617, 6235.0959110929, 6343.460805831504, 6365.108884756138, 6383.968080159599, 6451.903221451682, 6261.3425590313045, 6302.054691796233, 6313.747450101991, 6326.905096423558, 6370.394812492806, 5808.041673123929, 5874.647521148723, 5874.911876321869, 5897.793577224798, 5912.17229587858, 5693.77224911003, 5865.530757539386, 5877.617954832922, 5953.970230148865, 5964.2246507953905, 5728.541716662678, 5763.855903602402, 5831.125331200312, 5837.6497410155525, 5843.795467158671, 5731.971340143273, 5756.769729210869, 5802.564807639402, 5813.883722325572, 5824.796132135355, 5692.089265717506, 5764.538836893039, 5766.769329226826, 5780.971095144498, 5795.88408231833, 6240.469560087353, 6245.406686364833, 6260.029695397221, 6276.340984196685, 6319.74721011159, 6701.530892837503, 6719.93280067198, 6900.723971041152, 6908.7236510539515, 6927.307269273083, 6896.0, 6898.448124150106, 6968.965155174192, 6995.125609298838, 6997.160340759141, 6195.628262304276, 6288.465480434214, 6305.0, 6380.649064301461, 6445.162128923257, 6807.65961701913, 6880.8967865482055, 6928.44572434204, 6933.37599616037, 6958.79123626292, 6631.038499417557, 6733.0237115617965, 6744.190697116377, 6764.763233286812, 6778.423833974069, 6832.931670683329, 6835.418989385858, 6871.587704737732, 6924.99587559801, 6937.826554336081, 6802.523823332319, 6804.523683342119, 6869.072675188786, 6889.931100688929, 6895.655217239118, 6647.634380109141, 6725.9663701681175, 6751.392374686204, 6754.864902701918, 6772.322767723287, 5247.632665713363, 5253.50091741284, 5267.130923397596, 5269.947300526946, 5272.947270527246, 4617.261238201939, 4618.676692631483, 4672.322513235562, 4704.882377940579, 4717.8348757794, 5870.73581688825, 5872.265966754156, 5936.821895343147, 5949.10763385487, 5959.046552551657, 6863.862722745517, 6866.313368663094, 6930.93069069313, 6950.617716025566, 6957.965210173917, 5679.545636349082, 5810.070388737781, 5811.041178205549, 5819.156222347736, 5918.644881307136, 5725.227094342294, 5817.534597232211, 5914.497267732205, 5916.674582897982, 5926.199963005025, 6642.169728783902, 6677.098591690156, 6678.3989440950545, 6715.832104197434, 6736.6631668416385, 6868.828279293058, 6869.793906182923, 6952.270011648704, 6955.269696681877, 6983.127109111361, 6426.550141490051, 6543.901841472477, 6571.671416429063, 6617.569857959217, 6722.1261236039, 6766.391024807791, 6800.693968771318, 6814.0, 6822.079019332326, 6822.3859852613505, 6775.0, 6877.037214790033, 6883.965580172267, 6889.483288753263, 6907.930920690729, 6581.80254592363, 6649.03588979605, 6658.933410665832, 6673.366030227121, 6684.097646817618, 6522.184726909137, 6594.934050659432, 6597.406233439014, 6666.466682665278, 6703.363180497845, 6402.903956440564, 6413.294537600954, 6468.773592924225, 6488.610683359014, 6499.38255865692, 6564.671766411756, 6613.801585952237, 6613.867722645617, 6677.866442671217, 6707.865842683216, 6316.305206427382, 6406.23125224976, 6429.164208652845, 6462.192225971754, 6464.385883341075], "value": 6199.261271165918, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [852.4459101583972, 877.793718476158, 881.6473410635745, 885.1281487734584, 886.6586364249762, 41427.37858932118, 42731.359029614505, 42803.35794963074, 42843.35734963978, 43152.568474315245, 39533.60466395335, 39887.800560997224, 39965.80017099918, 39992.40011399824, 40058.0, 7210.495265331442, 7330.8900366494545, 7334.1565719942355, 7374.299441553044, 7374.889376659355, 7285.380742636882, 7362.66867990939, 7381.741639042634, 7406.481546291735, 7407.333339999399, 7357.632118394059, 7936.246056624612, 8168.060673022589, 8191.508509489449, 8207.302379297767, 8096.959515202416, 8143.918560814375, 8148.266656000958, 8152.38857085721, 8155.34757219421, 7954.9602251988945, 7998.64006119726, 8086.070101938235, 8094.231048050426, 8097.35221182304, 37897.62102378969, 38263.234735305414, 38315.0, 38327.61672383268, 38379.424308635396, 3235.7573182011206, 3258.5112233164978, 3259.1363288728508, 3265.967340326614, 3289.9835500822583, 31407.685923140703, 31420.371592568248, 31550.526742098886, 31628.05115846528, 31655.683443165504, 5275.683458992473, 5295.020421222048, 5323.680579165262, 5327.946720532784, 5394.05604019299, 187914.0, 189359.0, 189478.05260973744, 189626.0, 189757.0, 41855.58144418578, 43051.78474107672, 48993.26510102351, 49288.0, 50080.499195007586, 50576.49423505718, 50639.24041139386, 50663.0, 50709.74645126751, 50807.74596126996, 15982.680346393006, 16014.519564413085, 16073.67852642964, 16157.838421615868, 16160.111193884213, 13027.348632568333, 13035.674108147372, 13056.477740890448, 13070.738585228242, 13205.53780617693, 14099.718005640036, 14290.857091429161, 14319.498817541336, 14335.283235838375, 14335.713285734435, 5789.363170051291, 5858.47273745365, 5934.673592952343, 5973.283206015219, 6021.819345419644, 1014.4065721552827, 1027.0961553832578, 1028.6759670703705, 1036.549101141006, 1039.8856125826153, 22808.543829123657, 23083.0, 23154.884225578764, 23349.299521014396, 23526.176583819826, 1116.4752566293828, 1116.659418877234, 1123.1744667669257, 1128.7968165730083, 1129.390129330169, 12317.260964342167, 12365.814512782315, 12366.381680915918, 12377.009839212842, 12383.814242786366, 228187.0, 229054.0, 229394.0, 229842.0, 229967.0, 52141.47858521442, 52164.0, 52409.2138617921, 52425.2136217957, 52588.21117683161, 1434.12518363798, 1465.992670036643, 1468.0685164859945, 1483.2806089046803, 1496.5884381794876, 22977.195798146986, 22999.88500057489, 23055.538889221785, 23091.653625195973, 23161.76838231596, 37631.62368376281, 37717.245655087296, 37734.0, 37740.62259377371, 37837.0, 190460.0, 191777.0, 192261.0, 192263.0, 192748.0, 33868.661313386554, 34889.302213955074, 36739.63260367362, 36750.8162459186, 36849.447258291686, 45668.54331456643, 45835.0, 45836.770816145705, 46021.53978460172, 46022.53977460317, 33420.331593368486, 33517.994460166236, 33549.329013419105, 33559.66440335663, 33816.83091584526, 32718.50922236121, 32932.83533582317, 32972.1756956078, 33389.49915751314, 33918.321633567684, 37389.0, 37405.2518949614, 37436.812815935744, 37731.81134094312, 37798.43302350412, 31993.200169995936, 32009.67990320067, 32350.029499115066, 32444.83777581097, 32642.51036234411, 23846.449980751197, 24296.785160742245, 24350.87824560866, 24442.51114977726, 24821.0], "value": 17297.824274241557, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.1356871477142, 600.5705920267009, 606.390577469643, 606.4572207873953, 611.4558043341425, 22190.778092219072, 23144.65283020756, 23152.65271020936, 23179.0, 23190.0, 23313.883430582864, 24417.389565260877, 25267.241982740547, 25436.0, 25502.0, 6538.6076835389795, 6694.698738556753, 6712.127423434947, 6726.899096513532, 6727.394534491895, 6630.336966303355, 6733.764318248862, 6753.527253092286, 7144.535605185646, 7368.521046131984, 7331.340179383855, 7397.22329155439, 7414.814629634248, 7434.368078713316, 7435.330820226179, 7352.154502232258, 7357.558546487201, 7387.852242955165, 7395.8151046224, 7444.776656700254, 7215.927840721631, 7218.133823941161, 7266.600336981464, 7273.199948005715, 7275.636218189123, 30103.096907092822, 30773.53839692384, 31267.0, 31392.21519462009, 31474.842625786954, 2055.2806517718827, 2073.502359433725, 2099.5066159452517, 2102.9474263143543, 2112.0601332407086, 20098.799012009986, 20666.380008599765, 20766.48083797902, 20861.79138208629, 20882.58234835295, 2352.3704389408263, 2407.3620490570015, 2413.4931664350506, 2422.212780846232, 2483.962740558893, 123577.38211308976, 130042.0, 139690.0, 147253.99999999892, 147332.0, 21184.89407552983, 21464.57070858574, 22384.216552420912, 22435.87820608963, 22444.551108978056, 43172.568274317484, 43404.56595434069, 43446.56553434425, 43455.56544434515, 43720.0, 11422.885771142182, 11901.583444579399, 11985.460654270579, 12013.459394327276, 12099.0, 9860.704178874501, 9890.230735394101, 9910.702678919644, 9982.750431239132, 10012.749681258027, 11584.884151158549, 11825.226216067658, 11861.644150675493, 12042.518299268106, 12066.336351500577, 4388.912221755547, 4403.647708183337, 4435.401220835211, 4468.3074123510905, 4486.5962063414445, 981.2052237687496, 984.6996666016934, 993.7614972406715, 994.7662299359645, 1004.6483730694345, 19345.709814352795, 19871.900640496995, 19872.90063549702, 20027.09878055491, 20094.0, 1050.6270274052754, 1053.9631112911165, 1058.1111866032415, 1060.6234786650782, 1065.541817018674, 10685.786284274427, 10891.83662245067, 10914.181436392302, 10991.780164396667, 11023.44882755859, 431586.0, 438223.0, 439980.0, 445712.0, 446310.0, 113179.0, 117229.4138529319, 118222.40888795501, 122754.0, 124003.0, 1185.2769810415812, 1204.6325870609548, 1255.5354518828092, 1284.6595652152187, 1290.161395093197, 20860.37418877436, 21306.36080917575, 21503.892480537495, 21557.46106347354, 21623.2431864884, 62114.68942655258, 62488.37511625008, 62834.37165628285, 64037.0, 64775.35224647882, 73112.0, 73331.63334183295, 73596.0, 74233.2576674248, 77473.0, 14758.040727352687, 14997.400103995718, 15068.246587670354, 15095.924520377328, 15118.168500732565, 22990.195343162908, 23064.308070757903, 23165.536689266457, 23375.948082336676, 23479.530409391376, 18637.347692830685, 18664.720029199845, 18756.249750009847, 18814.0, 18947.526311842314, 16346.509604711877, 16366.672666546841, 16581.17094145312, 17002.319907203575, 17188.14059297055, 18915.243390264237, 18960.43118706441, 19082.0, 19245.326413575458, 19361.903190483958, 14876.0, 14900.403983840959, 14927.328270227648, 14953.401863925756, 15002.924985375003, 18663.160157793205, 18698.906505467385, 18708.438746837073, 18822.341218057303, 18868.905655471634], "value": 13048.487520092454, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.300542215337, 1421.3461807568517, 1434.5337765226304, 1436.5762100180445, 1441.8702316791487, 15588.454404095844, 16198.514044578667, 16220.513384598466, 16221.351145954148, 16223.756643650326, 15798.210089495537, 15874.920625396886, 15879.0, 15895.046297222174, 16011.519654410356, 2773.9167824965284, 2791.302174456391, 2834.1072562142963, 2834.3764371838265, 2843.445528122017, 2747.1346525844297, 2779.138467075203, 2794.1058861164433, 2795.65054368204, 2805.5230610796216, 2674.371522692166, 2685.0, 2686.570148776196, 2837.3757773289944, 2838.6593608766975, 2789.288731373497, 2803.088996076263, 2825.3642930340716, 2829.4906916754976, 2833.9574906376524, 2618.2407101940416, 2664.267326485213, 2665.1338315047688, 2680.0351873325585, 2683.5840444731098, 14926.0, 15241.466548670744, 15285.082895026335, 15286.694266114615, 15287.923560382238, 7998.480098793618, 8313.792155196108, 8406.705765298246, 8408.0, 8420.4947703138, 5224.216367544861, 5413.593980451482, 5546.334439867242, 5548.72256387179, 5555.527780138693, 1932.2657390191637, 1975.4073777866583, 1981.6730239510464, 2030.9187632494682, 2054.7020682001025, 27142.72857271422, 27180.728192718016, 27272.727272727414, 27289.454210915672, 27363.17910462689, 6854.931450685529, 6855.862882742417, 6892.104026476526, 7074.646267686595, 7077.858442831218, 17762.37831675911, 17778.555536111442, 17784.932904025798, 17839.643207135785, 17858.46424607264, 7907.0, 7986.241307075818, 7996.840063198703, 8061.758147255592, 8069.435139540294, 7065.964670176616, 7237.638118094074, 7262.854742905112, 7266.237045110187, 7420.220876807965, 4937.23472861707, 5053.5957123430035, 5108.259302401206, 5182.97408512955, 5203.14148165556, 1481.8814494840387, 1481.9259037048105, 1494.118470102623, 1510.546835949233, 1520.0879472316742, 879.2438502887512, 898.7483504618717, 902.702108304258, 906.6871929184421, 909.2862103248918, 7457.85084298311, 7464.85070298591, 7471.066116735408, 7473.476856620093, 7596.08846938371, 690.6374153569316, 738.9704411823575, 739.3973911262304, 742.8365759532894, 755.0825746717765, 4142.668586513072, 5041.319421878, 5286.788528458896, 5350.983313170485, 5428.0, 20744.0, 21435.464113396978, 21464.14143434245, 22000.0, 22031.449213769785, 21847.563048739255, 22132.446688832584, 22373.888130559244, 22414.103435862384, 22437.21469748551, 9601.47191904438, 10108.696739097839, 10279.434631095211, 10300.175985921109, 10311.535980880879, 7808.84382312362, 7862.921370786447, 8013.799655008672, 8031.116577176389, 8041.798955026171, 18586.721199182295, 18716.719249211546, 18721.25114995385, 18736.53158671044, 19035.714464283323, 15860.52418427449, 19153.233870645017, 19295.51761205981, 19385.903070484557, 19437.708434373777, 10991.120710342993, 11250.0, 11360.602378916697, 11361.147913906325, 11389.658310250707, 13866.930665346608, 13933.512327068504, 14011.0, 14089.15465072099, 14093.788593171315, 10827.24209305341, 11022.338659680445, 11068.335899845713, 11133.888661113286, 11322.32066076038, 10579.206559507913, 10662.413567253876, 10668.679939601825, 10795.244332896622, 11029.338239705643, 9835.508224588884, 10189.439580823126, 10214.489275536342, 10216.335938163995, 10220.233482488991, 7622.9237707622215, 7632.389408847168, 7662.540247585162, 7674.270944260286, 7781.221877812173, 7541.208173141741, 7581.886271705818, 7619.69521219145, 7644.885326719992, 7686.346660533917], "value": 6663.248192526227, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [518.1812735877313, 521.9321488206533, 532.9227262047006, 544.9863753406165, 559.1138046196778, 6169.514224463175, 6238.407351301625, 6238.906416403757, 6257.71840267188, 6264.154339164212, 6127.855250662266, 6129.356417576158, 6152.323244443119, 6206.844828879281, 6213.130161777355, 4900.142475066867, 5096.770645320969, 5186.118359878829, 5191.688498690071, 5204.297419848329, 5005.899882002358, 5044.571211447032, 5044.596432285427, 5053.216751403538, 5067.619928505358, 4987.301777751118, 4997.750112494361, 5001.7499125043605, 5005.47442518534, 5038.59691224703, 4992.650514463989, 5006.349174607296, 5019.469061936108, 5029.622778291643, 5047.974760126176, 4553.567411095941, 4557.0, 4565.223911934979, 4568.314752787075, 4599.264117741179, 6137.324894261627, 6153.1693221415435, 6204.596701214452, 6242.438180563771, 6245.0, 5696.658200507983, 5701.942980570183, 5743.5118014968775, 5778.682172480512, 5791.305043394819, 6535.967320163416, 6546.803595892131, 6565.179352580927, 6566.93433065668, 6615.702293396761, 4876.829310974099, 4929.556339929423, 4931.926021109651, 4947.169547267498, 4959.817680220692, 6878.65606719662, 6926.203486599055, 6943.201531823855, 6946.235914049451, 6991.545549539263, 5029.195328747456, 5034.471380504994, 5040.798368065236, 5050.242463630485, 5056.544910958031, 5064.518870707277, 5125.4874512549195, 5136.357955255593, 5148.9742551287, 5161.193582246259, 5646.6047376684055, 5667.716614169275, 5696.0, 5730.484256416942, 5743.501576814099, 6256.0, 6280.748770049238, 6436.452901503425, 6572.375624315682, 6580.934190658032, 1735.7136072547887, 1758.2791055667371, 1761.9119044047745, 1767.2842498788011, 1770.9468715938542, 1033.9586416543405, 1199.6341115959717, 1240.7034648792035, 1244.4213440750095, 1247.5758242197514, 5605.5515558755205, 5719.7426115824055, 5754.223179870748, 5762.2509073820975, 5805.6806875621405, 6783.796486105425, 6784.932150678529, 6809.353111454404, 6812.3187681232475, 6848.623325717033, 5766.769329226784, 5776.451237132465, 5809.157672137517, 5829.912551311733, 5839.824805255849, 3532.8410221540094, 3538.327717733622, 3539.6460353964912, 3545.503629491873, 3549.5917969433845, 6378.840528986812, 6422.9036564450635, 6467.773627923, 6478.481721462272, 6488.351164883568, 6682.828080873735, 6715.764948226789, 6763.004913550514, 6836.658167091725, 6863.828404289833, 6433.903491447632, 6477.611343319416, 6491.578047426902, 6493.188351456068, 6530.118434011348, 6509.902351464637, 6586.8353291168105, 6625.933740662725, 6646.634435105922, 6697.162854643169, 6428.0, 6482.384173503509, 6511.902321465276, 6523.967380163069, 6555.934440655532, 6445.16212892321, 6459.515536334888, 6460.547761656639, 6479.125318081999, 6481.481481481376, 4985.376827896513, 5018.573421259234, 5025.0, 5035.29505869186, 5078.339815824067, 6565.540412171103, 6633.6019838809825, 6667.399934005963, 6689.96305572637, 6692.464602831859, 6414.743410263537, 6447.323031081856, 6485.675716214265, 6507.316731743288, 6535.44448721864, 6377.808665740035, 6433.260175079926, 6449.54917131487, 6455.193100862392, 6535.183102112236, 6367.840803979938, 6411.326810684811, 6448.580842245239, 6486.189226346707, 6499.0, 6096.695165241809, 6427.325130861379, 6520.771772987922, 6539.182602174728, 6544.6727663615675, 6180.721867516063, 6222.253329600477, 6265.997440409513, 6267.0, 6335.420487424591], "value": 5058.490500795007, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.9666013359466, 875.8861348024756, 904.0159358630693, 915.8534634458488, 926.2497377124525, 17499.912500437513, 18107.54731131721, 18232.90883545582, 18323.083845807723, 18326.90836545819, 18009.36967206148, 18110.4566862994, 18177.182026808794, 18185.7272140918, 18189.18148683309, 941.5245301122941, 961.2262128986164, 965.4207475514705, 965.5510187762688, 966.2270183852925, 965.1169180200144, 970.7039352997333, 970.912617864392, 973.8880028796708, 977.1254727019314, 999.2855108597352, 1027.5016616940777, 1037.0148359058887, 1040.2562168049842, 1043.8799538053145, 1019.9031092046243, 1026.497016461932, 1026.5483187397522, 1027.193652982409, 1027.6249169053317, 718.1992078832117, 755.8715018446823, 756.6330329790047, 759.3431681595436, 760.095486371222, 20384.796152038438, 20592.073356698984, 20599.5880082399, 20618.072186751633, 20631.587368252553, 21245.15019399222, 21528.784712152836, 21955.780442195533, 22087.226947056937, 22101.0, 17302.740458893128, 17404.0, 17458.738118928228, 17494.825051749573, 17608.823911760846, 3772.5472943246714, 3791.639794219545, 3806.8286927088348, 3808.371618682914, 3815.236952609489, 108886.0, 132940.33529832386, 135262.0, 135921.0, 138473.99999999898, 17572.297108115534, 17969.281228750704, 18192.363267285848, 18195.0, 18203.635927281644, 31358.37283254322, 32635.18412039718, 32774.3445131096, 32814.8359258207, 32915.50626740601, 9065.184133428023, 9238.0, 9274.814503710022, 9317.813643727088, 9336.346455748033, 4252.936205956913, 4346.67399944999, 4354.042110735633, 4354.10740798142, 4358.6295164911335, 5617.775288988395, 5724.742386592613, 5737.741801618937, 5741.282339707536, 5747.195392645033, 1119.468252580018, 1145.032447581792, 1148.6439203846835, 1148.9487119285927, 1157.0916830288352, 618.8298217990089, 631.5011141198402, 634.5875181132209, 643.4241353988155, 647.5758378262245, 11634.592789252336, 12313.753724925451, 12423.937880310721, 13372.531961381304, 13519.526816561372, 682.9112215411965, 684.1755684400285, 686.7287421468492, 697.3340459860821, 701.2636731431976, 5541.695206763667, 5630.183623374588, 5631.408702086222, 5675.460831221028, 5727.656340619493, 124796.0, 126635.0, 128336.35831820968, 129116.0, 129959.35020324838, 102810.48594756979, 107496.70013199384, 108282.0, 110940.0, 111482.0, 92340.53829730809, 95149.0, 97705.0, 97987.51006244923, 100325.99999999854, 10649.877174412508, 10700.197485188797, 10789.62236321725, 10863.619773307897, 11012.944935275273, 44252.77873610611, 44450.0, 45224.32163517484, 45471.77264113658, 45717.771411142734, 58489.0, 58729.4127058724, 59589.70205148947, 59759.0, 59946.400535995825, 15081.095134291978, 15512.301946412214, 15558.066516009076, 15634.0, 15688.764668529753, 17352.47942561725, 17713.0, 17765.555861103578, 17852.0, 17975.820241797413, 15559.766603501183, 15801.841981580497, 15855.0, 15872.206389680701, 15883.920580397024, 15623.843761562239, 15745.291461884472, 15841.0, 15880.285387157375, 16102.67794644124, 14483.855161448251, 14688.339024743698, 14710.779338310149, 14728.55814325572, 14738.0, 11993.700157496132, 12025.819612705642, 12107.636770896886, 12125.27248365101, 12372.257664540157, 12034.157608967289, 12047.457864396298, 12119.515219391125, 12153.270803751802, 12225.449854756735], "value": 9006.664826440787, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1423.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 108.0, 109.0, 109.0, 109.0, 95.0, 107.0, 107.0, 108.0, 107.0], "value": 106.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 120.0, 117.0, 118.0, 118.0, 151.0, 118.0, 117.0, 117.0, 123.0], "value": 121.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 161.0, 163.0, 162.0, 161.0, 162.0, 164.0, 165.0, 166.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 81.0, 73.0, 75.0, 75.0, 73.0, 74.0, 74.0, 73.0], "value": 74.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 79.0, 80.0, 78.0, 79.0, 80.0, 80.0, 79.0], "value": 79.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 114.0, 115.0, 114.0, 114.0, 114.0, 113.0, 116.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 147.0, 148.0, 148.0, 148.0, 146.0, 150.0, 149.0, 150.0, 151.0], "value": 149.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 95.0, 95.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 54.0, 58.0, 58.0, 54.0, 55.0, 53.0, 54.0, 53.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 39.0, 39.0, 38.0, 39.0, 38.0, 39.0, 39.0, 39.0], "value": 38.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 73.0, 74.0, 76.0, 80.0, 82.0, 72.0, 73.0, 76.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 119.0, 110.0, 112.0, 114.0, 117.0, 118.0, 111.0, 114.0, 117.0], "value": 114.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 166.0, 166.0, 163.0, 178.0, 181.0, 181.0, 169.0, 164.0, 166.0], "value": 171.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 69.0, 66.0, 84.0, 65.0, 63.0, 65.0, 66.0], "value": 66.6, "unit": "score"}]}]} 04:04:11 INFO - Return code: 0 04:04:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:04:11 INFO - # TBPL SUCCESS # 04:04:11 INFO - Running post-action listener: _package_coverage_data 04:04:11 INFO - Running post-action listener: _resource_record_post_action 04:04:11 INFO - [mozharness: 2017-09-27 11:04:11.598081Z] Finished run-tests step (success) 04:04:11 INFO - Running post-run listener: _resource_record_post_run 04:04:11 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 12136448; Write bytes: 391180288; Read time: 6552; Write time: 689132 04:04:11 INFO - TinderboxPrint: CPU usage
13.2% 04:04:11 INFO - TinderboxPrint: I/O read bytes / time
12,136,448 / 6,552 04:04:11 INFO - TinderboxPrint: I/O write bytes / time
391,180,288 / 689,132 04:04:11 INFO - TinderboxPrint: CPU idle
8,276.7 (86.8%) 04:04:11 INFO - TinderboxPrint: CPU user
1,195.2 (12.5%) 04:04:11 INFO - TinderboxPrint: Swap in / out
0 / 0 04:04:11 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 107745280; Read time: 0; Write time: 259852 04:04:11 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:04:11 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 10391552; Write bytes: 257744896; Read time: 5932; Write time: 306936 04:04:12 INFO - Running post-run listener: _upload_blobber_files 04:04:12 INFO - Blob upload gear active. 04:04:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:04:12 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 04:04:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:04:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:04:12 INFO - (blobuploader) - INFO - Open directory for files ... 04:04:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:04:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:04:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:04:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:04:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:04:13 INFO - (blobuploader) - INFO - Done attempting. 04:04:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:04:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:04:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:04:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:04:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:04:20 INFO - (blobuploader) - INFO - Done attempting. 04:04:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:04:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:04:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:04:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:04:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:04:21 INFO - (blobuploader) - INFO - Done attempting. 04:04:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:04:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:04:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:04:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:04:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:04:22 INFO - (blobuploader) - INFO - Done attempting. 04:04:22 INFO - (blobuploader) - INFO - Iteration through files over. 04:04:22 INFO - Return code: 0 04:04:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:04:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:04:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5e9e54b9c88f1164e1239b2314008cbbe332843bcb579d67ee93caa904aed897ddc15c9171ef17b85ed58dc6d2577638b0084739232fcd1bf439eb79b2a275bf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17d053493aa5d026b91d0bdb6c5dc95e7ab8678f7f69b771e6799487cd6a8473ed3a7a319534e505c52861eb34c270bdbd85f9ce985dd71b70745cfa128451fb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/764318e2b0997d7c19f1325c593a06bbb41587fb8ec04e22f651562aba43594398ffd83ca362ba895d26d8c5f702a0a4d75925052c507c957411b793f0d48b09", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/079159cbfae2480bd856f791f2f88d61f92d0d64c1f70075915288f058a6701a7426af0690fb296fd20c6777c2892ab75ae4eaa27615fbce12717f8a2d2bbd6b"} 04:04:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:04:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:04:22 INFO - Contents: 04:04:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5e9e54b9c88f1164e1239b2314008cbbe332843bcb579d67ee93caa904aed897ddc15c9171ef17b85ed58dc6d2577638b0084739232fcd1bf439eb79b2a275bf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17d053493aa5d026b91d0bdb6c5dc95e7ab8678f7f69b771e6799487cd6a8473ed3a7a319534e505c52861eb34c270bdbd85f9ce985dd71b70745cfa128451fb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/764318e2b0997d7c19f1325c593a06bbb41587fb8ec04e22f651562aba43594398ffd83ca362ba895d26d8c5f702a0a4d75925052c507c957411b793f0d48b09", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/079159cbfae2480bd856f791f2f88d61f92d0d64c1f70075915288f058a6701a7426af0690fb296fd20c6777c2892ab75ae4eaa27615fbce12717f8a2d2bbd6b"} 04:04:22 INFO - Running post-run listener: copy_logs_to_upload_dir 04:04:22 INFO - Copying logs to upload dir... 04:04:22 INFO - mkdir: /builds/slave/test/build/upload/logs 04:04:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.000115 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-09-27 04:04:22.372184) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 04:04:22.375304) ========= 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=8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5e9e54b9c88f1164e1239b2314008cbbe332843bcb579d67ee93caa904aed897ddc15c9171ef17b85ed58dc6d2577638b0084739232fcd1bf439eb79b2a275bf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17d053493aa5d026b91d0bdb6c5dc95e7ab8678f7f69b771e6799487cd6a8473ed3a7a319534e505c52861eb34c270bdbd85f9ce985dd71b70745cfa128451fb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/764318e2b0997d7c19f1325c593a06bbb41587fb8ec04e22f651562aba43594398ffd83ca362ba895d26d8c5f702a0a4d75925052c507c957411b793f0d48b09", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/079159cbfae2480bd856f791f2f88d61f92d0d64c1f70075915288f058a6701a7426af0690fb296fd20c6777c2892ab75ae4eaa27615fbce12717f8a2d2bbd6b"} build_url:https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011052 build_url: 'https://queue.taskcluster.net/v1/task/FadbKXGRQEiHgoCzlzPmQA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5e9e54b9c88f1164e1239b2314008cbbe332843bcb579d67ee93caa904aed897ddc15c9171ef17b85ed58dc6d2577638b0084739232fcd1bf439eb79b2a275bf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/17d053493aa5d026b91d0bdb6c5dc95e7ab8678f7f69b771e6799487cd6a8473ed3a7a319534e505c52861eb34c270bdbd85f9ce985dd71b70745cfa128451fb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/764318e2b0997d7c19f1325c593a06bbb41587fb8ec04e22f651562aba43594398ffd83ca362ba895d26d8c5f702a0a4d75925052c507c957411b793f0d48b09", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/079159cbfae2480bd856f791f2f88d61f92d0d64c1f70075915288f058a6701a7426af0690fb296fd20c6777c2892ab75ae4eaa27615fbce12717f8a2d2bbd6b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 04:04:22.405670) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:04:22.405966) ========= 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=8783db0840cc980e80bb0bd400000236-1506509001.33899-526826409 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004423 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 04:04:22.484450) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 04:04:22.484730) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 04:04:22.487060) ========= ========= Total master_lag: 0.24 =========