builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-038 starttime: 1505214024.6 results: success (0) revision: b0e945eed81db8bf076daf64e381c514f70144f0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-12 04:00:24.599976) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-12 04:00:24.600735) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-12 04:00:24.601003) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-12 04:00:24.649011) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-12 04:00:24.649253) ========= 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-1505213325.219875-1096098407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-12 04:00:24-- 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% 109M=0s 2017-09-12 04:00:24 (109 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.374594 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-12 04:00:25.049478) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-12 04:00:25.052797) ========= 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-1505213325.219875-1096098407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016292 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-12 04:00:25.102475) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-12 04:00:25.102810) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b0e945eed81db8bf076daf64e381c514f70144f0 --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 b0e945eed81db8bf076daf64e381c514f70144f0 --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-1505213325.219875-1096098407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-12 04:00:25,083 truncating revision to first 12 chars 2017-09-12 04:00:25,084 Setting DEBUG logging. 2017-09-12 04:00:25,084 attempt 1/10 2017-09-12 04:00:25,084 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b0e945eed81d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-12 04:00:25,425 unpacking tar archive at: mozilla-central-b0e945eed81d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.659061 ========= master_lag: 0.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-12 04:00:25.994531) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-12 04:00:25.994842) ========= script_repo_revision: b0e945eed81db8bf076daf64e381c514f70144f0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-12 04:00:25.995201) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-12 04:00:25.995459) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-12 04:00:26.024722) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-12 04:00:26.025006) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1505213325.219875-1096098407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:00:26 INFO - MultiFileLogger online at 20170912 04:00:26 in /builds/slave/test 04:00:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:00:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:00:26 INFO - {'append_to_log': False, 04:00:26 INFO - 'base_work_dir': '/builds/slave/test', 04:00:26 INFO - 'blob_upload_branch': 'Firefox', 04:00:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:00:26 INFO - 'branch': 'Firefox', 04:00:26 INFO - 'buildbot_json_path': 'buildprops.json', 04:00:26 INFO - 'code_coverage': False, 04:00:26 INFO - 'config_files': ('talos/linux_config.py',), 04:00:26 INFO - 'default_actions': ('clobber', 04:00:26 INFO - 'read-buildbot-config', 04:00:26 INFO - 'download-and-extract', 04:00:26 INFO - 'populate-webroot', 04:00:26 INFO - 'create-virtualenv', 04:00:26 INFO - 'install', 04:00:26 INFO - 'setup-mitmproxy', 04:00:26 INFO - 'run-tests'), 04:00:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:00:26 INFO - 'disable_ccov_upload': False, 04:00:26 INFO - 'disable_stylo': False, 04:00:26 INFO - 'download_minidump_stackwalk': True, 04:00:26 INFO - 'download_symbols': 'ondemand', 04:00:26 INFO - 'enable_stylo': False, 04:00:26 INFO - 'enable_webrender': False, 04:00:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:00:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:00:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:00:26 INFO - 'gecko_profile': False, 04:00:26 INFO - 'gecko_profile_interval': 0, 04:00:26 INFO - 'installer_path': 'installer.exe', 04:00:26 INFO - 'log_level': 'info', 04:00:26 INFO - 'log_name': 'talos', 04:00:26 INFO - 'log_to_console': True, 04:00:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:00:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:00:26 INFO - 'opt_config_files': (), 04:00:26 INFO - 'pip_index': False, 04:00:26 INFO - 'suite': 'dromaeojs-e10s', 04:00:26 INFO - 'system_bits': '32', 04:00:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:00:26 INFO - 'title': 'talos-linux64-ix-038', 04:00:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:00:26 INFO - 'use_talos_json': True, 04:00:26 INFO - 'verify': 'False', 04:00:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:00:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:00:26 INFO - 'work_dir': 'build'} 04:00:26 INFO - [mozharness: 2017-09-12 11:00:26.139596Z] Running clobber step. 04:00:26 INFO - Running pre-action listener: _resource_record_pre_action 04:00:26 INFO - Running main action method: clobber 04:00:26 INFO - rmtree: /builds/slave/test/build 04:00:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:00:27 INFO - Running post-action listener: _resource_record_post_action 04:00:27 INFO - [mozharness: 2017-09-12 11:00:27.152315Z] Finished clobber step (success) 04:00:27 INFO - [mozharness: 2017-09-12 11:00:27.152414Z] Running read-buildbot-config step. 04:00:27 INFO - Running pre-action listener: _resource_record_pre_action 04:00:27 INFO - Running main action method: read_buildbot_config 04:00:27 INFO - Using buildbot properties: 04:00:27 INFO - { 04:00:27 INFO - "project": "", 04:00:27 INFO - "product": "firefox", 04:00:27 INFO - "who": "archaeopteryx@coole-files.de", 04:00:27 INFO - "installer_path": "public/build/target.tar.bz2", 04:00:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:00:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:00:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 04:00:27 INFO - "stage_platform": "linux64-pgo", 04:00:27 INFO - "basedir": "/builds/slave/test", 04:00:27 INFO - "buildnumber": 147, 04:00:27 INFO - "platform": "ubuntu64_hw", 04:00:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:00:27 INFO - "slavebuilddir": "test", 04:00:27 INFO - "taskId": "fYEj_bx-R7-G-NmOtlCACA", 04:00:27 INFO - "branch": "mozilla-central", 04:00:27 INFO - "script_repo_revision": "production", 04:00:27 INFO - "revision": "b0e945eed81db8bf076daf64e381c514f70144f0", 04:00:27 INFO - "slavename": "talos-linux64-ix-038", 04:00:27 INFO - "repo_path": "mozilla-central" 04:00:27 INFO - } 04:00:27 INFO - Finding installer, test and symbols from parent task. 04:00:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fYEj_bx-R7-G-NmOtlCACA'}, attempt #1 04:00:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fYEj_bx-R7-G-NmOtlCACA'}, attempt #1 04:00:28 INFO - Task dependencies: dQw1nXGeRJ-it9XJgZiT1A 04:00:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A'}, attempt #1 04:00:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.tar.bz2 04:00:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.test_packages.json 04:00:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.crashreporter-symbols.zip 04:00:28 INFO - Running post-action listener: _resource_record_post_action 04:00:28 INFO - [mozharness: 2017-09-12 11:00:28.511238Z] Finished read-buildbot-config step (success) 04:00:28 INFO - [mozharness: 2017-09-12 11:00:28.511456Z] Running download-and-extract step. 04:00:28 INFO - Running pre-action listener: _resource_record_pre_action 04:00:28 INFO - Running main action method: download_and_extract 04:00:28 INFO - mkdir: /builds/slave/test/build/tests 04:00: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')]} 04:00:28 INFO - trying https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.test_packages.json 04:00:28 INFO - Downloading https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:00:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:00:29 INFO - Downloaded 989 bytes. 04:00:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:00:29 INFO - Using the following test package requirements: 04:00:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:00:29 INFO - u'common': [u'target.common.tests.zip'], 04:00:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:00:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:00:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:00:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:00:29 INFO - u'mozbase': [u'target.common.tests.zip'], 04:00:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:00:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:00:29 INFO - u'web-platform': [u'target.common.tests.zip', 04:00:29 INFO - u'target.web-platform.tests.tar.gz'], 04:00:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:00:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 04:00:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.common.tests.zip 04:00:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:00:29 INFO - Fetch https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.common.tests.zip into memory 04:00:30 INFO - Content-Length response header: 55712821 04:00:30 INFO - Bytes received: 55712821 04:00:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.talos.tests.zip 04:00:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:00:41 INFO - Fetch https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.talos.tests.zip into memory 04:00:42 INFO - Content-Length response header: 14499231 04:00:42 INFO - Bytes received: 14499231 04:00: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')]} 04:00:42 INFO - trying https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.tar.bz2 04:00:42 INFO - Downloading https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:00:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:00:43 INFO - Downloaded 63671789 bytes. 04:00:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.tar.bz2 04:00:43 INFO - mkdir: /builds/slave/test/properties 04:00:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:00:43 INFO - Writing to file /builds/slave/test/properties/build_url 04:00:43 INFO - Contents: 04:00:43 INFO - build_url:https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.tar.bz2 04:00:43 INFO - Running post-action listener: _resource_record_post_action 04:00:43 INFO - Running post-action listener: find_tests_for_verification 04:00:43 INFO - Running post-action listener: set_extra_try_arguments 04:00:43 INFO - [mozharness: 2017-09-12 11:00:43.658950Z] Finished download-and-extract step (success) 04:00:43 INFO - [mozharness: 2017-09-12 11:00:43.659070Z] Running populate-webroot step. 04:00:43 INFO - Running pre-action listener: _resource_record_pre_action 04:00:43 INFO - Running main action method: populate_webroot 04:00:43 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:00:43 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:43 INFO - 'tests': ['tresize', 04:00:43 INFO - 'tcanvasmark']}, 04:00:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:00:43 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:43 INFO - 'tests': ['dromaeo_css', 04:00:43 INFO - 'kraken']}, 04:00:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:00:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:00:43 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:00:43 INFO - 'talos_options': ['--disable-stylo'], 04:00:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:00:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:00:43 INFO - 'tests': ['damp', 'tps']}, 04:00:43 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:00:43 INFO - 'talos_options': ['--disable-stylo'], 04:00:43 INFO - 'tests': ['damp', 'tps']}, 04:00:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:00:43 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:43 INFO - 'tests': ['dromaeo_dom']}, 04:00:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:00:43 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:43 INFO - 'tests': ['basic_compositor_video', 04:00:43 INFO - 'glvideo']}, 04:00:43 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:00:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:00:43 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:00:43 INFO - 'talos_options': ['--disable-stylo'], 04:00:43 INFO - 'tests': ['ts_paint_webext', 04:00:43 INFO - 'tp5o_webext']}, 04:00:43 INFO - 'other-e10s': {'tests': ['a11yr', 04:00:43 INFO - 'ts_paint', 04:00:43 INFO - 'tpaint', 04:00:43 INFO - 'sessionrestore', 04:00:43 INFO - 'sessionrestore_many_windows', 04:00:43 INFO - 'sessionrestore_no_auto_restore', 04:00:43 INFO - 'tabpaint']}, 04:00:43 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:43 INFO - 'tests': ['a11yr', 04:00:43 INFO - 'ts_paint', 04:00:43 INFO - 'tpaint', 04:00:43 INFO - 'sessionrestore', 04:00:43 INFO - 'sessionrestore_many_windows', 04:00:43 INFO - 'sessionrestore_no_auto_restore', 04:00:43 INFO - 'tabpaint']}, 04:00:43 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 04:00:43 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 04:00:43 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:43 INFO - 'tests': ['bloom_basic_singleton']}, 04:00:43 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:43 INFO - 'tests': ['bloom_basic']}, 04:00:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:00:43 INFO - 'tsvgr_opacity', 04:00:43 INFO - 'tart', 04:00:43 INFO - 'tscrollx', 04:00:43 INFO - 'tsvg_static']}, 04:00:43 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:00:43 INFO - 'tests': ['tsvgx', 04:00:43 INFO - 'tsvgr_opacity', 04:00:43 INFO - 'tart', 04:00:43 INFO - 'tscrollx', 04:00:43 INFO - 'tsvg_static']}, 04:00:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:00:43 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:00:43 INFO - 'talos_options': ['--disable-stylo'], 04:00:43 INFO - 'tests': ['tp5o']}, 04:00:43 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:00:43 INFO - 'talos_options': ['--mitmproxy', 04:00:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:00:43 INFO - '--firstNonBlankPaint'], 04:00:43 INFO - 'tests': ['tp6_google', 04:00:43 INFO - 'tp6_youtube', 04:00:43 INFO - 'tp6_amazon', 04:00:43 INFO - 'tp6_facebook']}, 04:00:43 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:00:43 INFO - 'talos_options': ['--disable-stylo', 04:00:43 INFO - '--mitmproxy', 04:00:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:00:43 INFO - '--firstNonBlankPaint'], 04:00:43 INFO - 'tests': ['tp6_google', 04:00:43 INFO - 'tp6_youtube', 04:00:43 INFO - 'tp6_amazon', 04:00:43 INFO - 'tp6_facebook']}, 04:00:43 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:00:43 INFO - 'talos_options': ['--stylo-threads=1', 04:00:43 INFO - '--mitmproxy', 04:00:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:00:43 INFO - '--firstNonBlankPaint'], 04:00:43 INFO - 'tests': ['tp6_google', 04:00:43 INFO - 'tp6_youtube', 04:00:43 INFO - 'tp6_amazon', 04:00:43 INFO - 'tp6_facebook']}, 04:00:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:00:43 INFO - 'talos_options': ['--xperf_path', 04:00:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:00:43 INFO - 'tests': ['tp5n']}, 04:00:43 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:00:43 INFO - 'talos_options': ['--disable-stylo', 04:00:43 INFO - '--xperf_path', 04:00:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:00:43 INFO - 'tests': ['tp5n']}}} 04:00:43 INFO - Running post-action listener: _resource_record_post_action 04:00:43 INFO - [mozharness: 2017-09-12 11:00:43.674500Z] Finished populate-webroot step (success) 04:00:43 INFO - [mozharness: 2017-09-12 11:00:43.674590Z] Running create-virtualenv step. 04:00:43 INFO - Running pre-action listener: _resource_record_pre_action 04:00:43 INFO - Running main action method: create_virtualenv 04:00:43 INFO - Creating virtualenv /builds/slave/test/build/venv 04:00:43 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:00:43 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:00:43 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:00:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:00:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:00:44 INFO - Installing distribute.............................................................................................................................................................................................done. 04:00:44 INFO - Installing pip...............done. 04:00:44 INFO - Return code: 0 04:00:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:00:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:00:44 INFO - Reading from file tmpfile_stdout 04:00:44 INFO - Output received: 04:00:44 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 04:00:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:00:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1457a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145b090>, '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 0x145bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1455f90>, '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-1505213325.219875-1096098407', '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 04:00:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:00:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:00:44 INFO - Using env: {'DISPLAY': ':0', 04:00:44 INFO - 'HOME': '/home/cltbld', 04:00:44 INFO - 'LANG': 'en_US.UTF-8', 04:00:44 INFO - 'LANGUAGE': 'en_US:en', 04:00:44 INFO - 'LOGNAME': 'cltbld', 04:00:44 INFO - 'MAIL': '/var/mail/cltbld', 04:00:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:44 INFO - 'MOZ_NO_REMOTE': '1', 04:00:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:44 INFO - 'NO_EM_RESTART': '1', 04:00:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:44 INFO - 'PWD': '/builds/slave/test', 04:00:44 INFO - 'SHELL': '/bin/bash', 04:00:44 INFO - 'SHLVL': '1', 04:00:44 INFO - 'TERM': 'linux', 04:00:44 INFO - 'TMOUT': '86400', 04:00:44 INFO - 'USER': 'cltbld', 04:00:44 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505213325.219875-1096098407', 04:00:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:44 INFO - '_': '/tools/buildbot/bin/python'} 04:00:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:00:50 INFO - Downloading/unpacking pip>=1.5 04:00:50 INFO - Running setup.py egg_info for package pip 04:00:50 INFO - warning: no files found matching 'pip/cacert.pem' 04:00:50 INFO - warning: no files found matching '*.html' under directory 'docs' 04:00:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:00:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:00:50 INFO - Installing collected packages: pip 04:00:50 INFO - Found existing installation: pip 1.1 04:00:50 INFO - Uninstalling pip: 04:00:50 INFO - Successfully uninstalled pip 04:00:50 INFO - Running setup.py install for pip 04:00:50 INFO - warning: no files found matching 'pip/cacert.pem' 04:00:50 INFO - warning: no files found matching '*.html' under directory 'docs' 04:00:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:00:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:00:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:00:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:00:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:00:50 INFO - Successfully installed pip 04:00:50 INFO - Cleaning up... 04:00:50 INFO - Return code: 0 04:00:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:00:50 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')]} 04:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:50 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')]} 04:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:50 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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1457a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145b090>, '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 0x145bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1455f90>, '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-1505213325.219875-1096098407', '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 04:00:50 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 04:00:50 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 04:00:50 INFO - Using env: {'DISPLAY': ':0', 04:00:50 INFO - 'HOME': '/home/cltbld', 04:00:50 INFO - 'LANG': 'en_US.UTF-8', 04:00:50 INFO - 'LANGUAGE': 'en_US:en', 04:00:50 INFO - 'LOGNAME': 'cltbld', 04:00:50 INFO - 'MAIL': '/var/mail/cltbld', 04:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:50 INFO - 'MOZ_NO_REMOTE': '1', 04:00:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:50 INFO - 'NO_EM_RESTART': '1', 04:00:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:50 INFO - 'PWD': '/builds/slave/test', 04:00:50 INFO - 'SHELL': '/bin/bash', 04:00:50 INFO - 'SHLVL': '1', 04:00:50 INFO - 'TERM': 'linux', 04:00:50 INFO - 'TMOUT': '86400', 04:00:50 INFO - 'USER': 'cltbld', 04:00:50 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505213325.219875-1096098407', 04:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:50 INFO - '_': '/tools/buildbot/bin/python'} 04:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:50 INFO - Downloading/unpacking psutil>=3.1.1 04:00:50 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 04:00:50 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 04:00:50 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 04:00:50 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 04:00:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:00:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:00:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:00:51 INFO - Installing collected packages: psutil 04:00:51 INFO - Running setup.py install for psutil 04:00:51 INFO - building 'psutil._psutil_linux' extension 04:00:51 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 04:00:51 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 04:00:51 INFO - building 'psutil._psutil_posix' extension 04:00:51 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 04:00:51 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 04:00:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:00:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:00:51 INFO - Successfully installed psutil 04:00:51 INFO - Cleaning up... 04:00:52 INFO - Return code: 0 04:00:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1457a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145b090>, '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 0x145bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1455f90>, '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-1505213325.219875-1096098407', '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 04:00:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:00:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:00:52 INFO - Using env: {'DISPLAY': ':0', 04:00:52 INFO - 'HOME': '/home/cltbld', 04:00:52 INFO - 'LANG': 'en_US.UTF-8', 04:00:52 INFO - 'LANGUAGE': 'en_US:en', 04:00:52 INFO - 'LOGNAME': 'cltbld', 04:00:52 INFO - 'MAIL': '/var/mail/cltbld', 04:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:52 INFO - 'MOZ_NO_REMOTE': '1', 04:00:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:52 INFO - 'NO_EM_RESTART': '1', 04:00:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:52 INFO - 'PWD': '/builds/slave/test', 04:00:52 INFO - 'SHELL': '/bin/bash', 04:00:52 INFO - 'SHLVL': '1', 04:00:52 INFO - 'TERM': 'linux', 04:00:52 INFO - 'TMOUT': '86400', 04:00:52 INFO - 'USER': 'cltbld', 04:00:52 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505213325.219875-1096098407', 04:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:52 INFO - '_': '/tools/buildbot/bin/python'} 04:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:00:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:00:53 INFO - Installing collected packages: mozsystemmonitor 04:00:53 INFO - Running setup.py install for mozsystemmonitor 04:00:53 INFO - Successfully installed mozsystemmonitor 04:00:53 INFO - Cleaning up... 04:00:53 INFO - Return code: 0 04:00:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:00: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')]} 04:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00: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')]} 04:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00: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', '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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1457a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145b090>, '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 0x145bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1455f90>, '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-1505213325.219875-1096098407', '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 04:00: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:00: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 jsonschema==2.5.1 04:00:53 INFO - Using env: {'DISPLAY': ':0', 04:00:53 INFO - 'HOME': '/home/cltbld', 04:00:53 INFO - 'LANG': 'en_US.UTF-8', 04:00:53 INFO - 'LANGUAGE': 'en_US:en', 04:00:53 INFO - 'LOGNAME': 'cltbld', 04:00:53 INFO - 'MAIL': '/var/mail/cltbld', 04:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:53 INFO - 'MOZ_NO_REMOTE': '1', 04:00:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:53 INFO - 'NO_EM_RESTART': '1', 04:00:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:53 INFO - 'PWD': '/builds/slave/test', 04:00:53 INFO - 'SHELL': '/bin/bash', 04:00:53 INFO - 'SHLVL': '1', 04:00:53 INFO - 'TERM': 'linux', 04:00:53 INFO - 'TMOUT': '86400', 04:00:53 INFO - 'USER': 'cltbld', 04:00:53 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505213325.219875-1096098407', 04:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:53 INFO - '_': '/tools/buildbot/bin/python'} 04:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:54 INFO - Downloading/unpacking jsonschema==2.5.1 04:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:00:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:00:55 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 04:00:55 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 04:00:55 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 04:00:55 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 04:00:55 INFO - Downloading functools32-3.2.3-2.tar.gz 04:00:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:00:55 INFO - warning: no files found matching '*.txt' 04:00:55 INFO - no previously-included directories found matching 'build' 04:00:55 INFO - no previously-included directories found matching 'dist' 04:00:55 INFO - no previously-included directories found matching '.git*' 04:00:55 INFO - Installing collected packages: jsonschema, functools32 04:00:55 INFO - Running setup.py install for functools32 04:00:55 INFO - warning: no files found matching '*.txt' 04:00:55 INFO - no previously-included directories found matching 'build' 04:00:55 INFO - no previously-included directories found matching 'dist' 04:00:55 INFO - no previously-included directories found matching '.git*' 04:00:55 INFO - Successfully installed jsonschema functools32 04:00:55 INFO - Cleaning up... 04:00:55 INFO - Return code: 0 04:00:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:55 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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1457a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145b090>, '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 0x145bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1455f90>, '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-1505213325.219875-1096098407', '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 04:00:55 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 04:00:55 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 04:00:55 INFO - Using env: {'DISPLAY': ':0', 04:00:55 INFO - 'HOME': '/home/cltbld', 04:00:55 INFO - 'LANG': 'en_US.UTF-8', 04:00:55 INFO - 'LANGUAGE': 'en_US:en', 04:00:55 INFO - 'LOGNAME': 'cltbld', 04:00:55 INFO - 'MAIL': '/var/mail/cltbld', 04:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:55 INFO - 'MOZ_NO_REMOTE': '1', 04:00:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:55 INFO - 'NO_EM_RESTART': '1', 04:00:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:55 INFO - 'PWD': '/builds/slave/test', 04:00:55 INFO - 'SHELL': '/bin/bash', 04:00:55 INFO - 'SHLVL': '1', 04:00:55 INFO - 'TERM': 'linux', 04:00:55 INFO - 'TMOUT': '86400', 04:00:55 INFO - 'USER': 'cltbld', 04:00:55 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505213325.219875-1096098407', 04:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:55 INFO - '_': '/tools/buildbot/bin/python'} 04:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:00:55 INFO - Cleaning up... 04:00:55 INFO - Return code: 0 04:00:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:55 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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1457a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145b090>, '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 0x145bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1455f90>, '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-1505213325.219875-1096098407', '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 04:00:55 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 04:00:55 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 04:00:55 INFO - Using env: {'DISPLAY': ':0', 04:00:55 INFO - 'HOME': '/home/cltbld', 04:00:55 INFO - 'LANG': 'en_US.UTF-8', 04:00:55 INFO - 'LANGUAGE': 'en_US:en', 04:00:55 INFO - 'LOGNAME': 'cltbld', 04:00:55 INFO - 'MAIL': '/var/mail/cltbld', 04:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:55 INFO - 'MOZ_NO_REMOTE': '1', 04:00:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:55 INFO - 'NO_EM_RESTART': '1', 04:00:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:55 INFO - 'PWD': '/builds/slave/test', 04:00:55 INFO - 'SHELL': '/bin/bash', 04:00:55 INFO - 'SHLVL': '1', 04:00:55 INFO - 'TERM': 'linux', 04:00:55 INFO - 'TMOUT': '86400', 04:00:55 INFO - 'USER': 'cltbld', 04:00:55 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505213325.219875-1096098407', 04:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:55 INFO - '_': '/tools/buildbot/bin/python'} 04:00:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:56 INFO - Downloading/unpacking blobuploader==1.2.4 04:00: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 04:00: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 04:00: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 04:00: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 04:00:57 INFO - Downloading blobuploader-1.2.4.tar.gz 04:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:00:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:00:57 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 04:00:57 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 04:00:57 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 04:00:57 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 04:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:00:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:00:57 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 04:00:57 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 04:00:57 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 04:00:57 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 04:00:57 INFO - Downloading docopt-0.6.1.tar.gz 04:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:00:57 INFO - Installing collected packages: blobuploader, requests, docopt 04:00:57 INFO - Running setup.py install for blobuploader 04:00:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:00:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:00:57 INFO - Running setup.py install for requests 04:00:58 INFO - Running setup.py install for docopt 04:00:58 INFO - Successfully installed blobuploader requests docopt 04:00:58 INFO - Cleaning up... 04:00:58 INFO - Return code: 0 04:00:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:58 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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1457a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145b090>, '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 0x145bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1455f90>, '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-1505213325.219875-1096098407', '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 04:00:58 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 04:00:58 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 04:00:58 INFO - Using env: {'DISPLAY': ':0', 04:00:58 INFO - 'HOME': '/home/cltbld', 04:00:58 INFO - 'LANG': 'en_US.UTF-8', 04:00:58 INFO - 'LANGUAGE': 'en_US:en', 04:00:58 INFO - 'LOGNAME': 'cltbld', 04:00:58 INFO - 'MAIL': '/var/mail/cltbld', 04:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:58 INFO - 'MOZ_NO_REMOTE': '1', 04:00:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:58 INFO - 'NO_EM_RESTART': '1', 04:00:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:58 INFO - 'PWD': '/builds/slave/test', 04:00:58 INFO - 'SHELL': '/bin/bash', 04:00:58 INFO - 'SHLVL': '1', 04:00:58 INFO - 'TERM': 'linux', 04:00:58 INFO - 'TMOUT': '86400', 04:00:58 INFO - 'USER': 'cltbld', 04:00:58 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505213325.219875-1096098407', 04:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:58 INFO - '_': '/tools/buildbot/bin/python'} 04:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:00:58 INFO - Running setup.py (path:/tmp/pip-t78rKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:00:58 INFO - Running setup.py (path:/tmp/pip-l1W8Hg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:00:58 INFO - Running setup.py (path:/tmp/pip-4vsfF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:00:58 INFO - Running setup.py (path:/tmp/pip-FO6hiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:00:58 INFO - Running setup.py (path:/tmp/pip-PxGFAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:00:58 INFO - Running setup.py (path:/tmp/pip-dPPfk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:00:58 INFO - Running setup.py (path:/tmp/pip-0eSJsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:00:59 INFO - Running setup.py (path:/tmp/pip-kePoAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:00:59 INFO - Running setup.py (path:/tmp/pip-y19gFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:00:59 INFO - Running setup.py (path:/tmp/pip-8syt_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:00:59 INFO - Running setup.py (path:/tmp/pip-F_yTzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:00:59 INFO - Running setup.py (path:/tmp/pip-K8WHVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:00:59 INFO - Running setup.py (path:/tmp/pip-3gFOeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:00:59 INFO - Running setup.py (path:/tmp/pip-UVEg72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:00:59 INFO - Running setup.py (path:/tmp/pip-cHXye_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:00:59 INFO - Running setup.py (path:/tmp/pip-Wqog6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:00:59 INFO - Running setup.py (path:/tmp/pip-Rcsi6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:00:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:00:59 INFO - Running setup.py install for manifestparser 04:00:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:00:59 INFO - Running setup.py install for mozcrash 04:00:59 INFO - Running setup.py install for mozdebug 04:01:00 INFO - Running setup.py install for mozdevice 04:01:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:01:00 INFO - Running setup.py install for mozfile 04:01:00 INFO - Running setup.py install for mozhttpd 04:01:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:01:00 INFO - Running setup.py install for mozinfo 04:01:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:01:00 INFO - Running setup.py install for mozInstall 04:01:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:01:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:01:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:01:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:01:00 INFO - Running setup.py install for mozleak 04:01:00 INFO - Running setup.py install for mozlog 04:01:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:01:00 INFO - Running setup.py install for moznetwork 04:01:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:01:00 INFO - Running setup.py install for mozprocess 04:01:00 INFO - Running setup.py install for mozprofile 04:01:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:01:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:01:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:01:01 INFO - Running setup.py install for mozrunner 04:01:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:01:01 INFO - Running setup.py install for mozscreenshot 04:01:01 INFO - Running setup.py install for moztest 04:01:01 INFO - Running setup.py install for mozversion 04:01:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:01:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:01:01 INFO - Cleaning up... 04:01:01 INFO - Return code: 0 04:01:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:01: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')]} 04:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01: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')]} 04:01:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:01 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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1457a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145b090>, '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 0x145bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1455f90>, '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-1505213325.219875-1096098407', '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 04:01:01 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 04:01:01 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 04:01:01 INFO - Using env: {'DISPLAY': ':0', 04:01:01 INFO - 'HOME': '/home/cltbld', 04:01:01 INFO - 'LANG': 'en_US.UTF-8', 04:01:01 INFO - 'LANGUAGE': 'en_US:en', 04:01:01 INFO - 'LOGNAME': 'cltbld', 04:01:01 INFO - 'MAIL': '/var/mail/cltbld', 04:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:01 INFO - 'MOZ_NO_REMOTE': '1', 04:01:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:01 INFO - 'NO_EM_RESTART': '1', 04:01:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:01 INFO - 'PWD': '/builds/slave/test', 04:01:01 INFO - 'SHELL': '/bin/bash', 04:01:01 INFO - 'SHLVL': '1', 04:01:01 INFO - 'TERM': 'linux', 04:01:01 INFO - 'TMOUT': '86400', 04:01:01 INFO - 'USER': 'cltbld', 04:01:01 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505213325.219875-1096098407', 04:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:01 INFO - '_': '/tools/buildbot/bin/python'} 04:01:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:01:01 INFO - Running setup.py (path:/tmp/pip-BogSTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:01:01 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)) 04:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:01:01 INFO - Running setup.py (path:/tmp/pip-dOBc6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:01:01 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)) 04:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:01:01 INFO - Running setup.py (path:/tmp/pip-ST3QFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:01:01 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)) 04:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:01:01 INFO - Running setup.py (path:/tmp/pip-2YL0En-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:01:01 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)) 04:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:01:01 INFO - Running setup.py (path:/tmp/pip-rxCmOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:01:02 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)) 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:01:02 INFO - Running setup.py (path:/tmp/pip-90jUIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:01:02 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)) 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:01:02 INFO - Running setup.py (path:/tmp/pip-rDuCRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:01:02 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)) 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:01:02 INFO - Running setup.py (path:/tmp/pip-1kVzF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:01:02 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)) 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:01:02 INFO - Running setup.py (path:/tmp/pip-WJS9Lk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:01:02 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)) 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:01:02 INFO - Running setup.py (path:/tmp/pip-pCBGlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:01:02 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)) 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:01:02 INFO - Running setup.py (path:/tmp/pip-92JNLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:01:02 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)) 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:01:02 INFO - Running setup.py (path:/tmp/pip-1xmPQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:01:02 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)) 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:01:02 INFO - Running setup.py (path:/tmp/pip-brEsxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:01:02 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)) 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:01:02 INFO - Running setup.py (path:/tmp/pip-1rX4Ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:01:02 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)) 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:02 INFO - Running setup.py (path:/tmp/pip-cW2p1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:02 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)) 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:01:02 INFO - Running setup.py (path:/tmp/pip-HIZ152-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:01:02 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)) 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:01:02 INFO - Running setup.py (path:/tmp/pip-Im_gB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:01:02 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)) 04:01:02 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)) 04:01:02 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)) 04:01:02 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)) 04:01:02 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)) 04:01:02 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)) 04:01:02 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)) 04:01:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:01: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 04:01: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 04:01: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 04:01: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 04:01:04 INFO - Downloading blessings-1.6.tar.gz 04:01:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:01:04 INFO - Installing collected packages: blessings 04:01:04 INFO - Running setup.py install for blessings 04:01:04 INFO - Successfully installed blessings 04:01:04 INFO - Cleaning up... 04:01:04 INFO - Return code: 0 04:01:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:01:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:01:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:01:04 INFO - Reading from file tmpfile_stdout 04:01:04 INFO - Current package versions: 04:01:04 INFO - argparse == 1.2.1 04:01:04 INFO - blessings == 1.6 04:01:04 INFO - blobuploader == 1.2.4 04:01:04 INFO - docopt == 0.6.1 04:01:04 INFO - functools32 == 3.2.3-2 04:01:04 INFO - jsonschema == 2.5.1 04:01:04 INFO - manifestparser == 1.1 04:01:04 INFO - mozInstall == 1.13 04:01:04 INFO - mozcrash == 1.0 04:01:04 INFO - mozdebug == 0.1 04:01:04 INFO - mozdevice == 0.51 04:01:04 INFO - mozfile == 1.2 04:01:04 INFO - mozhttpd == 0.7 04:01:04 INFO - mozinfo == 0.10 04:01:04 INFO - mozleak == 0.1 04:01:04 INFO - mozlog == 3.5 04:01:04 INFO - moznetwork == 0.27 04:01:04 INFO - mozprocess == 0.25 04:01:04 INFO - mozprofile == 0.28 04:01:04 INFO - mozrunner == 6.13 04:01:04 INFO - mozscreenshot == 0.1 04:01:04 INFO - mozsystemmonitor == 0.3 04:01:04 INFO - moztest == 0.8 04:01:04 INFO - mozversion == 1.4 04:01:04 INFO - psutil == 3.1.1 04:01:04 INFO - requests == 1.2.3 04:01:04 INFO - wsgiref == 0.1.2 04:01:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:04 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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1457a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145b090>, '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 0x145bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1455f90>, '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-1505213325.219875-1096098407', '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 04:01:04 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 04:01:04 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 04:01:04 INFO - Using env: {'DISPLAY': ':0', 04:01:04 INFO - 'HOME': '/home/cltbld', 04:01:04 INFO - 'LANG': 'en_US.UTF-8', 04:01:04 INFO - 'LANGUAGE': 'en_US:en', 04:01:04 INFO - 'LOGNAME': 'cltbld', 04:01:04 INFO - 'MAIL': '/var/mail/cltbld', 04:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:04 INFO - 'MOZ_NO_REMOTE': '1', 04:01:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:04 INFO - 'NO_EM_RESTART': '1', 04:01:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:04 INFO - 'PWD': '/builds/slave/test', 04:01:04 INFO - 'SHELL': '/bin/bash', 04:01:04 INFO - 'SHLVL': '1', 04:01:04 INFO - 'TERM': 'linux', 04:01:04 INFO - 'TMOUT': '86400', 04:01:04 INFO - 'USER': 'cltbld', 04:01:04 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505213325.219875-1096098407', 04:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:04 INFO - '_': '/tools/buildbot/bin/python'} 04:01:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:04 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)) 04:01:04 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)) 04:01:04 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)) 04:01:04 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)) 04:01:04 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)) 04:01:04 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)) 04:01:04 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)) 04:01:04 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)) 04:01:04 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)) 04:01:04 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:01: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 04:01: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 04:01: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 04:01: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 04:01:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:01:05 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)) 04:01:05 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)) 04:01:05 INFO - Installing collected packages: simplejson 04:01:05 INFO - Running setup.py install for simplejson 04:01:05 INFO - building 'simplejson._speedups' extension 04:01:05 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 04:01:06 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 04:01:06 INFO - Successfully installed simplejson 04:01:06 INFO - Cleaning up... 04:01:06 INFO - Return code: 0 04:01:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:01: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')]} 04:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01: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')]} 04:01:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:06 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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12b4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1457a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145b090>, '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 0x145bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1455f90>, '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-1505213325.219875-1096098407', '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 04:01:06 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 04:01:06 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 04:01:06 INFO - Using env: {'DISPLAY': ':0', 04:01:06 INFO - 'HOME': '/home/cltbld', 04:01:06 INFO - 'LANG': 'en_US.UTF-8', 04:01:06 INFO - 'LANGUAGE': 'en_US:en', 04:01:06 INFO - 'LOGNAME': 'cltbld', 04:01:06 INFO - 'MAIL': '/var/mail/cltbld', 04:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:06 INFO - 'MOZ_NO_REMOTE': '1', 04:01:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:06 INFO - 'NO_EM_RESTART': '1', 04:01:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:06 INFO - 'PWD': '/builds/slave/test', 04:01:06 INFO - 'SHELL': '/bin/bash', 04:01:06 INFO - 'SHLVL': '1', 04:01:06 INFO - 'TERM': 'linux', 04:01:06 INFO - 'TMOUT': '86400', 04:01:06 INFO - 'USER': 'cltbld', 04:01:06 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505213325.219875-1096098407', 04:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:06 INFO - '_': '/tools/buildbot/bin/python'} 04:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:01:07 INFO - Cleaning up... 04:01:07 INFO - Return code: 0 04:01:07 INFO - Running post-action listener: _resource_record_post_action 04:01:07 INFO - Running post-action listener: _start_resource_monitoring 04:01:07 INFO - Starting resource monitoring. 04:01:07 INFO - [mozharness: 2017-09-12 11:01:07.186627Z] Finished create-virtualenv step (success) 04:01:07 INFO - [mozharness: 2017-09-12 11:01:07.187136Z] Running install step. 04:01:07 INFO - Running pre-action listener: _resource_record_pre_action 04:01:07 INFO - Running main action method: install 04:01:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:01:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:01:07 INFO - Reading from file tmpfile_stdout 04:01:07 INFO - Detecting whether we're running mozinstall >=1.0... 04:01:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:01:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:01:07 INFO - Reading from file tmpfile_stdout 04:01:07 INFO - Output received: 04:01:07 INFO - Usage: mozinstall [options] installer 04:01:07 INFO - Options: 04:01:07 INFO - -h, --help show this help message and exit 04:01:07 INFO - -d DEST, --destination=DEST 04:01:07 INFO - Directory to install application into. [default: 04:01:07 INFO - "/builds/slave/test"] 04:01:07 INFO - --app=APP Application being installed. [default: firefox] 04:01:07 INFO - mkdir: /builds/slave/test/build/application 04:01:07 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'] 04:01:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:01:23 INFO - Reading from file tmpfile_stdout 04:01:23 INFO - Output received: 04:01:23 INFO - /builds/slave/test/build/application/firefox/firefox 04:01:23 INFO - Running post-action listener: _resource_record_post_action 04:01:23 INFO - [mozharness: 2017-09-12 11:01:23.945466Z] Finished install step (success) 04:01:23 INFO - [mozharness: 2017-09-12 11:01:23.945720Z] Running setup-mitmproxy step. 04:01:23 INFO - Running pre-action listener: _resource_record_pre_action 04:01:23 INFO - Running main action method: setup_mitmproxy 04:01:23 INFO - Skipping: mitmproxy is not required 04:01:23 INFO - Running post-action listener: _resource_record_post_action 04:01:23 INFO - [mozharness: 2017-09-12 11:01:23.946837Z] Finished setup-mitmproxy step (success) 04:01:23 INFO - [mozharness: 2017-09-12 11:01:23.947052Z] Running run-tests step. 04:01:23 INFO - Running pre-action listener: _resource_record_pre_action 04:01:23 INFO - Running pre-action listener: _set_gcov_prefix 04:01:23 INFO - Running main action method: run_tests 04:01:23 WARNING - Try message not found. 04:01:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:01:23 INFO - Python 2.7.3 04:01:23 INFO - Return code: 0 04:01:23 INFO - grabbing minidump binary from tooltool 04:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:01:23 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x145b090>, '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 0x145bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1455f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:01:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:01:23 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:01:23 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:01:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:01:24 INFO - Return code: 0 04:01:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:01:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:01:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:01:24 INFO - ENV: RUST_BACKTRACE is now full 04:01:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:01:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:01:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/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 04:01:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/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 04:01:24 INFO - Using env: {'DISPLAY': ':0', 04:01:24 INFO - 'HOME': '/home/cltbld', 04:01:24 INFO - 'LANG': 'en_US.UTF-8', 04:01:24 INFO - 'LANGUAGE': 'en_US:en', 04:01:24 INFO - 'LOGNAME': 'cltbld', 04:01:24 INFO - 'MAIL': '/var/mail/cltbld', 04:01:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:01:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:01:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:24 INFO - 'MOZ_NO_REMOTE': '1', 04:01:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:01:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:24 INFO - 'NO_EM_RESTART': '1', 04:01:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:24 INFO - 'PWD': '/builds/slave/test', 04:01:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:01:24 INFO - 'RUST_BACKTRACE': 'full', 04:01:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:01:24 INFO - 'SHELL': '/bin/bash', 04:01:24 INFO - 'SHLVL': '1', 04:01:24 INFO - 'TERM': 'linux', 04:01:24 INFO - 'TMOUT': '86400', 04:01:24 INFO - 'USER': 'cltbld', 04:01:24 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505213325.219875-1096098407', 04:01:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:24 INFO - '_': '/tools/buildbot/bin/python'} 04:01:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/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 04:01:24 INFO - mozversion application_buildid: 20170912093540 04:01:24 INFO - mozversion application_changeset: b0e945eed81db8bf076daf64e381c514f70144f0 04:01:24 INFO - mozversion application_display_name: Nightly 04:01:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:01:24 INFO - mozversion application_name: Firefox 04:01:24 INFO - mozversion application_remotingname: firefox 04:01:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:01:24 INFO - mozversion application_vendor: Mozilla 04:01:24 INFO - mozversion application_version: 57.0a1 04:01:24 INFO - mozversion platform_buildid: 20170912093540 04:01:24 INFO - mozversion platform_changeset: b0e945eed81db8bf076daf64e381c514f70144f0 04:01:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:01:24 INFO - mozversion platform_version: 57.0a1 04:01:24 INFO - using testdate: 1505214084 04:01:24 INFO - actual date: 1505214084 04:01:24 INFO - starting webserver on 'localhost:38908' 04:01:24 INFO - SUITE-START | Running 2 tests 04:01:24 INFO - TEST-START | dromaeo_css 04:01:24 INFO - Initialising browser for dromaeo_css test... 04:01:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38908/getInfo.html -profile /tmp/tmp2kn_0Y/profile 04:01:24 INFO - TEST-INFO | started process 5120 (/builds/slave/test/build/application/firefox/firefox http://localhost:38908/getInfo.html) 04:01:30 INFO - TEST-INFO | 5120: exit 0 04:01:30 INFO - Browser initialized. 04:01:30 INFO - Running cycle 1/1 for dromaeo_css test... 04:01:30 INFO - Using env: {'DISPLAY': ':0', 04:01:30 INFO - 'HOME': '/home/cltbld', 04:01:30 INFO - 'JSGC_DISABLE_POISONING': '1', 04:01:30 INFO - 'LANG': 'en_US.UTF-8', 04:01:30 INFO - 'LANGUAGE': 'en_US:en', 04:01:30 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:01:30 INFO - 'LOGNAME': 'cltbld', 04:01:30 INFO - 'MAIL': '/var/mail/cltbld', 04:01:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:01:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:01:30 INFO - 'MOZ_CRASHREPORTER': '1', 04:01:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:30 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:01:30 INFO - 'MOZ_NO_REMOTE': '1', 04:01:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:01:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:30 INFO - 'NO_EM_RESTART': '1', 04:01:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:30 INFO - 'PWD': '/builds/slave/test', 04:01:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:01:30 INFO - 'RUST_BACKTRACE': 'full', 04:01:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:01:30 INFO - 'SHELL': '/bin/bash', 04:01:30 INFO - 'SHLVL': '1', 04:01:30 INFO - 'TERM': 'linux', 04:01:30 INFO - 'TMOUT': '86400', 04:01:30 INFO - 'USER': 'cltbld', 04:01:30 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505213325.219875-1096098407', 04:01:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:30 INFO - '_': '/tools/buildbot/bin/python'} 04:01:30 INFO - TEST-INFO | started process 5319 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2kn_0Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:01:31 INFO - PID 5319 | 04:01:31 INFO - PID 5319 | (/builds/slave/test/build/application/firefox/firefox:5370): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:01:31 INFO - PID 5319 | 04:01:42 INFO - PID 5319 | RSS: Main: 166404096 04:01:42 INFO - PID 5319 | 04:04:39 INFO - PID 5319 | Cycle 1(1): loaded http://localhost:38908/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38908/tests/dromaeo/cssquery-ext.html) 04:04:40 INFO - PID 5319 | RSS: Main: 168931328 04:04:40 INFO - PID 5319 | 04:07:42 INFO - PID 5319 | Cycle 1(1): loaded http://localhost:38908/tests/dromaeo/cssquery-ext.html (next: http://localhost:38908/tests/dromaeo/cssquery-jquery.html) 04:07:43 INFO - PID 5319 | RSS: Main: 164667392 04:07:43 INFO - PID 5319 | 04:10:44 INFO - PID 5319 | Cycle 1(1): loaded http://localhost:38908/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38908/tests/dromaeo/cssquery-mootools.html) 04:10:45 INFO - PID 5319 | RSS: Main: 167161856 04:10:45 INFO - PID 5319 | 04:13:47 INFO - PID 5319 | Cycle 1(1): loaded http://localhost:38908/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38908/tests/dromaeo/cssquery-prototype.html) 04:13:47 INFO - PID 5319 | RSS: Main: 165367808 04:13:47 INFO - PID 5319 | 04:16:50 INFO - PID 5319 | Cycle 1(1): loaded http://localhost:38908/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38908/tests/dromaeo/cssquery-yui.html) 04:16:51 INFO - PID 5319 | RSS: Main: 167100416 04:16:51 INFO - PID 5319 | 04:19:52 INFO - PID 5319 | Cycle 1(1): loaded http://localhost:38908/tests/dromaeo/cssquery-yui.html (next: http://localhost:38908/tests/dromaeo/cssquery-dojo.html) 04:19:53 INFO - PID 5319 | RSS: Main: 169746432 04:19:53 INFO - PID 5319 | 04:19:53 INFO - PID 5319 | __start_tp_report 04:19:53 INFO - PID 5319 | _x_x_mozilla_page_load 04:19:53 INFO - PID 5319 | _x_x_mozilla_page_load_details 04:19:53 INFO - PID 5319 | |i|pagename|runs| 04:19:53 INFO - PID 5319 | |0;dojo.html;3683.5395575553057;3884.7280690351677;3916.6279203475697;3927.2734544109326;3948.8420463181474;6156.969215153918;6264.123022776815;6267.279262884769;6309.62142271464;6357.809265722024;6218.62688238706;6254.186955695754;6296.905546416808;6406.167198264231;6412.487001039905;5666.376698563155;5687.4312568743;5701;5717.942820571803;5732.0828667413225;5560.86097847555;5640.210370548127;5668.773249070053;5711.600187986843;5728.398518155596;5660.066089095294;5677.233573467571;5732.742026608813;5744.655320680772;5751.137329400582;5548.500634942834;5628.240187574666;5653.010723123437;5719.456651618089;5729.627574207663;5547.029269877796;5590.636608620467;5616.157576363537;5640.210370548127;5698.515626171739;6236.968815155895;6310.274318453345;6327.14583531222;6329.556931014831;6336.46140078094;6760.357766012221;6782.423494003016;6790.151231096113;6860.142482189726;6902.93097069033;6685.565438246547;6803.319668033207;6861.519693621449;6865.656717164122;6868.313168683142;6355.555111142268;6411.455026322768;6422.550421470499;6430.549861509696;6433.420992110686;7029.859402812018;7046.929530704628;7054.012438258648;7084.716611335592;7117.89323160153;6613.966930165415;6706.798796036127;6729.4279986200745;6754.054432379471;6834.692438840264;6885.931140688629;6931.133608298963;6936.583804971718;6940.9652951735925;6955.269696681877;6703.262641109474;6775.4918381121615;6861.1080559526945;6866.416354609914;6878.174619045746;6635.767748128792;6715.160604924385;6718.529702920847;6723.63020033893;6727.495437842182;4960.776765045582;4972;4973.850784476472;4978.975105124451;4979.551840334388;4546.227141385906;4608.239640459354;4613.907721845544;4631.58315751584;4633.837815676435;6131.509479241739;6206.410391012847;6284.968575157186;6288.717007734663;6351.618902865843;6711.765088221889;6735.158105236846;6753.49348798842;6783.18601767791;6839.897401538981;6277.748890044439;6324.114623952651;6325.367463253638;6338.587991780611;6338.936610633927;5457.263269458573;5470.890582188334;5480.616356854982;5530.170474428787;5537.446255374431;7031.156261248683;7042.507024508235;7115;7137.678804453812;7150.821229469201;6601.240857301472;6728.73085076611;6734.966325168343;6746.325367463214;6808.046873437822;7112.679929403293;7122.287771222836;7134;7137.143542774693;7142.5000249982;6726.932730672631;6741.393274605309;6758.729650814109;6844.315568443116;6858.622775747382;6694.832629184115;6815.829604259933;6829.419499342413;6918.100646915868;6921.965390173218;6497.837554061186;6536.477081833537;6593.340665933561;6624.635645039569;6638.5684930479365;6463.741450341934;6542.378474044958;6603.471722262111;6611.206655201407;6640.269570347355;6428.774992875414;6458.838529036811;6478.578892371981;6581.638009909501;6589.934100659124;6600.240972288057;6603.603783772797;6618.768343107968;6675.198976122702;6698.564593301615;6399.168108146097;6412.871742565216;6480;6495.285518593046;6497.382748638872 04:19:53 INFO - PID 5319 | |1;ext.html;957.8850537935447;977.3207620703614;980.8230123851375;983.4296108257212;991.9702408927735;43907.56092439079;44620.3306950396;44648;44690.55309446905;44704.776476117615;41570;41794.791026044906;41866;41910;41915.37126943098;7439.1816900141075;7591.658375373094;7948;8109.918900810975;8110.229528194812;7864.764057078268;7926.28663420292;7948.125706172316;7959.243871832166;7963.442559020842;7970.242826931432;7988.041435027805;8001.359891208689;8014.759557213264;8015.839683206362;7881.566513841736;7944.721934732285;7955.681772729084;7973.521588704696;8015.038195416588;8117.269445749911;8126.959365203136;8217.917820821775;8227.465214761021;8240.670373185065;39586.40620390696;39660.60339396598;39819.60180398188;40647.59352406468;40660.186796264206;3730.925381492355;3743.270062337845;3753.60587138352;3756.286305601954;3760.849566017356;34084.82957585221;34140.14649633754;34160;34215.31569368599;34243.31513369743;5157.432682404932;5426.240326354274;5460.26286451328;5491.121420572689;5504.64219825714;219359;220746;220899;221462;221697;48316.27525587119;48907;53463.732681337126;53470.19794703083;54290.18564721532;56262.43737562654;56685.43314566884;56762.7161864188;56779;56902.43097568971;17194.914025429793;17325.133743312785;17333;17342.913285433493;17344.739828902577;14047.929760351337;14052.156870587592;14107.78838317426;14113.08264962774;14200.64498387528;15356.616084597974;15433.996790208601;15443;15539.844601554067;15596.688066238612;6096.420840020191;6118;6141.385861413823;6203.565750397429;6433.420992110732;1088.7713580148259;1103.2828661370174;1105.264998775805;1112.4159816096453;1114.359243435028;25845.870770646026;25964.22107336783;26019.73980260211;26123.608145877828;26196.476070478864;1185.733210027754;1204.4459548607563;1219.2257916223084;1226.9325187114616;1238.1518659718122;13349.265790381622;13559.27127526828;13601.591952241448;13808.309584520935;13994.16035037901;271818;275551;275611;275935;276745;57779.13331300034;57974.71012644994;58050.41949580535;58107;58146.7092664534;1517.650940283741;1523.3297349166332;1548.07115730563;1562.0393458023234;1564.280431001752;25654.102106426184;25748.32635878662;25863.353416163995;25983.350416239744;26068.608970865072;41167.588324117576;41310;41351.37972930469;41384.37923431091;41400.37899431451;233020;233973;234026;234192;234230;36233.81883090568;38352.42471362987;38882.6111738879;39056.41415378715;39108.2178356437;49507.25739113844;51291.48708512867;51377.48622513877;51509;51620.74189629028;37238.8138059308;37351.813240933625;37357.252854942206;37501.62498374981;37745.81127094457;36348.818255908554;37080.25839483249;37345.4398184022;37345.813270933475;37649.4352584706;43328.56671433245;43641.56358436375;43648.781756092285;44225.33661995137;44262.778686106365;35907.64092359043;36068.819655901556;36152.819235903655;36247.275054498234;36685.26629467449;27010.72989270082;27374.45251095007;27383;27987.440251194454;28385.716142838308 04:19:53 INFO - PID 5319 | |2;jquery.html;568.5962966293931;571.8456016875443;579.8695293558947;590.2150140313382;605.7213681706414;22228.222012229577;22919.885400572995;23018.19436319727;23052.769472305274;23072.42318942027;23897.761022389772;25043.62434563483;25319;25383.2385028449;25534.361640958985;6408.038794180864;6612.768553100643;6619.635920024397;6620.238672552646;6621.768238111667;6475.5790873593305;6567.868642627144;6572.44134248587;6572.5399222054475;6811.557248778839;6736.730530778763;6759.560628559152;6761.628110453923;6770.966145169267;6772.255051944282;6496.3828436298945;6738.629375384352;6738.898916516257;6746.460283177334;6752.763653272112;6245.6252624842655;6253.030780229072;6264.749410023594;6271.560990730651;6275.309715931244;28346.43307133846;28791.56812647812;28808.855955720086;28820.71179288201;28830.567541486893;2197.813185879202;2210.027587861336;2210.9005094770614;2216.2243214874825;2220.289507357646;19421.223151074082;20355.69466458004;20558.177672893067;20566.07452664634;20672.689909651366;2421.662363039974;2441.865697386643;2473.146764366291;2564.487102579473;2594;142346;146829;154963;160584;161511;15525.922370388302;15589.142597157264;15602.765958510632;15913.602159946095;16620.252088656038;24938.750612493644;25004.249872503853;25025.499490010097;25052.373690657514;25053.62419563708;10726.678199654023;10978.341299522055;11031.724206894893;11047.613333533287;11077.169212309109;10130.797384052277;10157.441340726331;10216.16109100369;10227.94886025565;10228.335158214692;9622.37454565451;9623.711288661352;9775.413475191512;9824.312298139204;9932.304738668368;2772.7920405969635;2869.368738877485;2916.241777137917;2926.4000879819587;2945.8006618318686;1025.733309339577;1042.5100202904623;1043.186314674555;1046.6441409920662;1067.40758878823;20367.08348124338;20580.897095514727;20798.584028319652;20807.479813004797;20944.08365272969;1100.1199040767353;1122.6126986189752;1129.265977114882;1131.2533727739653;1137.3346592243465;11057.336559806437;11253.099752019982;11258.66224013281;11318;11430.942845285887;572559;590006;594557;615222;619454;129876;129887;130992;131570;134006;1096.7970925378831;1145.3128123126057;1216.173002358387;1222.6943264183953;1288.0146687783854;21785.385753709656;21937.34187974363;22442.01774276939;22699.664671971565;22840;61511.6924415375;63042.68478657577;63645;64854;66088.66955665383;82518.58740706259;86470;86636.56681716551;87122;87724;20328;20704;20730.170793168105;20742.481437964172;20785.89607051955;23394.298171054215;23428;23549.764502355443;23638.05447782138;23681.64477532873;18762.15570299367;18939.242430302635;19005.144768485727;19007.619847603248;19071.90464047671;15729.405062911843;15987.8401215991;16402.753958690868;16843.24205410784;16870;17673.702418685487;17837.910810445865;17839.10804459798;17906.462806115836;19489.220431182595;20164.697529536774;20220.59558808786;20229;20277.188912443336;20295.59408811845;18518.629627407645;18576.907115464335;18600.441986740418;18663.16015779266;18911 04:19:53 INFO - PID 5319 | |3;mootools.html;1349.2174538767515;1372.2384076837352;1378.2281922123611;1385.224274406332;1403.5087719298247;16682.165891705412;17385.478435646935;17418.56453588661;17419;17423.477295681136;17045.488635340927;17052.573685657866;17064.146792660347;17070.48788536343;17116.229769660364;2843.360243945106;2850.1307101334087;2910.8690108945148;2911.956320655181;2918.0370477742294;2838.2194896403553;2864.8854045838143;2879.3377523169684;2894.6815850256453;2897.4639691657007;2831.490331740286;2859.914202573926;2936.119164250727;2936.809107408022;2938.8236705797613;2832.5184718597684;2846.9288267793263;2874.956875646867;2882.4523340565224;2883.8702258398316;2448.20433359159;2752.380714339278;2837.233946834343;2852.8003039787222;2859.2994716294434;15898.364065437368;16069;16113.838861611352;16139.677206455804;16142.838571614251;8695.913040869573;8944.597493112826;8949.686760963335;8956.507392093432;8957.14907083826;5877.265341832271;5976.641401515923;6052.636841789506;6108.297545782203;6156.107364432177;1945.785963544009;1960.5490737130397;1962.6663467210608;2002.4893652118762;2024.1802070161461;30133.69866301353;30568.694313056807;30624.234394140323;30637.23406914845;30658.38683226345;7392.852142957218;7473.140588832354;7483.438742094365;7622.047244094489;7686.463467055858;19618.50953726168;19645.50886227827;19657.901710491642;19672.803271967383;19689.310874119335;8320.334373250125;8630.39587228888;9140.54297285133;9179.541022948826;9242.491662958468;8006.239407256301;8059.758207253792;8075.232852878967;8077.394195435366;8090.312323452573;5560.610757246954;5699.886002279931;5714.628549144292;5725.627834190765;5777.826665200051;1664.1429663723193;1667.5497615643708;1670.9164541772864;1679.202378870027;1701.4895531340549;890.2744263425287;894.5437826708295;897.7531178925717;905.8595917632778;910.8724778531011;7459.962700186573;7596.696132154762;7612.086549613971;7634.541927484258;7682.577458239851;779.9532028078333;781.8944442500303;784.7449578886884;785.4462603864262;786.2059320086639;4686.976565117221;5153.742312884341;5251.186066159751;5309.177077552986;5332.066888294533;24705.382365440648;24861.875690621793;24931.750682492944;25239.242822715347;25681.22956311314;25224.621630675556;25423.11019114322;25523.36191595188;25524.23427297184;25541;11882.64352069422;12604.243745375123;12693.49226030967;12784.232946023269;12896.935515322364;8285.95857020711;8654.956725216334;8668.696595619123;8868.778280543038;8912.376133670581;21339.25312614051;21554.353369398945;21576.842315768925;21663;22145.224917127824;22113.889430552743;22141;22154.556908862054;22372.440688982904;22561.09755609757;11679.532818687157;11850.525978960744;11879.465424055765;11907.52369905194;11908.880911190778;15558.455454059054;15618.531444056696;15660.68678626444;15744.527664170093;15850.207489625242;12513.186642868184;12622.116451848282;12678.619641410773;12875.871241287841;13061.804072938723;12333.986609496684;12349.19730217533;12409.62771116868;12464.252144871336;12821.679458013625;10774.461276935965;10839;10924.563017479211;11023.72440688957;11033;7518.924810751822;7604.391648668204;7606.50557713769;7615;7646.197149299466;7254.96372518134;7370.5946172961;7391.630418478947;7431.442641801995;7552.697892084475 04:19:53 INFO - PID 5319 | |4;prototype.html;543.6656456279389;556.4102051825065;571.2003195526264;574.4686165297104;578.1472328315734;6107.572469927107;6132.754689812407;6151.907721384177;6167.81496555103;6252.874942501158;5976.864395764802;6186.969065154668;6233.2208473940755;6234.875302493947;6238.656873871935;5089.618278629099;5156.252343410203;5165.457626949173;5189.870253243661;5205.349331333583;4832.347633069543;4976.154053810861;4996.875078123039;5113.105206588851;5163.948360516384;4904;4919.508049195088;4932.383452068491;4968.40379154502;4979.975100124494;4955.975220123913;4965.300850866091;4984.053029924321;4989;4991.201407774757;4387.890302742425;4432.601065904052;4451.955480445186;4454.242778727624;4500.369948207253;6045.637261764265;6075.362086980892;6076.149339092531;6095.817125486198;6109.816705498843;5824.88350232993;5830.4169583041785;5877.147813567051;5883.794067207627;5905.881882362329;6659.966700166468;6682.4319932805765;6693.263740988487;6697.631630260334;6709.3290670933;5035.420926593452;5039.521245481674;5043.722595257259;5062.898742025139;5081.415637201732;6854.828629284258;6895.655217239118;6938.722451101949;6967.338102880269;6985.231624521299;5139.357580302462;5165.354330708661;5167.767450464738;5177.585793136615;5178.430372659004;5225.216217567396;5262.105442074857;5267;5288.8413347599635;5289.391719952255;5720.1991721159075;5784.855378615568;5801.88396232073;5808.564357673107;5874.5887787854435;6347.174867267318;6495.447886929664;6581.539292249592;6628.635425051669;6652.234992975869;1656.8484902992304;1788.1238193285196;1788.9910550447164;1792.7221280701513;1794.8564114870778;1244.923141482629;1272.5546058879318;1278.1117123599195;1283.550757234979;1286.1768468180371;5983.431574000463;6041.848953776191;6042.758289668452;6123.755049797958;6168.784092556829;6537.771178008842;6610.7025183866845;6645.302243264484;6705.631190284581;6746.257911629717;6147.72335244915;6154.3845615438995;6157.753689852356;6203.379662033761;6222.284437289679;3505.2814173094453;3508.561489789174;3547.2905418915802;3553.9111522211633;3570.357335679603;6923.2038315593345;6941.791746247621;6948.409385202314;6975.90722783163;7011.754588589477;6799.286074962157;6847.794566163023;6882.793516194522;6883;6905.551139175937;6955.408790252784;6973.721051157999;7013.54411963231;7018.508154780944;7067.964660176666;6720.294369091175;6725.73097076131;6751.189857217166;6751.729930802713;6829.795106146824;6686.7993960181275;6746.898796517958;6757.560758550679;6765.008644899336;6778.830529236809;6982.274534881349;6990.755323563651;7005.334493223136;7025.754098606524;7034.824129396601;5021.271915572294;5062.772175252049;5063.316452279042;5092.490750924878;5099.949000510096;6368;6584.69931704963;6747.932520674731;6770.96614516944;6818.590884546943;6357.410647338165;6370.93629063722;6475.381154711322;6499.935000650122;6511.186101737283;6431.389018043278;6448.709808058742;6459.870802584016;6482.481401487776;6520.902186467112;6274.498040156868;6334.239891213084;6380.968095159495;6389.808305750835;6419.903701444388;6525.771597994048;6540.215174179129;6618.7352505899225;6629.171353580802;6631.171103612049;6422.582532135396;6424.678766061772;6437.326295163198;6458.935410645833;6485.416312531895 04:19:53 INFO - PID 5319 | |5;yui.html;870.538614534297;914.9130832570903;921.6958403726771;989.4607438945775;1018.4958445569442;18797.436076917682;19286;19410.41768746938;19527.121279542436;19545.51136221595;18472.630547389042;18621.7206741899;19099.140538675758;19441.902790486027;19497.220111195536;1052.442205631015;1065.5631191211623;1065.6270305393089;1066.4987455895716;1066.7173199102244;1026.2149455666422;1062.2935747727752;1068.1668298727006;1071.1109778883508;1074.785042991405;1144.1190283481715;1147.8278258261244;1147.9311241325547;1148.78747431725;1148.9712757181053;1063.1813503602223;1104.1442881766616;1108.833674948756;1140.9885901141006;1143.0912424622404;812.1594150054724;815.388458656008;816.8978877640295;817.3951276055735;822.2640736540823;20490.5901881963;20567.280145194996;20615.79384206154;20647.27734529284;20696.172153114007;20939.37181884546;21608.459788505257;21793.891030544903;21809.67285490719;21810.890945545172;16604.418845340475;17513.912430437766;17588.472345829647;17612.91193544037;17616.647667046716;3947.2500224957453;3969.622885825842;3971.543272523668;3972.523297204325;3972.7219094663387;155542;156320;157374;157499;159742;17206.397776077778;17223;17224.741628875578;17331;17391.826081739022;32210.355792884013;32484.34071015436;32626.83686581552;32636.347273054405;32678;8747.956260218785;8880.73357799267;8931.776705582412;8938.731838044869;8944.284457243404;4567.451905771329;4672.159011377917;4682.157211701926;4688;4691;5542.667439953616;5609.663420194802;5643.887122257532;5660.943390566124;5677.17680936262;1090.340344091829;1099.5931505343074;1116.8994790468896;1120.7198200449889;1121.1423261205184;673.9865202696017;683.0914883205324;686.821426429132;690.3338278561213;696.373264062348;12171.513139474499;12674.112812103249;12699.492020319083;12777.105602607728;12837;678.6131904814307;683.2586643491768;683.8939964305441;693.9861202775916;694.6804469943781;5891.558133140032;6020.879582408327;6153;6184.845378865474;6190.938090619036;150428;153714;153920.23039884953;154976;155589;128329.35835320763;130779;131028;131351.34324328316;135342.32328838488;109062.45468772606;109980;113056.43471782589;114713;115105.4244728771;11503.712407189887;11543.71140721455;11627.825582616098;11672.241304315192;11736.178467507192;55074;55958.720206398706;56095.15857262226;56576.15135772884;56643.71678141583;67091;68214;68721;68993.65503172453;71141.64429177821;13198.934005329911;13228.206307621573;13230;13295.734085318432;13350.332483375598;16747.413840515524;16904;16951.406700765907;17001.48995530136;17015.234314455633;15788.842111578737;15927.203639817732;15945.760813588038;16105.436309729103;16138.757918630996;15430.074195548303;15647.452339168074;15672.686546268782;15847.128407937675;15848.1283529407;16122.113283769506;16129.435469758502;16207.594810129842;16312.755308670618;16314.67370652604;12337.814932775836;12427.689307767378;12530.498780048696;12580.433880475217;12585.748285034431;10518.211134165122;10529.841717411231;10569.63006294776;10590.417527036087;10646.627368042082 04:19:53 INFO - PID 5319 | __end_tp_report 04:19:53 INFO - PID 5319 | __start_cc_report 04:19:53 INFO - PID 5319 | _x_x_mozilla_cycle_collect,346 04:19:53 INFO - PID 5319 | __end_cc_report 04:19:53 INFO - PID 5319 | __startTimestamp1505215193078__endTimestamp 04:19:53 INFO - PID 5319 | 04:19:53 INFO - PID 5319 | ------- Summary: start ------- 04:19:53 INFO - PID 5319 | Number of tests: 6 04:19:53 INFO - PID 5319 | 04:19:53 INFO - PID 5319 | [#0] dojo.html Cycles:165 Average:6277.28 Median:6495.29 stddev:740.07 (11.4%) stddev-sans-first:713.82 04:19:53 INFO - PID 5319 | Values: 3683.5 3884.7 3916.6 3927.3 3948.8 6157.0 6264.1 6267.3 6309.6 6357.8 6218.6 6254.2 6296.9 6406.2 6412.5 5666.4 5687.4 5701.0 5717.9 5732.1 5560.9 5640.2 5668.8 5711.6 5728.4 5660.1 5677.2 5732.7 5744.7 5751.1 5548.5 5628.2 5653.0 5719.5 5729.6 5547.0 5590.6 5616.2 5640.2 5698.5 6237.0 6310.3 6327.1 6329.6 6336.5 6760.4 6782.4 6790.2 6860.1 6902.9 6685.6 6803.3 6861.5 6865.7 6868.3 6355.6 6411.5 6422.6 6430.5 6433.4 7029.9 7046.9 7054.0 7084.7 7117.9 6614.0 6706.8 6729.4 6754.1 6834.7 6885.9 6931.1 6936.6 6941.0 6955.3 6703.3 6775.5 6861.1 6866.4 6878.2 6635.8 6715.2 6718.5 6723.6 6727.5 4960.8 4972.0 4973.9 4979.0 4979.6 4546.2 4608.2 4613.9 4631.6 4633.8 6131.5 6206.4 6285.0 6288.7 6351.6 6711.8 6735.2 6753.5 6783.2 6839.9 6277.7 6324.1 6325.4 6338.6 6338.9 5457.3 5470.9 5480.6 5530.2 5537.4 7031.2 7042.5 7115.0 7137.7 7150.8 6601.2 6728.7 6735.0 6746.3 6808.0 7112.7 7122.3 7134.0 7137.1 7142.5 6726.9 6741.4 6758.7 6844.3 6858.6 6694.8 6815.8 6829.4 6918.1 6922.0 6497.8 6536.5 6593.3 6624.6 6638.6 6463.7 6542.4 6603.5 6611.2 6640.3 6428.8 6458.8 6478.6 6581.6 6589.9 6600.2 6603.6 6618.8 6675.2 6698.6 6399.2 6412.9 6480.0 6495.3 6497.4 04:19:53 INFO - PID 5319 | 04:19:53 INFO - PID 5319 | [#1] ext.html Cycles:180 Average:42842.13 Median:38049.12 stddev:63443.25 (166.7%) stddev-sans-first:63543.27 04:19:53 INFO - PID 5319 | Values: 957.9 977.3 980.8 983.4 992.0 43907.6 44620.3 44648.0 44690.6 44704.8 41570.0 41794.8 41866.0 41910.0 41915.4 7439.2 7591.7 7948.0 8109.9 8110.2 7864.8 7926.3 7948.1 7959.2 7963.4 7970.2 7988.0 8001.4 8014.8 8015.8 7881.6 7944.7 7955.7 7973.5 8015.0 8117.3 8127.0 8217.9 8227.5 8240.7 39586.4 39660.6 39819.6 40647.6 40660.2 3730.9 3743.3 3753.6 3756.3 3760.8 34084.8 34140.1 34160.0 34215.3 34243.3 5157.4 5426.2 5460.3 5491.1 5504.6 219359.0 220746.0 220899.0 221462.0 221697.0 48316.3 48907.0 53463.7 53470.2 54290.2 56262.4 56685.4 56762.7 56779.0 56902.4 17194.9 17325.1 17333.0 17342.9 17344.7 14047.9 14052.2 14107.8 14113.1 14200.6 15356.6 15434.0 15443.0 15539.8 15596.7 6096.4 6118.0 6141.4 6203.6 6433.4 1088.8 1103.3 1105.3 1112.4 1114.4 25845.9 25964.2 26019.7 26123.6 26196.5 1185.7 1204.4 1219.2 1226.9 1238.2 13349.3 13559.3 13601.6 13808.3 13994.2 271818.0 275551.0 275611.0 275935.0 276745.0 57779.1 57974.7 58050.4 58107.0 58146.7 1517.7 1523.3 1548.1 1562.0 1564.3 25654.1 25748.3 25863.4 25983.4 26068.6 41167.6 41310.0 41351.4 41384.4 41400.4 233020.0 233973.0 234026.0 234192.0 234230.0 36233.8 38352.4 38882.6 39056.4 39108.2 49507.3 51291.5 51377.5 51509.0 51620.7 37238.8 37351.8 37357.3 37501.6 37745.8 36348.8 37080.3 37345.4 37345.8 37649.4 43328.6 43641.6 43648.8 44225.3 44262.8 35907.6 36068.8 36152.8 36247.3 36685.3 27010.7 27374.5 27383.0 27987.4 28385.7 04:19:53 INFO - PID 5319 | 04:19:53 INFO - PID 5319 | [#2] jquery.html Cycles:180 Average:39911.38 Median:2218.26 stddev:100393.98 (4525.8%) stddev-sans-first:100632.15 04:19:53 INFO - PID 5319 | Values: 568.6 571.8 579.9 590.2 605.7 22228.2 22919.9 23018.2 23052.8 23072.4 23897.8 25043.6 25319.0 25383.2 25534.4 6408.0 6612.8 6619.6 6620.2 6621.8 6475.6 6567.9 6572.4 6572.5 6811.6 6736.7 6759.6 6761.6 6771.0 6772.3 6496.4 6738.6 6738.9 6746.5 6752.8 6245.6 6253.0 6264.7 6271.6 6275.3 28346.4 28791.6 28808.9 28820.7 28830.6 2197.8 2210.0 2210.9 2216.2 2220.3 19421.2 20355.7 20558.2 20566.1 20672.7 2421.7 2441.9 2473.1 2564.5 2594.0 142346.0 146829.0 154963.0 160584.0 161511.0 15525.9 15589.1 15602.8 15913.6 16620.3 24938.8 25004.2 25025.5 25052.4 25053.6 10726.7 10978.3 11031.7 11047.6 11077.2 10130.8 10157.4 10216.2 10227.9 10228.3 9622.4 9623.7 9775.4 9824.3 9932.3 2772.8 2869.4 2916.2 2926.4 2945.8 1025.7 1042.5 1043.2 1046.6 1067.4 20367.1 20580.9 20798.6 20807.5 20944.1 1100.1 1122.6 1129.3 1131.3 1137.3 11057.3 11253.1 11258.7 11318.0 11430.9 572559.0 590006.0 594557.0 615222.0 619454.0 129876.0 129887.0 130992.0 131570.0 134006.0 1096.8 1145.3 1216.2 1222.7 1288.0 21785.4 21937.3 22442.0 22699.7 22840.0 61511.7 63042.7 63645.0 64854.0 66088.7 82518.6 86470.0 86636.6 87122.0 87724.0 20328.0 20704.0 20730.2 20742.5 20785.9 23394.3 23428.0 23549.8 23638.1 23681.6 18762.2 18939.2 19005.1 19007.6 19071.9 15729.4 15987.8 16402.8 16843.2 16870.0 17673.7 17837.9 17839.1 17906.5 19489.2 20164.7 20220.6 20229.0 20277.2 20295.6 18518.6 18576.9 18600.4 18663.2 18911.0 04:19:53 INFO - PID 5319 | 04:19:53 INFO - PID 5319 | [#3] mootools.html Cycles:180 Average:10384.30 Median:2840.79 stddev:7803.90 (274.7%) stddev-sans-first:7796.27 04:19:53 INFO - PID 5319 | Values: 1349.2 1372.2 1378.2 1385.2 1403.5 16682.2 17385.5 17418.6 17419.0 17423.5 17045.5 17052.6 17064.1 17070.5 17116.2 2843.4 2850.1 2910.9 2912.0 2918.0 2838.2 2864.9 2879.3 2894.7 2897.5 2831.5 2859.9 2936.1 2936.8 2938.8 2832.5 2846.9 2875.0 2882.5 2883.9 2448.2 2752.4 2837.2 2852.8 2859.3 15898.4 16069.0 16113.8 16139.7 16142.8 8695.9 8944.6 8949.7 8956.5 8957.1 5877.3 5976.6 6052.6 6108.3 6156.1 1945.8 1960.5 1962.7 2002.5 2024.2 30133.7 30568.7 30624.2 30637.2 30658.4 7392.9 7473.1 7483.4 7622.0 7686.5 19618.5 19645.5 19657.9 19672.8 19689.3 8320.3 8630.4 9140.5 9179.5 9242.5 8006.2 8059.8 8075.2 8077.4 8090.3 5560.6 5699.9 5714.6 5725.6 5777.8 1664.1 1667.5 1670.9 1679.2 1701.5 890.3 894.5 897.8 905.9 910.9 7460.0 7596.7 7612.1 7634.5 7682.6 780.0 781.9 784.7 785.4 786.2 4687.0 5153.7 5251.2 5309.2 5332.1 24705.4 24861.9 24931.8 25239.2 25681.2 25224.6 25423.1 25523.4 25524.2 25541.0 11882.6 12604.2 12693.5 12784.2 12896.9 8286.0 8655.0 8668.7 8868.8 8912.4 21339.3 21554.4 21576.8 21663.0 22145.2 22113.9 22141.0 22154.6 22372.4 22561.1 11679.5 11850.5 11879.5 11907.5 11908.9 15558.5 15618.5 15660.7 15744.5 15850.2 12513.2 12622.1 12678.6 12875.9 13061.8 12334.0 12349.2 12409.6 12464.3 12821.7 10774.5 10839.0 10924.6 11023.7 11033.0 7518.9 7604.4 7606.5 7615.0 7646.2 7255.0 7370.6 7391.6 7431.4 7552.7 04:19:53 INFO - PID 5319 | 04:19:53 INFO - PID 5319 | [#4] prototype.html Cycles:180 Average:5602.05 Median:6162.78 stddev:1568.85 (25.5%) stddev-sans-first:1526.62 04:19:53 INFO - PID 5319 | Values: 543.7 556.4 571.2 574.5 578.1 6107.6 6132.8 6151.9 6167.8 6252.9 5976.9 6187.0 6233.2 6234.9 6238.7 5089.6 5156.3 5165.5 5189.9 5205.3 4832.3 4976.2 4996.9 5113.1 5163.9 4904.0 4919.5 4932.4 4968.4 4980.0 4956.0 4965.3 4984.1 4989.0 4991.2 4387.9 4432.6 4452.0 4454.2 4500.4 6045.6 6075.4 6076.1 6095.8 6109.8 5824.9 5830.4 5877.1 5883.8 5905.9 6660.0 6682.4 6693.3 6697.6 6709.3 5035.4 5039.5 5043.7 5062.9 5081.4 6854.8 6895.7 6938.7 6967.3 6985.2 5139.4 5165.4 5167.8 5177.6 5178.4 5225.2 5262.1 5267.0 5288.8 5289.4 5720.2 5784.9 5801.9 5808.6 5874.6 6347.2 6495.4 6581.5 6628.6 6652.2 1656.8 1788.1 1789.0 1792.7 1794.9 1244.9 1272.6 1278.1 1283.6 1286.2 5983.4 6041.8 6042.8 6123.8 6168.8 6537.8 6610.7 6645.3 6705.6 6746.3 6147.7 6154.4 6157.8 6203.4 6222.3 3505.3 3508.6 3547.3 3553.9 3570.4 6923.2 6941.8 6948.4 6975.9 7011.8 6799.3 6847.8 6882.8 6883.0 6905.6 6955.4 6973.7 7013.5 7018.5 7068.0 6720.3 6725.7 6751.2 6751.7 6829.8 6686.8 6746.9 6757.6 6765.0 6778.8 6982.3 6990.8 7005.3 7025.8 7034.8 5021.3 5062.8 5063.3 5092.5 5099.9 6368.0 6584.7 6747.9 6771.0 6818.6 6357.4 6370.9 6475.4 6499.9 6511.2 6431.4 6448.7 6459.9 6482.5 6520.9 6274.5 6334.2 6381.0 6389.8 6419.9 6525.8 6540.2 6618.7 6629.2 6631.2 6422.6 6424.7 6437.3 6458.9 6485.4 04:19:53 INFO - PID 5319 | 04:19:53 INFO - PID 5319 | [#5] yui.html Cycles:180 Average:27517.74 Median:17008.36 stddev:42336.92 (248.9%) stddev-sans-first:42408.41 04:19:53 INFO - PID 5319 | Values: 870.5 914.9 921.7 989.5 1018.5 18797.4 19286.0 19410.4 19527.1 19545.5 18472.6 18621.7 19099.1 19441.9 19497.2 1052.4 1065.6 1065.6 1066.5 1066.7 1026.2 1062.3 1068.2 1071.1 1074.8 1144.1 1147.8 1147.9 1148.8 1149.0 1063.2 1104.1 1108.8 1141.0 1143.1 812.2 815.4 816.9 817.4 822.3 20490.6 20567.3 20615.8 20647.3 20696.2 20939.4 21608.5 21793.9 21809.7 21810.9 16604.4 17513.9 17588.5 17612.9 17616.6 3947.3 3969.6 3971.5 3972.5 3972.7 155542.0 156320.0 157374.0 157499.0 159742.0 17206.4 17223.0 17224.7 17331.0 17391.8 32210.4 32484.3 32626.8 32636.3 32678.0 8748.0 8880.7 8931.8 8938.7 8944.3 4567.5 4672.2 4682.2 4688.0 4691.0 5542.7 5609.7 5643.9 5660.9 5677.2 1090.3 1099.6 1116.9 1120.7 1121.1 674.0 683.1 686.8 690.3 696.4 12171.5 12674.1 12699.5 12777.1 12837.0 678.6 683.3 683.9 694.0 694.7 5891.6 6020.9 6153.0 6184.8 6190.9 150428.0 153714.0 153920.2 154976.0 155589.0 128329.4 130779.0 131028.0 131351.3 135342.3 109062.5 109980.0 113056.4 114713.0 115105.4 11503.7 11543.7 11627.8 11672.2 11736.2 55074.0 55958.7 56095.2 56576.2 56643.7 67091.0 68214.0 68721.0 68993.7 71141.6 13198.9 13228.2 13230.0 13295.7 13350.3 16747.4 16904.0 16951.4 17001.5 17015.2 15788.8 15927.2 15945.8 16105.4 16138.8 15430.1 15647.5 15672.7 15847.1 15848.1 16122.1 16129.4 16207.6 16312.8 16314.7 12337.8 12427.7 12530.5 12580.4 12585.7 10518.2 10529.8 10569.6 10590.4 10646.6 04:19:53 INFO - PID 5319 | -------- Summary: end -------- 04:19:53 INFO - PID 5319 | 04:19:53 INFO - PID 5319 | 04:19:53 INFO - PID 5319 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 04:19:53 INFO - PID 5319 | 04:19:53 INFO - TEST-INFO | 5319: exit 0 04:19:53 INFO - TEST-OK | dromaeo_css | took 1109516ms 04:19:53 INFO - TEST-START | kraken 04:19:53 INFO - Initialising browser for kraken test... 04:19:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38908/getInfo.html -profile /tmp/tmpAwFvzf/profile 04:19:53 INFO - TEST-INFO | started process 5707 (/builds/slave/test/build/application/firefox/firefox http://localhost:38908/getInfo.html) 04:19:59 INFO - TEST-INFO | 5707: exit 0 04:19:59 INFO - Browser initialized. 04:19:59 INFO - Running cycle 1/1 for kraken test... 04:19:59 INFO - Using env: {'DISPLAY': ':0', 04:19:59 INFO - 'HOME': '/home/cltbld', 04:19:59 INFO - 'JSGC_DISABLE_POISONING': '1', 04:19:59 INFO - 'LANG': 'en_US.UTF-8', 04:19:59 INFO - 'LANGUAGE': 'en_US:en', 04:19:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:19:59 INFO - 'LOGNAME': 'cltbld', 04:19:59 INFO - 'MAIL': '/var/mail/cltbld', 04:19:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:19:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:19:59 INFO - 'MOZ_CRASHREPORTER': '1', 04:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:19:59 INFO - 'MOZ_NO_REMOTE': '1', 04:19:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:19:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:19:59 INFO - 'NO_EM_RESTART': '1', 04:19:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:59 INFO - 'PWD': '/builds/slave/test', 04:19:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:19:59 INFO - 'RUST_BACKTRACE': 'full', 04:19:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:19:59 INFO - 'SHELL': '/bin/bash', 04:19:59 INFO - 'SHLVL': '1', 04:19:59 INFO - 'TERM': 'linux', 04:19:59 INFO - 'TMOUT': '86400', 04:19:59 INFO - 'USER': 'cltbld', 04:19:59 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505213325.219875-1096098407', 04:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:59 INFO - '_': '/tools/buildbot/bin/python'} 04:19:59 INFO - TEST-INFO | started process 5907 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAwFvzf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:20:00 INFO - PID 5907 | 04:20:00 INFO - PID 5907 | (/builds/slave/test/build/application/firefox/firefox:5957): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:20:00 INFO - PID 5907 | 04:20:10 INFO - PID 5907 | RSS: Main: 166686720 04:20:10 INFO - PID 5907 | 04:20:13 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:38908/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38908/tests/kraken/driver.html?testName=audio-beat-detection) 04:20:14 INFO - PID 5907 | RSS: Main: 171225088 04:20:14 INFO - PID 5907 | 04:20:16 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:38908/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38908/tests/kraken/driver.html?testName=audio-dft) 04:20:17 INFO - PID 5907 | RSS: Main: 171782144 04:20:17 INFO - PID 5907 | 04:20:20 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:38908/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38908/tests/kraken/driver.html?testName=audio-fft) 04:20:21 INFO - PID 5907 | RSS: Main: 166285312 04:20:21 INFO - PID 5907 | 04:20:23 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:38908/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38908/tests/kraken/driver.html?testName=audio-oscillator) 04:20:23 INFO - PID 5907 | RSS: Main: 167596032 04:20:23 INFO - PID 5907 | 04:20:25 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:38908/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38908/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:20:26 INFO - PID 5907 | RSS: Main: 167727104 04:20:26 INFO - PID 5907 | 04:20:29 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:38908/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38908/tests/kraken/driver.html?testName=imaging-darkroom) 04:20:30 INFO - PID 5907 | RSS: Main: 170704896 04:20:30 INFO - PID 5907 | 04:20:33 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:38908/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38908/tests/kraken/driver.html?testName=imaging-desaturate) 04:20:34 INFO - PID 5907 | RSS: Main: 169988096 04:20:34 INFO - PID 5907 | 04:20:37 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:38908/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38908/tests/kraken/driver.html?testName=json-parse-financial) 04:20:37 INFO - PID 5907 | RSS: Main: 170397696 04:20:37 INFO - PID 5907 | 04:20:39 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:38908/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38908/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:20:40 INFO - PID 5907 | RSS: Main: 170573824 04:20:40 INFO - PID 5907 | 04:20:41 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:38908/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38908/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:20:42 INFO - PID 5907 | RSS: Main: 170369024 04:20:42 INFO - PID 5907 | 04:20:44 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:38908/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38908/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:20:45 INFO - PID 5907 | RSS: Main: 167743488 04:20:45 INFO - PID 5907 | 04:20:47 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:38908/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38908/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:20:48 INFO - PID 5907 | RSS: Main: 167587840 04:20:48 INFO - PID 5907 | 04:20:50 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:38908/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38908/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:20:51 INFO - PID 5907 | RSS: Main: 167882752 04:20:51 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | Cycle 1(1): loaded http://localhost:38908/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38908/tests/kraken/driver.html?testName=ai-astar) 04:20:53 INFO - PID 5907 | RSS: Main: 169754624 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | __start_tp_report 04:20:53 INFO - PID 5907 | _x_x_mozilla_page_load 04:20:53 INFO - PID 5907 | _x_x_mozilla_page_load_details 04:20:53 INFO - PID 5907 | |i|pagename|runs| 04:20:53 INFO - PID 5907 | |0;ai-astar;109;106;106;105;105;104;108;106;106;106 04:20:53 INFO - PID 5907 | |1;audio-beat-detection;118;112;115;115;111;115;113;139;113;115 04:20:53 INFO - PID 5907 | |2;audio-dft;162;161;163;162;162;162;162;164;164;163 04:20:53 INFO - PID 5907 | |3;audio-fft;72;72;73;73;72;77;75;78;76;73 04:20:53 INFO - PID 5907 | |4;audio-oscillator;78;79;85;81;80;78;77;81;82;82 04:20:53 INFO - PID 5907 | |5;imaging-gaussian-blur;110;114;110;113;110;114;121;114;114;114 04:20:53 INFO - PID 5907 | |6;imaging-darkroom;149;149;150;149;148;150;151;150;150;150 04:20:53 INFO - PID 5907 | |7;imaging-desaturate;94;96;98;94;94;94;94;95;94;94 04:20:53 INFO - PID 5907 | |8;json-parse-financial;49;44;44;46;45;45;45;46;47;47 04:20:53 INFO - PID 5907 | |9;json-stringify-tinderbox;36;37;37;37;37;37;37;37;37;37 04:20:53 INFO - PID 5907 | |10;stanford-crypto-aes;74;72;71;77;72;72;72;71;71;72 04:20:53 INFO - PID 5907 | |11;stanford-crypto-ccm;113;103;104;105;107;105;106;103;102;106 04:20:53 INFO - PID 5907 | |12;stanford-crypto-pbkdf2;153;156;154;155;164;157;162;161;159;154 04:20:53 INFO - PID 5907 | |13;stanford-crypto-sha256-iterative;65;63;60;60;58;61;62;59;60;58 04:20:53 INFO - PID 5907 | __end_tp_report 04:20:53 INFO - PID 5907 | __start_cc_report 04:20:53 INFO - PID 5907 | _x_x_mozilla_cycle_collect,910 04:20:53 INFO - PID 5907 | __end_cc_report 04:20:53 INFO - PID 5907 | __startTimestamp1505215253395__endTimestamp 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | ------- Summary: start ------- 04:20:53 INFO - PID 5907 | Number of tests: 14 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | [#0] ai-astar Cycles:10 Average:106.10 Median:106.00 stddev:1.45 (1.4%) stddev-sans-first:1.09 04:20:53 INFO - PID 5907 | Values: 109.0 106.0 106.0 105.0 105.0 104.0 108.0 106.0 106.0 106.0 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | [#1] audio-beat-detection Cycles:10 Average:116.60 Median:115.00 stddev:8.11 (7.1%) stddev-sans-first:8.59 04:20:53 INFO - PID 5907 | Values: 118.0 112.0 115.0 115.0 111.0 115.0 113.0 139.0 113.0 115.0 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.50 stddev:0.97 (0.6%) stddev-sans-first:1.01 04:20:53 INFO - PID 5907 | Values: 162.0 161.0 163.0 162.0 162.0 162.0 162.0 164.0 164.0 163.0 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | [#3] audio-fft Cycles:10 Average:74.10 Median:74.00 stddev:2.23 (3.0%) stddev-sans-first:2.24 04:20:53 INFO - PID 5907 | Values: 72.0 72.0 73.0 73.0 72.0 77.0 75.0 78.0 76.0 73.0 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | [#4] audio-oscillator Cycles:10 Average:80.30 Median:81.00 stddev:2.41 (3.0%) stddev-sans-first:2.40 04:20:53 INFO - PID 5907 | Values: 78.0 79.0 85.0 81.0 80.0 78.0 77.0 81.0 82.0 82.0 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:114.00 stddev:3.24 (2.8%) stddev-sans-first:3.19 04:20:53 INFO - PID 5907 | Values: 110.0 114.0 110.0 113.0 110.0 114.0 121.0 114.0 114.0 114.0 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | [#6] imaging-darkroom Cycles:10 Average:149.60 Median:150.00 stddev:0.84 (0.6%) stddev-sans-first:0.87 04:20:53 INFO - PID 5907 | Values: 149.0 149.0 150.0 149.0 148.0 150.0 151.0 150.0 150.0 150.0 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.00 stddev:1.34 (1.4%) stddev-sans-first:1.39 04:20:53 INFO - PID 5907 | Values: 94.0 96.0 98.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | [#8] json-parse-financial Cycles:10 Average:45.80 Median:46.00 stddev:1.55 (3.4%) stddev-sans-first:1.13 04:20:53 INFO - PID 5907 | Values: 49.0 44.0 44.0 46.0 45.0 45.0 45.0 46.0 47.0 47.0 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | [#9] json-stringify-tinderbox Cycles:10 Average:36.90 Median:37.00 stddev:0.32 (0.9%) stddev-sans-first:0.00 04:20:53 INFO - PID 5907 | Values: 36.0 37.0 37.0 37.0 37.0 37.0 37.0 37.0 37.0 37.0 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | [#10] stanford-crypto-aes Cycles:10 Average:72.40 Median:72.00 stddev:1.84 (2.6%) stddev-sans-first:1.86 04:20:53 INFO - PID 5907 | Values: 74.0 72.0 71.0 77.0 72.0 72.0 72.0 71.0 71.0 72.0 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | [#11] stanford-crypto-ccm Cycles:10 Average:105.40 Median:105.50 stddev:3.10 (2.9%) stddev-sans-first:1.67 04:20:53 INFO - PID 5907 | Values: 113.0 103.0 104.0 105.0 107.0 105.0 106.0 103.0 102.0 106.0 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.50 Median:158.00 stddev:3.81 (2.4%) stddev-sans-first:3.67 04:20:53 INFO - PID 5907 | Values: 153.0 156.0 154.0 155.0 164.0 157.0 162.0 161.0 159.0 154.0 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.60 Median:60.50 stddev:2.22 (3.7%) stddev-sans-first:1.69 04:20:53 INFO - PID 5907 | Values: 65.0 63.0 60.0 60.0 58.0 61.0 62.0 59.0 60.0 58.0 04:20:53 INFO - PID 5907 | -------- Summary: end -------- 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 04:20:53 INFO - PID 5907 | 04:20:53 INFO - PID 5907 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:20:54 INFO - TEST-INFO | 5907: exit 0 04:20:54 INFO - TEST-OK | kraken | took 60297ms 04:20:54 INFO - SUITE-END | took 1169s 04:20:54 INFO - Completed test suite (00:19:30) 04:20:54 INFO - javascript benchmark 04:20:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8991.288904208277, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3683.5395575553057, 3884.7280690351677, 3916.6279203475697, 3927.2734544109326, 3948.8420463181474, 6156.969215153918, 6264.123022776815, 6267.279262884769, 6309.62142271464, 6357.809265722024, 6218.62688238706, 6254.186955695754, 6296.905546416808, 6406.167198264231, 6412.487001039905, 5666.376698563155, 5687.4312568743, 5701.0, 5717.942820571803, 5732.0828667413225, 5560.86097847555, 5640.210370548127, 5668.773249070053, 5711.600187986843, 5728.398518155596, 5660.066089095294, 5677.233573467571, 5732.742026608813, 5744.655320680772, 5751.137329400582, 5548.500634942834, 5628.240187574666, 5653.010723123437, 5719.456651618089, 5729.627574207663, 5547.029269877796, 5590.636608620467, 5616.157576363537, 5640.210370548127, 5698.515626171739, 6236.968815155895, 6310.274318453345, 6327.14583531222, 6329.556931014831, 6336.46140078094, 6760.357766012221, 6782.423494003016, 6790.151231096113, 6860.142482189726, 6902.93097069033, 6685.565438246547, 6803.319668033207, 6861.519693621449, 6865.656717164122, 6868.313168683142, 6355.555111142268, 6411.455026322768, 6422.550421470499, 6430.549861509696, 6433.420992110686, 7029.859402812018, 7046.929530704628, 7054.012438258648, 7084.716611335592, 7117.89323160153, 6613.966930165415, 6706.798796036127, 6729.4279986200745, 6754.054432379471, 6834.692438840264, 6885.931140688629, 6931.133608298963, 6936.583804971718, 6940.9652951735925, 6955.269696681877, 6703.262641109474, 6775.4918381121615, 6861.1080559526945, 6866.416354609914, 6878.174619045746, 6635.767748128792, 6715.160604924385, 6718.529702920847, 6723.63020033893, 6727.495437842182, 4960.776765045582, 4972.0, 4973.850784476472, 4978.975105124451, 4979.551840334388, 4546.227141385906, 4608.239640459354, 4613.907721845544, 4631.58315751584, 4633.837815676435, 6131.509479241739, 6206.410391012847, 6284.968575157186, 6288.717007734663, 6351.618902865843, 6711.765088221889, 6735.158105236846, 6753.49348798842, 6783.18601767791, 6839.897401538981, 6277.748890044439, 6324.114623952651, 6325.367463253638, 6338.587991780611, 6338.936610633927, 5457.263269458573, 5470.890582188334, 5480.616356854982, 5530.170474428787, 5537.446255374431, 7031.156261248683, 7042.507024508235, 7115.0, 7137.678804453812, 7150.821229469201, 6601.240857301472, 6728.73085076611, 6734.966325168343, 6746.325367463214, 6808.046873437822, 7112.679929403293, 7122.287771222836, 7134.0, 7137.143542774693, 7142.5000249982, 6726.932730672631, 6741.393274605309, 6758.729650814109, 6844.315568443116, 6858.622775747382, 6694.832629184115, 6815.829604259933, 6829.419499342413, 6918.100646915868, 6921.965390173218, 6497.837554061186, 6536.477081833537, 6593.340665933561, 6624.635645039569, 6638.5684930479365, 6463.741450341934, 6542.378474044958, 6603.471722262111, 6611.206655201407, 6640.269570347355, 6428.774992875414, 6458.838529036811, 6478.578892371981, 6581.638009909501, 6589.934100659124, 6600.240972288057, 6603.603783772797, 6618.768343107968, 6675.198976122702, 6698.564593301615, 6399.168108146097, 6412.871742565216, 6480.0, 6495.285518593046, 6497.382748638872], "value": 6227.4469021807445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [957.8850537935447, 977.3207620703614, 980.8230123851375, 983.4296108257212, 991.9702408927735, 43907.56092439079, 44620.3306950396, 44648.0, 44690.55309446905, 44704.776476117615, 41570.0, 41794.791026044906, 41866.0, 41910.0, 41915.37126943098, 7439.1816900141075, 7591.658375373094, 7948.0, 8109.918900810975, 8110.229528194812, 7864.764057078268, 7926.28663420292, 7948.125706172316, 7959.243871832166, 7963.442559020842, 7970.242826931432, 7988.041435027805, 8001.359891208689, 8014.759557213264, 8015.839683206362, 7881.566513841736, 7944.721934732285, 7955.681772729084, 7973.521588704696, 8015.038195416588, 8117.269445749911, 8126.959365203136, 8217.917820821775, 8227.465214761021, 8240.670373185065, 39586.40620390696, 39660.60339396598, 39819.60180398188, 40647.59352406468, 40660.186796264206, 3730.925381492355, 3743.270062337845, 3753.60587138352, 3756.286305601954, 3760.849566017356, 34084.82957585221, 34140.14649633754, 34160.0, 34215.31569368599, 34243.31513369743, 5157.432682404932, 5426.240326354274, 5460.26286451328, 5491.121420572689, 5504.64219825714, 219359.0, 220746.0, 220899.0, 221462.0, 221697.0, 48316.27525587119, 48907.0, 53463.732681337126, 53470.19794703083, 54290.18564721532, 56262.43737562654, 56685.43314566884, 56762.7161864188, 56779.0, 56902.43097568971, 17194.914025429793, 17325.133743312785, 17333.0, 17342.913285433493, 17344.739828902577, 14047.929760351337, 14052.156870587592, 14107.78838317426, 14113.08264962774, 14200.64498387528, 15356.616084597974, 15433.996790208601, 15443.0, 15539.844601554067, 15596.688066238612, 6096.420840020191, 6118.0, 6141.385861413823, 6203.565750397429, 6433.420992110732, 1088.7713580148259, 1103.2828661370174, 1105.264998775805, 1112.4159816096453, 1114.359243435028, 25845.870770646026, 25964.22107336783, 26019.73980260211, 26123.608145877828, 26196.476070478864, 1185.733210027754, 1204.4459548607563, 1219.2257916223084, 1226.9325187114616, 1238.1518659718122, 13349.265790381622, 13559.27127526828, 13601.591952241448, 13808.309584520935, 13994.16035037901, 271818.0, 275551.0, 275611.0, 275935.0, 276745.0, 57779.13331300034, 57974.71012644994, 58050.41949580535, 58107.0, 58146.7092664534, 1517.650940283741, 1523.3297349166332, 1548.07115730563, 1562.0393458023234, 1564.280431001752, 25654.102106426184, 25748.32635878662, 25863.353416163995, 25983.350416239744, 26068.608970865072, 41167.588324117576, 41310.0, 41351.37972930469, 41384.37923431091, 41400.37899431451, 233020.0, 233973.0, 234026.0, 234192.0, 234230.0, 36233.81883090568, 38352.42471362987, 38882.6111738879, 39056.41415378715, 39108.2178356437, 49507.25739113844, 51291.48708512867, 51377.48622513877, 51509.0, 51620.74189629028, 37238.8138059308, 37351.813240933625, 37357.252854942206, 37501.62498374981, 37745.81127094457, 36348.818255908554, 37080.25839483249, 37345.4398184022, 37345.813270933475, 37649.4352584706, 43328.56671433245, 43641.56358436375, 43648.781756092285, 44225.33661995137, 44262.778686106365, 35907.64092359043, 36068.819655901556, 36152.819235903655, 36247.275054498234, 36685.26629467449, 27010.72989270082, 27374.45251095007, 27383.0, 27987.440251194454, 28385.716142838308], "value": 18885.860733413367, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.5962966293931, 571.8456016875443, 579.8695293558947, 590.2150140313382, 605.7213681706414, 22228.222012229577, 22919.885400572995, 23018.19436319727, 23052.769472305274, 23072.42318942027, 23897.761022389772, 25043.62434563483, 25319.0, 25383.2385028449, 25534.361640958985, 6408.038794180864, 6612.768553100643, 6619.635920024397, 6620.238672552646, 6621.768238111667, 6475.5790873593305, 6567.868642627144, 6572.44134248587, 6572.5399222054475, 6811.557248778839, 6736.730530778763, 6759.560628559152, 6761.628110453923, 6770.966145169267, 6772.255051944282, 6496.3828436298945, 6738.629375384352, 6738.898916516257, 6746.460283177334, 6752.763653272112, 6245.6252624842655, 6253.030780229072, 6264.749410023594, 6271.560990730651, 6275.309715931244, 28346.43307133846, 28791.56812647812, 28808.855955720086, 28820.71179288201, 28830.567541486893, 2197.813185879202, 2210.027587861336, 2210.9005094770614, 2216.2243214874825, 2220.289507357646, 19421.223151074082, 20355.69466458004, 20558.177672893067, 20566.07452664634, 20672.689909651366, 2421.662363039974, 2441.865697386643, 2473.146764366291, 2564.487102579473, 2594.0, 142346.0, 146829.0, 154963.0, 160584.0, 161511.0, 15525.922370388302, 15589.142597157264, 15602.765958510632, 15913.602159946095, 16620.252088656038, 24938.750612493644, 25004.249872503853, 25025.499490010097, 25052.373690657514, 25053.62419563708, 10726.678199654023, 10978.341299522055, 11031.724206894893, 11047.613333533287, 11077.169212309109, 10130.797384052277, 10157.441340726331, 10216.16109100369, 10227.94886025565, 10228.335158214692, 9622.37454565451, 9623.711288661352, 9775.413475191512, 9824.312298139204, 9932.304738668368, 2772.7920405969635, 2869.368738877485, 2916.241777137917, 2926.4000879819587, 2945.8006618318686, 1025.733309339577, 1042.5100202904623, 1043.186314674555, 1046.6441409920662, 1067.40758878823, 20367.08348124338, 20580.897095514727, 20798.584028319652, 20807.479813004797, 20944.08365272969, 1100.1199040767353, 1122.6126986189752, 1129.265977114882, 1131.2533727739653, 1137.3346592243465, 11057.336559806437, 11253.099752019982, 11258.66224013281, 11318.0, 11430.942845285887, 572559.0, 590006.0, 594557.0, 615222.0, 619454.0, 129876.0, 129887.0, 130992.0, 131570.0, 134006.0, 1096.7970925378831, 1145.3128123126057, 1216.173002358387, 1222.6943264183953, 1288.0146687783854, 21785.385753709656, 21937.34187974363, 22442.01774276939, 22699.664671971565, 22840.0, 61511.6924415375, 63042.68478657577, 63645.0, 64854.0, 66088.66955665383, 82518.58740706259, 86470.0, 86636.56681716551, 87122.0, 87724.0, 20328.0, 20704.0, 20730.170793168105, 20742.481437964172, 20785.89607051955, 23394.298171054215, 23428.0, 23549.764502355443, 23638.05447782138, 23681.64477532873, 18762.15570299367, 18939.242430302635, 19005.144768485727, 19007.619847603248, 19071.90464047671, 15729.405062911843, 15987.8401215991, 16402.753958690868, 16843.24205410784, 16870.0, 17673.702418685487, 17837.910810445865, 17839.10804459798, 17906.462806115836, 19489.220431182595, 20164.697529536774, 20220.59558808786, 20229.0, 20277.188912443336, 20295.59408811845, 18518.629627407645, 18576.907115464335, 18600.441986740418, 18663.16015779266, 18911.0], "value": 12848.50559992823, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1349.2174538767515, 1372.2384076837352, 1378.2281922123611, 1385.224274406332, 1403.5087719298247, 16682.165891705412, 17385.478435646935, 17418.56453588661, 17419.0, 17423.477295681136, 17045.488635340927, 17052.573685657866, 17064.146792660347, 17070.48788536343, 17116.229769660364, 2843.360243945106, 2850.1307101334087, 2910.8690108945148, 2911.956320655181, 2918.0370477742294, 2838.2194896403553, 2864.8854045838143, 2879.3377523169684, 2894.6815850256453, 2897.4639691657007, 2831.490331740286, 2859.914202573926, 2936.119164250727, 2936.809107408022, 2938.8236705797613, 2832.5184718597684, 2846.9288267793263, 2874.956875646867, 2882.4523340565224, 2883.8702258398316, 2448.20433359159, 2752.380714339278, 2837.233946834343, 2852.8003039787222, 2859.2994716294434, 15898.364065437368, 16069.0, 16113.838861611352, 16139.677206455804, 16142.838571614251, 8695.913040869573, 8944.597493112826, 8949.686760963335, 8956.507392093432, 8957.14907083826, 5877.265341832271, 5976.641401515923, 6052.636841789506, 6108.297545782203, 6156.107364432177, 1945.785963544009, 1960.5490737130397, 1962.6663467210608, 2002.4893652118762, 2024.1802070161461, 30133.69866301353, 30568.694313056807, 30624.234394140323, 30637.23406914845, 30658.38683226345, 7392.852142957218, 7473.140588832354, 7483.438742094365, 7622.047244094489, 7686.463467055858, 19618.50953726168, 19645.50886227827, 19657.901710491642, 19672.803271967383, 19689.310874119335, 8320.334373250125, 8630.39587228888, 9140.54297285133, 9179.541022948826, 9242.491662958468, 8006.239407256301, 8059.758207253792, 8075.232852878967, 8077.394195435366, 8090.312323452573, 5560.610757246954, 5699.886002279931, 5714.628549144292, 5725.627834190765, 5777.826665200051, 1664.1429663723193, 1667.5497615643708, 1670.9164541772864, 1679.202378870027, 1701.4895531340549, 890.2744263425287, 894.5437826708295, 897.7531178925717, 905.8595917632778, 910.8724778531011, 7459.962700186573, 7596.696132154762, 7612.086549613971, 7634.541927484258, 7682.577458239851, 779.9532028078333, 781.8944442500303, 784.7449578886884, 785.4462603864262, 786.2059320086639, 4686.976565117221, 5153.742312884341, 5251.186066159751, 5309.177077552986, 5332.066888294533, 24705.382365440648, 24861.875690621793, 24931.750682492944, 25239.242822715347, 25681.22956311314, 25224.621630675556, 25423.11019114322, 25523.36191595188, 25524.23427297184, 25541.0, 11882.64352069422, 12604.243745375123, 12693.49226030967, 12784.232946023269, 12896.935515322364, 8285.95857020711, 8654.956725216334, 8668.696595619123, 8868.778280543038, 8912.376133670581, 21339.25312614051, 21554.353369398945, 21576.842315768925, 21663.0, 22145.224917127824, 22113.889430552743, 22141.0, 22154.556908862054, 22372.440688982904, 22561.09755609757, 11679.532818687157, 11850.525978960744, 11879.465424055765, 11907.52369905194, 11908.880911190778, 15558.455454059054, 15618.531444056696, 15660.68678626444, 15744.527664170093, 15850.207489625242, 12513.186642868184, 12622.116451848282, 12678.619641410773, 12875.871241287841, 13061.804072938723, 12333.986609496684, 12349.19730217533, 12409.62771116868, 12464.252144871336, 12821.679458013625, 10774.461276935965, 10839.0, 10924.563017479211, 11023.72440688957, 11033.0, 7518.924810751822, 7604.391648668204, 7606.50557713769, 7615.0, 7646.197149299466, 7254.96372518134, 7370.5946172961, 7391.630418478947, 7431.442641801995, 7552.697892084475], "value": 7187.5549942212, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [543.6656456279389, 556.4102051825065, 571.2003195526264, 574.4686165297104, 578.1472328315734, 6107.572469927107, 6132.754689812407, 6151.907721384177, 6167.81496555103, 6252.874942501158, 5976.864395764802, 6186.969065154668, 6233.2208473940755, 6234.875302493947, 6238.656873871935, 5089.618278629099, 5156.252343410203, 5165.457626949173, 5189.870253243661, 5205.349331333583, 4832.347633069543, 4976.154053810861, 4996.875078123039, 5113.105206588851, 5163.948360516384, 4904.0, 4919.508049195088, 4932.383452068491, 4968.40379154502, 4979.975100124494, 4955.975220123913, 4965.300850866091, 4984.053029924321, 4989.0, 4991.201407774757, 4387.890302742425, 4432.601065904052, 4451.955480445186, 4454.242778727624, 4500.369948207253, 6045.637261764265, 6075.362086980892, 6076.149339092531, 6095.817125486198, 6109.816705498843, 5824.88350232993, 5830.4169583041785, 5877.147813567051, 5883.794067207627, 5905.881882362329, 6659.966700166468, 6682.4319932805765, 6693.263740988487, 6697.631630260334, 6709.3290670933, 5035.420926593452, 5039.521245481674, 5043.722595257259, 5062.898742025139, 5081.415637201732, 6854.828629284258, 6895.655217239118, 6938.722451101949, 6967.338102880269, 6985.231624521299, 5139.357580302462, 5165.354330708661, 5167.767450464738, 5177.585793136615, 5178.430372659004, 5225.216217567396, 5262.105442074857, 5267.0, 5288.8413347599635, 5289.391719952255, 5720.1991721159075, 5784.855378615568, 5801.88396232073, 5808.564357673107, 5874.5887787854435, 6347.174867267318, 6495.447886929664, 6581.539292249592, 6628.635425051669, 6652.234992975869, 1656.8484902992304, 1788.1238193285196, 1788.9910550447164, 1792.7221280701513, 1794.8564114870778, 1244.923141482629, 1272.5546058879318, 1278.1117123599195, 1283.550757234979, 1286.1768468180371, 5983.431574000463, 6041.848953776191, 6042.758289668452, 6123.755049797958, 6168.784092556829, 6537.771178008842, 6610.7025183866845, 6645.302243264484, 6705.631190284581, 6746.257911629717, 6147.72335244915, 6154.3845615438995, 6157.753689852356, 6203.379662033761, 6222.284437289679, 3505.2814173094453, 3508.561489789174, 3547.2905418915802, 3553.9111522211633, 3570.357335679603, 6923.2038315593345, 6941.791746247621, 6948.409385202314, 6975.90722783163, 7011.754588589477, 6799.286074962157, 6847.794566163023, 6882.793516194522, 6883.0, 6905.551139175937, 6955.408790252784, 6973.721051157999, 7013.54411963231, 7018.508154780944, 7067.964660176666, 6720.294369091175, 6725.73097076131, 6751.189857217166, 6751.729930802713, 6829.795106146824, 6686.7993960181275, 6746.898796517958, 6757.560758550679, 6765.008644899336, 6778.830529236809, 6982.274534881349, 6990.755323563651, 7005.334493223136, 7025.754098606524, 7034.824129396601, 5021.271915572294, 5062.772175252049, 5063.316452279042, 5092.490750924878, 5099.949000510096, 6368.0, 6584.69931704963, 6747.932520674731, 6770.96614516944, 6818.590884546943, 6357.410647338165, 6370.93629063722, 6475.381154711322, 6499.935000650122, 6511.186101737283, 6431.389018043278, 6448.709808058742, 6459.870802584016, 6482.481401487776, 6520.902186467112, 6274.498040156868, 6334.239891213084, 6380.968095159495, 6389.808305750835, 6419.903701444388, 6525.771597994048, 6540.215174179129, 6618.7352505899225, 6629.171353580802, 6631.171103612049, 6422.582532135396, 6424.678766061772, 6437.326295163198, 6458.935410645833, 6485.416312531895], "value": 5150.192327765456, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [870.538614534297, 914.9130832570903, 921.6958403726771, 989.4607438945775, 1018.4958445569442, 18797.436076917682, 19286.0, 19410.41768746938, 19527.121279542436, 19545.51136221595, 18472.630547389042, 18621.7206741899, 19099.140538675758, 19441.902790486027, 19497.220111195536, 1052.442205631015, 1065.5631191211623, 1065.6270305393089, 1066.4987455895716, 1066.7173199102244, 1026.2149455666422, 1062.2935747727752, 1068.1668298727006, 1071.1109778883508, 1074.785042991405, 1144.1190283481715, 1147.8278258261244, 1147.9311241325547, 1148.78747431725, 1148.9712757181053, 1063.1813503602223, 1104.1442881766616, 1108.833674948756, 1140.9885901141006, 1143.0912424622404, 812.1594150054724, 815.388458656008, 816.8978877640295, 817.3951276055735, 822.2640736540823, 20490.5901881963, 20567.280145194996, 20615.79384206154, 20647.27734529284, 20696.172153114007, 20939.37181884546, 21608.459788505257, 21793.891030544903, 21809.67285490719, 21810.890945545172, 16604.418845340475, 17513.912430437766, 17588.472345829647, 17612.91193544037, 17616.647667046716, 3947.2500224957453, 3969.622885825842, 3971.543272523668, 3972.523297204325, 3972.7219094663387, 155542.0, 156320.0, 157374.0, 157499.0, 159742.0, 17206.397776077778, 17223.0, 17224.741628875578, 17331.0, 17391.826081739022, 32210.355792884013, 32484.34071015436, 32626.83686581552, 32636.347273054405, 32678.0, 8747.956260218785, 8880.73357799267, 8931.776705582412, 8938.731838044869, 8944.284457243404, 4567.451905771329, 4672.159011377917, 4682.157211701926, 4688.0, 4691.0, 5542.667439953616, 5609.663420194802, 5643.887122257532, 5660.943390566124, 5677.17680936262, 1090.340344091829, 1099.5931505343074, 1116.8994790468896, 1120.7198200449889, 1121.1423261205184, 673.9865202696017, 683.0914883205324, 686.821426429132, 690.3338278561213, 696.373264062348, 12171.513139474499, 12674.112812103249, 12699.492020319083, 12777.105602607728, 12837.0, 678.6131904814307, 683.2586643491768, 683.8939964305441, 693.9861202775916, 694.6804469943781, 5891.558133140032, 6020.879582408327, 6153.0, 6184.845378865474, 6190.938090619036, 150428.0, 153714.0, 153920.23039884953, 154976.0, 155589.0, 128329.35835320763, 130779.0, 131028.0, 131351.34324328316, 135342.32328838488, 109062.45468772606, 109980.0, 113056.43471782589, 114713.0, 115105.4244728771, 11503.712407189887, 11543.71140721455, 11627.825582616098, 11672.241304315192, 11736.178467507192, 55074.0, 55958.720206398706, 56095.15857262226, 56576.15135772884, 56643.71678141583, 67091.0, 68214.0, 68721.0, 68993.65503172453, 71141.64429177821, 13198.934005329911, 13228.206307621573, 13230.0, 13295.734085318432, 13350.332483375598, 16747.413840515524, 16904.0, 16951.406700765907, 17001.48995530136, 17015.234314455633, 15788.842111578737, 15927.203639817732, 15945.760813588038, 16105.436309729103, 16138.757918630996, 15430.074195548303, 15647.452339168074, 15672.686546268782, 15847.128407937675, 15848.1283529407, 16122.113283769506, 16129.435469758502, 16207.594810129842, 16312.755308670618, 16314.67370652604, 12337.814932775836, 12427.689307767378, 12530.498780048696, 12580.433880475217, 12585.748285034431, 10518.211134165122, 10529.841717411231, 10569.63006294776, 10590.417527036087, 10646.627368042082], "value": 9444.961727504084, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1375.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 106.0, 106.0, 105.0, 105.0, 104.0, 108.0, 106.0, 106.0, 106.0], "value": 106.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 112.0, 115.0, 115.0, 111.0, 115.0, 113.0, 139.0, 113.0, 115.0], "value": 116.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 162.0, 162.0, 162.0, 162.0, 164.0, 164.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 73.0, 73.0, 72.0, 77.0, 75.0, 78.0, 76.0, 73.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 85.0, 81.0, 80.0, 78.0, 77.0, 81.0, 82.0, 82.0], "value": 80.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 110.0, 113.0, 110.0, 114.0, 121.0, 114.0, 114.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 149.0, 148.0, 150.0, 151.0, 150.0, 150.0, 150.0], "value": 149.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 98.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 44.0, 44.0, 46.0, 45.0, 45.0, 45.0, 46.0, 47.0, 47.0], "value": 45.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0], "value": 36.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 71.0, 77.0, 72.0, 72.0, 72.0, 71.0, 71.0, 72.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 103.0, 104.0, 105.0, 107.0, 105.0, 106.0, 103.0, 102.0, 106.0], "value": 105.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 156.0, 154.0, 155.0, 164.0, 157.0, 162.0, 161.0, 159.0, 154.0], "value": 157.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 60.0, 60.0, 58.0, 61.0, 62.0, 59.0, 60.0, 58.0], "value": 60.6, "unit": "score"}]}]} 04:20:54 INFO - Return code: 0 04:20:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:20:54 INFO - # TBPL SUCCESS # 04:20:54 INFO - Running post-action listener: _package_coverage_data 04:20:54 INFO - Running post-action listener: _resource_record_post_action 04:20:54 INFO - [mozharness: 2017-09-12 11:20:54.667669Z] Finished run-tests step (success) 04:20:54 INFO - Running post-run listener: _resource_record_post_run 04:20:54 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 12288; Write bytes: 296402944; Read time: 724; Write time: 641064 04:20:54 INFO - TinderboxPrint: CPU usage
13.1% 04:20:54 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 724 04:20:54 INFO - TinderboxPrint: I/O write bytes / time
296,402,944 / 641,064 04:20:54 INFO - TinderboxPrint: CPU idle
8,222.2 (86.9%) 04:20:54 INFO - TinderboxPrint: CPU user
1,184.8 (12.5%) 04:20:54 INFO - TinderboxPrint: Swap in / out
0 / 0 04:20:54 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 18628608; Read time: 0; Write time: 14812 04:20:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:20:54 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 12288; Write bytes: 277774336; Read time: 724; Write time: 626204 04:20:55 INFO - Running post-run listener: _upload_blobber_files 04:20:55 INFO - Blob upload gear active. 04:20:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:20:55 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:20:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:20:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:20:55 INFO - (blobuploader) - INFO - Open directory for files ... 04:20:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:20:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:20:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:20:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:20:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:20:56 INFO - (blobuploader) - INFO - Done attempting. 04:20:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:20:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:20:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:21:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:21:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:21:03 INFO - (blobuploader) - INFO - Done attempting. 04:21:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:21:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:21:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:21:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:21:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:21:04 INFO - (blobuploader) - INFO - Done attempting. 04:21:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:21:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:21:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:21:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:21:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:21:05 INFO - (blobuploader) - INFO - Done attempting. 04:21:05 INFO - (blobuploader) - INFO - Iteration through files over. 04:21:05 INFO - Return code: 0 04:21:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:21:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:21:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fe6689cc6033b9d55bb24f27574858023171cb0c2e8e5bc455dbc5934dec1924d24566a5846472f58a4bfb677a4f456e1dbd3456964a1fa91c37c76615f9215", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39df4d7401f8b3c1bccb807592efbfd5e19e5706d033edfd37f2c8e2cd9f2254c1c078b63a1fcffeebf19776700d997e458bb5b1245510774aa584ce1f7ebd73", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a468f06e4ede530491a8dde76a44052bb2575a382338ffa2f493891d74d3b4dd17abeae032e30319935bfa0cde1a8d186526ceb07031dd9b0a6e88c2e96d26c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a90f4ee34e081e9b50fae7a62296c7b091c8d9c78e7b6861f81a7c4f164e809540c3dee525548dbc73d1a2f0d97266ffaa71521ec19924f7780492d0999c402"} 04:21:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:21:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:21:05 INFO - Contents: 04:21:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fe6689cc6033b9d55bb24f27574858023171cb0c2e8e5bc455dbc5934dec1924d24566a5846472f58a4bfb677a4f456e1dbd3456964a1fa91c37c76615f9215", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39df4d7401f8b3c1bccb807592efbfd5e19e5706d033edfd37f2c8e2cd9f2254c1c078b63a1fcffeebf19776700d997e458bb5b1245510774aa584ce1f7ebd73", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a468f06e4ede530491a8dde76a44052bb2575a382338ffa2f493891d74d3b4dd17abeae032e30319935bfa0cde1a8d186526ceb07031dd9b0a6e88c2e96d26c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a90f4ee34e081e9b50fae7a62296c7b091c8d9c78e7b6861f81a7c4f164e809540c3dee525548dbc73d1a2f0d97266ffaa71521ec19924f7780492d0999c402"} 04:21:05 INFO - Running post-run listener: copy_logs_to_upload_dir 04:21:05 INFO - Copying logs to upload dir... 04:21:05 INFO - mkdir: /builds/slave/test/build/upload/logs 04:21:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.403631 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-09-12 04:21:05.480418) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-12 04:21:05.484999) ========= 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-1505213325.219875-1096098407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fe6689cc6033b9d55bb24f27574858023171cb0c2e8e5bc455dbc5934dec1924d24566a5846472f58a4bfb677a4f456e1dbd3456964a1fa91c37c76615f9215", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39df4d7401f8b3c1bccb807592efbfd5e19e5706d033edfd37f2c8e2cd9f2254c1c078b63a1fcffeebf19776700d997e458bb5b1245510774aa584ce1f7ebd73", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a468f06e4ede530491a8dde76a44052bb2575a382338ffa2f493891d74d3b4dd17abeae032e30319935bfa0cde1a8d186526ceb07031dd9b0a6e88c2e96d26c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a90f4ee34e081e9b50fae7a62296c7b091c8d9c78e7b6861f81a7c4f164e809540c3dee525548dbc73d1a2f0d97266ffaa71521ec19924f7780492d0999c402"} build_url:https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011240 build_url: 'https://queue.taskcluster.net/v1/task/dQw1nXGeRJ-it9XJgZiT1A/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fe6689cc6033b9d55bb24f27574858023171cb0c2e8e5bc455dbc5934dec1924d24566a5846472f58a4bfb677a4f456e1dbd3456964a1fa91c37c76615f9215", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39df4d7401f8b3c1bccb807592efbfd5e19e5706d033edfd37f2c8e2cd9f2254c1c078b63a1fcffeebf19776700d997e458bb5b1245510774aa584ce1f7ebd73", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a468f06e4ede530491a8dde76a44052bb2575a382338ffa2f493891d74d3b4dd17abeae032e30319935bfa0cde1a8d186526ceb07031dd9b0a6e88c2e96d26c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a90f4ee34e081e9b50fae7a62296c7b091c8d9c78e7b6861f81a7c4f164e809540c3dee525548dbc73d1a2f0d97266ffaa71521ec19924f7780492d0999c402"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-12 04:21:05.515944) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-12 04:21:05.516311) ========= 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-1505213325.219875-1096098407 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004425 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-12 04:21:05.598259) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-12 04:21:05.602433) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-12 04:21:05.602783) ========= ========= Total master_lag: 0.44 =========