builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-011 starttime: 1506164606.57 results: success (0) revision: 8c3a1558322331c1fab9d1d2d7a94ef69f2a9212 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 04:03:26.570293) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 04:03:26.570799) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 04:03:26.571111) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 04:03:26.590514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 04:03:26.590938) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-23 04:03:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.2M=0s 2017-09-23 04:03:26 (38.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.181347 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 04:03:26.791365) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 04:03:26.791784) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019698 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 04:03:26.844355) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 04:03:26.844772) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8c3a1558322331c1fab9d1d2d7a94ef69f2a9212 --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 8c3a1558322331c1fab9d1d2d7a94ef69f2a9212 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-23 04:03:26,886 truncating revision to first 12 chars 2017-09-23 04:03:26,886 Setting DEBUG logging. 2017-09-23 04:03:26,886 attempt 1/10 2017-09-23 04:03:26,886 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8c3a15583223?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-23 04:03:27,330 unpacking tar archive at: mozilla-central-8c3a15583223/testing/mozharness/ program finished with exit code 0 elapsedTime=0.757109 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 04:03:27.622133) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 04:03:27.622626) ========= script_repo_revision: 8c3a1558322331c1fab9d1d2d7a94ef69f2a9212 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 04:03:27.623101) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 04:03:27.623440) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 04:03:27.640657) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-23 04:03:27.641073) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:03:27 INFO - MultiFileLogger online at 20170923 04:03:27 in /builds/slave/test 04:03:27 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:03:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:03:27 INFO - {'append_to_log': False, 04:03:27 INFO - 'base_work_dir': '/builds/slave/test', 04:03:27 INFO - 'blob_upload_branch': 'Firefox', 04:03:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:03:27 INFO - 'branch': 'Firefox', 04:03:27 INFO - 'buildbot_json_path': 'buildprops.json', 04:03:27 INFO - 'code_coverage': False, 04:03:27 INFO - 'config_files': ('talos/linux_config.py',), 04:03:27 INFO - 'default_actions': ('clobber', 04:03:27 INFO - 'read-buildbot-config', 04:03:27 INFO - 'download-and-extract', 04:03:27 INFO - 'populate-webroot', 04:03:27 INFO - 'create-virtualenv', 04:03:27 INFO - 'install', 04:03:27 INFO - 'setup-mitmproxy', 04:03:27 INFO - 'run-tests'), 04:03:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:03:27 INFO - 'disable_ccov_upload': False, 04:03:27 INFO - 'disable_stylo': False, 04:03:27 INFO - 'download_minidump_stackwalk': True, 04:03:27 INFO - 'download_symbols': 'ondemand', 04:03:27 INFO - 'enable_stylo': False, 04:03:27 INFO - 'enable_webrender': False, 04:03:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:03:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:03:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:03:27 INFO - 'gecko_profile': False, 04:03:27 INFO - 'gecko_profile_interval': 0, 04:03:27 INFO - 'installer_path': 'installer.exe', 04:03:27 INFO - 'log_level': 'info', 04:03:27 INFO - 'log_name': 'talos', 04:03:27 INFO - 'log_to_console': True, 04:03:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:03:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:03:27 INFO - 'opt_config_files': (), 04:03:27 INFO - 'pip_index': False, 04:03:27 INFO - 'suite': 'dromaeojs-e10s', 04:03:27 INFO - 'system_bits': '32', 04:03:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:03:27 INFO - 'title': 'talos-linux64-ix-011', 04:03:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:03:27 INFO - 'use_talos_json': True, 04:03:27 INFO - 'verify': 'False', 04:03:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:03:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:03:27 INFO - 'work_dir': 'build'} 04:03:27 INFO - [mozharness: 2017-09-23 11:03:27.824829Z] Running clobber step. 04:03:27 INFO - Running pre-action listener: _resource_record_pre_action 04:03:27 INFO - Running main action method: clobber 04:03:27 INFO - rmtree: /builds/slave/test/build 04:03:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:03:29 INFO - Running post-action listener: _resource_record_post_action 04:03:29 INFO - [mozharness: 2017-09-23 11:03:29.515461Z] Finished clobber step (success) 04:03:29 INFO - [mozharness: 2017-09-23 11:03:29.515559Z] Running read-buildbot-config step. 04:03:29 INFO - Running pre-action listener: _resource_record_pre_action 04:03:29 INFO - Running main action method: read_buildbot_config 04:03:29 INFO - Using buildbot properties: 04:03:29 INFO - { 04:03:29 INFO - "project": "", 04:03:29 INFO - "product": "firefox", 04:03:29 INFO - "who": "archaeopteryx@coole-files.de", 04:03:29 INFO - "installer_path": "public/build/target.tar.bz2", 04:03:29 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:03:29 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:03:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 04:03:29 INFO - "stage_platform": "linux64-pgo", 04:03:29 INFO - "basedir": "/builds/slave/test", 04:03:29 INFO - "buildnumber": 199, 04:03:29 INFO - "platform": "ubuntu64_hw", 04:03:29 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:03:29 INFO - "slavebuilddir": "test", 04:03:29 INFO - "taskId": "aXX1zWSBSsmpbPz7p9ShPw", 04:03:29 INFO - "branch": "mozilla-central", 04:03:29 INFO - "script_repo_revision": "production", 04:03:29 INFO - "revision": "8c3a1558322331c1fab9d1d2d7a94ef69f2a9212", 04:03:29 INFO - "slavename": "talos-linux64-ix-011", 04:03:29 INFO - "repo_path": "mozilla-central" 04:03:29 INFO - } 04:03:29 INFO - Finding installer, test and symbols from parent task. 04:03:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aXX1zWSBSsmpbPz7p9ShPw'}, attempt #1 04:03:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aXX1zWSBSsmpbPz7p9ShPw'}, attempt #1 04:03:30 INFO - Task dependencies: YK2hyMrlSTiJS-ZWTXWUag 04:03:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag'}, attempt #1 04:03:31 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.tar.bz2 04:03:31 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.test_packages.json 04:03:31 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.crashreporter-symbols.zip 04:03:31 INFO - Running post-action listener: _resource_record_post_action 04:03:31 INFO - [mozharness: 2017-09-23 11:03:31.060903Z] Finished read-buildbot-config step (success) 04:03:31 INFO - [mozharness: 2017-09-23 11:03:31.061121Z] Running download-and-extract step. 04:03:31 INFO - Running pre-action listener: _resource_record_pre_action 04:03:31 INFO - Running main action method: download_and_extract 04:03:31 INFO - mkdir: /builds/slave/test/build/tests 04:03:31 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:03:31 INFO - trying https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.test_packages.json 04:03:31 INFO - Downloading https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:03:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:03:31 INFO - Downloaded 989 bytes. 04:03:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:03:31 INFO - Using the following test package requirements: 04:03:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:03:31 INFO - u'common': [u'target.common.tests.zip'], 04:03:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:03:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:03:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:03:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:03:31 INFO - u'mozbase': [u'target.common.tests.zip'], 04:03:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:03:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:03:31 INFO - u'web-platform': [u'target.common.tests.zip', 04:03:31 INFO - u'target.web-platform.tests.tar.gz'], 04:03:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:03:31 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 04:03:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.common.tests.zip 04:03:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:03:31 INFO - Fetch https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.common.tests.zip into memory 04:03:32 INFO - Content-Length response header: 55347541 04:03:32 INFO - Bytes received: 55347541 04:03:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.talos.tests.zip 04:03:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:03:43 INFO - Fetch https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.talos.tests.zip into memory 04:03:44 INFO - Content-Length response header: 14513823 04:03:44 INFO - Bytes received: 14513823 04:03: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:03:44 INFO - trying https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.tar.bz2 04:03:44 INFO - Downloading https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:03:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:03:45 INFO - Downloaded 62503122 bytes. 04:03:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.tar.bz2 04:03:45 INFO - mkdir: /builds/slave/test/properties 04:03:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:03:45 INFO - Writing to file /builds/slave/test/properties/build_url 04:03:45 INFO - Contents: 04:03:45 INFO - build_url:https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.tar.bz2 04:03:45 INFO - Running post-action listener: _resource_record_post_action 04:03:45 INFO - Running post-action listener: find_tests_for_verification 04:03:45 INFO - Running post-action listener: set_extra_try_arguments 04:03:45 INFO - [mozharness: 2017-09-23 11:03:45.838305Z] Finished download-and-extract step (success) 04:03:45 INFO - [mozharness: 2017-09-23 11:03:45.838405Z] Running populate-webroot step. 04:03:45 INFO - Running pre-action listener: _resource_record_pre_action 04:03:45 INFO - Running main action method: populate_webroot 04:03:45 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:03:45 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:03:45 INFO - 'tests': ['tresize', 04:03:45 INFO - 'tcanvasmark']}, 04:03:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:03:45 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:03:45 INFO - 'tests': ['dromaeo_css', 04:03:45 INFO - 'kraken']}, 04:03:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:03:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:03:45 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:03:45 INFO - 'talos_options': ['--disable-stylo'], 04:03:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:03:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:03:45 INFO - 'tests': ['damp', 'tps']}, 04:03:45 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:03:45 INFO - 'talos_options': ['--disable-stylo'], 04:03:45 INFO - 'tests': ['damp', 'tps']}, 04:03:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:03:45 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:03:45 INFO - 'tests': ['dromaeo_dom']}, 04:03:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:03:45 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:03:45 INFO - 'tests': ['basic_compositor_video', 04:03:45 INFO - 'glvideo']}, 04:03:45 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:03:45 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:03:45 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:03:45 INFO - 'talos_options': ['--disable-stylo'], 04:03:45 INFO - 'tests': ['ts_paint_webext', 04:03:45 INFO - 'tp5o_webext']}, 04:03:45 INFO - 'other-e10s': {'tests': ['a11yr', 04:03:45 INFO - 'ts_paint', 04:03:45 INFO - 'tpaint', 04:03:45 INFO - 'sessionrestore', 04:03:45 INFO - 'sessionrestore_many_windows', 04:03:45 INFO - 'sessionrestore_no_auto_restore', 04:03:45 INFO - 'tabpaint']}, 04:03:45 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:03:45 INFO - 'tests': ['a11yr', 04:03:45 INFO - 'ts_paint', 04:03:45 INFO - 'tpaint', 04:03:45 INFO - 'sessionrestore', 04:03:45 INFO - 'sessionrestore_many_windows', 04:03:45 INFO - 'sessionrestore_no_auto_restore', 04:03:45 INFO - 'tabpaint']}, 04:03:45 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 04:03:45 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 04:03:45 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:03:45 INFO - 'tests': ['perf_reftest_singletons']}, 04:03:45 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:03:45 INFO - 'tests': ['perf_reftest']}, 04:03:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:03:45 INFO - 'tsvgr_opacity', 04:03:45 INFO - 'tart', 04:03:45 INFO - 'tscrollx', 04:03:45 INFO - 'tsvg_static']}, 04:03:45 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:03:45 INFO - 'tests': ['tsvgx', 04:03:45 INFO - 'tsvgr_opacity', 04:03:45 INFO - 'tart', 04:03:45 INFO - 'tscrollx', 04:03:45 INFO - 'tsvg_static']}, 04:03:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:03:45 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:03:45 INFO - 'talos_options': ['--disable-stylo'], 04:03:45 INFO - 'tests': ['tp5o']}, 04:03:45 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:03:45 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:03:45 INFO - 'talos_options': ['--mitmproxy', 04:03:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:03:45 INFO - '--firstNonBlankPaint'], 04:03:45 INFO - 'tests': ['tp6_google', 04:03:45 INFO - 'tp6_youtube', 04:03:45 INFO - 'tp6_amazon', 04:03:45 INFO - 'tp6_facebook']}, 04:03:45 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:03:45 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:03:45 INFO - 'talos_options': ['--disable-stylo', 04:03:45 INFO - '--mitmproxy', 04:03:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:03:45 INFO - '--firstNonBlankPaint'], 04:03:45 INFO - 'tests': ['tp6_google', 04:03:45 INFO - 'tp6_youtube', 04:03:45 INFO - 'tp6_amazon', 04:03:45 INFO - 'tp6_facebook']}, 04:03:45 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:03:45 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:03:45 INFO - 'talos_options': ['--stylo-threads=1', 04:03:45 INFO - '--mitmproxy', 04:03:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:03:45 INFO - '--firstNonBlankPaint'], 04:03:45 INFO - 'tests': ['tp6_google', 04:03:45 INFO - 'tp6_youtube', 04:03:45 INFO - 'tp6_amazon', 04:03:45 INFO - 'tp6_facebook']}, 04:03:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:03:45 INFO - 'talos_options': ['--xperf_path', 04:03:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:03:45 INFO - 'tests': ['tp5n']}, 04:03:45 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:03:45 INFO - 'talos_options': ['--disable-stylo', 04:03:45 INFO - '--xperf_path', 04:03:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:03:45 INFO - 'tests': ['tp5n']}}} 04:03:45 INFO - Running post-action listener: _resource_record_post_action 04:03:45 INFO - [mozharness: 2017-09-23 11:03:45.853842Z] Finished populate-webroot step (success) 04:03:45 INFO - [mozharness: 2017-09-23 11:03:45.853932Z] Running create-virtualenv step. 04:03:45 INFO - Running pre-action listener: _resource_record_pre_action 04:03:45 INFO - Running main action method: create_virtualenv 04:03:45 INFO - Creating virtualenv /builds/slave/test/build/venv 04:03:45 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:03:45 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:03:45 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:03:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:03:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:03:46 INFO - Installing distribute.............................................................................................................................................................................................done. 04:03:46 INFO - Installing pip...............done. 04:03:46 INFO - Return code: 0 04:03:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:03:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:03:46 INFO - Reading from file tmpfile_stdout 04:03:46 INFO - Output received: 04:03:46 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:03:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:03:46 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:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:46 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:03:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:46 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 0x21de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548d60>, '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 0x2549a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', '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:03:46 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:03:46 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:03:46 INFO - Using env: {'DISPLAY': ':0', 04:03:46 INFO - 'HOME': '/home/cltbld', 04:03:46 INFO - 'LANG': 'en_US.UTF-8', 04:03:46 INFO - 'LANGUAGE': 'en_US:en', 04:03:46 INFO - 'LOGNAME': 'cltbld', 04:03:46 INFO - 'MAIL': '/var/mail/cltbld', 04:03:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:46 INFO - 'MOZ_NO_REMOTE': '1', 04:03:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:46 INFO - 'NO_EM_RESTART': '1', 04:03:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:46 INFO - 'PWD': '/builds/slave/test', 04:03:46 INFO - 'SHELL': '/bin/bash', 04:03:46 INFO - 'SHLVL': '1', 04:03:46 INFO - 'TERM': 'linux', 04:03:46 INFO - 'TMOUT': '86400', 04:03:46 INFO - 'USER': 'cltbld', 04:03:46 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', 04:03:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:46 INFO - '_': '/tools/buildbot/bin/python'} 04:03:48 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:03:48 INFO - Downloading/unpacking pip>=1.5 04:03:48 INFO - Running setup.py egg_info for package pip 04:03:48 INFO - warning: no files found matching 'pip/cacert.pem' 04:03:48 INFO - warning: no files found matching '*.html' under directory 'docs' 04:03:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:03:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:03:48 INFO - Installing collected packages: pip 04:03:48 INFO - Found existing installation: pip 1.1 04:03:48 INFO - Uninstalling pip: 04:03:48 INFO - Successfully uninstalled pip 04:03:48 INFO - Running setup.py install for pip 04:03:48 INFO - warning: no files found matching 'pip/cacert.pem' 04:03:48 INFO - warning: no files found matching '*.html' under directory 'docs' 04:03:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:03:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:03:48 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:03:48 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:03:48 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:03:48 INFO - Successfully installed pip 04:03:48 INFO - Cleaning up... 04:03:48 INFO - Return code: 0 04:03:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:03:48 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:03:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:48 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:03:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:48 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 0x21de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548d60>, '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 0x2549a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', '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:03:48 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:03:48 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:03:48 INFO - Using env: {'DISPLAY': ':0', 04:03:48 INFO - 'HOME': '/home/cltbld', 04:03:48 INFO - 'LANG': 'en_US.UTF-8', 04:03:48 INFO - 'LANGUAGE': 'en_US:en', 04:03:48 INFO - 'LOGNAME': 'cltbld', 04:03:48 INFO - 'MAIL': '/var/mail/cltbld', 04:03:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:48 INFO - 'MOZ_NO_REMOTE': '1', 04:03:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:48 INFO - 'NO_EM_RESTART': '1', 04:03:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:48 INFO - 'PWD': '/builds/slave/test', 04:03:48 INFO - 'SHELL': '/bin/bash', 04:03:48 INFO - 'SHLVL': '1', 04:03:48 INFO - 'TERM': 'linux', 04:03:48 INFO - 'TMOUT': '86400', 04:03:48 INFO - 'USER': 'cltbld', 04:03:48 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', 04:03:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:48 INFO - '_': '/tools/buildbot/bin/python'} 04:03:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:48 INFO - Downloading/unpacking psutil>=3.1.1 04:03:48 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:03:48 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:03:48 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:03:48 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:03:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:03:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:03:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:03:49 INFO - Installing collected packages: psutil 04:03:49 INFO - Running setup.py install for psutil 04:03:49 INFO - building 'psutil._psutil_linux' extension 04:03:49 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:03:49 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:03:49 INFO - building 'psutil._psutil_posix' extension 04:03:49 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:03:49 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:03:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:03:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:03:50 INFO - Successfully installed psutil 04:03:50 INFO - Cleaning up... 04:03:50 INFO - Return code: 0 04:03:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:03: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:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03: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:03:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548d60>, '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 0x2549a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', '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:03: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:03: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 mozsystemmonitor==0.3 04:03:50 INFO - Using env: {'DISPLAY': ':0', 04:03:50 INFO - 'HOME': '/home/cltbld', 04:03:50 INFO - 'LANG': 'en_US.UTF-8', 04:03:50 INFO - 'LANGUAGE': 'en_US:en', 04:03:50 INFO - 'LOGNAME': 'cltbld', 04:03:50 INFO - 'MAIL': '/var/mail/cltbld', 04:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:50 INFO - 'MOZ_NO_REMOTE': '1', 04:03:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:50 INFO - 'NO_EM_RESTART': '1', 04:03:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:50 INFO - 'PWD': '/builds/slave/test', 04:03:50 INFO - 'SHELL': '/bin/bash', 04:03:50 INFO - 'SHLVL': '1', 04:03:50 INFO - 'TERM': 'linux', 04:03:50 INFO - 'TMOUT': '86400', 04:03:50 INFO - 'USER': 'cltbld', 04:03:50 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', 04:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:50 INFO - '_': '/tools/buildbot/bin/python'} 04:03:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:03: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:03: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:03: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:03: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:03:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:03:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:03:51 INFO - Installing collected packages: mozsystemmonitor 04:03:51 INFO - Running setup.py install for mozsystemmonitor 04:03:51 INFO - Successfully installed mozsystemmonitor 04:03:51 INFO - Cleaning up... 04:03:52 INFO - Return code: 0 04:03:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:03: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:03:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03: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:03:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548d60>, '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 0x2549a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', '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:03:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:03:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:03:52 INFO - Using env: {'DISPLAY': ':0', 04:03:52 INFO - 'HOME': '/home/cltbld', 04:03:52 INFO - 'LANG': 'en_US.UTF-8', 04:03:52 INFO - 'LANGUAGE': 'en_US:en', 04:03:52 INFO - 'LOGNAME': 'cltbld', 04:03:52 INFO - 'MAIL': '/var/mail/cltbld', 04:03:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:52 INFO - 'MOZ_NO_REMOTE': '1', 04:03:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:52 INFO - 'NO_EM_RESTART': '1', 04:03:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:52 INFO - 'PWD': '/builds/slave/test', 04:03:52 INFO - 'SHELL': '/bin/bash', 04:03:52 INFO - 'SHLVL': '1', 04:03:52 INFO - 'TERM': 'linux', 04:03:52 INFO - 'TMOUT': '86400', 04:03:52 INFO - 'USER': 'cltbld', 04:03:52 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', 04:03:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:52 INFO - '_': '/tools/buildbot/bin/python'} 04:03:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:52 INFO - Downloading/unpacking jsonschema==2.5.1 04:03: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:03: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:03: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:03: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:03:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:03:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:03:53 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:03:53 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:03:53 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:03:53 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:03:53 INFO - Downloading functools32-3.2.3-2.tar.gz 04:03:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:03:54 INFO - warning: no files found matching '*.txt' 04:03:54 INFO - no previously-included directories found matching 'build' 04:03:54 INFO - no previously-included directories found matching 'dist' 04:03:54 INFO - no previously-included directories found matching '.git*' 04:03:54 INFO - Installing collected packages: jsonschema, functools32 04:03:54 INFO - Running setup.py install for functools32 04:03:54 INFO - warning: no files found matching '*.txt' 04:03:54 INFO - no previously-included directories found matching 'build' 04:03:54 INFO - no previously-included directories found matching 'dist' 04:03:54 INFO - no previously-included directories found matching '.git*' 04:03:54 INFO - Successfully installed jsonschema functools32 04:03:54 INFO - Cleaning up... 04:03:54 INFO - Return code: 0 04:03:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:03:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:03:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548d60>, '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 0x2549a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', '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:03:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:03:54 INFO - Using env: {'DISPLAY': ':0', 04:03:54 INFO - 'HOME': '/home/cltbld', 04:03:54 INFO - 'LANG': 'en_US.UTF-8', 04:03:54 INFO - 'LANGUAGE': 'en_US:en', 04:03:54 INFO - 'LOGNAME': 'cltbld', 04:03:54 INFO - 'MAIL': '/var/mail/cltbld', 04:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:54 INFO - 'MOZ_NO_REMOTE': '1', 04:03:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:54 INFO - 'NO_EM_RESTART': '1', 04:03:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:54 INFO - 'PWD': '/builds/slave/test', 04:03:54 INFO - 'SHELL': '/bin/bash', 04:03:54 INFO - 'SHLVL': '1', 04:03:54 INFO - 'TERM': 'linux', 04:03:54 INFO - 'TMOUT': '86400', 04:03:54 INFO - 'USER': 'cltbld', 04:03:54 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', 04:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:54 INFO - '_': '/tools/buildbot/bin/python'} 04:03:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:03:54 INFO - Cleaning up... 04:03:54 INFO - Return code: 0 04:03:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:03:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:03:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548d60>, '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 0x2549a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', '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:03:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:03:54 INFO - Using env: {'DISPLAY': ':0', 04:03:54 INFO - 'HOME': '/home/cltbld', 04:03:54 INFO - 'LANG': 'en_US.UTF-8', 04:03:54 INFO - 'LANGUAGE': 'en_US:en', 04:03:54 INFO - 'LOGNAME': 'cltbld', 04:03:54 INFO - 'MAIL': '/var/mail/cltbld', 04:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:54 INFO - 'MOZ_NO_REMOTE': '1', 04:03:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:54 INFO - 'NO_EM_RESTART': '1', 04:03:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:54 INFO - 'PWD': '/builds/slave/test', 04:03:54 INFO - 'SHELL': '/bin/bash', 04:03:54 INFO - 'SHLVL': '1', 04:03:54 INFO - 'TERM': 'linux', 04:03:54 INFO - 'TMOUT': '86400', 04:03:54 INFO - 'USER': 'cltbld', 04:03:54 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', 04:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:54 INFO - '_': '/tools/buildbot/bin/python'} 04:03:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:54 INFO - Downloading/unpacking blobuploader==1.2.4 04:03: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:03: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:03: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:03: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:03:55 INFO - Downloading blobuploader-1.2.4.tar.gz 04:03:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:03:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:03: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:03: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:03: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:03: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:03:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:03:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:03: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:03: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:03: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:03: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:03:56 INFO - Downloading docopt-0.6.1.tar.gz 04:03:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:03:56 INFO - Installing collected packages: blobuploader, requests, docopt 04:03:56 INFO - Running setup.py install for blobuploader 04:03:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:03:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:03:56 INFO - Running setup.py install for requests 04:03:56 INFO - Running setup.py install for docopt 04:03:56 INFO - Successfully installed blobuploader requests docopt 04:03:56 INFO - Cleaning up... 04:03:57 INFO - Return code: 0 04:03:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:03:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:03:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:03:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:03:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548d60>, '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 0x2549a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', '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:03:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:03:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:03:57 INFO - Using env: {'DISPLAY': ':0', 04:03:57 INFO - 'HOME': '/home/cltbld', 04:03:57 INFO - 'LANG': 'en_US.UTF-8', 04:03:57 INFO - 'LANGUAGE': 'en_US:en', 04:03:57 INFO - 'LOGNAME': 'cltbld', 04:03:57 INFO - 'MAIL': '/var/mail/cltbld', 04:03:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:03:57 INFO - 'MOZ_NO_REMOTE': '1', 04:03:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:03:57 INFO - 'NO_EM_RESTART': '1', 04:03:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:03:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:03:57 INFO - 'PWD': '/builds/slave/test', 04:03:57 INFO - 'SHELL': '/bin/bash', 04:03:57 INFO - 'SHLVL': '1', 04:03:57 INFO - 'TERM': 'linux', 04:03:57 INFO - 'TMOUT': '86400', 04:03:57 INFO - 'USER': 'cltbld', 04:03:57 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', 04:03:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:03:57 INFO - '_': '/tools/buildbot/bin/python'} 04:03:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:03:57 INFO - Running setup.py (path:/tmp/pip-wJAr6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:03:57 INFO - Running setup.py (path:/tmp/pip-vzlqUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:03:57 INFO - Running setup.py (path:/tmp/pip-NEaseU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:03:57 INFO - Running setup.py (path:/tmp/pip-_buArc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:03:57 INFO - Running setup.py (path:/tmp/pip-ZH9HzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:03:57 INFO - Running setup.py (path:/tmp/pip-5nYheV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:03:57 INFO - Running setup.py (path:/tmp/pip-1kznVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:03:57 INFO - Running setup.py (path:/tmp/pip-U2sH5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:03:57 INFO - Running setup.py (path:/tmp/pip-dgwH1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:03:57 INFO - Running setup.py (path:/tmp/pip-wS499D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:03:57 INFO - Running setup.py (path:/tmp/pip-8uTVwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:03:58 INFO - Running setup.py (path:/tmp/pip-_6frgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:03:58 INFO - Running setup.py (path:/tmp/pip-Izqq1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:03:58 INFO - Running setup.py (path:/tmp/pip-faePq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:03:58 INFO - Running setup.py (path:/tmp/pip-IBlhAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:03:58 INFO - Running setup.py (path:/tmp/pip-TXFDUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:03:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:03:58 INFO - Running setup.py (path:/tmp/pip-JxJxzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:03:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:03:58 INFO - Running setup.py install for manifestparser 04:03:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:03:58 INFO - Running setup.py install for mozcrash 04:03:58 INFO - Running setup.py install for mozdebug 04:03:58 INFO - Running setup.py install for mozdevice 04:03:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:03:58 INFO - Running setup.py install for mozfile 04:03:58 INFO - Running setup.py install for mozhttpd 04:03:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Running setup.py install for mozinfo 04:03:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Running setup.py install for mozInstall 04:03:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Running setup.py install for mozleak 04:03:59 INFO - Running setup.py install for mozlog 04:03:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Running setup.py install for moznetwork 04:03:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Running setup.py install for mozprocess 04:03:59 INFO - Running setup.py install for mozprofile 04:03:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Running setup.py install for mozrunner 04:03:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:03:59 INFO - Running setup.py install for mozscreenshot 04:03:59 INFO - Running setup.py install for moztest 04:04:00 INFO - Running setup.py install for mozversion 04:04:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:04:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:04:00 INFO - Cleaning up... 04:04:00 INFO - Return code: 0 04:04:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:04:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:04:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:04:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:04:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548d60>, '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 0x2549a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', '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:04:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:04:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:04:00 INFO - Using env: {'DISPLAY': ':0', 04:04:00 INFO - 'HOME': '/home/cltbld', 04:04:00 INFO - 'LANG': 'en_US.UTF-8', 04:04:00 INFO - 'LANGUAGE': 'en_US:en', 04:04:00 INFO - 'LOGNAME': 'cltbld', 04:04:00 INFO - 'MAIL': '/var/mail/cltbld', 04:04:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:00 INFO - 'MOZ_NO_REMOTE': '1', 04:04:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:04:00 INFO - 'NO_EM_RESTART': '1', 04:04:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:04:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:00 INFO - 'PWD': '/builds/slave/test', 04:04:00 INFO - 'SHELL': '/bin/bash', 04:04:00 INFO - 'SHLVL': '1', 04:04:00 INFO - 'TERM': 'linux', 04:04:00 INFO - 'TMOUT': '86400', 04:04:00 INFO - 'USER': 'cltbld', 04:04:00 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', 04:04:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:00 INFO - '_': '/tools/buildbot/bin/python'} 04:04:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:04:00 INFO - Running setup.py (path:/tmp/pip-ti7zBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:04:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:04:00 INFO - Running setup.py (path:/tmp/pip-iHHGgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:04:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:04:00 INFO - Running setup.py (path:/tmp/pip-Y1ZPZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:04:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:04:00 INFO - Running setup.py (path:/tmp/pip-F5E2Sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:04:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:04:00 INFO - Running setup.py (path:/tmp/pip-WIEkbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:04:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:04:00 INFO - Running setup.py (path:/tmp/pip-DhFLmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:04:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:04:00 INFO - Running setup.py (path:/tmp/pip-Wt3Gb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:04:00 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:04:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:04:00 INFO - Running setup.py (path:/tmp/pip-KKY9iz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:04:01 INFO - Running setup.py (path:/tmp/pip-BYqwz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:04:01 INFO - Running setup.py (path:/tmp/pip-CoIeFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:04:01 INFO - Running setup.py (path:/tmp/pip-KoAAZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:04:01 INFO - Running setup.py (path:/tmp/pip-7aYWvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:04:01 INFO - Running setup.py (path:/tmp/pip-jWPIaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:04:01 INFO - Running setup.py (path:/tmp/pip-lvd7jp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:04:01 INFO - Running setup.py (path:/tmp/pip-SL46ox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:04:01 INFO - Running setup.py (path:/tmp/pip-_GDCv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:04:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:04:01 INFO - Running setup.py (path:/tmp/pip-4O4eXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:04:01 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:04:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:04:02 INFO - Downloading blessings-1.6.tar.gz 04:04:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:04:02 INFO - Installing collected packages: blessings 04:04:02 INFO - Running setup.py install for blessings 04:04:02 INFO - Successfully installed blessings 04:04:02 INFO - Cleaning up... 04:04:02 INFO - Return code: 0 04:04:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:04:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:04:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:04:03 INFO - Reading from file tmpfile_stdout 04:04:03 INFO - Current package versions: 04:04:03 INFO - argparse == 1.2.1 04:04:03 INFO - blessings == 1.6 04:04:03 INFO - blobuploader == 1.2.4 04:04:03 INFO - docopt == 0.6.1 04:04:03 INFO - functools32 == 3.2.3-2 04:04:03 INFO - jsonschema == 2.5.1 04:04:03 INFO - manifestparser == 1.1 04:04:03 INFO - mozInstall == 1.13 04:04:03 INFO - mozcrash == 1.0 04:04:03 INFO - mozdebug == 0.1 04:04:03 INFO - mozdevice == 0.51 04:04:03 INFO - mozfile == 1.2 04:04:03 INFO - mozhttpd == 0.7 04:04:03 INFO - mozinfo == 0.10 04:04:03 INFO - mozleak == 0.1 04:04:03 INFO - mozlog == 3.5 04:04:03 INFO - moznetwork == 0.27 04:04:03 INFO - mozprocess == 0.25 04:04:03 INFO - mozprofile == 0.28 04:04:03 INFO - mozrunner == 6.13 04:04:03 INFO - mozscreenshot == 0.1 04:04:03 INFO - mozsystemmonitor == 0.3 04:04:03 INFO - moztest == 0.8 04:04:03 INFO - mozversion == 1.4 04:04:03 INFO - psutil == 3.1.1 04:04:03 INFO - requests == 1.2.3 04:04:03 INFO - wsgiref == 0.1.2 04:04:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:04:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:04:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:04:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:04:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548d60>, '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 0x2549a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', '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:04:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:04:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:04:03 INFO - Using env: {'DISPLAY': ':0', 04:04:03 INFO - 'HOME': '/home/cltbld', 04:04:03 INFO - 'LANG': 'en_US.UTF-8', 04:04:03 INFO - 'LANGUAGE': 'en_US:en', 04:04:03 INFO - 'LOGNAME': 'cltbld', 04:04:03 INFO - 'MAIL': '/var/mail/cltbld', 04:04:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:03 INFO - 'MOZ_NO_REMOTE': '1', 04:04:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:04:03 INFO - 'NO_EM_RESTART': '1', 04:04:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:04:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:03 INFO - 'PWD': '/builds/slave/test', 04:04:03 INFO - 'SHELL': '/bin/bash', 04:04:03 INFO - 'SHLVL': '1', 04:04:03 INFO - 'TERM': 'linux', 04:04:03 INFO - 'TMOUT': '86400', 04:04:03 INFO - 'USER': 'cltbld', 04:04:03 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', 04:04:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:03 INFO - '_': '/tools/buildbot/bin/python'} 04:04:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:04:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:04:03 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:04:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:04:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:04:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:04:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:04:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:04:04 INFO - Installing collected packages: simplejson 04:04:04 INFO - Running setup.py install for simplejson 04:04:04 INFO - building 'simplejson._speedups' extension 04:04:04 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 04:04:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 04:04:05 INFO - Successfully installed simplejson 04:04:05 INFO - Cleaning up... 04:04:05 INFO - Return code: 0 04:04:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:04:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:04:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:04:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:04:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:04:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21de1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23a6b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254b9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2548d60>, '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 0x2549a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', '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:04:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:04:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:04:05 INFO - Using env: {'DISPLAY': ':0', 04:04:05 INFO - 'HOME': '/home/cltbld', 04:04:05 INFO - 'LANG': 'en_US.UTF-8', 04:04:05 INFO - 'LANGUAGE': 'en_US:en', 04:04:05 INFO - 'LOGNAME': 'cltbld', 04:04:05 INFO - 'MAIL': '/var/mail/cltbld', 04:04:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:05 INFO - 'MOZ_NO_REMOTE': '1', 04:04:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:04:05 INFO - 'NO_EM_RESTART': '1', 04:04:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:04:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:05 INFO - 'PWD': '/builds/slave/test', 04:04:05 INFO - 'SHELL': '/bin/bash', 04:04:05 INFO - 'SHLVL': '1', 04:04:05 INFO - 'TERM': 'linux', 04:04:05 INFO - 'TMOUT': '86400', 04:04:05 INFO - 'USER': 'cltbld', 04:04:05 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', 04:04:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:05 INFO - '_': '/tools/buildbot/bin/python'} 04:04:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:04:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:04:05 INFO - Cleaning up... 04:04:05 INFO - Return code: 0 04:04:05 INFO - Running post-action listener: _resource_record_post_action 04:04:05 INFO - Running post-action listener: _start_resource_monitoring 04:04:05 INFO - Starting resource monitoring. 04:04:05 INFO - [mozharness: 2017-09-23 11:04:05.926365Z] Finished create-virtualenv step (success) 04:04:05 INFO - [mozharness: 2017-09-23 11:04:05.926843Z] Running install step. 04:04:05 INFO - Running pre-action listener: _resource_record_pre_action 04:04:05 INFO - Running main action method: install 04:04:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:04:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:04:06 INFO - Reading from file tmpfile_stdout 04:04:06 INFO - Detecting whether we're running mozinstall >=1.0... 04:04:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:04:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:04:06 INFO - Reading from file tmpfile_stdout 04:04:06 INFO - Output received: 04:04:06 INFO - Usage: mozinstall [options] installer 04:04:06 INFO - Options: 04:04:06 INFO - -h, --help show this help message and exit 04:04:06 INFO - -d DEST, --destination=DEST 04:04:06 INFO - Directory to install application into. [default: 04:04:06 INFO - "/builds/slave/test"] 04:04:06 INFO - --app=APP Application being installed. [default: firefox] 04:04:06 INFO - mkdir: /builds/slave/test/build/application 04:04:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:04:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:04:23 INFO - Reading from file tmpfile_stdout 04:04:23 INFO - Output received: 04:04:23 INFO - /builds/slave/test/build/application/firefox/firefox 04:04:23 INFO - Running post-action listener: _resource_record_post_action 04:04:23 INFO - [mozharness: 2017-09-23 11:04:23.898703Z] Finished install step (success) 04:04:23 INFO - [mozharness: 2017-09-23 11:04:23.898957Z] Running setup-mitmproxy step. 04:04:23 INFO - Running pre-action listener: _resource_record_pre_action 04:04:23 INFO - Running main action method: setup_mitmproxy 04:04:23 INFO - Skipping: mitmproxy is not required 04:04:23 INFO - Running post-action listener: _resource_record_post_action 04:04:23 INFO - [mozharness: 2017-09-23 11:04:23.900092Z] Finished setup-mitmproxy step (success) 04:04:23 INFO - [mozharness: 2017-09-23 11:04:23.900312Z] Running run-tests step. 04:04:23 INFO - Running pre-action listener: _resource_record_pre_action 04:04:23 INFO - Running pre-action listener: _set_gcov_prefix 04:04:23 INFO - Running main action method: run_tests 04:04:23 WARNING - Try message not found. 04:04:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:04:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:04:23 INFO - Python 2.7.3 04:04:23 INFO - Return code: 0 04:04:23 INFO - grabbing minidump binary from tooltool 04:04: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:04: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 0x2548d60>, '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 0x2549a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:04: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:04: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:04: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:04:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:04:24 INFO - Return code: 0 04:04:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:04:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:04:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:04:24 INFO - ENV: RUST_BACKTRACE is now full 04:04:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:04:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:04: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/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:04: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/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:04:24 INFO - Using env: {'DISPLAY': ':0', 04:04:24 INFO - 'HOME': '/home/cltbld', 04:04:24 INFO - 'LANG': 'en_US.UTF-8', 04:04:24 INFO - 'LANGUAGE': 'en_US:en', 04:04:24 INFO - 'LOGNAME': 'cltbld', 04:04:24 INFO - 'MAIL': '/var/mail/cltbld', 04:04:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:04:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:04:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:24 INFO - 'MOZ_NO_REMOTE': '1', 04:04:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:04:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:04:24 INFO - 'NO_EM_RESTART': '1', 04:04:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:04:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:24 INFO - 'PWD': '/builds/slave/test', 04:04:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:04:24 INFO - 'RUST_BACKTRACE': 'full', 04:04:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:04:24 INFO - 'SHELL': '/bin/bash', 04:04:24 INFO - 'SHLVL': '1', 04:04:24 INFO - 'TERM': 'linux', 04:04:24 INFO - 'TMOUT': '86400', 04:04:24 INFO - 'USER': 'cltbld', 04:04:24 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', 04:04:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:24 INFO - '_': '/tools/buildbot/bin/python'} 04:04: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/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:04:24 INFO - mozversion application_buildid: 20170923093626 04:04:24 INFO - mozversion application_changeset: 8c3a1558322331c1fab9d1d2d7a94ef69f2a9212 04:04:24 INFO - mozversion application_display_name: Nightly 04:04:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:04:24 INFO - mozversion application_name: Firefox 04:04:24 INFO - mozversion application_remotingname: firefox 04:04:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:04:24 INFO - mozversion application_vendor: Mozilla 04:04:24 INFO - mozversion application_version: 58.0a1 04:04:24 INFO - mozversion platform_buildid: 20170923093626 04:04:24 INFO - mozversion platform_changeset: 8c3a1558322331c1fab9d1d2d7a94ef69f2a9212 04:04:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:04:24 INFO - mozversion platform_version: 58.0a1 04:04:24 INFO - using testdate: 1506164664 04:04:24 INFO - actual date: 1506164664 04:04:24 INFO - starting webserver on 'localhost:48519' 04:04:24 INFO - SUITE-START | Running 2 tests 04:04:24 INFO - TEST-START | dromaeo_css 04:04:24 INFO - Initialising browser for dromaeo_css test... 04:04:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48519/getInfo.html -profile /tmp/tmpE3bCrC/profile 04:04:24 INFO - TEST-INFO | started process 19984 (/builds/slave/test/build/application/firefox/firefox http://localhost:48519/getInfo.html) 04:04:30 INFO - TEST-INFO | 19984: exit 0 04:04:30 INFO - Browser initialized. 04:04:30 INFO - Running cycle 1/1 for dromaeo_css test... 04:04:30 INFO - Using env: {'DISPLAY': ':0', 04:04:30 INFO - 'HOME': '/home/cltbld', 04:04:30 INFO - 'JSGC_DISABLE_POISONING': '1', 04:04:30 INFO - 'LANG': 'en_US.UTF-8', 04:04:30 INFO - 'LANGUAGE': 'en_US:en', 04:04:30 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:04:30 INFO - 'LOGNAME': 'cltbld', 04:04:30 INFO - 'MAIL': '/var/mail/cltbld', 04:04:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:04:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:04:30 INFO - 'MOZ_CRASHREPORTER': '1', 04:04:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:04:30 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:04:30 INFO - 'MOZ_NO_REMOTE': '1', 04:04:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:04:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:04:30 INFO - 'NO_EM_RESTART': '1', 04:04:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:04:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:04:30 INFO - 'PWD': '/builds/slave/test', 04:04:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:04:30 INFO - 'RUST_BACKTRACE': 'full', 04:04:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:04:30 INFO - 'SHELL': '/bin/bash', 04:04:30 INFO - 'SHLVL': '1', 04:04:30 INFO - 'TERM': 'linux', 04:04:30 INFO - 'TMOUT': '86400', 04:04:30 INFO - 'USER': 'cltbld', 04:04:30 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', 04:04:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:04:30 INFO - '_': '/tools/buildbot/bin/python'} 04:04:30 INFO - TEST-INFO | started process 20220 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE3bCrC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:04:31 INFO - PID 20220 | 04:04:31 INFO - PID 20220 | (/builds/slave/test/build/application/firefox/firefox:20269): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:04:31 INFO - PID 20220 | 04:04:41 INFO - PID 20220 | RSS: Main: 144650240 04:04:41 INFO - PID 20220 | 04:07:39 INFO - PID 20220 | Cycle 1(1): loaded http://localhost:48519/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48519/tests/dromaeo/cssquery-ext.html) 04:07:40 INFO - PID 20220 | RSS: Main: 146391040 04:07:40 INFO - PID 20220 | 04:10:42 INFO - PID 20220 | Cycle 1(1): loaded http://localhost:48519/tests/dromaeo/cssquery-ext.html (next: http://localhost:48519/tests/dromaeo/cssquery-jquery.html) 04:10:43 INFO - PID 20220 | RSS: Main: 146468864 04:10:43 INFO - PID 20220 | 04:13:44 INFO - PID 20220 | Cycle 1(1): loaded http://localhost:48519/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48519/tests/dromaeo/cssquery-mootools.html) 04:13:45 INFO - PID 20220 | RSS: Main: 148492288 04:13:45 INFO - PID 20220 | 04:16:46 INFO - PID 20220 | Cycle 1(1): loaded http://localhost:48519/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48519/tests/dromaeo/cssquery-prototype.html) 04:16:47 INFO - PID 20220 | RSS: Main: 147603456 04:16:47 INFO - PID 20220 | 04:19:50 INFO - PID 20220 | Cycle 1(1): loaded http://localhost:48519/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48519/tests/dromaeo/cssquery-yui.html) 04:19:51 INFO - PID 20220 | RSS: Main: 148381696 04:19:51 INFO - PID 20220 | 04:22:52 INFO - PID 20220 | Cycle 1(1): loaded http://localhost:48519/tests/dromaeo/cssquery-yui.html (next: http://localhost:48519/tests/dromaeo/cssquery-dojo.html) 04:22:52 INFO - PID 20220 | RSS: Main: 151478272 04:22:52 INFO - PID 20220 | 04:22:52 INFO - PID 20220 | __start_tp_report 04:22:52 INFO - PID 20220 | _x_x_mozilla_page_load 04:22:52 INFO - PID 20220 | _x_x_mozilla_page_load_details 04:22:52 INFO - PID 20220 | |i|pagename|runs| 04:22:52 INFO - PID 20220 | |0;dojo.html;3883.165119499307;3893.1045859452324;3893.3575959966597;3930.0764320384706;3938.8621398251066;6187.938120618793;6252.249730032397;6316.242050953886;6337.176167098277;6338.176037115169;6221.875562488747;6247.750089996394;6320.778772742955;6471.70877310521;6493.902591461132;5659.264295641581;5686.488216060554;5731.684757338345;5748.5975981681295;5788.04497257952;5382;5557.110862262019;5609.8597535061745;5632.887342253152;5676.375598684142;5672.978863804514;5683.801066962657;5746.1668058131545;5766.077427611562;5774.0761478163295;5555.944440555623;5704.030314846486;5739.512141467939;5758.280214973129;5767.538596912279;5687.630304030266;5695.515881150107;5711.343195532484;5720.599558030939;5742.598018138732;6298.307186209513;6305.842353941188;6364.6181229126405;6385.840353991141;6407.711652975628;6873.243943166248;6885.862282754317;6889.758858439981;6966.059581956421;6971.058907047534;6856.280090590466;6930.61881596512;6936.79189624312;6940.930590694079;6942.93057069433;6323.74705011799;6379.36206379363;6387.584806987531;6447.742090316428;6473.902891456632;7007.509474336747;7102.751403700846;7191.065161529073;7198.496105272686;7210.098737657793;6807.863842723217;6850.760223392257;6883.793486195422;6924.272951340137;6932.064171336906;6835.282295359015;6850.554713943578;6891.931080689229;6905.309469053054;6912.585244885323;6585.934140658532;6821.590704557742;6872.828179295558;6919.411849992706;6967.198772141167;6842.486813488908;6879.105716256855;6941.409980151744;6957.82605434858;6978.860422791516;5004.874878128003;5022;5080.060188864998;5096.082705113041;5111.642185047011;4498.730076195438;4539.568740969603;4565.086982603426;4580.679352445297;4584.5186255442695;6380.649064301508;6406.391392817675;6425.228972523234;6426.550141490145;6429.03564465334;6843.965780171067;6852.897206541906;6862.142232220973;6868.793936181923;6941.167059952737;6247.250329960434;6317.4630156437215;6387.520935929824;6394.648294343856;6415.518836087219;5558.916616250759;5565.16522521625;5617.101263797773;5618.690971996498;5623.465770751772;7041.788746337618;7129.465290103263;7135.82160445993;7146.678399472036;7245.275472452712;6830.112085428863;6852.486063545154;6869.416099631388;6875.1749790025515;6879.690413931486;7130.393916516947;7134.143902731706;7154.642267886689;7160.534565253241;7163.140423149255;6810.557313774589;6823.795286141423;6826.590404575742;6832.897506537505;6920.031195632716;6877.552959057645;6917.239103698488;6983.650817459208;7005.684744186626;7011.3339232772805;6665.50008749336;6698.866022679616;6783.86432271342;6784.38940495338;6786.966065169642;6520.119783829123;6636.2368327642935;6664.366885145903;6673.833154171185;6761.425278851156;6608.405243527914;6609.669516524251;6628;6628.53600247978;6631.701573429304;6715.328467153246;6740.224874139537;6770.796876093725;6778.3221677831825;6831.658417079226;6531.281559028408;6546.770863019771;6604.14146160996;6626.56927299724;6639.70121344531 04:22:52 INFO - PID 20220 | |1;ext.html;911.3848152497063;976.4775844922965;984.9556769945352;1000.6897861662885;1007.6926537406091;45463.77268113659;45955.77022114893;46210;46335;46388.536114638846;42447.57552424475;43080.3537946931;43173.56826431735;43270.35094473585;43366.349504757454;7765;7807;7878.921210787905;7900.683972641087;7916.6833326666865;7710.730124445646;7732.729354472594;7777.261160189773;7785.338246249075;7807.219278072176;8298.875516867252;8488.32093432527;8495.575221238943;8497.915020849805;8503.574821258942;8382.329413646894;8390.706325278647;8431.072582015973;8445.36659750518;8458.450200736932;8248.13394593565;8468.66125354985;8472.19514146155;8473.067962524117;8473.95763021181;40665.1866962662;41085.794571026956;41085.794571026956;41093.38359924604;41126;3691.8892433227047;3694.685951694082;3713;3714.7028237740915;3723.9069023274365;32873.67126328754;33120.17199570006;33379;33469.49795753066;33489.83255083733;5485.314335708036;5510.090835012218;5553.666779993173;5574.665520068809;5649.745761440704;215555.92222038948;216550;217197;217206;218013;47587.52412475831;53903.460965389844;54680;54727.72636136794;54798.72600637051;56354;56505.15242271369;56675.71662141745;56703.71648141733;56985;15226.162561059027;15299.311530981133;15328.616784580474;15373;15534.300956456986;12313.322767247888;12345.753084938251;12382.876171238353;12389;12393.318367489694;15488.606025457762;15576.454824081104;15582;15678.921605392128;15787.368505259661;6007.27912650476;6147.631142131397;6194;6270.404311590391;6271.592346497463;1106.6292791914639;1111.8109921313396;1113.1261959361861;1117.80997230471;1124.1175677093443;25885.482290354463;26052.478950420886;26099.478010439685;26396.47207055848;26495.470090598465;1202.2546021466746;1212.4362171590253;1217.9086568507398;1225.9763097813425;1229.8585662648732;13607.115537490032;13800.93099534496;13854.16874987504;13983.650408739659;14100.153990760587;269169;270980;271264;271608;272183;59598.40401596015;59767.103493447634;59815.10277345756;59844.40155598388;59942.700286498286;1468.3539242733164;1526.9236538173047;1529.6175956010998;1545.3355057325457;1547.0562956596468;25494.362640933385;26080.73919260783;26134;26155.476890462465;26322.60516092222;42318.36522452104;42346;42390.78804605957;42432;42490.36264455974;221500;224867;225553;226155;227101;36923.815380922926;40661.59338406696;43216.35175472307;43296.13407731891;43424.78287608542;51169.48830511647;52103.73948130235;52411;52488;52786.208206877694;38725.80637096796;38730;38989.02527436837;39278;39329.41005884971;38167.2366552673;38193.80903095467;38481.23037539289;38916.80541597274;39303.41044884272;44999;45848.54151458576;45981.310280345155;46489;46545;37438.43842342422;37755.43366849554;38006.8099659511;38139.42790858195;38209;28252.717472825832;29005.27486812762;29059.7094029057;29353;29402.852985734935 04:22:52 INFO - PID 20220 | |2;jquery.html;609.841023498279;619.1424876545982;626.8056902360272;633.4615576759754;635.9586626869254;23063.192788252414;23576.29271121867;24478.877605611946;24509.01963921443;24548.75451245487;21855.781442185573;23147.652785208193;23149.189778357762;23149.884250578725;23165.768342316573;6658.3674550917585;6830.316968303167;6835.487338449611;6846.931530684679;6849.452043836506;6851.349121833443;6900.965495172542;6902.068220790205;6902.965485172567;6903.654817259142;6846.965765171167;6852.5203235773515;6852.760153394632;6858.417034552069;6860.55406398585;6791.932080679204;6818.761343352959;6846.8972965405555;6870.20992585854;6873.140857392826;6379.266384365811;6400.103985442041;6403.9359606403805;6413.903791443132;6427.325130861286;29244.268893277625;30532.847335763403;30557.847210764026;30717;30781;2272.749997500274;2290;2296.0471404367167;2300.183434880627;2304.089884495615;20115.29596464117;20854.165833366646;20897.164113435443;20979.790202097935;21027.26404575848;2433.8052955763496;2511.6860392450944;2544.694636643596;2558.731333210005;2569.691637003553;137542.31228843893;144164;158317;163872;164693;15857.68767462913;16202.675946481004;16430;16520.42178523746;16530.20624019021;25489.107881224067;25491.107811226888;25561.744382555935;25574.744252557237;25630.359241018752;9938.652147174958;9957.153641940371;10153.543090560943;10184.861179152462;10199.13307368882;8640.351973602004;8738.650453981896;8973.820523589491;9022.774430639156;9031.683891063913;9580.808383832284;9759.096976089595;9966.006798640301;10019.248556358303;10109.696709098738;2978.4936560784718;2979.9851000744857;3009.9097027089224;3020.2298413904314;3076.35396566719;1045.163868904873;1045.278757657214;1053.9578416863394;1070.82866741321;1074.462768615692;21342.679859802116;21366.679499807517;21390.465238368855;21821.67267490989;21968.231111911005;1095.3318475729798;1102.1513434655271;1105.5798796457295;1114.1532435349193;1121.2375584602548;11279.718007049725;11861.940690296666;11868.643940681794;11878.346690932081;12143.757124857453;581679;595499;598670;607047;615927;130837;131008;131756.3412182933;136107.99999999802;136279;1227.7851376009162;1229.4467489629703;1261.5899832554458;1266.468083404972;1271.0721173543366;19111.23555057782;19265.325713600516;19316.71024934599;19389.51526211856;19496;61079.38920610915;61811.38188618056;62112;62491;64606;79667;79685;80439.59780201061;80957.59521202356;81950;15636;15750.369985200463;15791.052536847827;15796.447124350592;15920.681586368439;23961.28116156518;24080.036798527864;24104.276871693877;24374;24391.26826195217;19231;19749.901250493655;19844.801551984296;19892.70160947616;19926.50183745418;17441;17533.386331478338;17924.91037544804;18178.90910545439;18283.26866925308;20080.196792128736;20169.899150504152;20540.281090161774;20580.79419205789;20742.481437964172;15489.30298136609;15521.76717349218;15662.451814186448;15677.764833527279;15738.134402607508;19257.903710481918;19586.804131959067;19595.804041959396;19688.7202331848;19722.506937326107 04:22:52 INFO - PID 20220 | |3;mootools.html;1446.855314468553;1508.313717258648;1512.1305249481552;1516.9014014089084;1528.106057956096;16798.999999999985;17455.563610909732;17525.824741752578;17526.824731752662;17581.3846515372;17185.140742962834;17230.483085507425;17391.652166956686;17399;17434.38979635713;2893.5370340745485;2894.7539459145996;2948.557716342544;2951.4097180563867;2953.0550223928353;2868.813527120741;2869.985650071747;2898.884044638212;2901.4052119315597;2905.5351143816993;2659.175655546777;2827.533456979596;2840.1195629354966;2840.872160752771;2842.957355639667;2894.3198348388114;2913.475574396608;2922.240217543454;2929.7216764407453;2950.911472655813;2841.559558268472;2852.3011862093713;2862.327353072026;2867.7705783537476;2877.453283876057;16573.50279491617;16910.74633880493;16929.746053809202;16979.235934382858;16984.49046528606;8275.337972962148;8500.234978851871;8551.059383467813;8554.657813687445;8574.142585741438;5558.527525160366;5592.664440133564;5706.258186435737;5729.541636669056;5801.448862358069;1980.6830907054803;1982.7918068602733;2024.6456870047682;2058.1355830551206;2064.1846470644014;31050.53424198639;31050.844745776354;31214;31221.37557248842;31256.374872502423;6685.5654382465955;6959.234484206733;7030.156381234285;7091.25541818112;7266.45501587383;18757.34349297768;19954.70067948982;19955;19990.50023749418;20030.198792048446;7865.921340786634;8094.635741391651;8108.270255677017;8138.633761480747;8154.266116049584;7355.2277010913085;7386.113666360071;7447.218042105609;7454;7461.664225109883;5753.482186603226;5827.446392592782;5879.617824841372;5979.970100149471;5983.551233657493;1734.167599552222;1748.4841971618403;1770.725537541683;1770.9911450442664;1773.6186719855293;949.3402085550614;967.0281367225892;970.7330484116783;972.7908499672604;973.1436336024253;8409.621567029499;8424.831503369898;8426.578671066422;8485.575721213914;8599;797.1072398913254;799.9200079991954;805.8912046873597;806.9354451643854;808.8301456694043;5297.470252974672;5902.321233058167;5998.460138587461;6131.080337949344;6283.6858157091965;24461.87769061179;24503.264902052968;24772.752272477046;25508.744912551007;25561.23316300514;24133.51732965331;24830.25509234726;24834.875825620755;24928.37679058038;24928.5014299713;12809.231446113263;12816.102872798814;13101.672458188621;13121.409536570687;13132.868671313356;9169.954150229207;9193.448393096436;9195.218406435528;9232.21526170283;9248.306377021616;21367.14531418788;21541.138354465646;21566.892165539073;21747.565048699253;22078.77921220767;22013.889930550245;22077.889610551843;22152.113915443202;22207.44481387978;22429.43926401853;12481.500739970299;12507.874921251036;12517;12568.497260109492;12643.683907902376;15607.21963901823;15792.36830526766;15832.60418489501;15850.207489625704;15857.682846342777;12480;12620.305883176514;12682.556110536456;12767.297798621164;12788.104832661997;10781.730456738644;10807.783844323227;10907.509162087566;11101.444927753419;11247.493862776359;10715.464226788785;10732.892671073188;10766.515506802056;10906.836397454203;10916.836247456453;8604.440711353742;8615.095414981588;8676.479411235094;8721.56392180401;8752.824943501222;8152.9184708154535;8171.42800003994;8184.468009579358;8192.30365418946;8262.297704695167 04:22:52 INFO - PID 20220 | |4;prototype.html;562.5921207124835;567.8949394362044;577.3129975329358;577.5321989188755;585.4847733994087;6232.750689972401;6243.250809902806;6277.748890044399;6281.623102613847;6324.209473815773;6079.756809727605;6149.692515374236;6178.351273116315;6194.318624951252;6290.402411770874;4892.877678058059;5103.591712662987;5111.386633603973;5130.563902068328;5148;4968.1554135797005;5011.949880501203;5013.593475131939;5052.292679024939;5067.467915868836;4951.331570238026;4969.155243608577;4994.350734404522;4996.675216110959;4998.77505512251;4974.303597496317;4999.525045120708;5002.499750025005;5019.749012549394;5025.7235852028125;4543.40935678363;4574.673344730024;4590.472095708969;4601.12578610063;4621.884452888671;6173.59871608344;6181.9690901545655;6201.906971395432;6232.345603711636;6259.7496100156395;5774.451427114417;5814.563907706896;5867.11993201019;5900.822975310747;5916.674582897939;6674.06563081169;6676.13210282665;6685.565438246498;6691.765788197438;6696.19645642526;4789.305550695164;5099.413567439718;5100.872478188038;5105.846824595268;5118.10433174193;6658.4673226141795;6752.189737231514;6917.377436030731;6953.826154346131;6993.0908981832035;5172.353455818023;5229.398619158846;5239.711815850089;5270.736463176826;5272.2882410874045;5296.443873393238;5300.867478313073;5330.7068111253475;5331.520163185254;5350.545203657733;5831.912521312183;5850.882982340329;5881.264841894763;5893.675847828411;5910.261217347832;6759.526833121733;6763.323667633197;6776.593404395752;6783.898241526381;6804.183497980274;1806.674798536276;1824.8175182481912;1832.1755210155484;1834.7889992651017;1854.174892172997;1220.2312543097792;1277.6039427777418;1290.8838204561634;1305.5626365167775;1315.2634524666219;6024.427679370453;6090.269167699905;6140.263168419729;6156.476699480505;6227.657478838707;5959.553033522503;6560.8359791005605;6654.800355989231;6675.064231372945;6760.628165450948;6118;6123.142760013601;6128;6184.134221209034;6188.81433556985;3543.185067434453;3557.679808817219;3566.625504322061;3574.946375804365;3598.9640103599154;6923.307669233036;6926;6971.163460384786;7012.193597736224;7043.612601306977;6726.159230096238;6813.454923606099;6836.666849964209;6955.234924158339;6962.721491140298;6804.01964850558;7027.964860175666;7053.294670532906;7087.979925721144;7102.325279098477;6677.465802735673;6801.863962720817;6818.113645226287;6841.692123854339;6842.55523390978;6772.288909664414;6773.830654233487;6811.386975172259;6827.692753825991;6861.794146175623;6835.418989385759;7007.124109486314;7060.117485314336;7084.893726594209;7085.716571337089;5114.846554603368;5128.102582048052;5164.509371609691;5166.689998600096;5170.896582068262;6579.934200657932;6669.7332106715185;6722.6638668065425;6757.594544327356;6779.491538134561;6312.431881130537;6421.743130274736;6525.2169739631545;6544.6727663617585;6609.339066093352;6495.253045899782;6521.902171467337;6569.967150164219;6578.868422631616;6590.340965903563;6398.808035758934;6435.195600549931;6535.836604084746;6547.770828020996;6603.20761508622;6522.380373864476;6661.3338666132995;6674.766383176566;6710.865782684221;6718.764843230463;6482.546221764431;6492.870142597216;6509.414152726277;6510;6574.605523668595 04:22:52 INFO - PID 20220 | |5;yui.html;963.0898800633399;972.6255391674206;991.5736233419631;997.0478193325366;1009.9343542669726;19276.132574034167;19323.903380483112;19455.902720486396;19538.90230548849;19543.706844397344;16786.664266714688;16790.244439000242;17032.318707251725;17037;17187.312507499715;1037.8287582548871;1056.3820165203365;1058.2169194795833;1060.7825395793843;1062.1131355318319;1049.4385503755493;1053.978920421595;1054.051353781595;1054.7679510507714;1055.5144633468615;1039.516624769482;1086.0931122512711;1088.6298658456124;1091.2088735666657;1104.6023431564631;1078.191356482638;1079.967600971968;1082.7239054041247;1083.3391631105019;1083.9891601084046;762.7330434347989;789.9960500197519;792;793.6190628498354;797.8364435290752;18463.35378261768;18466;18488.53778655531;18491.722624160648;18698.532536686693;23119.07523699067;23146;23840.64239036416;23881.880590596935;23968.880155599283;17112.229949652294;17215.913920430445;17530.47408577732;17535.298588056463;17662.293508259656;4064.10589670275;4094.447249621293;4098.221337945781;4100.138970816132;4102.138550904314;126004;165414.3822962549;167397;168821.15589422098;168928;17760.644787104186;18380.08099595015;18445.90777046106;18754.24983000692;18871.811281887007;32754.344913102083;33179.17052073718;33270;33404.832975834965;33929.32141357159;9492.667756628485;9503.477308747946;9508.524573771283;9542.713718588453;9544.284178686627;4288.678349123828;4289.957100429018;4299.9570004300185;4332.718373305725;4381.2332841753205;6090.086487026982;6169.907451388232;6201.441870231701;6202.596831205957;6220.595661282093;1197.706561892342;1220.2495465288785;1222.8471441069867;1226.4297101847683;1237.016571825401;753.7964749517599;766.1801871997021;767.6545554500497;776.1578687124534;781.4607920534927;13571.1857288563;13581.796273055714;13619.523316683868;14223.28883555818;14259.42962281497;735.3528894572848;735.4484136897327;739.1167554772103;743.3458556470272;753.4951582439778;6473.611583305017;6496.740130394825;6591.901121483182;6612.801615951529;6678.766243181465;146348;148578;149538;152923.23538382238;154045;99617.50191249143;102900.48549757354;122317;130711.34644326718;137109;108363;109481;110370.44814775875;112983;113004.43497782623;13744;13805.811764353042;14018.22899740524;14143.292835358397;14748.188849613374;53232.467675322754;55553;55841;56120.438795613154;56640.10194481659;72207.63896180486;72567.63716181385;73028.63485682537;74300.88548671667;75153.24846751463;19663.213471460982;20183.192672292942;20278.695819562425;20438.795612044283;20636.17455301771;21970.34088977333;22140.557188856455;22304.553908921407;22324.77675223227;22344.77655223427;20891.164353426302;21148.788512114683;21156.47108822292;21319.25382611601;21425.571488570455;20108.899455503215;20257.797422026182;20288.492787680425;20435.182592696125;20528.794712053284;19770.90114549418;20057.197712091936;20133.09401076926;20200.49498762543;20223.29218477346;14492.710145796815;14569.562913112624;14598.343074561883;14608.123512589278;14697.265136742906;15630.843691563394;15731.528054158394;15908.44320448825;15931;15933.36266549325 04:22:52 INFO - PID 20220 | __end_tp_report 04:22:52 INFO - PID 20220 | __start_cc_report 04:22:52 INFO - PID 20220 | _x_x_mozilla_cycle_collect,265 04:22:52 INFO - PID 20220 | __end_cc_report 04:22:52 INFO - PID 20220 | __startTimestamp1506165772871__endTimestamp 04:22:52 INFO - PID 20220 | 04:22:52 INFO - PID 20220 | ------- Summary: start ------- 04:22:52 INFO - PID 20220 | Number of tests: 6 04:22:52 INFO - PID 20220 | 04:22:52 INFO - PID 20220 | [#0] dojo.html Cycles:165 Average:6345.79 Median:6626.57 stddev:763.02 (11.5%) stddev-sans-first:740.49 04:22:52 INFO - PID 20220 | Values: 3883.2 3893.1 3893.4 3930.1 3938.9 6187.9 6252.2 6316.2 6337.2 6338.2 6221.9 6247.8 6320.8 6471.7 6493.9 5659.3 5686.5 5731.7 5748.6 5788.0 5382.0 5557.1 5609.9 5632.9 5676.4 5673.0 5683.8 5746.2 5766.1 5774.1 5555.9 5704.0 5739.5 5758.3 5767.5 5687.6 5695.5 5711.3 5720.6 5742.6 6298.3 6305.8 6364.6 6385.8 6407.7 6873.2 6885.9 6889.8 6966.1 6971.1 6856.3 6930.6 6936.8 6940.9 6942.9 6323.7 6379.4 6387.6 6447.7 6473.9 7007.5 7102.8 7191.1 7198.5 7210.1 6807.9 6850.8 6883.8 6924.3 6932.1 6835.3 6850.6 6891.9 6905.3 6912.6 6585.9 6821.6 6872.8 6919.4 6967.2 6842.5 6879.1 6941.4 6957.8 6978.9 5004.9 5022.0 5080.1 5096.1 5111.6 4498.7 4539.6 4565.1 4580.7 4584.5 6380.6 6406.4 6425.2 6426.6 6429.0 6844.0 6852.9 6862.1 6868.8 6941.2 6247.3 6317.5 6387.5 6394.6 6415.5 5558.9 5565.2 5617.1 5618.7 5623.5 7041.8 7129.5 7135.8 7146.7 7245.3 6830.1 6852.5 6869.4 6875.2 6879.7 7130.4 7134.1 7154.6 7160.5 7163.1 6810.6 6823.8 6826.6 6832.9 6920.0 6877.6 6917.2 6983.7 7005.7 7011.3 6665.5 6698.9 6783.9 6784.4 6787.0 6520.1 6636.2 6664.4 6673.8 6761.4 6608.4 6609.7 6628.0 6628.5 6631.7 6715.3 6740.2 6770.8 6778.3 6831.7 6531.3 6546.8 6604.1 6626.6 6639.7 04:22:52 INFO - PID 20220 | 04:22:52 INFO - PID 20220 | [#1] ext.html Cycles:180 Average:42867.02 Median:39290.71 stddev:62009.05 (157.8%) stddev-sans-first:62102.97 04:22:52 INFO - PID 20220 | Values: 911.4 976.5 985.0 1000.7 1007.7 45463.8 45955.8 46210.0 46335.0 46388.5 42447.6 43080.4 43173.6 43270.4 43366.3 7765.0 7807.0 7878.9 7900.7 7916.7 7710.7 7732.7 7777.3 7785.3 7807.2 8298.9 8488.3 8495.6 8497.9 8503.6 8382.3 8390.7 8431.1 8445.4 8458.5 8248.1 8468.7 8472.2 8473.1 8474.0 40665.2 41085.8 41085.8 41093.4 41126.0 3691.9 3694.7 3713.0 3714.7 3723.9 32873.7 33120.2 33379.0 33469.5 33489.8 5485.3 5510.1 5553.7 5574.7 5649.7 215555.9 216550.0 217197.0 217206.0 218013.0 47587.5 53903.5 54680.0 54727.7 54798.7 56354.0 56505.2 56675.7 56703.7 56985.0 15226.2 15299.3 15328.6 15373.0 15534.3 12313.3 12345.8 12382.9 12389.0 12393.3 15488.6 15576.5 15582.0 15678.9 15787.4 6007.3 6147.6 6194.0 6270.4 6271.6 1106.6 1111.8 1113.1 1117.8 1124.1 25885.5 26052.5 26099.5 26396.5 26495.5 1202.3 1212.4 1217.9 1226.0 1229.9 13607.1 13800.9 13854.2 13983.7 14100.2 269169.0 270980.0 271264.0 271608.0 272183.0 59598.4 59767.1 59815.1 59844.4 59942.7 1468.4 1526.9 1529.6 1545.3 1547.1 25494.4 26080.7 26134.0 26155.5 26322.6 42318.4 42346.0 42390.8 42432.0 42490.4 221500.0 224867.0 225553.0 226155.0 227101.0 36923.8 40661.6 43216.4 43296.1 43424.8 51169.5 52103.7 52411.0 52488.0 52786.2 38725.8 38730.0 38989.0 39278.0 39329.4 38167.2 38193.8 38481.2 38916.8 39303.4 44999.0 45848.5 45981.3 46489.0 46545.0 37438.4 37755.4 38006.8 38139.4 38209.0 28252.7 29005.3 29059.7 29353.0 29402.9 04:22:52 INFO - PID 20220 | 04:22:52 INFO - PID 20220 | [#2] jquery.html Cycles:180 Average:39721.19 Median:12683.64 stddev:100613.14 (793.3%) stddev-sans-first:100852.53 04:22:52 INFO - PID 20220 | Values: 609.8 619.1 626.8 633.5 636.0 23063.2 23576.3 24478.9 24509.0 24548.8 21855.8 23147.7 23149.2 23149.9 23165.8 6658.4 6830.3 6835.5 6846.9 6849.5 6851.3 6901.0 6902.1 6903.0 6903.7 6847.0 6852.5 6852.8 6858.4 6860.6 6791.9 6818.8 6846.9 6870.2 6873.1 6379.3 6400.1 6403.9 6413.9 6427.3 29244.3 30532.8 30557.8 30717.0 30781.0 2272.7 2290.0 2296.0 2300.2 2304.1 20115.3 20854.2 20897.2 20979.8 21027.3 2433.8 2511.7 2544.7 2558.7 2569.7 137542.3 144164.0 158317.0 163872.0 164693.0 15857.7 16202.7 16430.0 16520.4 16530.2 25489.1 25491.1 25561.7 25574.7 25630.4 9938.7 9957.2 10153.5 10184.9 10199.1 8640.4 8738.7 8973.8 9022.8 9031.7 9580.8 9759.1 9966.0 10019.2 10109.7 2978.5 2980.0 3009.9 3020.2 3076.4 1045.2 1045.3 1054.0 1070.8 1074.5 21342.7 21366.7 21390.5 21821.7 21968.2 1095.3 1102.2 1105.6 1114.2 1121.2 11279.7 11861.9 11868.6 11878.3 12143.8 581679.0 595499.0 598670.0 607047.0 615927.0 130837.0 131008.0 131756.3 136108.0 136279.0 1227.8 1229.4 1261.6 1266.5 1271.1 19111.2 19265.3 19316.7 19389.5 19496.0 61079.4 61811.4 62112.0 62491.0 64606.0 79667.0 79685.0 80439.6 80957.6 81950.0 15636.0 15750.4 15791.1 15796.4 15920.7 23961.3 24080.0 24104.3 24374.0 24391.3 19231.0 19749.9 19844.8 19892.7 19926.5 17441.0 17533.4 17924.9 18178.9 18283.3 20080.2 20169.9 20540.3 20580.8 20742.5 15489.3 15521.8 15662.5 15677.8 15738.1 19257.9 19586.8 19595.8 19688.7 19722.5 04:22:52 INFO - PID 20220 | 04:22:52 INFO - PID 20220 | [#3] mootools.html Cycles:180 Average:10451.53 Median:2847.63 stddev:7802.75 (274.0%) stddev-sans-first:7795.31 04:22:52 INFO - PID 20220 | Values: 1446.9 1508.3 1512.1 1516.9 1528.1 16799.0 17455.6 17525.8 17526.8 17581.4 17185.1 17230.5 17391.7 17399.0 17434.4 2893.5 2894.8 2948.6 2951.4 2953.1 2868.8 2870.0 2898.9 2901.4 2905.5 2659.2 2827.5 2840.1 2840.9 2843.0 2894.3 2913.5 2922.2 2929.7 2950.9 2841.6 2852.3 2862.3 2867.8 2877.5 16573.5 16910.7 16929.7 16979.2 16984.5 8275.3 8500.2 8551.1 8554.7 8574.1 5558.5 5592.7 5706.3 5729.5 5801.4 1980.7 1982.8 2024.6 2058.1 2064.2 31050.5 31050.8 31214.0 31221.4 31256.4 6685.6 6959.2 7030.2 7091.3 7266.5 18757.3 19954.7 19955.0 19990.5 20030.2 7865.9 8094.6 8108.3 8138.6 8154.3 7355.2 7386.1 7447.2 7454.0 7461.7 5753.5 5827.4 5879.6 5980.0 5983.6 1734.2 1748.5 1770.7 1771.0 1773.6 949.3 967.0 970.7 972.8 973.1 8409.6 8424.8 8426.6 8485.6 8599.0 797.1 799.9 805.9 806.9 808.8 5297.5 5902.3 5998.5 6131.1 6283.7 24461.9 24503.3 24772.8 25508.7 25561.2 24133.5 24830.3 24834.9 24928.4 24928.5 12809.2 12816.1 13101.7 13121.4 13132.9 9170.0 9193.4 9195.2 9232.2 9248.3 21367.1 21541.1 21566.9 21747.6 22078.8 22013.9 22077.9 22152.1 22207.4 22429.4 12481.5 12507.9 12517.0 12568.5 12643.7 15607.2 15792.4 15832.6 15850.2 15857.7 12480.0 12620.3 12682.6 12767.3 12788.1 10781.7 10807.8 10907.5 11101.4 11247.5 10715.5 10732.9 10766.5 10906.8 10916.8 8604.4 8615.1 8676.5 8721.6 8752.8 8152.9 8171.4 8184.5 8192.3 8262.3 04:22:52 INFO - PID 20220 | 04:22:52 INFO - PID 20220 | [#4] prototype.html Cycles:180 Average:5640.66 Median:6198.11 stddev:1571.37 (25.4%) stddev-sans-first:1528.85 04:22:52 INFO - PID 20220 | Values: 562.6 567.9 577.3 577.5 585.5 6232.8 6243.3 6277.7 6281.6 6324.2 6079.8 6149.7 6178.4 6194.3 6290.4 4892.9 5103.6 5111.4 5130.6 5148.0 4968.2 5011.9 5013.6 5052.3 5067.5 4951.3 4969.2 4994.4 4996.7 4998.8 4974.3 4999.5 5002.5 5019.7 5025.7 4543.4 4574.7 4590.5 4601.1 4621.9 6173.6 6182.0 6201.9 6232.3 6259.7 5774.5 5814.6 5867.1 5900.8 5916.7 6674.1 6676.1 6685.6 6691.8 6696.2 4789.3 5099.4 5100.9 5105.8 5118.1 6658.5 6752.2 6917.4 6953.8 6993.1 5172.4 5229.4 5239.7 5270.7 5272.3 5296.4 5300.9 5330.7 5331.5 5350.5 5831.9 5850.9 5881.3 5893.7 5910.3 6759.5 6763.3 6776.6 6783.9 6804.2 1806.7 1824.8 1832.2 1834.8 1854.2 1220.2 1277.6 1290.9 1305.6 1315.3 6024.4 6090.3 6140.3 6156.5 6227.7 5959.6 6560.8 6654.8 6675.1 6760.6 6118.0 6123.1 6128.0 6184.1 6188.8 3543.2 3557.7 3566.6 3574.9 3599.0 6923.3 6926.0 6971.2 7012.2 7043.6 6726.2 6813.5 6836.7 6955.2 6962.7 6804.0 7028.0 7053.3 7088.0 7102.3 6677.5 6801.9 6818.1 6841.7 6842.6 6772.3 6773.8 6811.4 6827.7 6861.8 6835.4 7007.1 7060.1 7084.9 7085.7 5114.8 5128.1 5164.5 5166.7 5170.9 6579.9 6669.7 6722.7 6757.6 6779.5 6312.4 6421.7 6525.2 6544.7 6609.3 6495.3 6521.9 6570.0 6578.9 6590.3 6398.8 6435.2 6535.8 6547.8 6603.2 6522.4 6661.3 6674.8 6710.9 6718.8 6482.5 6492.9 6509.4 6510.0 6574.6 04:22:52 INFO - PID 20220 | 04:22:52 INFO - PID 20220 | [#5] yui.html Cycles:180 Average:28261.99 Median:19717.06 stddev:41309.58 (209.5%) stddev-sans-first:41374.61 04:22:52 INFO - PID 20220 | Values: 963.1 972.6 991.6 997.0 1009.9 19276.1 19323.9 19455.9 19538.9 19543.7 16786.7 16790.2 17032.3 17037.0 17187.3 1037.8 1056.4 1058.2 1060.8 1062.1 1049.4 1054.0 1054.1 1054.8 1055.5 1039.5 1086.1 1088.6 1091.2 1104.6 1078.2 1080.0 1082.7 1083.3 1084.0 762.7 790.0 792.0 793.6 797.8 18463.4 18466.0 18488.5 18491.7 18698.5 23119.1 23146.0 23840.6 23881.9 23968.9 17112.2 17215.9 17530.5 17535.3 17662.3 4064.1 4094.4 4098.2 4100.1 4102.1 126004.0 165414.4 167397.0 168821.2 168928.0 17760.6 18380.1 18445.9 18754.2 18871.8 32754.3 33179.2 33270.0 33404.8 33929.3 9492.7 9503.5 9508.5 9542.7 9544.3 4288.7 4290.0 4300.0 4332.7 4381.2 6090.1 6169.9 6201.4 6202.6 6220.6 1197.7 1220.2 1222.8 1226.4 1237.0 753.8 766.2 767.7 776.2 781.5 13571.2 13581.8 13619.5 14223.3 14259.4 735.4 735.4 739.1 743.3 753.5 6473.6 6496.7 6591.9 6612.8 6678.8 146348.0 148578.0 149538.0 152923.2 154045.0 99617.5 102900.5 122317.0 130711.3 137109.0 108363.0 109481.0 110370.4 112983.0 113004.4 13744.0 13805.8 14018.2 14143.3 14748.2 53232.5 55553.0 55841.0 56120.4 56640.1 72207.6 72567.6 73028.6 74300.9 75153.2 19663.2 20183.2 20278.7 20438.8 20636.2 21970.3 22140.6 22304.6 22324.8 22344.8 20891.2 21148.8 21156.5 21319.3 21425.6 20108.9 20257.8 20288.5 20435.2 20528.8 19770.9 20057.2 20133.1 20200.5 20223.3 14492.7 14569.6 14598.3 14608.1 14697.3 15630.8 15731.5 15908.4 15931.0 15933.4 04:22:52 INFO - PID 20220 | -------- Summary: end -------- 04:22:52 INFO - PID 20220 | 04:22:53 INFO - TEST-INFO | 20220: exit 0 04:22:53 INFO - TEST-OK | dromaeo_css | took 1109268ms 04:22:53 INFO - TEST-START | kraken 04:22:53 INFO - Initialising browser for kraken test... 04:22:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48519/getInfo.html -profile /tmp/tmpK99j0f/profile 04:22:53 INFO - TEST-INFO | started process 20592 (/builds/slave/test/build/application/firefox/firefox http://localhost:48519/getInfo.html) 04:22:59 INFO - TEST-INFO | 20592: exit 0 04:22:59 INFO - Browser initialized. 04:22:59 INFO - Running cycle 1/1 for kraken test... 04:22:59 INFO - Using env: {'DISPLAY': ':0', 04:22:59 INFO - 'HOME': '/home/cltbld', 04:22:59 INFO - 'JSGC_DISABLE_POISONING': '1', 04:22:59 INFO - 'LANG': 'en_US.UTF-8', 04:22:59 INFO - 'LANGUAGE': 'en_US:en', 04:22:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:22:59 INFO - 'LOGNAME': 'cltbld', 04:22:59 INFO - 'MAIL': '/var/mail/cltbld', 04:22:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:22:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:22:59 INFO - 'MOZ_CRASHREPORTER': '1', 04:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:22:59 INFO - 'MOZ_NO_REMOTE': '1', 04:22:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:22:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:59 INFO - 'NO_EM_RESTART': '1', 04:22:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:59 INFO - 'PWD': '/builds/slave/test', 04:22:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:22:59 INFO - 'RUST_BACKTRACE': 'full', 04:22:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:22:59 INFO - 'SHELL': '/bin/bash', 04:22:59 INFO - 'SHLVL': '1', 04:22:59 INFO - 'TERM': 'linux', 04:22:59 INFO - 'TMOUT': '86400', 04:22:59 INFO - 'USER': 'cltbld', 04:22:59 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253', 04:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:59 INFO - '_': '/tools/buildbot/bin/python'} 04:22:59 INFO - TEST-INFO | started process 20828 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK99j0f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:23:00 INFO - PID 20828 | 04:23:00 INFO - PID 20828 | (/builds/slave/test/build/application/firefox/firefox:20878): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:23:00 INFO - PID 20828 | 04:23:10 INFO - PID 20828 | RSS: Main: 140673024 04:23:10 INFO - PID 20828 | 04:23:13 INFO - PID 20828 | Cycle 1(1): loaded http://localhost:48519/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48519/tests/kraken/driver.html?testName=audio-beat-detection) 04:23:13 INFO - PID 20828 | RSS: Main: 147304448 04:23:13 INFO - PID 20828 | 04:23:16 INFO - PID 20828 | Cycle 1(1): loaded http://localhost:48519/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48519/tests/kraken/driver.html?testName=audio-dft) 04:23:17 INFO - PID 20828 | RSS: Main: 147427328 04:23:17 INFO - PID 20828 | 04:23:19 INFO - PID 20828 | Cycle 1(1): loaded http://localhost:48519/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48519/tests/kraken/driver.html?testName=audio-fft) 04:23:20 INFO - PID 20828 | RSS: Main: 144031744 04:23:20 INFO - PID 20828 | 04:23:22 INFO - PID 20828 | Cycle 1(1): loaded http://localhost:48519/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48519/tests/kraken/driver.html?testName=audio-oscillator) 04:23:23 INFO - PID 20828 | RSS: Main: 145317888 04:23:23 INFO - PID 20828 | 04:23:25 INFO - PID 20828 | Cycle 1(1): loaded http://localhost:48519/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48519/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:23:25 INFO - PID 20828 | RSS: Main: 145219584 04:23:25 INFO - PID 20828 | 04:23:28 INFO - PID 20828 | Cycle 1(1): loaded http://localhost:48519/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48519/tests/kraken/driver.html?testName=imaging-darkroom) 04:23:29 INFO - PID 20828 | RSS: Main: 145121280 04:23:29 INFO - PID 20828 | 04:23:33 INFO - PID 20828 | Cycle 1(1): loaded http://localhost:48519/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48519/tests/kraken/driver.html?testName=imaging-desaturate) 04:23:33 INFO - PID 20828 | RSS: Main: 146284544 04:23:33 INFO - PID 20828 | 04:23:36 INFO - PID 20828 | Cycle 1(1): loaded http://localhost:48519/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48519/tests/kraken/driver.html?testName=json-parse-financial) 04:23:37 INFO - PID 20828 | RSS: Main: 148008960 04:23:37 INFO - PID 20828 | 04:23:38 INFO - PID 20828 | Cycle 1(1): loaded http://localhost:48519/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48519/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:23:39 INFO - PID 20828 | RSS: Main: 148004864 04:23:39 INFO - PID 20828 | 04:23:40 INFO - PID 20828 | Cycle 1(1): loaded http://localhost:48519/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48519/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:23:41 INFO - PID 20828 | RSS: Main: 148180992 04:23:41 INFO - PID 20828 | 04:23:43 INFO - PID 20828 | Cycle 1(1): loaded http://localhost:48519/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48519/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:23:44 INFO - PID 20828 | RSS: Main: 148201472 04:23:44 INFO - PID 20828 | 04:23:46 INFO - PID 20828 | Cycle 1(1): loaded http://localhost:48519/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48519/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:23:47 INFO - PID 20828 | RSS: Main: 148058112 04:23:47 INFO - PID 20828 | 04:23:49 INFO - PID 20828 | Cycle 1(1): loaded http://localhost:48519/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48519/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:23:50 INFO - PID 20828 | RSS: Main: 148508672 04:23:50 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | Cycle 1(1): loaded http://localhost:48519/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48519/tests/kraken/driver.html?testName=ai-astar) 04:23:52 INFO - PID 20828 | RSS: Main: 149979136 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | __start_tp_report 04:23:52 INFO - PID 20828 | _x_x_mozilla_page_load 04:23:52 INFO - PID 20828 | _x_x_mozilla_page_load_details 04:23:52 INFO - PID 20828 | |i|pagename|runs| 04:23:52 INFO - PID 20828 | |0;ai-astar;105;105;106;106;105;105;108;107;106;105 04:23:52 INFO - PID 20828 | |1;audio-beat-detection;116;115;115;113;139;114;113;114;113;113 04:23:52 INFO - PID 20828 | |2;audio-dft;163;162;163;163;164;162;162;162;165;165 04:23:52 INFO - PID 20828 | |3;audio-fft;74;73;72;73;73;74;73;73;75;73 04:23:52 INFO - PID 20828 | |4;audio-oscillator;91;78;77;77;77;77;78;77;82;78 04:23:52 INFO - PID 20828 | |5;imaging-gaussian-blur;114;113;114;113;115;114;114;113;115;114 04:23:52 INFO - PID 20828 | |6;imaging-darkroom;150;150;149;149;149;149;150;153;150;153 04:23:52 INFO - PID 20828 | |7;imaging-desaturate;94;95;94;94;94;95;95;95;95;96 04:23:52 INFO - PID 20828 | |8;json-parse-financial;45;45;46;49;45;44;44;43;44;46 04:23:52 INFO - PID 20828 | |9;json-stringify-tinderbox;37;37;37;37;37;46;38;37;38;38 04:23:52 INFO - PID 20828 | |10;stanford-crypto-aes;72;71;72;72;79;72;72;74;73;71 04:23:52 INFO - PID 20828 | |11;stanford-crypto-ccm;108;107;105;107;109;107;105;107;106;110 04:23:52 INFO - PID 20828 | |12;stanford-crypto-pbkdf2;160;149;149;163;152;160;148;154;155;156 04:23:52 INFO - PID 20828 | |13;stanford-crypto-sha256-iterative;65;66;60;60;67;59;63;59;62;62 04:23:52 INFO - PID 20828 | __end_tp_report 04:23:52 INFO - PID 20828 | __start_cc_report 04:23:52 INFO - PID 20828 | _x_x_mozilla_cycle_collect,702 04:23:52 INFO - PID 20828 | __end_cc_report 04:23:52 INFO - PID 20828 | __startTimestamp1506165832522__endTimestamp 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | ------- Summary: start ------- 04:23:52 INFO - PID 20828 | Number of tests: 14 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | [#0] ai-astar Cycles:10 Average:105.80 Median:106.00 stddev:1.03 (1.0%) stddev-sans-first:1.05 04:23:52 INFO - PID 20828 | Values: 105.0 105.0 106.0 106.0 105.0 105.0 108.0 107.0 106.0 105.0 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | [#1] audio-beat-detection Cycles:10 Average:116.50 Median:114.50 stddev:7.98 (7.0%) stddev-sans-first:8.46 04:23:52 INFO - PID 20828 | Values: 116.0 115.0 115.0 113.0 139.0 114.0 113.0 114.0 113.0 113.0 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:1.20 (0.7%) stddev-sans-first:1.27 04:23:52 INFO - PID 20828 | Values: 163.0 162.0 163.0 163.0 164.0 162.0 162.0 162.0 165.0 165.0 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | [#3] audio-fft Cycles:10 Average:73.30 Median:73.00 stddev:0.82 (1.1%) stddev-sans-first:0.83 04:23:52 INFO - PID 20828 | Values: 74.0 73.0 72.0 73.0 73.0 74.0 73.0 73.0 75.0 73.0 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | [#4] audio-oscillator Cycles:10 Average:79.20 Median:78.00 stddev:4.42 (5.7%) stddev-sans-first:1.62 04:23:52 INFO - PID 20828 | Values: 91.0 78.0 77.0 77.0 77.0 77.0 78.0 77.0 82.0 78.0 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 04:23:52 INFO - PID 20828 | Values: 114.0 113.0 114.0 113.0 115.0 114.0 114.0 113.0 115.0 114.0 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | [#6] imaging-darkroom Cycles:10 Average:150.20 Median:150.00 stddev:1.55 (1.0%) stddev-sans-first:1.64 04:23:52 INFO - PID 20828 | Values: 150.0 150.0 149.0 149.0 149.0 149.0 150.0 153.0 150.0 153.0 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 04:23:52 INFO - PID 20828 | Values: 94.0 95.0 94.0 94.0 94.0 95.0 95.0 95.0 95.0 96.0 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | [#8] json-parse-financial Cycles:10 Average:45.10 Median:45.00 stddev:1.66 (3.7%) stddev-sans-first:1.76 04:23:52 INFO - PID 20828 | Values: 45.0 45.0 46.0 49.0 45.0 44.0 44.0 43.0 44.0 46.0 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:37.50 stddev:2.78 (7.4%) stddev-sans-first:2.92 04:23:52 INFO - PID 20828 | Values: 37.0 37.0 37.0 37.0 37.0 46.0 38.0 37.0 38.0 38.0 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | [#10] stanford-crypto-aes Cycles:10 Average:72.80 Median:72.00 stddev:2.35 (3.3%) stddev-sans-first:2.47 04:23:52 INFO - PID 20828 | Values: 72.0 71.0 72.0 72.0 79.0 72.0 72.0 74.0 73.0 71.0 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | [#11] stanford-crypto-ccm Cycles:10 Average:107.10 Median:107.00 stddev:1.60 (1.5%) stddev-sans-first:1.66 04:23:52 INFO - PID 20828 | Values: 108.0 107.0 105.0 107.0 109.0 107.0 105.0 107.0 106.0 110.0 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.60 Median:155.50 stddev:5.21 (3.4%) stddev-sans-first:5.15 04:23:52 INFO - PID 20828 | Values: 160.0 149.0 149.0 163.0 152.0 160.0 148.0 154.0 155.0 156.0 04:23:52 INFO - PID 20828 | 04:23:52 INFO - PID 20828 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.50 stddev:2.91 (4.7%) stddev-sans-first:2.92 04:23:52 INFO - PID 20828 | Values: 65.0 66.0 60.0 60.0 67.0 59.0 63.0 59.0 62.0 62.0 04:23:52 INFO - PID 20828 | -------- Summary: end -------- 04:23:52 INFO - PID 20828 | 04:23:53 INFO - PID 20828 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:23:53 INFO - TEST-INFO | 20828: exit 0 04:23:53 INFO - TEST-OK | kraken | took 60045ms 04:23:53 INFO - SUITE-END | took 1169s 04:23:53 INFO - Completed test suite (00:19:29) 04:23:53 INFO - javascript benchmark 04:23:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9157.28861868209, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3883.165119499307, 3893.1045859452324, 3893.3575959966597, 3930.0764320384706, 3938.8621398251066, 6187.938120618793, 6252.249730032397, 6316.242050953886, 6337.176167098277, 6338.176037115169, 6221.875562488747, 6247.750089996394, 6320.778772742955, 6471.70877310521, 6493.902591461132, 5659.264295641581, 5686.488216060554, 5731.684757338345, 5748.5975981681295, 5788.04497257952, 5382.0, 5557.110862262019, 5609.8597535061745, 5632.887342253152, 5676.375598684142, 5672.978863804514, 5683.801066962657, 5746.1668058131545, 5766.077427611562, 5774.0761478163295, 5555.944440555623, 5704.030314846486, 5739.512141467939, 5758.280214973129, 5767.538596912279, 5687.630304030266, 5695.515881150107, 5711.343195532484, 5720.599558030939, 5742.598018138732, 6298.307186209513, 6305.842353941188, 6364.6181229126405, 6385.840353991141, 6407.711652975628, 6873.243943166248, 6885.862282754317, 6889.758858439981, 6966.059581956421, 6971.058907047534, 6856.280090590466, 6930.61881596512, 6936.79189624312, 6940.930590694079, 6942.93057069433, 6323.74705011799, 6379.36206379363, 6387.584806987531, 6447.742090316428, 6473.902891456632, 7007.509474336747, 7102.751403700846, 7191.065161529073, 7198.496105272686, 7210.098737657793, 6807.863842723217, 6850.760223392257, 6883.793486195422, 6924.272951340137, 6932.064171336906, 6835.282295359015, 6850.554713943578, 6891.931080689229, 6905.309469053054, 6912.585244885323, 6585.934140658532, 6821.590704557742, 6872.828179295558, 6919.411849992706, 6967.198772141167, 6842.486813488908, 6879.105716256855, 6941.409980151744, 6957.82605434858, 6978.860422791516, 5004.874878128003, 5022.0, 5080.060188864998, 5096.082705113041, 5111.642185047011, 4498.730076195438, 4539.568740969603, 4565.086982603426, 4580.679352445297, 4584.5186255442695, 6380.649064301508, 6406.391392817675, 6425.228972523234, 6426.550141490145, 6429.03564465334, 6843.965780171067, 6852.897206541906, 6862.142232220973, 6868.793936181923, 6941.167059952737, 6247.250329960434, 6317.4630156437215, 6387.520935929824, 6394.648294343856, 6415.518836087219, 5558.916616250759, 5565.16522521625, 5617.101263797773, 5618.690971996498, 5623.465770751772, 7041.788746337618, 7129.465290103263, 7135.82160445993, 7146.678399472036, 7245.275472452712, 6830.112085428863, 6852.486063545154, 6869.416099631388, 6875.1749790025515, 6879.690413931486, 7130.393916516947, 7134.143902731706, 7154.642267886689, 7160.534565253241, 7163.140423149255, 6810.557313774589, 6823.795286141423, 6826.590404575742, 6832.897506537505, 6920.031195632716, 6877.552959057645, 6917.239103698488, 6983.650817459208, 7005.684744186626, 7011.3339232772805, 6665.50008749336, 6698.866022679616, 6783.86432271342, 6784.38940495338, 6786.966065169642, 6520.119783829123, 6636.2368327642935, 6664.366885145903, 6673.833154171185, 6761.425278851156, 6608.405243527914, 6609.669516524251, 6628.0, 6628.53600247978, 6631.701573429304, 6715.328467153246, 6740.224874139537, 6770.796876093725, 6778.3221677831825, 6831.658417079226, 6531.281559028408, 6546.770863019771, 6604.14146160996, 6626.56927299724, 6639.70121344531], "value": 6293.3101417613, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [911.3848152497063, 976.4775844922965, 984.9556769945352, 1000.6897861662885, 1007.6926537406091, 45463.77268113659, 45955.77022114893, 46210.0, 46335.0, 46388.536114638846, 42447.57552424475, 43080.3537946931, 43173.56826431735, 43270.35094473585, 43366.349504757454, 7765.0, 7807.0, 7878.921210787905, 7900.683972641087, 7916.6833326666865, 7710.730124445646, 7732.729354472594, 7777.261160189773, 7785.338246249075, 7807.219278072176, 8298.875516867252, 8488.32093432527, 8495.575221238943, 8497.915020849805, 8503.574821258942, 8382.329413646894, 8390.706325278647, 8431.072582015973, 8445.36659750518, 8458.450200736932, 8248.13394593565, 8468.66125354985, 8472.19514146155, 8473.067962524117, 8473.95763021181, 40665.1866962662, 41085.794571026956, 41085.794571026956, 41093.38359924604, 41126.0, 3691.8892433227047, 3694.685951694082, 3713.0, 3714.7028237740915, 3723.9069023274365, 32873.67126328754, 33120.17199570006, 33379.0, 33469.49795753066, 33489.83255083733, 5485.314335708036, 5510.090835012218, 5553.666779993173, 5574.665520068809, 5649.745761440704, 215555.92222038948, 216550.0, 217197.0, 217206.0, 218013.0, 47587.52412475831, 53903.460965389844, 54680.0, 54727.72636136794, 54798.72600637051, 56354.0, 56505.15242271369, 56675.71662141745, 56703.71648141733, 56985.0, 15226.162561059027, 15299.311530981133, 15328.616784580474, 15373.0, 15534.300956456986, 12313.322767247888, 12345.753084938251, 12382.876171238353, 12389.0, 12393.318367489694, 15488.606025457762, 15576.454824081104, 15582.0, 15678.921605392128, 15787.368505259661, 6007.27912650476, 6147.631142131397, 6194.0, 6270.404311590391, 6271.592346497463, 1106.6292791914639, 1111.8109921313396, 1113.1261959361861, 1117.80997230471, 1124.1175677093443, 25885.482290354463, 26052.478950420886, 26099.478010439685, 26396.47207055848, 26495.470090598465, 1202.2546021466746, 1212.4362171590253, 1217.9086568507398, 1225.9763097813425, 1229.8585662648732, 13607.115537490032, 13800.93099534496, 13854.16874987504, 13983.650408739659, 14100.153990760587, 269169.0, 270980.0, 271264.0, 271608.0, 272183.0, 59598.40401596015, 59767.103493447634, 59815.10277345756, 59844.40155598388, 59942.700286498286, 1468.3539242733164, 1526.9236538173047, 1529.6175956010998, 1545.3355057325457, 1547.0562956596468, 25494.362640933385, 26080.73919260783, 26134.0, 26155.476890462465, 26322.60516092222, 42318.36522452104, 42346.0, 42390.78804605957, 42432.0, 42490.36264455974, 221500.0, 224867.0, 225553.0, 226155.0, 227101.0, 36923.815380922926, 40661.59338406696, 43216.35175472307, 43296.13407731891, 43424.78287608542, 51169.48830511647, 52103.73948130235, 52411.0, 52488.0, 52786.208206877694, 38725.80637096796, 38730.0, 38989.02527436837, 39278.0, 39329.41005884971, 38167.2366552673, 38193.80903095467, 38481.23037539289, 38916.80541597274, 39303.41044884272, 44999.0, 45848.54151458576, 45981.310280345155, 46489.0, 46545.0, 37438.43842342422, 37755.43366849554, 38006.8099659511, 38139.42790858195, 38209.0, 28252.717472825832, 29005.27486812762, 29059.7094029057, 29353.0, 29402.852985734935], "value": 19050.29894112067, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.841023498279, 619.1424876545982, 626.8056902360272, 633.4615576759754, 635.9586626869254, 23063.192788252414, 23576.29271121867, 24478.877605611946, 24509.01963921443, 24548.75451245487, 21855.781442185573, 23147.652785208193, 23149.189778357762, 23149.884250578725, 23165.768342316573, 6658.3674550917585, 6830.316968303167, 6835.487338449611, 6846.931530684679, 6849.452043836506, 6851.349121833443, 6900.965495172542, 6902.068220790205, 6902.965485172567, 6903.654817259142, 6846.965765171167, 6852.5203235773515, 6852.760153394632, 6858.417034552069, 6860.55406398585, 6791.932080679204, 6818.761343352959, 6846.8972965405555, 6870.20992585854, 6873.140857392826, 6379.266384365811, 6400.103985442041, 6403.9359606403805, 6413.903791443132, 6427.325130861286, 29244.268893277625, 30532.847335763403, 30557.847210764026, 30717.0, 30781.0, 2272.749997500274, 2290.0, 2296.0471404367167, 2300.183434880627, 2304.089884495615, 20115.29596464117, 20854.165833366646, 20897.164113435443, 20979.790202097935, 21027.26404575848, 2433.8052955763496, 2511.6860392450944, 2544.694636643596, 2558.731333210005, 2569.691637003553, 137542.31228843893, 144164.0, 158317.0, 163872.0, 164693.0, 15857.68767462913, 16202.675946481004, 16430.0, 16520.42178523746, 16530.20624019021, 25489.107881224067, 25491.107811226888, 25561.744382555935, 25574.744252557237, 25630.359241018752, 9938.652147174958, 9957.153641940371, 10153.543090560943, 10184.861179152462, 10199.13307368882, 8640.351973602004, 8738.650453981896, 8973.820523589491, 9022.774430639156, 9031.683891063913, 9580.808383832284, 9759.096976089595, 9966.006798640301, 10019.248556358303, 10109.696709098738, 2978.4936560784718, 2979.9851000744857, 3009.9097027089224, 3020.2298413904314, 3076.35396566719, 1045.163868904873, 1045.278757657214, 1053.9578416863394, 1070.82866741321, 1074.462768615692, 21342.679859802116, 21366.679499807517, 21390.465238368855, 21821.67267490989, 21968.231111911005, 1095.3318475729798, 1102.1513434655271, 1105.5798796457295, 1114.1532435349193, 1121.2375584602548, 11279.718007049725, 11861.940690296666, 11868.643940681794, 11878.346690932081, 12143.757124857453, 581679.0, 595499.0, 598670.0, 607047.0, 615927.0, 130837.0, 131008.0, 131756.3412182933, 136107.99999999802, 136279.0, 1227.7851376009162, 1229.4467489629703, 1261.5899832554458, 1266.468083404972, 1271.0721173543366, 19111.23555057782, 19265.325713600516, 19316.71024934599, 19389.51526211856, 19496.0, 61079.38920610915, 61811.38188618056, 62112.0, 62491.0, 64606.0, 79667.0, 79685.0, 80439.59780201061, 80957.59521202356, 81950.0, 15636.0, 15750.369985200463, 15791.052536847827, 15796.447124350592, 15920.681586368439, 23961.28116156518, 24080.036798527864, 24104.276871693877, 24374.0, 24391.26826195217, 19231.0, 19749.901250493655, 19844.801551984296, 19892.70160947616, 19926.50183745418, 17441.0, 17533.386331478338, 17924.91037544804, 18178.90910545439, 18283.26866925308, 20080.196792128736, 20169.899150504152, 20540.281090161774, 20580.79419205789, 20742.481437964172, 15489.30298136609, 15521.76717349218, 15662.451814186448, 15677.764833527279, 15738.134402607508, 19257.903710481918, 19586.804131959067, 19595.804041959396, 19688.7202331848, 19722.506937326107], "value": 12807.313967940196, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1446.855314468553, 1508.313717258648, 1512.1305249481552, 1516.9014014089084, 1528.106057956096, 16798.999999999985, 17455.563610909732, 17525.824741752578, 17526.824731752662, 17581.3846515372, 17185.140742962834, 17230.483085507425, 17391.652166956686, 17399.0, 17434.38979635713, 2893.5370340745485, 2894.7539459145996, 2948.557716342544, 2951.4097180563867, 2953.0550223928353, 2868.813527120741, 2869.985650071747, 2898.884044638212, 2901.4052119315597, 2905.5351143816993, 2659.175655546777, 2827.533456979596, 2840.1195629354966, 2840.872160752771, 2842.957355639667, 2894.3198348388114, 2913.475574396608, 2922.240217543454, 2929.7216764407453, 2950.911472655813, 2841.559558268472, 2852.3011862093713, 2862.327353072026, 2867.7705783537476, 2877.453283876057, 16573.50279491617, 16910.74633880493, 16929.746053809202, 16979.235934382858, 16984.49046528606, 8275.337972962148, 8500.234978851871, 8551.059383467813, 8554.657813687445, 8574.142585741438, 5558.527525160366, 5592.664440133564, 5706.258186435737, 5729.541636669056, 5801.448862358069, 1980.6830907054803, 1982.7918068602733, 2024.6456870047682, 2058.1355830551206, 2064.1846470644014, 31050.53424198639, 31050.844745776354, 31214.0, 31221.37557248842, 31256.374872502423, 6685.5654382465955, 6959.234484206733, 7030.156381234285, 7091.25541818112, 7266.45501587383, 18757.34349297768, 19954.70067948982, 19955.0, 19990.50023749418, 20030.198792048446, 7865.921340786634, 8094.635741391651, 8108.270255677017, 8138.633761480747, 8154.266116049584, 7355.2277010913085, 7386.113666360071, 7447.218042105609, 7454.0, 7461.664225109883, 5753.482186603226, 5827.446392592782, 5879.617824841372, 5979.970100149471, 5983.551233657493, 1734.167599552222, 1748.4841971618403, 1770.725537541683, 1770.9911450442664, 1773.6186719855293, 949.3402085550614, 967.0281367225892, 970.7330484116783, 972.7908499672604, 973.1436336024253, 8409.621567029499, 8424.831503369898, 8426.578671066422, 8485.575721213914, 8599.0, 797.1072398913254, 799.9200079991954, 805.8912046873597, 806.9354451643854, 808.8301456694043, 5297.470252974672, 5902.321233058167, 5998.460138587461, 6131.080337949344, 6283.6858157091965, 24461.87769061179, 24503.264902052968, 24772.752272477046, 25508.744912551007, 25561.23316300514, 24133.51732965331, 24830.25509234726, 24834.875825620755, 24928.37679058038, 24928.5014299713, 12809.231446113263, 12816.102872798814, 13101.672458188621, 13121.409536570687, 13132.868671313356, 9169.954150229207, 9193.448393096436, 9195.218406435528, 9232.21526170283, 9248.306377021616, 21367.14531418788, 21541.138354465646, 21566.892165539073, 21747.565048699253, 22078.77921220767, 22013.889930550245, 22077.889610551843, 22152.113915443202, 22207.44481387978, 22429.43926401853, 12481.500739970299, 12507.874921251036, 12517.0, 12568.497260109492, 12643.683907902376, 15607.21963901823, 15792.36830526766, 15832.60418489501, 15850.207489625704, 15857.682846342777, 12480.0, 12620.305883176514, 12682.556110536456, 12767.297798621164, 12788.104832661997, 10781.730456738644, 10807.783844323227, 10907.509162087566, 11101.444927753419, 11247.493862776359, 10715.464226788785, 10732.892671073188, 10766.515506802056, 10906.836397454203, 10916.836247456453, 8604.440711353742, 8615.095414981588, 8676.479411235094, 8721.56392180401, 8752.824943501222, 8152.9184708154535, 8171.42800003994, 8184.468009579358, 8192.30365418946, 8262.297704695167], "value": 7292.158057619476, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.5921207124835, 567.8949394362044, 577.3129975329358, 577.5321989188755, 585.4847733994087, 6232.750689972401, 6243.250809902806, 6277.748890044399, 6281.623102613847, 6324.209473815773, 6079.756809727605, 6149.692515374236, 6178.351273116315, 6194.318624951252, 6290.402411770874, 4892.877678058059, 5103.591712662987, 5111.386633603973, 5130.563902068328, 5148.0, 4968.1554135797005, 5011.949880501203, 5013.593475131939, 5052.292679024939, 5067.467915868836, 4951.331570238026, 4969.155243608577, 4994.350734404522, 4996.675216110959, 4998.77505512251, 4974.303597496317, 4999.525045120708, 5002.499750025005, 5019.749012549394, 5025.7235852028125, 4543.40935678363, 4574.673344730024, 4590.472095708969, 4601.12578610063, 4621.884452888671, 6173.59871608344, 6181.9690901545655, 6201.906971395432, 6232.345603711636, 6259.7496100156395, 5774.451427114417, 5814.563907706896, 5867.11993201019, 5900.822975310747, 5916.674582897939, 6674.06563081169, 6676.13210282665, 6685.565438246498, 6691.765788197438, 6696.19645642526, 4789.305550695164, 5099.413567439718, 5100.872478188038, 5105.846824595268, 5118.10433174193, 6658.4673226141795, 6752.189737231514, 6917.377436030731, 6953.826154346131, 6993.0908981832035, 5172.353455818023, 5229.398619158846, 5239.711815850089, 5270.736463176826, 5272.2882410874045, 5296.443873393238, 5300.867478313073, 5330.7068111253475, 5331.520163185254, 5350.545203657733, 5831.912521312183, 5850.882982340329, 5881.264841894763, 5893.675847828411, 5910.261217347832, 6759.526833121733, 6763.323667633197, 6776.593404395752, 6783.898241526381, 6804.183497980274, 1806.674798536276, 1824.8175182481912, 1832.1755210155484, 1834.7889992651017, 1854.174892172997, 1220.2312543097792, 1277.6039427777418, 1290.8838204561634, 1305.5626365167775, 1315.2634524666219, 6024.427679370453, 6090.269167699905, 6140.263168419729, 6156.476699480505, 6227.657478838707, 5959.553033522503, 6560.8359791005605, 6654.800355989231, 6675.064231372945, 6760.628165450948, 6118.0, 6123.142760013601, 6128.0, 6184.134221209034, 6188.81433556985, 3543.185067434453, 3557.679808817219, 3566.625504322061, 3574.946375804365, 3598.9640103599154, 6923.307669233036, 6926.0, 6971.163460384786, 7012.193597736224, 7043.612601306977, 6726.159230096238, 6813.454923606099, 6836.666849964209, 6955.234924158339, 6962.721491140298, 6804.01964850558, 7027.964860175666, 7053.294670532906, 7087.979925721144, 7102.325279098477, 6677.465802735673, 6801.863962720817, 6818.113645226287, 6841.692123854339, 6842.55523390978, 6772.288909664414, 6773.830654233487, 6811.386975172259, 6827.692753825991, 6861.794146175623, 6835.418989385759, 7007.124109486314, 7060.117485314336, 7084.893726594209, 7085.716571337089, 5114.846554603368, 5128.102582048052, 5164.509371609691, 5166.689998600096, 5170.896582068262, 6579.934200657932, 6669.7332106715185, 6722.6638668065425, 6757.594544327356, 6779.491538134561, 6312.431881130537, 6421.743130274736, 6525.2169739631545, 6544.6727663617585, 6609.339066093352, 6495.253045899782, 6521.902171467337, 6569.967150164219, 6578.868422631616, 6590.340965903563, 6398.808035758934, 6435.195600549931, 6535.836604084746, 6547.770828020996, 6603.20761508622, 6522.380373864476, 6661.3338666132995, 6674.766383176566, 6710.865782684221, 6718.764843230463, 6482.546221764431, 6492.870142597216, 6509.414152726277, 6510.0, 6574.605523668595], "value": 5191.077669761421, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [963.0898800633399, 972.6255391674206, 991.5736233419631, 997.0478193325366, 1009.9343542669726, 19276.132574034167, 19323.903380483112, 19455.902720486396, 19538.90230548849, 19543.706844397344, 16786.664266714688, 16790.244439000242, 17032.318707251725, 17037.0, 17187.312507499715, 1037.8287582548871, 1056.3820165203365, 1058.2169194795833, 1060.7825395793843, 1062.1131355318319, 1049.4385503755493, 1053.978920421595, 1054.051353781595, 1054.7679510507714, 1055.5144633468615, 1039.516624769482, 1086.0931122512711, 1088.6298658456124, 1091.2088735666657, 1104.6023431564631, 1078.191356482638, 1079.967600971968, 1082.7239054041247, 1083.3391631105019, 1083.9891601084046, 762.7330434347989, 789.9960500197519, 792.0, 793.6190628498354, 797.8364435290752, 18463.35378261768, 18466.0, 18488.53778655531, 18491.722624160648, 18698.532536686693, 23119.07523699067, 23146.0, 23840.64239036416, 23881.880590596935, 23968.880155599283, 17112.229949652294, 17215.913920430445, 17530.47408577732, 17535.298588056463, 17662.293508259656, 4064.10589670275, 4094.447249621293, 4098.221337945781, 4100.138970816132, 4102.138550904314, 126004.0, 165414.3822962549, 167397.0, 168821.15589422098, 168928.0, 17760.644787104186, 18380.08099595015, 18445.90777046106, 18754.24983000692, 18871.811281887007, 32754.344913102083, 33179.17052073718, 33270.0, 33404.832975834965, 33929.32141357159, 9492.667756628485, 9503.477308747946, 9508.524573771283, 9542.713718588453, 9544.284178686627, 4288.678349123828, 4289.957100429018, 4299.9570004300185, 4332.718373305725, 4381.2332841753205, 6090.086487026982, 6169.907451388232, 6201.441870231701, 6202.596831205957, 6220.595661282093, 1197.706561892342, 1220.2495465288785, 1222.8471441069867, 1226.4297101847683, 1237.016571825401, 753.7964749517599, 766.1801871997021, 767.6545554500497, 776.1578687124534, 781.4607920534927, 13571.1857288563, 13581.796273055714, 13619.523316683868, 14223.28883555818, 14259.42962281497, 735.3528894572848, 735.4484136897327, 739.1167554772103, 743.3458556470272, 753.4951582439778, 6473.611583305017, 6496.740130394825, 6591.901121483182, 6612.801615951529, 6678.766243181465, 146348.0, 148578.0, 149538.0, 152923.23538382238, 154045.0, 99617.50191249143, 102900.48549757354, 122317.0, 130711.34644326718, 137109.0, 108363.0, 109481.0, 110370.44814775875, 112983.0, 113004.43497782623, 13744.0, 13805.811764353042, 14018.22899740524, 14143.292835358397, 14748.188849613374, 53232.467675322754, 55553.0, 55841.0, 56120.438795613154, 56640.10194481659, 72207.63896180486, 72567.63716181385, 73028.63485682537, 74300.88548671667, 75153.24846751463, 19663.213471460982, 20183.192672292942, 20278.695819562425, 20438.795612044283, 20636.17455301771, 21970.34088977333, 22140.557188856455, 22304.553908921407, 22324.77675223227, 22344.77655223427, 20891.164353426302, 21148.788512114683, 21156.47108822292, 21319.25382611601, 21425.571488570455, 20108.899455503215, 20257.797422026182, 20288.492787680425, 20435.182592696125, 20528.794712053284, 19770.90114549418, 20057.197712091936, 20133.09401076926, 20200.49498762543, 20223.29218477346, 14492.710145796815, 14569.562913112624, 14598.343074561883, 14608.123512589278, 14697.265136742906, 15630.843691563394, 15731.528054158394, 15908.44320448825, 15931.0, 15933.36266549325], "value": 10144.289742715733, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1376.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 105.0, 106.0, 106.0, 105.0, 105.0, 108.0, 107.0, 106.0, 105.0], "value": 105.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 115.0, 113.0, 139.0, 114.0, 113.0, 114.0, 113.0, 113.0], "value": 116.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 163.0, 164.0, 162.0, 162.0, 162.0, 165.0, 165.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 72.0, 73.0, 73.0, 74.0, 73.0, 73.0, 75.0, 73.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 78.0, 77.0, 77.0, 77.0, 77.0, 78.0, 77.0, 82.0, 78.0], "value": 79.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 113.0, 115.0, 114.0, 114.0, 113.0, 115.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 149.0, 149.0, 149.0, 149.0, 150.0, 153.0, 150.0, 153.0], "value": 150.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 45.0, 46.0, 49.0, 45.0, 44.0, 44.0, 43.0, 44.0, 46.0], "value": 45.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 37.0, 37.0, 46.0, 38.0, 37.0, 38.0, 38.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 72.0, 72.0, 79.0, 72.0, 72.0, 74.0, 73.0, 71.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 107.0, 105.0, 107.0, 109.0, 107.0, 105.0, 107.0, 106.0, 110.0], "value": 107.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 149.0, 149.0, 163.0, 152.0, 160.0, 148.0, 154.0, 155.0, 156.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 60.0, 60.0, 67.0, 59.0, 63.0, 59.0, 62.0, 62.0], "value": 62.3, "unit": "score"}]}]} 04:23:53 INFO - Return code: 0 04:23:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:23:53 INFO - # TBPL SUCCESS # 04:23:53 INFO - Running post-action listener: _package_coverage_data 04:23:53 INFO - Running post-action listener: _resource_record_post_action 04:23:53 INFO - [mozharness: 2017-09-23 11:23:53.819480Z] Finished run-tests step (success) 04:23:53 INFO - Running post-run listener: _resource_record_post_run 04:23:53 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 12288; Write bytes: 402235392; Read time: 32; Write time: 455668 04:23:53 INFO - TinderboxPrint: CPU usage
13.1% 04:23:53 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 32 04:23:53 INFO - TinderboxPrint: I/O write bytes / time
402,235,392 / 455,668 04:23:53 INFO - TinderboxPrint: CPU idle
8,224.3 (86.9%) 04:23:53 INFO - TinderboxPrint: CPU user
1,188.0 (12.6%) 04:23:53 INFO - TinderboxPrint: Swap in / out
0 / 0 04:23:53 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 75132928; Read time: 0; Write time: 84872 04:23:53 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:23:53 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 12288; Write bytes: 326488064; Read time: 32; Write time: 370792 04:23:54 INFO - Running post-run listener: _upload_blobber_files 04:23:54 INFO - Blob upload gear active. 04:23:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:23:54 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:23:54 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:23:54 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:23:54 INFO - (blobuploader) - INFO - Open directory for files ... 04:23:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:23:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:23:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:23:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:23:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:23:55 INFO - (blobuploader) - INFO - Done attempting. 04:23:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:23:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:23:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:23:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:23:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:23:56 INFO - (blobuploader) - INFO - Done attempting. 04:23:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:23:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:23:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:24:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:24:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:24:03 INFO - (blobuploader) - INFO - Done attempting. 04:24:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:24:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:24:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:24:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:24:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:24:04 INFO - (blobuploader) - INFO - Done attempting. 04:24:04 INFO - (blobuploader) - INFO - Iteration through files over. 04:24:04 INFO - Return code: 0 04:24:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:24:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:24:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f25d1c2042c37d9f31b5ec9d2628d2369a5b8d939896fd1755a53060d3888324471668eb28209548afd161d451cc9985e5b4aed05334e0d104e26e58fc99756", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fa438a6808b96b07121ce52ccab13355e11bc67915872c81f02978ea3ecfbf5cde9c0118117393742b550a61ab7e7a88890cde90b40fc68cbe22b131e3ef223", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a02811e8d218fd678baa527d10e1937b991c7b2e03547da020cfb69f3145705a0e2634b288ce843c659e68b0406053a8e52780e3f94522c248f6c92341f7e64c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/551e688a60eaebe5ea10816a42c3d9c064682a5c467baba2bbaddd25d2d69952bd3381d0071d8ab4462a8a09851b49d633c590530d72ab158736750888b0aff4"} 04:24:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:24:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:24:04 INFO - Contents: 04:24:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f25d1c2042c37d9f31b5ec9d2628d2369a5b8d939896fd1755a53060d3888324471668eb28209548afd161d451cc9985e5b4aed05334e0d104e26e58fc99756", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fa438a6808b96b07121ce52ccab13355e11bc67915872c81f02978ea3ecfbf5cde9c0118117393742b550a61ab7e7a88890cde90b40fc68cbe22b131e3ef223", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a02811e8d218fd678baa527d10e1937b991c7b2e03547da020cfb69f3145705a0e2634b288ce843c659e68b0406053a8e52780e3f94522c248f6c92341f7e64c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/551e688a60eaebe5ea10816a42c3d9c064682a5c467baba2bbaddd25d2d69952bd3381d0071d8ab4462a8a09851b49d633c590530d72ab158736750888b0aff4"} 04:24:04 INFO - Running post-run listener: copy_logs_to_upload_dir 04:24:04 INFO - Copying logs to upload dir... 04:24:04 INFO - mkdir: /builds/slave/test/build/upload/logs 04:24:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.275221 ========= master_lag: 0.79 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-23 04:24:05.703189) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 04:24:05.705559) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253 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/8f25d1c2042c37d9f31b5ec9d2628d2369a5b8d939896fd1755a53060d3888324471668eb28209548afd161d451cc9985e5b4aed05334e0d104e26e58fc99756", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fa438a6808b96b07121ce52ccab13355e11bc67915872c81f02978ea3ecfbf5cde9c0118117393742b550a61ab7e7a88890cde90b40fc68cbe22b131e3ef223", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a02811e8d218fd678baa527d10e1937b991c7b2e03547da020cfb69f3145705a0e2634b288ce843c659e68b0406053a8e52780e3f94522c248f6c92341f7e64c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/551e688a60eaebe5ea10816a42c3d9c064682a5c467baba2bbaddd25d2d69952bd3381d0071d8ab4462a8a09851b49d633c590530d72ab158736750888b0aff4"} build_url:https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011243 build_url: 'https://queue.taskcluster.net/v1/task/YK2hyMrlSTiJS-ZWTXWUag/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f25d1c2042c37d9f31b5ec9d2628d2369a5b8d939896fd1755a53060d3888324471668eb28209548afd161d451cc9985e5b4aed05334e0d104e26e58fc99756", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fa438a6808b96b07121ce52ccab13355e11bc67915872c81f02978ea3ecfbf5cde9c0118117393742b550a61ab7e7a88890cde90b40fc68cbe22b131e3ef223", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a02811e8d218fd678baa527d10e1937b991c7b2e03547da020cfb69f3145705a0e2634b288ce843c659e68b0406053a8e52780e3f94522c248f6c92341f7e64c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/551e688a60eaebe5ea10816a42c3d9c064682a5c467baba2bbaddd25d2d69952bd3381d0071d8ab4462a8a09851b49d633c590530d72ab158736750888b0aff4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 04:24:05.741542) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 04:24:05.741856) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1506164605.445179-1155013253 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004528 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 04:24:05.793181) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 04:24:05.793486) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 04:24:05.793809) ========= ========= Total master_lag: 0.93 =========