builder: mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s-pgo slave: talos-linux64-ix-086 starttime: 1505299586.56 results: success (0) revision: 124f2094635bbf1901c1d14fc2c0a80bf7f847bb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.555789) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.556218) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.556528) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.600521) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.600802) ========= 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=438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-13 03:46: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% 46.2M=0s 2017-09-13 03:46:26 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.110502 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.738096) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.738420) ========= 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=438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018666 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.790961) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:26.791250) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 124f2094635bbf1901c1d14fc2c0a80bf7f847bb --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 124f2094635bbf1901c1d14fc2c0a80bf7f847bb --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=438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-13 03:46:26,786 truncating revision to first 12 chars 2017-09-13 03:46:26,786 Setting DEBUG logging. 2017-09-13 03:46:26,786 attempt 1/10 2017-09-13 03:46:26,786 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/124f2094635b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-13 03:46:27,148 unpacking tar archive at: mozilla-central-124f2094635b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.677044 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:27.490397) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:27.490692) ========= script_repo_revision: 124f2094635bbf1901c1d14fc2c0a80bf7f847bb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:27.491068) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:27.491322) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 03:46:27.507030) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-09-13 03:46:27.507282) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-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-stylo-disabled-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=438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:46:27 INFO - MultiFileLogger online at 20170913 03:46:27 in /builds/slave/test 03:46:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:46:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:46:27 INFO - {'append_to_log': False, 03:46:27 INFO - 'base_work_dir': '/builds/slave/test', 03:46:27 INFO - 'blob_upload_branch': 'Firefox', 03:46:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:46:27 INFO - 'branch': 'Firefox', 03:46:27 INFO - 'buildbot_json_path': 'buildprops.json', 03:46:27 INFO - 'code_coverage': False, 03:46:27 INFO - 'config_files': ('talos/linux_config.py',), 03:46:27 INFO - 'default_actions': ('clobber', 03:46:27 INFO - 'read-buildbot-config', 03:46:27 INFO - 'download-and-extract', 03:46:27 INFO - 'populate-webroot', 03:46:27 INFO - 'create-virtualenv', 03:46:27 INFO - 'install', 03:46:27 INFO - 'setup-mitmproxy', 03:46:27 INFO - 'run-tests'), 03:46:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:46:27 INFO - 'disable_ccov_upload': False, 03:46:27 INFO - 'disable_stylo': False, 03:46:27 INFO - 'download_minidump_stackwalk': True, 03:46:27 INFO - 'download_symbols': 'ondemand', 03:46:27 INFO - 'enable_stylo': False, 03:46:27 INFO - 'enable_webrender': False, 03:46:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:46:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:46:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:46:27 INFO - 'gecko_profile': False, 03:46:27 INFO - 'gecko_profile_interval': 0, 03:46:27 INFO - 'installer_path': 'installer.exe', 03:46:27 INFO - 'log_level': 'info', 03:46:27 INFO - 'log_name': 'talos', 03:46:27 INFO - 'log_to_console': True, 03:46:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:46:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:46:27 INFO - 'opt_config_files': (), 03:46:27 INFO - 'pip_index': False, 03:46:27 INFO - 'suite': 'dromaeojs-stylo-disabled-e10s', 03:46:27 INFO - 'system_bits': '32', 03:46:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:46:27 INFO - 'title': 'talos-linux64-ix-086', 03:46:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:46:27 INFO - 'use_talos_json': True, 03:46:27 INFO - 'verify': 'False', 03:46:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:46:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:46:27 INFO - 'work_dir': 'build'} 03:46:27 INFO - [mozharness: 2017-09-13 10:46:27.626893Z] Running clobber step. 03:46:27 INFO - Running pre-action listener: _resource_record_pre_action 03:46:27 INFO - Running main action method: clobber 03:46:27 INFO - rmtree: /builds/slave/test/build 03:46:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:46:28 INFO - Running post-action listener: _resource_record_post_action 03:46:28 INFO - [mozharness: 2017-09-13 10:46:28.646437Z] Finished clobber step (success) 03:46:28 INFO - [mozharness: 2017-09-13 10:46:28.646535Z] Running read-buildbot-config step. 03:46:28 INFO - Running pre-action listener: _resource_record_pre_action 03:46:28 INFO - Running main action method: read_buildbot_config 03:46:28 INFO - Using buildbot properties: 03:46:28 INFO - { 03:46:28 INFO - "project": "", 03:46:28 INFO - "product": "firefox", 03:46:28 INFO - "who": "archaeopteryx@coole-files.de", 03:46:28 INFO - "installer_path": "public/build/target.tar.bz2", 03:46:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-stylo-disabled-e10s", 03:46:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:46:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-stylo-disabled-e10s", 03:46:28 INFO - "stage_platform": "linux64-pgo", 03:46:28 INFO - "basedir": "/builds/slave/test", 03:46:28 INFO - "buildnumber": 5, 03:46:28 INFO - "platform": "ubuntu64_hw", 03:46:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:46:28 INFO - "slavebuilddir": "test", 03:46:28 INFO - "taskId": "IupX_ytTQEWn9gAJgUL0jA", 03:46:28 INFO - "branch": "mozilla-central", 03:46:28 INFO - "script_repo_revision": "production", 03:46:28 INFO - "revision": "124f2094635bbf1901c1d14fc2c0a80bf7f847bb", 03:46:28 INFO - "slavename": "talos-linux64-ix-086", 03:46:28 INFO - "repo_path": "mozilla-central" 03:46:28 INFO - } 03:46:28 INFO - Finding installer, test and symbols from parent task. 03:46:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IupX_ytTQEWn9gAJgUL0jA'}, attempt #1 03:46:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IupX_ytTQEWn9gAJgUL0jA'}, attempt #1 03:46:29 INFO - Task dependencies: UIAOECxVTHSi9bdMrSDP9w 03:46:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w'}, attempt #1 03:46:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2 03:46:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.test_packages.json 03:46:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.crashreporter-symbols.zip 03:46:29 INFO - Running post-action listener: _resource_record_post_action 03:46:29 INFO - [mozharness: 2017-09-13 10:46:29.965418Z] Finished read-buildbot-config step (success) 03:46:29 INFO - [mozharness: 2017-09-13 10:46:29.965691Z] Running download-and-extract step. 03:46:29 INFO - Running pre-action listener: _resource_record_pre_action 03:46:29 INFO - Running main action method: download_and_extract 03:46:29 INFO - mkdir: /builds/slave/test/build/tests 03:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:29 INFO - trying https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.test_packages.json 03:46:29 INFO - Downloading https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:46:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:46:30 INFO - Downloaded 989 bytes. 03:46:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:46:30 INFO - Using the following test package requirements: 03:46:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:46:30 INFO - u'common': [u'target.common.tests.zip'], 03:46:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:46:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:46:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:46:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:46:30 INFO - u'mozbase': [u'target.common.tests.zip'], 03:46:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:46:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:46:30 INFO - u'web-platform': [u'target.common.tests.zip', 03:46:30 INFO - u'target.web-platform.tests.tar.gz'], 03:46:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:46:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 03:46:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.common.tests.zip 03:46:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:46:30 INFO - Fetch https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.common.tests.zip into memory 03:46:31 INFO - Content-Length response header: 55664180 03:46:31 INFO - Bytes received: 55664180 03:46:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.talos.tests.zip 03:46:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:46:42 INFO - Fetch https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.talos.tests.zip into memory 03:46:43 INFO - Content-Length response header: 14499231 03:46:43 INFO - Bytes received: 14499231 03:46:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:43 INFO - trying https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2 03:46:43 INFO - Downloading https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:46:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:46:46 INFO - Downloaded 63695375 bytes. 03:46:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2 03:46:47 INFO - mkdir: /builds/slave/test/properties 03:46:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:46:47 INFO - Writing to file /builds/slave/test/properties/build_url 03:46:47 INFO - Contents: 03:46:47 INFO - build_url:https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2 03:46:47 INFO - Running post-action listener: _resource_record_post_action 03:46:47 INFO - Running post-action listener: find_tests_for_verification 03:46:47 INFO - Running post-action listener: set_extra_try_arguments 03:46:47 INFO - [mozharness: 2017-09-13 10:46:47.108402Z] Finished download-and-extract step (success) 03:46:47 INFO - [mozharness: 2017-09-13 10:46:47.108658Z] Running populate-webroot step. 03:46:47 INFO - Running pre-action listener: _resource_record_pre_action 03:46:47 INFO - Running main action method: populate_webroot 03:46:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:46:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:47 INFO - 'tests': ['tresize', 03:46:47 INFO - 'tcanvasmark']}, 03:46:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:46:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:47 INFO - 'tests': ['dromaeo_css', 03:46:47 INFO - 'kraken']}, 03:46:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:46:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:46:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:47 INFO - 'talos_options': ['--disable-stylo'], 03:46:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:46:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:46:47 INFO - 'tests': ['damp', 'tps']}, 03:46:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:47 INFO - 'talos_options': ['--disable-stylo'], 03:46:47 INFO - 'tests': ['damp', 'tps']}, 03:46:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:46:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:47 INFO - 'tests': ['dromaeo_dom']}, 03:46:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:46:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:47 INFO - 'tests': ['basic_compositor_video', 03:46:47 INFO - 'glvideo']}, 03:46:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:46:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:46:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:47 INFO - 'talos_options': ['--disable-stylo'], 03:46:47 INFO - 'tests': ['ts_paint_webext', 03:46:47 INFO - 'tp5o_webext']}, 03:46:47 INFO - 'other-e10s': {'tests': ['a11yr', 03:46:47 INFO - 'ts_paint', 03:46:47 INFO - 'tpaint', 03:46:47 INFO - 'sessionrestore', 03:46:47 INFO - 'sessionrestore_many_windows', 03:46:47 INFO - 'sessionrestore_no_auto_restore', 03:46:47 INFO - 'tabpaint']}, 03:46:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:47 INFO - 'tests': ['a11yr', 03:46:47 INFO - 'ts_paint', 03:46:47 INFO - 'tpaint', 03:46:47 INFO - 'sessionrestore', 03:46:47 INFO - 'sessionrestore_many_windows', 03:46:47 INFO - 'sessionrestore_no_auto_restore', 03:46:47 INFO - 'tabpaint']}, 03:46:47 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:46:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:46:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:47 INFO - 'tests': ['bloom_basic_singleton']}, 03:46:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:47 INFO - 'tests': ['bloom_basic']}, 03:46:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:46:47 INFO - 'tsvgr_opacity', 03:46:47 INFO - 'tart', 03:46:47 INFO - 'tscrollx', 03:46:47 INFO - 'tsvg_static']}, 03:46:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:46:47 INFO - 'tests': ['tsvgx', 03:46:47 INFO - 'tsvgr_opacity', 03:46:47 INFO - 'tart', 03:46:47 INFO - 'tscrollx', 03:46:47 INFO - 'tsvg_static']}, 03:46:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:46:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:47 INFO - 'talos_options': ['--disable-stylo'], 03:46:47 INFO - 'tests': ['tp5o']}, 03:46:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:46:47 INFO - 'talos_options': ['--mitmproxy', 03:46:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:46:47 INFO - '--firstNonBlankPaint'], 03:46:47 INFO - 'tests': ['tp6_google', 03:46:47 INFO - 'tp6_youtube', 03:46:47 INFO - 'tp6_amazon', 03:46:47 INFO - 'tp6_facebook']}, 03:46:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:46:47 INFO - 'talos_options': ['--disable-stylo', 03:46:47 INFO - '--mitmproxy', 03:46:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:46:47 INFO - '--firstNonBlankPaint'], 03:46:47 INFO - 'tests': ['tp6_google', 03:46:47 INFO - 'tp6_youtube', 03:46:47 INFO - 'tp6_amazon', 03:46:47 INFO - 'tp6_facebook']}, 03:46:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:46:47 INFO - 'talos_options': ['--stylo-threads=1', 03:46:47 INFO - '--mitmproxy', 03:46:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:46:47 INFO - '--firstNonBlankPaint'], 03:46:47 INFO - 'tests': ['tp6_google', 03:46:47 INFO - 'tp6_youtube', 03:46:47 INFO - 'tp6_amazon', 03:46:47 INFO - 'tp6_facebook']}, 03:46:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:46:47 INFO - 'talos_options': ['--xperf_path', 03:46:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:46:47 INFO - 'tests': ['tp5n']}, 03:46:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:46:47 INFO - 'talos_options': ['--disable-stylo', 03:46:47 INFO - '--xperf_path', 03:46:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:46:47 INFO - 'tests': ['tp5n']}}} 03:46:47 INFO - Running post-action listener: _resource_record_post_action 03:46:47 INFO - [mozharness: 2017-09-13 10:46:47.129334Z] Finished populate-webroot step (success) 03:46:47 INFO - [mozharness: 2017-09-13 10:46:47.129426Z] Running create-virtualenv step. 03:46:47 INFO - Running pre-action listener: _resource_record_pre_action 03:46:47 INFO - Running main action method: create_virtualenv 03:46:47 INFO - Creating virtualenv /builds/slave/test/build/venv 03:46:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:46:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:46:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:46:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:46:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:46:47 INFO - Installing distribute.............................................................................................................................................................................................done. 03:46:47 INFO - Installing pip...............done. 03:46:48 INFO - Return code: 0 03:46:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:46:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:46:48 INFO - Reading from file tmpfile_stdout 03:46:48 INFO - Output received: 03:46:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:46:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:46: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')]} 03:46:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46: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')]} 03:46:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2751a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f8090>, '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 0x28f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f2f90>, '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': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:46: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', 'pip>=1.5'] in /builds/slave/test/build 03:46: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 pip>=1.5 03:46:48 INFO - Using env: {'DISPLAY': ':0', 03:46:48 INFO - 'HOME': '/home/cltbld', 03:46:48 INFO - 'LANG': 'en_US.UTF-8', 03:46:48 INFO - 'LANGUAGE': 'en_US:en', 03:46:48 INFO - 'LOGNAME': 'cltbld', 03:46:48 INFO - 'MAIL': '/var/mail/cltbld', 03:46:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:48 INFO - 'MOZ_NO_REMOTE': '1', 03:46:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:48 INFO - 'NO_EM_RESTART': '1', 03:46:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:48 INFO - 'PWD': '/builds/slave/test', 03:46:48 INFO - 'SHELL': '/bin/bash', 03:46:48 INFO - 'SHLVL': '1', 03:46:48 INFO - 'TERM': 'linux', 03:46:48 INFO - 'TMOUT': '86400', 03:46:48 INFO - 'USER': 'cltbld', 03:46:48 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 03:46:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:48 INFO - '_': '/tools/buildbot/bin/python'} 03:46:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:46:49 INFO - Downloading/unpacking pip>=1.5 03:46:49 INFO - Running setup.py egg_info for package pip 03:46:49 INFO - warning: no files found matching 'pip/cacert.pem' 03:46:49 INFO - warning: no files found matching '*.html' under directory 'docs' 03:46:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:46:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:46:49 INFO - Installing collected packages: pip 03:46:49 INFO - Found existing installation: pip 1.1 03:46:49 INFO - Uninstalling pip: 03:46:49 INFO - Successfully uninstalled pip 03:46:49 INFO - Running setup.py install for pip 03:46:49 INFO - warning: no files found matching 'pip/cacert.pem' 03:46:49 INFO - warning: no files found matching '*.html' under directory 'docs' 03:46:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:46:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:46:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:46:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:46:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:46:49 INFO - Successfully installed pip 03:46:49 INFO - Cleaning up... 03:46:49 INFO - Return code: 0 03:46:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2751a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f8090>, '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 0x28f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f2f90>, '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': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:46:49 INFO - Using env: {'DISPLAY': ':0', 03:46:49 INFO - 'HOME': '/home/cltbld', 03:46:49 INFO - 'LANG': 'en_US.UTF-8', 03:46:49 INFO - 'LANGUAGE': 'en_US:en', 03:46:49 INFO - 'LOGNAME': 'cltbld', 03:46:49 INFO - 'MAIL': '/var/mail/cltbld', 03:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:49 INFO - 'MOZ_NO_REMOTE': '1', 03:46:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:49 INFO - 'NO_EM_RESTART': '1', 03:46:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:49 INFO - 'PWD': '/builds/slave/test', 03:46:49 INFO - 'SHELL': '/bin/bash', 03:46:49 INFO - 'SHLVL': '1', 03:46:49 INFO - 'TERM': 'linux', 03:46:49 INFO - 'TMOUT': '86400', 03:46:49 INFO - 'USER': 'cltbld', 03:46:49 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 03:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:49 INFO - '_': '/tools/buildbot/bin/python'} 03:46:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:49 INFO - Downloading/unpacking psutil>=3.1.1 03:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:46:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:46:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:46:50 INFO - Installing collected packages: psutil 03:46:50 INFO - Running setup.py install for psutil 03:46:50 INFO - building 'psutil._psutil_linux' extension 03:46:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:46:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:46:50 INFO - building 'psutil._psutil_posix' extension 03:46:50 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:46:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:46:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:46:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:46:51 INFO - Successfully installed psutil 03:46:51 INFO - Cleaning up... 03:46:51 INFO - Return code: 0 03:46:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:46:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:51 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 0x25881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2751a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f8090>, '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 0x28f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f2f90>, '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': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:46:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:46:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:46:51 INFO - Using env: {'DISPLAY': ':0', 03:46:51 INFO - 'HOME': '/home/cltbld', 03:46:51 INFO - 'LANG': 'en_US.UTF-8', 03:46:51 INFO - 'LANGUAGE': 'en_US:en', 03:46:51 INFO - 'LOGNAME': 'cltbld', 03:46:51 INFO - 'MAIL': '/var/mail/cltbld', 03:46:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:51 INFO - 'MOZ_NO_REMOTE': '1', 03:46:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:51 INFO - 'NO_EM_RESTART': '1', 03:46:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:51 INFO - 'PWD': '/builds/slave/test', 03:46:51 INFO - 'SHELL': '/bin/bash', 03:46:51 INFO - 'SHLVL': '1', 03:46:51 INFO - 'TERM': 'linux', 03:46:51 INFO - 'TMOUT': '86400', 03:46:51 INFO - 'USER': 'cltbld', 03:46:51 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 03:46:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:51 INFO - '_': '/tools/buildbot/bin/python'} 03:46:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:46:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:46:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:46:52 INFO - Installing collected packages: mozsystemmonitor 03:46:52 INFO - Running setup.py install for mozsystemmonitor 03:46:52 INFO - Successfully installed mozsystemmonitor 03:46:52 INFO - Cleaning up... 03:46:53 INFO - Return code: 0 03:46:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2751a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f8090>, '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 0x28f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f2f90>, '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': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:46:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:46:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:46:53 INFO - Using env: {'DISPLAY': ':0', 03:46:53 INFO - 'HOME': '/home/cltbld', 03:46:53 INFO - 'LANG': 'en_US.UTF-8', 03:46:53 INFO - 'LANGUAGE': 'en_US:en', 03:46:53 INFO - 'LOGNAME': 'cltbld', 03:46:53 INFO - 'MAIL': '/var/mail/cltbld', 03:46:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:53 INFO - 'MOZ_NO_REMOTE': '1', 03:46:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:53 INFO - 'NO_EM_RESTART': '1', 03:46:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:53 INFO - 'PWD': '/builds/slave/test', 03:46:53 INFO - 'SHELL': '/bin/bash', 03:46:53 INFO - 'SHLVL': '1', 03:46:53 INFO - 'TERM': 'linux', 03:46:53 INFO - 'TMOUT': '86400', 03:46:53 INFO - 'USER': 'cltbld', 03:46:53 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 03:46:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:53 INFO - '_': '/tools/buildbot/bin/python'} 03:46:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:53 INFO - Downloading/unpacking jsonschema==2.5.1 03:46: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 03:46: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 03:46: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 03:46: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 03:46:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:46:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:46: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 03:46: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 03:46: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 03:46: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 03:46:55 INFO - Downloading functools32-3.2.3-2.tar.gz 03:46:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:46:55 INFO - warning: no files found matching '*.txt' 03:46:55 INFO - no previously-included directories found matching 'build' 03:46:55 INFO - no previously-included directories found matching 'dist' 03:46:55 INFO - no previously-included directories found matching '.git*' 03:46:55 INFO - Installing collected packages: jsonschema, functools32 03:46:55 INFO - Running setup.py install for functools32 03:46:55 INFO - warning: no files found matching '*.txt' 03:46:55 INFO - no previously-included directories found matching 'build' 03:46:55 INFO - no previously-included directories found matching 'dist' 03:46:55 INFO - no previously-included directories found matching '.git*' 03:46:55 INFO - Successfully installed jsonschema functools32 03:46:55 INFO - Cleaning up... 03:46:56 INFO - Return code: 0 03:46:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2751a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f8090>, '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 0x28f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f2f90>, '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': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:46:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:46:56 INFO - Using env: {'DISPLAY': ':0', 03:46:56 INFO - 'HOME': '/home/cltbld', 03:46:56 INFO - 'LANG': 'en_US.UTF-8', 03:46:56 INFO - 'LANGUAGE': 'en_US:en', 03:46:56 INFO - 'LOGNAME': 'cltbld', 03:46:56 INFO - 'MAIL': '/var/mail/cltbld', 03:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:56 INFO - 'MOZ_NO_REMOTE': '1', 03:46:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:56 INFO - 'NO_EM_RESTART': '1', 03:46:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:56 INFO - 'PWD': '/builds/slave/test', 03:46:56 INFO - 'SHELL': '/bin/bash', 03:46:56 INFO - 'SHLVL': '1', 03:46:56 INFO - 'TERM': 'linux', 03:46:56 INFO - 'TMOUT': '86400', 03:46:56 INFO - 'USER': 'cltbld', 03:46:56 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 03:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:56 INFO - '_': '/tools/buildbot/bin/python'} 03:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:46:56 INFO - Cleaning up... 03:46:56 INFO - Return code: 0 03:46:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2751a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f8090>, '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 0x28f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f2f90>, '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': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:46:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:46:56 INFO - Using env: {'DISPLAY': ':0', 03:46:56 INFO - 'HOME': '/home/cltbld', 03:46:56 INFO - 'LANG': 'en_US.UTF-8', 03:46:56 INFO - 'LANGUAGE': 'en_US:en', 03:46:56 INFO - 'LOGNAME': 'cltbld', 03:46:56 INFO - 'MAIL': '/var/mail/cltbld', 03:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:56 INFO - 'MOZ_NO_REMOTE': '1', 03:46:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:56 INFO - 'NO_EM_RESTART': '1', 03:46:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:56 INFO - 'PWD': '/builds/slave/test', 03:46:56 INFO - 'SHELL': '/bin/bash', 03:46:56 INFO - 'SHLVL': '1', 03:46:56 INFO - 'TERM': 'linux', 03:46:56 INFO - 'TMOUT': '86400', 03:46:56 INFO - 'USER': 'cltbld', 03:46:56 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 03:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:56 INFO - '_': '/tools/buildbot/bin/python'} 03:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:56 INFO - Downloading/unpacking blobuploader==1.2.4 03:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:57 INFO - Downloading blobuploader-1.2.4.tar.gz 03:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:46:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:46:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:58 INFO - Downloading docopt-0.6.1.tar.gz 03:46:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:46:58 INFO - Installing collected packages: blobuploader, requests, docopt 03:46:58 INFO - Running setup.py install for blobuploader 03:46:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:46:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:46:58 INFO - Running setup.py install for requests 03:46:58 INFO - Running setup.py install for docopt 03:46:58 INFO - Successfully installed blobuploader requests docopt 03:46:58 INFO - Cleaning up... 03:46:58 INFO - Return code: 0 03:46:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2751a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f8090>, '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 0x28f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f2f90>, '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': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:46:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:46:58 INFO - Using env: {'DISPLAY': ':0', 03:46:58 INFO - 'HOME': '/home/cltbld', 03:46:58 INFO - 'LANG': 'en_US.UTF-8', 03:46:58 INFO - 'LANGUAGE': 'en_US:en', 03:46:58 INFO - 'LOGNAME': 'cltbld', 03:46:58 INFO - 'MAIL': '/var/mail/cltbld', 03:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:58 INFO - 'MOZ_NO_REMOTE': '1', 03:46:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:46:58 INFO - 'NO_EM_RESTART': '1', 03:46:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:58 INFO - 'PWD': '/builds/slave/test', 03:46:58 INFO - 'SHELL': '/bin/bash', 03:46:58 INFO - 'SHLVL': '1', 03:46:58 INFO - 'TERM': 'linux', 03:46:58 INFO - 'TMOUT': '86400', 03:46:58 INFO - 'USER': 'cltbld', 03:46:58 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 03:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:58 INFO - '_': '/tools/buildbot/bin/python'} 03:46:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:46:58 INFO - Running setup.py (path:/tmp/pip-7LZpcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:46:59 INFO - Running setup.py (path:/tmp/pip-R751zN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:46:59 INFO - Running setup.py (path:/tmp/pip-yaK0ew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:46:59 INFO - Running setup.py (path:/tmp/pip-2dKC0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:46:59 INFO - Running setup.py (path:/tmp/pip-4eYrC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:46:59 INFO - Running setup.py (path:/tmp/pip-WmVu3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:46:59 INFO - Running setup.py (path:/tmp/pip-vLE_Gw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:46:59 INFO - Running setup.py (path:/tmp/pip-3dIMVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:46:59 INFO - Running setup.py (path:/tmp/pip-Zjn6p8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:46:59 INFO - Running setup.py (path:/tmp/pip-jFvfMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:46:59 INFO - Running setup.py (path:/tmp/pip-Lbz9Ev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:46:59 INFO - Running setup.py (path:/tmp/pip-BCN1yq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:46:59 INFO - Running setup.py (path:/tmp/pip-oi78gQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:46:59 INFO - Running setup.py (path:/tmp/pip-wb_PVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:59 INFO - Running setup.py (path:/tmp/pip-j2GFQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:47:00 INFO - Running setup.py (path:/tmp/pip-EKYKnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:47:00 INFO - Running setup.py (path:/tmp/pip-1E3nvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:47:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:47:00 INFO - Running setup.py install for manifestparser 03:47:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:47:00 INFO - Running setup.py install for mozcrash 03:47:00 INFO - Running setup.py install for mozdebug 03:47:00 INFO - Running setup.py install for mozdevice 03:47:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:47:00 INFO - Running setup.py install for mozfile 03:47:00 INFO - Running setup.py install for mozhttpd 03:47:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:47:00 INFO - Running setup.py install for mozinfo 03:47:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:47:00 INFO - Running setup.py install for mozInstall 03:47:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:47:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:47:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:47:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:47:00 INFO - Running setup.py install for mozleak 03:47:01 INFO - Running setup.py install for mozlog 03:47:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Running setup.py install for moznetwork 03:47:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Running setup.py install for mozprocess 03:47:01 INFO - Running setup.py install for mozprofile 03:47:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Running setup.py install for mozrunner 03:47:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Running setup.py install for mozscreenshot 03:47:01 INFO - Running setup.py install for moztest 03:47:01 INFO - Running setup.py install for mozversion 03:47:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:47:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:47:01 INFO - Cleaning up... 03:47:01 INFO - Return code: 0 03:47:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:47:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2751a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f8090>, '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 0x28f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f2f90>, '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': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:47:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:47:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:47:01 INFO - Using env: {'DISPLAY': ':0', 03:47:01 INFO - 'HOME': '/home/cltbld', 03:47:01 INFO - 'LANG': 'en_US.UTF-8', 03:47:01 INFO - 'LANGUAGE': 'en_US:en', 03:47:01 INFO - 'LOGNAME': 'cltbld', 03:47:01 INFO - 'MAIL': '/var/mail/cltbld', 03:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:01 INFO - 'MOZ_NO_REMOTE': '1', 03:47:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:01 INFO - 'NO_EM_RESTART': '1', 03:47:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:01 INFO - 'PWD': '/builds/slave/test', 03:47:01 INFO - 'SHELL': '/bin/bash', 03:47:01 INFO - 'SHLVL': '1', 03:47:01 INFO - 'TERM': 'linux', 03:47:01 INFO - 'TMOUT': '86400', 03:47:01 INFO - 'USER': 'cltbld', 03:47:01 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 03:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:01 INFO - '_': '/tools/buildbot/bin/python'} 03:47:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:47:02 INFO - Running setup.py (path:/tmp/pip-p4nfLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:47:02 INFO - Running setup.py (path:/tmp/pip-I1JR70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:47:02 INFO - Running setup.py (path:/tmp/pip-rJ09cR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:47:02 INFO - Running setup.py (path:/tmp/pip-Z3ax9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:47:02 INFO - Running setup.py (path:/tmp/pip-tf8GnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:47:02 INFO - Running setup.py (path:/tmp/pip-fT3b8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:47:02 INFO - Running setup.py (path:/tmp/pip-KM_GSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:47:02 INFO - Running setup.py (path:/tmp/pip-REmVyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:47:02 INFO - Running setup.py (path:/tmp/pip-XcsWxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:47:02 INFO - Running setup.py (path:/tmp/pip-Yf8RLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:47:02 INFO - Running setup.py (path:/tmp/pip-MvhPTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:47:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:47:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:47:02 INFO - Running setup.py (path:/tmp/pip-GFgLlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:47:03 INFO - Running setup.py (path:/tmp/pip-PTOpSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:47:03 INFO - Running setup.py (path:/tmp/pip-lLDZL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:47:03 INFO - Running setup.py (path:/tmp/pip-PLGrz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:47:03 INFO - Running setup.py (path:/tmp/pip-aUhE7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:47:03 INFO - Running setup.py (path:/tmp/pip-ptchw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:47:03 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:47:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:04 INFO - Downloading blessings-1.6.tar.gz 03:47:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:47:04 INFO - Installing collected packages: blessings 03:47:04 INFO - Running setup.py install for blessings 03:47:04 INFO - Successfully installed blessings 03:47:04 INFO - Cleaning up... 03:47:04 INFO - Return code: 0 03:47:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:47:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:47:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:47:04 INFO - Reading from file tmpfile_stdout 03:47:04 INFO - Current package versions: 03:47:04 INFO - argparse == 1.2.1 03:47:04 INFO - blessings == 1.6 03:47:04 INFO - blobuploader == 1.2.4 03:47:04 INFO - docopt == 0.6.1 03:47:04 INFO - functools32 == 3.2.3-2 03:47:04 INFO - jsonschema == 2.5.1 03:47:04 INFO - manifestparser == 1.1 03:47:04 INFO - mozInstall == 1.13 03:47:04 INFO - mozcrash == 1.0 03:47:04 INFO - mozdebug == 0.1 03:47:04 INFO - mozdevice == 0.51 03:47:04 INFO - mozfile == 1.2 03:47:04 INFO - mozhttpd == 0.7 03:47:04 INFO - mozinfo == 0.10 03:47:04 INFO - mozleak == 0.1 03:47:04 INFO - mozlog == 3.5 03:47:04 INFO - moznetwork == 0.27 03:47:04 INFO - mozprocess == 0.25 03:47:04 INFO - mozprofile == 0.28 03:47:04 INFO - mozrunner == 6.13 03:47:04 INFO - mozscreenshot == 0.1 03:47:04 INFO - mozsystemmonitor == 0.3 03:47:04 INFO - moztest == 0.8 03:47:04 INFO - mozversion == 1.4 03:47:04 INFO - psutil == 3.1.1 03:47:04 INFO - requests == 1.2.3 03:47:04 INFO - wsgiref == 0.1.2 03:47:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2751a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f8090>, '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 0x28f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f2f90>, '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': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:47:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:47:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:47:04 INFO - Using env: {'DISPLAY': ':0', 03:47:04 INFO - 'HOME': '/home/cltbld', 03:47:04 INFO - 'LANG': 'en_US.UTF-8', 03:47:04 INFO - 'LANGUAGE': 'en_US:en', 03:47:04 INFO - 'LOGNAME': 'cltbld', 03:47:04 INFO - 'MAIL': '/var/mail/cltbld', 03:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:04 INFO - 'MOZ_NO_REMOTE': '1', 03:47:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:04 INFO - 'NO_EM_RESTART': '1', 03:47:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:04 INFO - 'PWD': '/builds/slave/test', 03:47:04 INFO - 'SHELL': '/bin/bash', 03:47:04 INFO - 'SHLVL': '1', 03:47:04 INFO - 'TERM': 'linux', 03:47:04 INFO - 'TMOUT': '86400', 03:47:04 INFO - 'USER': 'cltbld', 03:47:04 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 03:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:04 INFO - '_': '/tools/buildbot/bin/python'} 03:47:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:47:05 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:47:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:47:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:47:06 INFO - Installing collected packages: simplejson 03:47:06 INFO - Running setup.py install for simplejson 03:47:06 INFO - building 'simplejson._speedups' extension 03:47:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:47:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 03:47:07 INFO - Successfully installed simplejson 03:47:07 INFO - Cleaning up... 03:47:07 INFO - Return code: 0 03:47:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:47:07 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 0x25881f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2751a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28f4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28f8090>, '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 0x28f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f2f90>, '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': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:47:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:47:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:47:07 INFO - Using env: {'DISPLAY': ':0', 03:47:07 INFO - 'HOME': '/home/cltbld', 03:47:07 INFO - 'LANG': 'en_US.UTF-8', 03:47:07 INFO - 'LANGUAGE': 'en_US:en', 03:47:07 INFO - 'LOGNAME': 'cltbld', 03:47:07 INFO - 'MAIL': '/var/mail/cltbld', 03:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:07 INFO - 'MOZ_NO_REMOTE': '1', 03:47:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:07 INFO - 'NO_EM_RESTART': '1', 03:47:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:07 INFO - 'PWD': '/builds/slave/test', 03:47:07 INFO - 'SHELL': '/bin/bash', 03:47:07 INFO - 'SHLVL': '1', 03:47:07 INFO - 'TERM': 'linux', 03:47:07 INFO - 'TMOUT': '86400', 03:47:07 INFO - 'USER': 'cltbld', 03:47:07 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 03:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:07 INFO - '_': '/tools/buildbot/bin/python'} 03:47:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:47:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:47:07 INFO - Cleaning up... 03:47:07 INFO - Return code: 0 03:47:07 INFO - Running post-action listener: _resource_record_post_action 03:47:07 INFO - Running post-action listener: _start_resource_monitoring 03:47:07 INFO - Starting resource monitoring. 03:47:07 INFO - [mozharness: 2017-09-13 10:47:07.643866Z] Finished create-virtualenv step (success) 03:47:07 INFO - [mozharness: 2017-09-13 10:47:07.644338Z] Running install step. 03:47:07 INFO - Running pre-action listener: _resource_record_pre_action 03:47:07 INFO - Running main action method: install 03:47:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:47:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:47:07 INFO - Reading from file tmpfile_stdout 03:47:07 INFO - Detecting whether we're running mozinstall >=1.0... 03:47:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:47:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:47:07 INFO - Reading from file tmpfile_stdout 03:47:07 INFO - Output received: 03:47:07 INFO - Usage: mozinstall [options] installer 03:47:07 INFO - Options: 03:47:07 INFO - -h, --help show this help message and exit 03:47:07 INFO - -d DEST, --destination=DEST 03:47:07 INFO - Directory to install application into. [default: 03:47:07 INFO - "/builds/slave/test"] 03:47:07 INFO - --app=APP Application being installed. [default: firefox] 03:47:07 INFO - mkdir: /builds/slave/test/build/application 03:47:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:47:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:47:24 INFO - Reading from file tmpfile_stdout 03:47:24 INFO - Output received: 03:47:24 INFO - /builds/slave/test/build/application/firefox/firefox 03:47:24 INFO - Running post-action listener: _resource_record_post_action 03:47:24 INFO - [mozharness: 2017-09-13 10:47:24.356720Z] Finished install step (success) 03:47:24 INFO - [mozharness: 2017-09-13 10:47:24.356974Z] Running setup-mitmproxy step. 03:47:24 INFO - Running pre-action listener: _resource_record_pre_action 03:47:24 INFO - Running main action method: setup_mitmproxy 03:47:24 INFO - Skipping: mitmproxy is not required 03:47:24 INFO - Running post-action listener: _resource_record_post_action 03:47:24 INFO - [mozharness: 2017-09-13 10:47:24.358175Z] Finished setup-mitmproxy step (success) 03:47:24 INFO - [mozharness: 2017-09-13 10:47:24.358397Z] Running run-tests step. 03:47:24 INFO - Running pre-action listener: _resource_record_pre_action 03:47:24 INFO - Running pre-action listener: _set_gcov_prefix 03:47:24 INFO - Running main action method: run_tests 03:47:24 WARNING - Try message not found. 03:47:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:47:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:47:24 INFO - Python 2.7.3 03:47:24 INFO - Return code: 0 03:47:24 INFO - grabbing minidump binary from tooltool 03:47:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:47:24 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 0x28f8090>, '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 0x28f8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28f2f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:47:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:47:24 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:47:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:47:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:47:24 INFO - Return code: 0 03:47:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:47:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:47:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:47:24 INFO - ENV: RUST_BACKTRACE is now full 03:47:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:47:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:47: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-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-086', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] in /builds/slave/test/build 03:47: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-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-086 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log 03:47:24 INFO - Using env: {'DISPLAY': ':0', 03:47:24 INFO - 'HOME': '/home/cltbld', 03:47:24 INFO - 'LANG': 'en_US.UTF-8', 03:47:24 INFO - 'LANGUAGE': 'en_US:en', 03:47:24 INFO - 'LOGNAME': 'cltbld', 03:47:24 INFO - 'MAIL': '/var/mail/cltbld', 03:47:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:47:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:47:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:24 INFO - 'MOZ_NO_REMOTE': '1', 03:47:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:47:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:24 INFO - 'NO_EM_RESTART': '1', 03:47:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:24 INFO - 'PWD': '/builds/slave/test', 03:47:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:47:24 INFO - 'RUST_BACKTRACE': 'full', 03:47:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:47:24 INFO - 'SHELL': '/bin/bash', 03:47:24 INFO - 'SHLVL': '1', 03:47:24 INFO - 'TERM': 'linux', 03:47:24 INFO - 'TMOUT': '86400', 03:47:24 INFO - 'USER': 'cltbld', 03:47:24 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 03:47:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:24 INFO - '_': '/tools/buildbot/bin/python'} 03:47:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-086', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log'] with output_timeout 3600 03:47:24 INFO - mozversion application_buildid: 20170913092615 03:47:24 INFO - mozversion application_changeset: 124f2094635bbf1901c1d14fc2c0a80bf7f847bb 03:47:24 INFO - mozversion application_display_name: Nightly 03:47:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:47:24 INFO - mozversion application_name: Firefox 03:47:24 INFO - mozversion application_remotingname: firefox 03:47:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:47:24 INFO - mozversion application_vendor: Mozilla 03:47:24 INFO - mozversion application_version: 57.0a1 03:47:24 INFO - mozversion platform_buildid: 20170913092615 03:47:24 INFO - mozversion platform_changeset: 124f2094635bbf1901c1d14fc2c0a80bf7f847bb 03:47:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:47:24 INFO - mozversion platform_version: 57.0a1 03:47:24 INFO - using testdate: 1505299644 03:47:24 INFO - actual date: 1505299644 03:47:24 INFO - starting webserver on 'localhost:47040' 03:47:24 INFO - SUITE-START | Running 2 tests 03:47:24 INFO - TEST-START | dromaeo_css 03:47:24 INFO - Initialising browser for dromaeo_css test... 03:47:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47040/getInfo.html -profile /tmp/tmp0fYsaZ/profile 03:47:24 INFO - TEST-INFO | started process 28928 (/builds/slave/test/build/application/firefox/firefox http://localhost:47040/getInfo.html) 03:47:29 INFO - TEST-INFO | 28928: exit 0 03:47:29 INFO - Browser initialized. 03:47:29 INFO - Running cycle 1/1 for dromaeo_css test... 03:47:29 INFO - Using env: {'DISPLAY': ':0', 03:47:29 INFO - 'HOME': '/home/cltbld', 03:47:29 INFO - 'JSGC_DISABLE_POISONING': '1', 03:47:29 INFO - 'LANG': 'en_US.UTF-8', 03:47:29 INFO - 'LANGUAGE': 'en_US:en', 03:47:29 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:47:29 INFO - 'LOGNAME': 'cltbld', 03:47:29 INFO - 'MAIL': '/var/mail/cltbld', 03:47:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:47:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:47:29 INFO - 'MOZ_CRASHREPORTER': '1', 03:47:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:47:29 INFO - 'MOZ_NO_REMOTE': '1', 03:47:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:47:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:47:29 INFO - 'NO_EM_RESTART': '1', 03:47:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:47:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:29 INFO - 'PWD': '/builds/slave/test', 03:47:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:47:29 INFO - 'RUST_BACKTRACE': 'full', 03:47:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:47:29 INFO - 'SHELL': '/bin/bash', 03:47:29 INFO - 'SHLVL': '1', 03:47:29 INFO - 'STYLO_FORCE_DISABLED': '1', 03:47:29 INFO - 'TERM': 'linux', 03:47:29 INFO - 'TMOUT': '86400', 03:47:29 INFO - 'USER': 'cltbld', 03:47:29 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 03:47:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:29 INFO - '_': '/tools/buildbot/bin/python'} 03:47:29 INFO - TEST-INFO | started process 29124 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0fYsaZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:47:30 INFO - PID 29124 | 03:47:30 INFO - PID 29124 | (/builds/slave/test/build/application/firefox/firefox:29177): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:47:30 INFO - PID 29124 | 03:47:41 INFO - PID 29124 | RSS: Main: 163528704 03:47:41 INFO - PID 29124 | 03:50:37 INFO - PID 29124 | Cycle 1(1): loaded http://localhost:47040/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47040/tests/dromaeo/cssquery-ext.html) 03:50:39 INFO - PID 29124 | RSS: Main: 166625280 03:50:39 INFO - PID 29124 | 03:53:40 INFO - PID 29124 | Cycle 1(1): loaded http://localhost:47040/tests/dromaeo/cssquery-ext.html (next: http://localhost:47040/tests/dromaeo/cssquery-jquery.html) 03:53:41 INFO - PID 29124 | RSS: Main: 162213888 03:53:41 INFO - PID 29124 | 03:56:42 INFO - PID 29124 | Cycle 1(1): loaded http://localhost:47040/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47040/tests/dromaeo/cssquery-mootools.html) 03:56:43 INFO - PID 29124 | RSS: Main: 164843520 03:56:43 INFO - PID 29124 | 03:59:45 INFO - PID 29124 | Cycle 1(1): loaded http://localhost:47040/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47040/tests/dromaeo/cssquery-prototype.html) 03:59:45 INFO - PID 29124 | RSS: Main: 163348480 03:59:45 INFO - PID 29124 | 04:02:48 INFO - PID 29124 | Cycle 1(1): loaded http://localhost:47040/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47040/tests/dromaeo/cssquery-yui.html) 04:02:49 INFO - PID 29124 | RSS: Main: 164634624 04:02:49 INFO - PID 29124 | 04:05:50 INFO - PID 29124 | Cycle 1(1): loaded http://localhost:47040/tests/dromaeo/cssquery-yui.html (next: http://localhost:47040/tests/dromaeo/cssquery-dojo.html) 04:05:50 INFO - PID 29124 | RSS: Main: 167268352 04:05:50 INFO - PID 29124 | 04:05:50 INFO - PID 29124 | __start_tp_report 04:05:50 INFO - PID 29124 | _x_x_mozilla_page_load 04:05:50 INFO - PID 29124 | _x_x_mozilla_page_load_details 04:05:50 INFO - PID 29124 | |i|pagename|runs| 04:05:50 INFO - PID 29124 | |0;dojo.html;3832.0611450194706;3845.3078445879732;3849.6342847429482;3866.4007078902773;3867.7872717000573;6166.414190651892;6233.563650544464;6297.842553936154;6302.306746257914;6382.138411314477;6258.687065646723;6297.181366422359;6297.433231009209;6330.715117819697;6332.176817013793;5659.943400565983;5732.656040637554;5755.625884317506;5768.336641286264;5770.134479828017;170.1533347102968;5685.346185188715;5737.368889422181;5762.049261871807;5781.479666830005;5498.917516237259;5586.804461843836;5617.887642247153;5720.11338242573;5760.8271751847515;5634.492895639412;5672.801451949162;5707.914381284284;5747.62640428375;5758.884822303572;5627.803026894039;5639.661620302754;5708.686022268774;5719.19931209631;5722.141678748179;6229.781957631507;6304.148939893102;6312.3372045934975;6373.808785736389;6416.582922110095;6718.529702920798;6835.555688880207;6848.828779280508;6868.862622747517;6922.203946546111;6881.793546193622;6932;6937.063496427968;6943.166819981635;6943.409810166142;6369.044643303495;6392.584482008701;6435.8391040223905;6457.709403076826;6463.903041454382;7034.331738484836;7045.978333141666;7116.181639111465;7138.46461515378;7140.892886606705;6641.33586641332;6749.898751518731;6760.898586521206;6773.62745049027;6786.524943254023;6841.555298905555;6858.485613579001;6898.862022759517;6922.7923162305215;6945.89581156283;6808;6830.248672646005;6901.482388820758;6934.375906168469;6967.616781077089;6864.176298844171;6869.347411995853;6877.449804015667;6960.234374218832;6965.756198533027;4921.729304888266;4967.652264341461;4994.40067191932;5022.221555658879;5057.140286151363;4526.909461810746;4566.771661416916;4592;4603.49361570227;4624.468186158635;6132.63204207749;6183.876322473615;6223.439890409885;6292.4966002719675;6295.559310848196;6741.1236539249585;6795.422389096883;6845;6912.239653638096;6932.0988271524375;6163.3528479509905;6196.287426945869;6197.876042479125;6236.719347629368;6245.781397651061;5471.644343117685;5484.808031718871;5555.416681248492;5560.388357280696;5565.805196818092;6904.516683832184;6916.896246556305;6917.031615573824;6918.481113916477;6918.8962165567555;6771.01820236063;6776.7628133015105;6780.898286525608;6783.152105986752;6784.118064651662;6808.251092379934;6874.896876546756;6877.243503214542;6890.276520965226;6923.584584924921;6751.93248067513;6798.796036118924;6830.829229269308;6850.520463567503;6881.243063262937;6844.418224450778;6896.4827637926355;6898.482613803884;6972.51192416526;6972.825679358057;6505.121808555974;6600;6613.470922326299;6685.298043705341;6694.765683201259;6510.21877374718;6535.934640653722;6548.345165483413;6556.114924485324;6592.044153597797;6510.902336465051;6515.2833188348295;6584.868302634016;6586.012098185119;6589.077529145828;6622.867542649216;6654.800355989328;6660.167479065117;6714.362135597111;6718.093057437183;6400.615963042233;6414.679266036773;6469.676516174267;6509.479241660751;6516.446102081376 04:05:50 INFO - PID 29124 | |1;ext.html;949.1931857920769;958.5973890965794;996.8504724291355;1008.6822650864978;1011.802698473797;42440.15119697608;44092.33861492072;44314.77842610786;44451.333230001495;44569.33146002812;41028.38457423141;41052.58947410525;41194.176116477654;41259.58740412595;41285.7935710321;7335.88996165058;7498.4001279897475;7499.47503674743;7504.099508059041;7506.399488040971;7341.119065712122;7397.81505462365;7434.9256507434775;7441.144268409148;7441.739539116132;7421.0723659542555;8054.758357249262;8224.177582241759;8231.629576669035;8234.794130146734;8117.594120293991;8168.591570421485;8184.304334131606;8185.631646575918;8190.918090819075;7843.960780196063;8067.878981815277;8071.31393831525;8143;8218.178182181735;41369.37945930813;41546.79226603878;41682.1663566727;41729;41878.1624367514;3806.9619303806885;3848.076461649212;3848.9615103848882;3852.191039881628;3857.691384689246;33069.17327066843;33108.6689133108;33119.6688033119;33147.6685233147;33219.169520761934;5408.594355423359;5419.729013549346;5426.375966763833;5451.318585176853;5475.972620136914;219324;220482;220578;223795;238330;51366;51472.22791658128;51761.48238517567;51832.48167518352;51862.481375185766;54377.45622543803;54467;54680.45319546833;54684;54765.452345476035;16073.598160046093;16239;16258.349666013228;16275.674486510203;16521.504354869372;13426.187464692142;13498.52755153565;13531.932340338431;13639.045266831226;13661.795073073912;14355.497557585435;14370.425182992773;14394.42422303117;14426.278686065654;14533.927330363493;5827.339208325607;5881.264841894763;5921.792737254262;5985.910211346833;5993.250843644544;1106.4301884529473;1119.3787447966456;1119.6864877834219;1125.4372813593204;1130.197559732599;25341.493170136495;25534;25541.106061287766;25732.742672573033;25913.481730365664;1199.4182821331647;1205.6383085074617;1212.805951047828;1216.5681183179843;1222.370479203211;13607.795883061763;13613.931930340483;13662.385192666354;13788.379522921492;14056.71886562263;294224;296258;296483;297026;298128;58183;58421.70789146027;58449.415505845245;58532.70733646305;58565.707171464725;1520.6046427928818;1528.4192007037254;1544.4825983295498;1554.4015554011787;1570.9057456552644;25256.87371563204;25328.620070699322;25393.49213015766;25513.23460296194;25605.743942560337;40956.18087638171;41015.58984410237;41052.384214237405;41092.58907410887;41153.38269926013;240986;242190;243093;243278;243390;34333.313333733684;38574.42138367983;38894.6110538891;39133;39152;50111.74944125256;50639.49360506348;50783;51057.74471127621;51252;34767.30465390729;34995;35013.47479787856;35441.46837797383;35740.82129589335;37082.25835483329;37087.81456092702;37199.07002324964;37272.254554908206;37409.25181496409;42737.786311068245;43896.34155487606;43974;44045.77977110094;44128.77935610301;35253.82373088118;35323.82338088293;35591.11022224362;35668.82165589155;36265.45601816028;26905.461890762468;26922.29023551878;26969.325766855985;27035.324116897234;27184.184474465797 04:05:50 INFO - PID 29124 | |2;jquery.html;555.1783360626273;574.4198359656746;581.6248519704787;597.4891467795035;598.2462097756827;23295.534089318204;23981.880090599523;24023;24042.879785601068;24051.759482405214;25073.874630626866;26080.739192608067;26305.21084367467;26630;26638.73361266387;6572.21133463985;6694.297098804628;6728.192616885956;6729.528932974694;6731.798046058601;6435.420812126908;6589.637569933651;6695.665216739168;6698.531102822804;6705.731770729165;6661.600303981752;6664.966675166617;6667.366600172976;6667.566608170478;6671.466282697373;6410.134631824737;6647.634380109068;6654.833629159286;6658;6661.600303981752;6186.474149697281;6198.34917333678;6200.131981522591;6200.162977997958;6201.224846894138;29695.70304296951;30155.396892062035;30163.849180754176;30193.396132077454;30231;2276.8975396107217;2297.4945511987335;2301.8849057547054;2310.688057112302;2310.745817960023;19866.900665496723;20308;20817.479563010893;20842.5831483371;20898;2361.409647588103;2384.4873352229356;2437.97562024381;2448.5959816630234;2463.8028957683523;143576.28211858874;147295;150328.2483587586;152669;161353;15350.539483815503;15477.458288959833;15595.688086238211;15992.200389980455;16009.439669611507;24918.875405622857;25030;25042;25054.12310569158;25065.87467062653;10451.581936722598;10595.788084238273;10650.147988160928;10651.84022239667;10818.29681070728;9818;9822.65620703272;9833.901660983442;9908.25688073383;9929.80140397188;9250.95374523123;9446.952765236267;9472.242220622335;9501.952490237505;9861.901380986243;2629.4872499862345;2654.2037388783674;2704.553748631493;2714.6335244742104;2738.3564862257354;1007.3653598233067;1038.0864838941684;1039.2673165418362;1040.78143589847;1056.0759335581367;20648.58702825935;20823;20888.268910588056;21116.47208819792;21220.89389553073;1079.4764539198482;1081.426843772786;1097.5609756097624;1098.6758906122714;1099.8900109988938;11144.219904606762;11357;11360.715982100513;11565;11607.883921160848;620450;626279;629668;632253;643268;126770.36614816867;127692.36153819357;128394;129098.35450822685;129540.3522982379;1105.8341248812742;1162.337467643435;1215.070471089617;1226.1294480918468;1270.4219580091037;21476.570468590227;21707.674384884554;21865.453363666034;21937.67093493631;22150.446238844157;60659.39340606538;61630.91845407622;62104.689476552325;62494;64114;77995.22004780106;78154;78912;79365.60317198378;81411;14727.631809204855;15103.546893592771;15185;15196.772048419061;15202.46791362297;23053;23432.531349373257;23436.414089647213;23580.528389432457;23811.404714881573;19480.123394447;19556.21775128979;19616.705749414057;19715;19820.90089549543;16496.505104846874;16549.58626034359;17283.654326913642;17553.297868085134;17984.190711417756;19149;19730;19873.205071796965;20163.89918050459;20180;14662.926685366505;14773.778393323893;14820.7776883349;14855.702885942437;14873.85126148768;19358;19366.709499357803;19465.4160375189;19504.34213091903;19571.315003974792 04:05:50 INFO - PID 29124 | |3;mootools.html;1409.851965543618;1448.5591777854315;1473.587395529251;1476.557032890133;1477.6896851661152;13852.515161969333;14514.274286285683;14550.054246473985;16776.245068971893;17378.13109344531;17000.234989425506;17241.827581724177;17255.223514941827;17255.91372043138;17293.740593891103;2731.617573539706;2822.209781261245;2839.630847989769;2867.068202834077;2870.153304775086;2871.8994835180774;2904.6950070242638;2914.591957126004;2917.781166412517;2942.7351538361545;2677.6786785585755;2731.6721993360825;2735.753782159605;2781.5410457274525;2837.7162283771663;2824.7740180785486;2838.3897462045657;2849.715028497144;2855.728705772948;2858.5997960285576;2744.8353098814137;2750.972490275092;2770.1827960751625;2772.625695531118;2774.375765452777;16190.190490475547;16543.83456165435;16579.751303730452;16586.58533536659;16603.169841507875;8115;8496.277816385615;8501.234888860032;8506.191911768372;8542.658293668246;5585.525230355424;5712.80005199816;5764.798232061858;5841.211436456109;5865.120231965197;2011.0950072467301;2072.849568489488;2081.7814129516487;2116.386247988082;2142.4108370198164;29785.55321670155;30062;30071.69928300733;30093;30096.54855177152;6997.265287144878;7002.299770023059;7239.674214660353;7252.927470725331;7265.273472652692;19288.421347359603;19291.710624340645;19363.806361936484;19376.903115484612;19384.903075484814;8416.368772342099;8606.26846718036;8629.52537610425;8630.611622477003;8635.568221589021;7651.961740191264;7662;7773.494722842997;7889.053313602404;7893.1712170222445;5666.546676265889;5698.1196205253;5727.570432217601;5736.110902810071;5753.568482363839;1620.894641848276;1652.3803573659877;1661.1279078483892;1663.5924198571427;1681.9831801682071;943.9716808495756;961.038961038961;968.2931460034215;972.6401231544373;973.5181085362649;8426.789330266793;8453.112423195476;8479.576021198915;8525.403221774417;8540.14598540141;779.1546172403006;784.2980532423448;790.237420888845;801.338895411288;804.2962407893093;5660.179274005246;5683.119116536944;5792.781701548606;5874.765009399662;5893.528517718572;24051.879740601184;24881;24909.875450622632;24920.252392428258;25252.621210681853;24793.5041299173;24841;24859.254222373358;24994.50010999806;25091.498170036863;11584.131190160771;12199.75600488003;12247.69380765488;12273.140880138304;12384.31886246247;9408.43549387039;9483.336166468282;9486.762830929281;9512.95243523778;9516.333856630246;20214.494637634176;20594;20594.176232950515;21084.367468975954;21468;21635;21656.133754649636;21699.240526582125;21983.450413739785;22356.77643223547;12018.75962480763;12111.273323600612;12213.206141600767;12218;12238.816417753918;15192.392304307705;15507.844921551092;15520.456784012506;15521.37914483408;15523.922380388025;11282.94358528202;11307;11412.942935285602;11429.371384573928;11619.709507262049;10870.402127883042;11013.118950483782;11086.61196858106;11276.9436152822;11428.942855286003;10659.307145035547;10842;10885.619003334845;10898.400587967737;10909.34543927367;8122.878156827534;8235.50586964784;8240.670373185245;8281.54451505149;8337.874931876147;8023.398245131521;8133.308668763221;8139.430239883151;8212.630431630472;8213.342932565261 04:05:50 INFO - PID 29124 | |4;prototype.html;508.42293996314197;547.5865721380359;547.6850810783799;555.3141869790805;557.0391075394945;6198.287196972349;6237.37626237376;6312.222622046296;6313.558050936436;6402.615843049421;6170.568060235785;6208.951046154758;6236.625802451856;6268.749250029993;6332.683365831713;5159.871003224921;5168.198929165967;5173.5085166909275;5176.948230517703;5206.167013277876;4866.345442549533;5006.148954677696;5105.106606343894;5146.202338637517;5195.584353251749;4953.269627505723;4963.478834722357;4966.826161084363;5009.649324547283;5017.422996355411;4935.555799978001;4966.2054071348775;4984.501549845023;4989.451160372356;5026.29631851541;4421;4472.105578884236;4492.977535112336;4515.63874890008;4538.1377538267625;6066.211392518989;6067.605605635619;6142.293636231846;6191.709067644955;6237.87524249517;5842.97078514609;5899.203607512974;5936.228290322274;5952.7023648817385;5961.403859614047;6684;6772.966135169342;6781.627010514419;6813.965930170367;6834.624095674736;5051;5082.923756143624;5095.541401273866;5101.82143624975;5123.3339665843205;6698.263191048981;6927;6954.860902781916;6978.232394436557;7034.788956331318;5140.228965655182;5166.689998600096;5182.144946083854;5217.791288348423;5234;5276.8390953990065;5280.970210808932;5288.127458969227;5309.33633295838;5309.389420216725;5848.356680765112;5867.090600956859;5873.559483038789;5904.468597826216;5961.7615295387695;6718.044060613314;6720.563163394364;6749.646478021648;6780.93219067803;6783.38949494538;1817.4547635709234;1831.7527133837027;1832.1480511562188;1833.5599456130699;1837.9523671507113;1230.8921970226868;1272.63729836996;1293.6765808547864;1298.6753311672082;1307.1242267680677;6087.726052327655;6102.664353460514;6103.816885493442;6119.571629985947;6152.569320147636;6394.680265986682;6723.7646682365885;6766.188057433041;6835.359513716771;6946.756863509753;5957.761689532457;6022.97609406402;6056.454919057306;6078;6141.263048434127;3558.3061303045656;3581.212133330663;3585.9779962710504;3593.8742144025355;3615.783053016775;6909.930900691029;6921.584704917721;6951.131108611424;6958.652067396712;7019.543729657556;6891.862162756717;6927.203371612229;6946.305369463114;6965.303469653096;6990.790276291719;6842.144731908512;6894.689738961659;6913.930860691429;6947.722091116501;6968.860622787516;6771.119754432089;6774.8645027100165;6853.348931851466;6862.622555759282;6870.416014638811;6758.7296508139125;6826.429921118071;6826.658667066726;6852.177738671192;6864.725410983705;6929.89605155913;7000.929990700028;7014.2635023323855;7024.192217895007;7054;5213.608979326642;5231.189165679326;5241.9737901310245;5253.290805741176;5275.604329675367;6584.703688333941;6611.611561572124;6816.727330906709;6826.385625293748;6925.722971081101;6451.419372256331;6505.73977040932;6513.576617519846;6544.9672751635935;6619.7683081093855;6444.806655800334;6467.773627923;6517.185351831021;6553.344665533408;6557.442617377576;6252.843678908063;6380.808575742735;6428.389303016206;6546.247181573989;6576.80269591913;6487.610743355413;6545.770898018546;6597.736090556324;6608.240052394036;6692.966535167293;6402.743890244338;6407.295197528175;6445.51753096781;6475.255345635313;6485.837854053686 04:05:50 INFO - PID 29124 | |5;yui.html;972.810301991112;1000.5947591225553;1007.1640538353167;1013.4780587997183;1029.7734498410352;20267.08798104085;20689.586208275825;20714.067866946003;20730.170793168254;20731.896340518313;18827.341043063494;18836.246550137977;18855.811441885577;19112.522186945334;19336.323228686997;1015.9441230732307;1046.1682962045193;1046.4140081554315;1046.5081411736471;1047.2669131607847;1028.3058935218735;1031.4997226345217;1051.6844946516053;1057.323313079626;1061.47987486132;1136.3806725334712;1142.817149256115;1143.9885601144008;1146.403869987606;1146.8050431426636;1059.7403636109125;1084.3331351219022;1131.835883796849;1134.909207263421;1135.829625556165;802.5265093594807;809.9878501822477;811.7929927868415;812.3420029775841;814.5967745965785;21200.363989080353;21403.67894481584;21404.357869263946;21421.785782142135;21423.785762142335;23201.419964500852;23913;23992;23999.760002399926;24017;18615;18641.62716745671;18736.62526749471;18741.34405295808;18762.906185468986;4007.8998025049314;4032.1330913853367;4034.394840773878;4035.1122752994293;4037.717359784787;160039;160707;163318;163665;166772.16613916974;17942.641147176982;17992.190351434216;18285.90857045733;18457;18463.446096617125;34577.30845383078;34637;34931.4760278596;35034.82482587622;35390;8640.654373825102;8687.522186279815;8722.781930451663;8746.56267186638;8766.079561645935;4435.534268901783;4606.631469482433;4677.883052923704;4693.647976401783;4732.763361831963;6122.724477398528;6169.383061693795;6208.813735587849;6224;6250.531210159256;1076.1928553584812;1077.7197928538637;1122.270524159303;1126.0878688262533;1127.644791890552;736.6243215959792;739.4343327354578;755.5051441305953;761.0942977856333;767.9577623230664;11986.64040078799;12017.759644807054;12204;12239.082068844695;12466.81299780504;738.8189893476133;741.7218543046357;746.6042997211487;750.8047907544078;759.2483441392981;5571.052921003439;5582;5631.605787594829;5670.5463562914865;5693.259876216148;158321;158451;158578.20710896372;160384;165898;136534;140093;140202;142702;143701;115599;118991;119148.40425797815;119256;120145.39927300482;10511.526981285708;10562;10624.309419887682;10683.94658026705;10748.89251107479;56561;56906.43093569177;56968.715156423954;57723.71138144282;58026.7098664504;73335;74677.6266118666;74846;75266.24733752811;76845.23154768381;16577.17114144264;16814;16815.66368672595;16837.91581042087;16930.32278708838;17887;18417.907910460362;18700.81299186991;18776.24895004239;18923.621527569096;15522.146281954148;15569.84430155684;15590.14254216029;15763;15823.762643560125;15940.601484962968;16117.838821611635;16218.513444596681;16386.262618181972;16457.753133703245;16036.839631603534;16172.110533920277;16253.349866005228;16384.918075409547;16388.590285242964;13017.349132543146;13218.537351192663;13232.13991090576;13423.328833558478;13471.797923030967;12799.48802047945;12832.935835320764;13001.634828342887;13116.540921067717;13179.14335568185 04:05:50 INFO - PID 29124 | __end_tp_report 04:05:50 INFO - PID 29124 | __start_cc_report 04:05:50 INFO - PID 29124 | _x_x_mozilla_cycle_collect,356 04:05:50 INFO - PID 29124 | __end_cc_report 04:05:50 INFO - PID 29124 | __startTimestamp1505300750842__endTimestamp 04:05:50 INFO - PID 29124 | 04:05:50 INFO - PID 29124 | ------- Summary: start ------- 04:05:50 INFO - PID 29124 | Number of tests: 6 04:05:50 INFO - PID 29124 | 04:05:50 INFO - PID 29124 | [#0] dojo.html Cycles:165 Average:6255.09 Median:6515.28 stddev:880.11 (13.5%) stddev-sans-first:862.04 04:05:50 INFO - PID 29124 | Values: 3832.1 3845.3 3849.6 3866.4 3867.8 6166.4 6233.6 6297.8 6302.3 6382.1 6258.7 6297.2 6297.4 6330.7 6332.2 5659.9 5732.7 5755.6 5768.3 5770.1 170.2 5685.3 5737.4 5762.0 5781.5 5498.9 5586.8 5617.9 5720.1 5760.8 5634.5 5672.8 5707.9 5747.6 5758.9 5627.8 5639.7 5708.7 5719.2 5722.1 6229.8 6304.1 6312.3 6373.8 6416.6 6718.5 6835.6 6848.8 6868.9 6922.2 6881.8 6932.0 6937.1 6943.2 6943.4 6369.0 6392.6 6435.8 6457.7 6463.9 7034.3 7046.0 7116.2 7138.5 7140.9 6641.3 6749.9 6760.9 6773.6 6786.5 6841.6 6858.5 6898.9 6922.8 6945.9 6808.0 6830.2 6901.5 6934.4 6967.6 6864.2 6869.3 6877.4 6960.2 6965.8 4921.7 4967.7 4994.4 5022.2 5057.1 4526.9 4566.8 4592.0 4603.5 4624.5 6132.6 6183.9 6223.4 6292.5 6295.6 6741.1 6795.4 6845.0 6912.2 6932.1 6163.4 6196.3 6197.9 6236.7 6245.8 5471.6 5484.8 5555.4 5560.4 5565.8 6904.5 6916.9 6917.0 6918.5 6918.9 6771.0 6776.8 6780.9 6783.2 6784.1 6808.3 6874.9 6877.2 6890.3 6923.6 6751.9 6798.8 6830.8 6850.5 6881.2 6844.4 6896.5 6898.5 6972.5 6972.8 6505.1 6600.0 6613.5 6685.3 6694.8 6510.2 6535.9 6548.3 6556.1 6592.0 6510.9 6515.3 6584.9 6586.0 6589.1 6622.9 6654.8 6660.2 6714.4 6718.1 6400.6 6414.7 6469.7 6509.5 6516.4 04:05:50 INFO - PID 29124 | 04:05:50 INFO - PID 29124 | [#1] ext.html Cycles:180 Average:43471.22 Median:3850.58 stddev:66737.76 (1733.2%) stddev-sans-first:66848.60 04:05:50 INFO - PID 29124 | Values: 949.2 958.6 996.9 1008.7 1011.8 42440.2 44092.3 44314.8 44451.3 44569.3 41028.4 41052.6 41194.2 41259.6 41285.8 7335.9 7498.4 7499.5 7504.1 7506.4 7341.1 7397.8 7434.9 7441.1 7441.7 7421.1 8054.8 8224.2 8231.6 8234.8 8117.6 8168.6 8184.3 8185.6 8190.9 7844.0 8067.9 8071.3 8143.0 8218.2 41369.4 41546.8 41682.2 41729.0 41878.2 3807.0 3848.1 3849.0 3852.2 3857.7 33069.2 33108.7 33119.7 33147.7 33219.2 5408.6 5419.7 5426.4 5451.3 5476.0 219324.0 220482.0 220578.0 223795.0 238330.0 51366.0 51472.2 51761.5 51832.5 51862.5 54377.5 54467.0 54680.5 54684.0 54765.5 16073.6 16239.0 16258.3 16275.7 16521.5 13426.2 13498.5 13531.9 13639.0 13661.8 14355.5 14370.4 14394.4 14426.3 14533.9 5827.3 5881.3 5921.8 5985.9 5993.3 1106.4 1119.4 1119.7 1125.4 1130.2 25341.5 25534.0 25541.1 25732.7 25913.5 1199.4 1205.6 1212.8 1216.6 1222.4 13607.8 13613.9 13662.4 13788.4 14056.7 294224.0 296258.0 296483.0 297026.0 298128.0 58183.0 58421.7 58449.4 58532.7 58565.7 1520.6 1528.4 1544.5 1554.4 1570.9 25256.9 25328.6 25393.5 25513.2 25605.7 40956.2 41015.6 41052.4 41092.6 41153.4 240986.0 242190.0 243093.0 243278.0 243390.0 34333.3 38574.4 38894.6 39133.0 39152.0 50111.7 50639.5 50783.0 51057.7 51252.0 34767.3 34995.0 35013.5 35441.5 35740.8 37082.3 37087.8 37199.1 37272.3 37409.3 42737.8 43896.3 43974.0 44045.8 44128.8 35253.8 35323.8 35591.1 35668.8 36265.5 26905.5 26922.3 26969.3 27035.3 27184.2 04:05:50 INFO - PID 29124 | 04:05:50 INFO - PID 29124 | [#2] jquery.html Cycles:180 Average:40282.33 Median:12803.14 stddev:105168.48 (821.4%) stddev-sans-first:105421.20 04:05:50 INFO - PID 29124 | Values: 555.2 574.4 581.6 597.5 598.2 23295.5 23981.9 24023.0 24042.9 24051.8 25073.9 26080.7 26305.2 26630.0 26638.7 6572.2 6694.3 6728.2 6729.5 6731.8 6435.4 6589.6 6695.7 6698.5 6705.7 6661.6 6665.0 6667.4 6667.6 6671.5 6410.1 6647.6 6654.8 6658.0 6661.6 6186.5 6198.3 6200.1 6200.2 6201.2 29695.7 30155.4 30163.8 30193.4 30231.0 2276.9 2297.5 2301.9 2310.7 2310.7 19866.9 20308.0 20817.5 20842.6 20898.0 2361.4 2384.5 2438.0 2448.6 2463.8 143576.3 147295.0 150328.2 152669.0 161353.0 15350.5 15477.5 15595.7 15992.2 16009.4 24918.9 25030.0 25042.0 25054.1 25065.9 10451.6 10595.8 10650.1 10651.8 10818.3 9818.0 9822.7 9833.9 9908.3 9929.8 9251.0 9447.0 9472.2 9502.0 9861.9 2629.5 2654.2 2704.6 2714.6 2738.4 1007.4 1038.1 1039.3 1040.8 1056.1 20648.6 20823.0 20888.3 21116.5 21220.9 1079.5 1081.4 1097.6 1098.7 1099.9 11144.2 11357.0 11360.7 11565.0 11607.9 620450.0 626279.0 629668.0 632253.0 643268.0 126770.4 127692.4 128394.0 129098.4 129540.4 1105.8 1162.3 1215.1 1226.1 1270.4 21476.6 21707.7 21865.5 21937.7 22150.4 60659.4 61630.9 62104.7 62494.0 64114.0 77995.2 78154.0 78912.0 79365.6 81411.0 14727.6 15103.5 15185.0 15196.8 15202.5 23053.0 23432.5 23436.4 23580.5 23811.4 19480.1 19556.2 19616.7 19715.0 19820.9 16496.5 16549.6 17283.7 17553.3 17984.2 19149.0 19730.0 19873.2 20163.9 20180.0 14662.9 14773.8 14820.8 14855.7 14873.9 19358.0 19366.7 19465.4 19504.3 19571.3 04:05:50 INFO - PID 29124 | 04:05:50 INFO - PID 29124 | [#3] mootools.html Cycles:180 Average:10238.82 Median:2760.58 stddev:7593.84 (275.1%) stddev-sans-first:7586.17 04:05:50 INFO - PID 29124 | Values: 1409.9 1448.6 1473.6 1476.6 1477.7 13852.5 14514.3 14550.1 16776.2 17378.1 17000.2 17241.8 17255.2 17255.9 17293.7 2731.6 2822.2 2839.6 2867.1 2870.2 2871.9 2904.7 2914.6 2917.8 2942.7 2677.7 2731.7 2735.8 2781.5 2837.7 2824.8 2838.4 2849.7 2855.7 2858.6 2744.8 2751.0 2770.2 2772.6 2774.4 16190.2 16543.8 16579.8 16586.6 16603.2 8115.0 8496.3 8501.2 8506.2 8542.7 5585.5 5712.8 5764.8 5841.2 5865.1 2011.1 2072.8 2081.8 2116.4 2142.4 29785.6 30062.0 30071.7 30093.0 30096.5 6997.3 7002.3 7239.7 7252.9 7265.3 19288.4 19291.7 19363.8 19376.9 19384.9 8416.4 8606.3 8629.5 8630.6 8635.6 7652.0 7662.0 7773.5 7889.1 7893.2 5666.5 5698.1 5727.6 5736.1 5753.6 1620.9 1652.4 1661.1 1663.6 1682.0 944.0 961.0 968.3 972.6 973.5 8426.8 8453.1 8479.6 8525.4 8540.1 779.2 784.3 790.2 801.3 804.3 5660.2 5683.1 5792.8 5874.8 5893.5 24051.9 24881.0 24909.9 24920.3 25252.6 24793.5 24841.0 24859.3 24994.5 25091.5 11584.1 12199.8 12247.7 12273.1 12384.3 9408.4 9483.3 9486.8 9513.0 9516.3 20214.5 20594.0 20594.2 21084.4 21468.0 21635.0 21656.1 21699.2 21983.5 22356.8 12018.8 12111.3 12213.2 12218.0 12238.8 15192.4 15507.8 15520.5 15521.4 15523.9 11282.9 11307.0 11412.9 11429.4 11619.7 10870.4 11013.1 11086.6 11276.9 11428.9 10659.3 10842.0 10885.6 10898.4 10909.3 8122.9 8235.5 8240.7 8281.5 8337.9 8023.4 8133.3 8139.4 8212.6 8213.3 04:05:50 INFO - PID 29124 | 04:05:50 INFO - PID 29124 | [#4] prototype.html Cycles:180 Average:5647.26 Median:6203.62 stddev:1576.58 (25.4%) stddev-sans-first:1533.09 04:05:50 INFO - PID 29124 | Values: 508.4 547.6 547.7 555.3 557.0 6198.3 6237.4 6312.2 6313.6 6402.6 6170.6 6209.0 6236.6 6268.7 6332.7 5159.9 5168.2 5173.5 5176.9 5206.2 4866.3 5006.1 5105.1 5146.2 5195.6 4953.3 4963.5 4966.8 5009.6 5017.4 4935.6 4966.2 4984.5 4989.5 5026.3 4421.0 4472.1 4493.0 4515.6 4538.1 6066.2 6067.6 6142.3 6191.7 6237.9 5843.0 5899.2 5936.2 5952.7 5961.4 6684.0 6773.0 6781.6 6814.0 6834.6 5051.0 5082.9 5095.5 5101.8 5123.3 6698.3 6927.0 6954.9 6978.2 7034.8 5140.2 5166.7 5182.1 5217.8 5234.0 5276.8 5281.0 5288.1 5309.3 5309.4 5848.4 5867.1 5873.6 5904.5 5961.8 6718.0 6720.6 6749.6 6780.9 6783.4 1817.5 1831.8 1832.1 1833.6 1838.0 1230.9 1272.6 1293.7 1298.7 1307.1 6087.7 6102.7 6103.8 6119.6 6152.6 6394.7 6723.8 6766.2 6835.4 6946.8 5957.8 6023.0 6056.5 6078.0 6141.3 3558.3 3581.2 3586.0 3593.9 3615.8 6909.9 6921.6 6951.1 6958.7 7019.5 6891.9 6927.2 6946.3 6965.3 6990.8 6842.1 6894.7 6913.9 6947.7 6968.9 6771.1 6774.9 6853.3 6862.6 6870.4 6758.7 6826.4 6826.7 6852.2 6864.7 6929.9 7000.9 7014.3 7024.2 7054.0 5213.6 5231.2 5242.0 5253.3 5275.6 6584.7 6611.6 6816.7 6826.4 6925.7 6451.4 6505.7 6513.6 6545.0 6619.8 6444.8 6467.8 6517.2 6553.3 6557.4 6252.8 6380.8 6428.4 6546.2 6576.8 6487.6 6545.8 6597.7 6608.2 6693.0 6402.7 6407.3 6445.5 6475.3 6485.8 04:05:50 INFO - PID 29124 | 04:05:50 INFO - PID 29124 | [#5] yui.html Cycles:180 Average:28940.96 Median:17967.42 stddev:44463.10 (247.5%) stddev-sans-first:44538.24 04:05:50 INFO - PID 29124 | Values: 972.8 1000.6 1007.2 1013.5 1029.8 20267.1 20689.6 20714.1 20730.2 20731.9 18827.3 18836.2 18855.8 19112.5 19336.3 1015.9 1046.2 1046.4 1046.5 1047.3 1028.3 1031.5 1051.7 1057.3 1061.5 1136.4 1142.8 1144.0 1146.4 1146.8 1059.7 1084.3 1131.8 1134.9 1135.8 802.5 810.0 811.8 812.3 814.6 21200.4 21403.7 21404.4 21421.8 21423.8 23201.4 23913.0 23992.0 23999.8 24017.0 18615.0 18641.6 18736.6 18741.3 18762.9 4007.9 4032.1 4034.4 4035.1 4037.7 160039.0 160707.0 163318.0 163665.0 166772.2 17942.6 17992.2 18285.9 18457.0 18463.4 34577.3 34637.0 34931.5 35034.8 35390.0 8640.7 8687.5 8722.8 8746.6 8766.1 4435.5 4606.6 4677.9 4693.6 4732.8 6122.7 6169.4 6208.8 6224.0 6250.5 1076.2 1077.7 1122.3 1126.1 1127.6 736.6 739.4 755.5 761.1 768.0 11986.6 12017.8 12204.0 12239.1 12466.8 738.8 741.7 746.6 750.8 759.2 5571.1 5582.0 5631.6 5670.5 5693.3 158321.0 158451.0 158578.2 160384.0 165898.0 136534.0 140093.0 140202.0 142702.0 143701.0 115599.0 118991.0 119148.4 119256.0 120145.4 10511.5 10562.0 10624.3 10683.9 10748.9 56561.0 56906.4 56968.7 57723.7 58026.7 73335.0 74677.6 74846.0 75266.2 76845.2 16577.2 16814.0 16815.7 16837.9 16930.3 17887.0 18417.9 18700.8 18776.2 18923.6 15522.1 15569.8 15590.1 15763.0 15823.8 15940.6 16117.8 16218.5 16386.3 16457.8 16036.8 16172.1 16253.3 16384.9 16388.6 13017.3 13218.5 13232.1 13423.3 13471.8 12799.5 12832.9 13001.6 13116.5 13179.1 04:05:50 INFO - PID 29124 | -------- Summary: end -------- 04:05:50 INFO - PID 29124 | 04:05:51 INFO - PID 29124 | 04:05:51 INFO - PID 29124 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 04:05:51 INFO - PID 29124 | 04:05:51 INFO - TEST-INFO | 29124: exit 0 04:05:51 INFO - TEST-OK | dromaeo_css | took 1106831ms 04:05:51 INFO - TEST-START | kraken 04:05:51 INFO - Initialising browser for kraken test... 04:05:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47040/getInfo.html -profile /tmp/tmpMLbe57/profile 04:05:51 INFO - TEST-INFO | started process 29501 (/builds/slave/test/build/application/firefox/firefox http://localhost:47040/getInfo.html) 04:05:56 INFO - TEST-INFO | 29501: exit 0 04:05:56 INFO - Browser initialized. 04:05:56 INFO - Running cycle 1/1 for kraken test... 04:05:56 INFO - Using env: {'DISPLAY': ':0', 04:05:56 INFO - 'HOME': '/home/cltbld', 04:05:56 INFO - 'JSGC_DISABLE_POISONING': '1', 04:05:56 INFO - 'LANG': 'en_US.UTF-8', 04:05:56 INFO - 'LANGUAGE': 'en_US:en', 04:05:56 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:05:56 INFO - 'LOGNAME': 'cltbld', 04:05:56 INFO - 'MAIL': '/var/mail/cltbld', 04:05:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:05:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:05:56 INFO - 'MOZ_CRASHREPORTER': '1', 04:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:05:56 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:05:56 INFO - 'MOZ_NO_REMOTE': '1', 04:05:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:05:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:05:56 INFO - 'NO_EM_RESTART': '1', 04:05:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:05:56 INFO - 'PWD': '/builds/slave/test', 04:05:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:05:56 INFO - 'RUST_BACKTRACE': 'full', 04:05:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:05:56 INFO - 'SHELL': '/bin/bash', 04:05:56 INFO - 'SHLVL': '1', 04:05:56 INFO - 'STYLO_FORCE_DISABLED': '1', 04:05:56 INFO - 'TERM': 'linux', 04:05:56 INFO - 'TMOUT': '86400', 04:05:56 INFO - 'USER': 'cltbld', 04:05:56 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836', 04:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:05:56 INFO - '_': '/tools/buildbot/bin/python'} 04:05:56 INFO - TEST-INFO | started process 29699 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMLbe57/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:05:57 INFO - PID 29699 | 04:05:57 INFO - PID 29699 | (/builds/slave/test/build/application/firefox/firefox:29750): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:05:57 INFO - PID 29699 | 04:06:07 INFO - PID 29699 | RSS: Main: 163422208 04:06:07 INFO - PID 29699 | 04:06:10 INFO - PID 29699 | Cycle 1(1): loaded http://localhost:47040/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47040/tests/kraken/driver.html?testName=audio-beat-detection) 04:06:11 INFO - PID 29699 | RSS: Main: 168222720 04:06:11 INFO - PID 29699 | 04:06:13 INFO - PID 29699 | Cycle 1(1): loaded http://localhost:47040/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47040/tests/kraken/driver.html?testName=audio-dft) 04:06:14 INFO - PID 29699 | RSS: Main: 168996864 04:06:14 INFO - PID 29699 | 04:06:17 INFO - PID 29699 | Cycle 1(1): loaded http://localhost:47040/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47040/tests/kraken/driver.html?testName=audio-fft) 04:06:17 INFO - PID 29699 | RSS: Main: 163434496 04:06:17 INFO - PID 29699 | 04:06:19 INFO - PID 29699 | Cycle 1(1): loaded http://localhost:47040/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47040/tests/kraken/driver.html?testName=audio-oscillator) 04:06:20 INFO - PID 29699 | RSS: Main: 164720640 04:06:20 INFO - PID 29699 | 04:06:22 INFO - PID 29699 | Cycle 1(1): loaded http://localhost:47040/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47040/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:06:23 INFO - PID 29699 | RSS: Main: 164651008 04:06:23 INFO - PID 29699 | 04:06:26 INFO - PID 29699 | Cycle 1(1): loaded http://localhost:47040/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47040/tests/kraken/driver.html?testName=imaging-darkroom) 04:06:26 INFO - PID 29699 | RSS: Main: 164941824 04:06:26 INFO - PID 29699 | 04:06:30 INFO - PID 29699 | Cycle 1(1): loaded http://localhost:47040/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47040/tests/kraken/driver.html?testName=imaging-desaturate) 04:06:31 INFO - PID 29699 | RSS: Main: 165904384 04:06:31 INFO - PID 29699 | 04:06:33 INFO - PID 29699 | Cycle 1(1): loaded http://localhost:47040/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47040/tests/kraken/driver.html?testName=json-parse-financial) 04:06:34 INFO - PID 29699 | RSS: Main: 167362560 04:06:34 INFO - PID 29699 | 04:06:35 INFO - PID 29699 | Cycle 1(1): loaded http://localhost:47040/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47040/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:06:36 INFO - PID 29699 | RSS: Main: 167378944 04:06:36 INFO - PID 29699 | 04:06:38 INFO - PID 29699 | Cycle 1(1): loaded http://localhost:47040/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47040/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:06:38 INFO - PID 29699 | RSS: Main: 167383040 04:06:38 INFO - PID 29699 | 04:06:40 INFO - PID 29699 | Cycle 1(1): loaded http://localhost:47040/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47040/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:06:41 INFO - PID 29699 | RSS: Main: 164487168 04:06:41 INFO - PID 29699 | 04:06:43 INFO - PID 29699 | Cycle 1(1): loaded http://localhost:47040/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47040/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:06:44 INFO - PID 29699 | RSS: Main: 164249600 04:06:44 INFO - PID 29699 | 04:06:47 INFO - PID 29699 | Cycle 1(1): loaded http://localhost:47040/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47040/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:06:47 INFO - PID 29699 | RSS: Main: 165019648 04:06:47 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | Cycle 1(1): loaded http://localhost:47040/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47040/tests/kraken/driver.html?testName=ai-astar) 04:06:49 INFO - PID 29699 | RSS: Main: 167006208 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | __start_tp_report 04:06:49 INFO - PID 29699 | _x_x_mozilla_page_load 04:06:49 INFO - PID 29699 | _x_x_mozilla_page_load_details 04:06:49 INFO - PID 29699 | |i|pagename|runs| 04:06:49 INFO - PID 29699 | |0;ai-astar;106;105;106;105;106;108;107;107;106;106 04:06:49 INFO - PID 29699 | |1;audio-beat-detection;110;111;114;111;141;115;112;114;118;112 04:06:49 INFO - PID 29699 | |2;audio-dft;163;163;162;162;161;162;161;164;166;163 04:06:49 INFO - PID 29699 | |3;audio-fft;72;72;73;78;75;72;72;73;74;74 04:06:49 INFO - PID 29699 | |4;audio-oscillator;78;79;76;76;78;78;77;81;75;77 04:06:49 INFO - PID 29699 | |5;imaging-gaussian-blur;114;113;114;110;114;113;113;115;117;116 04:06:49 INFO - PID 29699 | |6;imaging-darkroom;150;150;149;150;152;152;151;148;148;150 04:06:49 INFO - PID 29699 | |7;imaging-desaturate;94;94;94;94;94;95;94;95;93;93 04:06:49 INFO - PID 29699 | |8;json-parse-financial;46;45;45;45;46;46;46;45;45;45 04:06:49 INFO - PID 29699 | |9;json-stringify-tinderbox;37;37;37;37;38;37;37;49;37;36 04:06:49 INFO - PID 29699 | |10;stanford-crypto-aes;79;72;74;75;71;72;71;78;76;71 04:06:49 INFO - PID 29699 | |11;stanford-crypto-ccm;114;104;105;111;106;104;109;105;108;112 04:06:49 INFO - PID 29699 | |12;stanford-crypto-pbkdf2;171;151;152;159;152;152;167;159;160;156 04:06:49 INFO - PID 29699 | |13;stanford-crypto-sha256-iterative;64;62;60;61;63;59;59;60;60;67 04:06:49 INFO - PID 29699 | __end_tp_report 04:06:49 INFO - PID 29699 | __start_cc_report 04:06:49 INFO - PID 29699 | _x_x_mozilla_cycle_collect,876 04:06:49 INFO - PID 29699 | __end_cc_report 04:06:49 INFO - PID 29699 | __startTimestamp1505300809903__endTimestamp 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | ------- Summary: start ------- 04:06:49 INFO - PID 29699 | Number of tests: 14 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | [#0] ai-astar Cycles:10 Average:106.20 Median:106.00 stddev:0.92 (0.9%) stddev-sans-first:0.97 04:06:49 INFO - PID 29699 | Values: 106.0 105.0 106.0 105.0 106.0 108.0 107.0 107.0 106.0 106.0 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | [#1] audio-beat-detection Cycles:10 Average:115.80 Median:114.00 stddev:9.16 (8.0%) stddev-sans-first:9.48 04:06:49 INFO - PID 29699 | Values: 110.0 111.0 114.0 111.0 141.0 115.0 112.0 114.0 118.0 112.0 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | [#2] audio-dft Cycles:10 Average:162.70 Median:163.00 stddev:1.49 (0.9%) stddev-sans-first:1.58 04:06:49 INFO - PID 29699 | Values: 163.0 163.0 162.0 162.0 161.0 162.0 161.0 164.0 166.0 163.0 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | [#3] audio-fft Cycles:10 Average:73.50 Median:73.50 stddev:1.90 (2.6%) stddev-sans-first:1.94 04:06:49 INFO - PID 29699 | Values: 72.0 72.0 73.0 78.0 75.0 72.0 72.0 73.0 74.0 74.0 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:78.00 stddev:1.72 (2.2%) stddev-sans-first:1.81 04:06:49 INFO - PID 29699 | Values: 78.0 79.0 76.0 76.0 78.0 78.0 77.0 81.0 75.0 77.0 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:1.91 (1.7%) stddev-sans-first:2.03 04:06:49 INFO - PID 29699 | Values: 114.0 113.0 114.0 110.0 114.0 113.0 113.0 115.0 117.0 116.0 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | [#6] imaging-darkroom Cycles:10 Average:150.00 Median:150.00 stddev:1.41 (0.9%) stddev-sans-first:1.50 04:06:49 INFO - PID 29699 | Values: 150.0 150.0 149.0 150.0 152.0 152.0 151.0 148.0 148.0 150.0 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 04:06:49 INFO - PID 29699 | Values: 94.0 94.0 94.0 94.0 94.0 95.0 94.0 95.0 93.0 93.0 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | [#8] json-parse-financial Cycles:10 Average:45.40 Median:45.50 stddev:0.52 (1.1%) stddev-sans-first:0.50 04:06:49 INFO - PID 29699 | Values: 46.0 45.0 45.0 45.0 46.0 46.0 46.0 45.0 45.0 45.0 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:37.00 stddev:3.82 (10.3%) stddev-sans-first:4.03 04:06:49 INFO - PID 29699 | Values: 37.0 37.0 37.0 37.0 38.0 37.0 37.0 49.0 37.0 36.0 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | [#10] stanford-crypto-aes Cycles:10 Average:73.90 Median:74.50 stddev:3.00 (4.0%) stddev-sans-first:2.55 04:06:49 INFO - PID 29699 | Values: 79.0 72.0 74.0 75.0 71.0 72.0 71.0 78.0 76.0 71.0 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | [#11] stanford-crypto-ccm Cycles:10 Average:107.80 Median:108.50 stddev:3.58 (3.3%) stddev-sans-first:3.02 04:06:49 INFO - PID 29699 | Values: 114.0 104.0 105.0 111.0 106.0 104.0 109.0 105.0 108.0 112.0 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.90 Median:159.00 stddev:6.81 (4.3%) stddev-sans-first:5.32 04:06:49 INFO - PID 29699 | Values: 171.0 151.0 152.0 159.0 152.0 152.0 167.0 159.0 160.0 156.0 04:06:49 INFO - PID 29699 | 04:06:49 INFO - PID 29699 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:61.50 stddev:2.55 (4.1%) stddev-sans-first:2.54 04:06:49 INFO - PID 29699 | Values: 64.0 62.0 60.0 61.0 63.0 59.0 59.0 60.0 60.0 67.0 04:06:49 INFO - PID 29699 | -------- Summary: end -------- 04:06:49 INFO - PID 29699 | 04:06:50 INFO - PID 29699 | 04:06:50 INFO - PID 29699 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 04:06:50 INFO - PID 29699 | 04:06:50 INFO - PID 29699 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:06:50 INFO - TEST-INFO | 29699: exit 0 04:06:50 INFO - TEST-OK | kraken | took 59074ms 04:06:50 INFO - SUITE-END | took 1165s 04:06:51 INFO - Completed test suite (00:19:26) 04:06:51 INFO - javascript benchmark 04:06:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9011.016778358518, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3832.0611450194706, 3845.3078445879732, 3849.6342847429482, 3866.4007078902773, 3867.7872717000573, 6166.414190651892, 6233.563650544464, 6297.842553936154, 6302.306746257914, 6382.138411314477, 6258.687065646723, 6297.181366422359, 6297.433231009209, 6330.715117819697, 6332.176817013793, 5659.943400565983, 5732.656040637554, 5755.625884317506, 5768.336641286264, 5770.134479828017, 170.1533347102968, 5685.346185188715, 5737.368889422181, 5762.049261871807, 5781.479666830005, 5498.917516237259, 5586.804461843836, 5617.887642247153, 5720.11338242573, 5760.8271751847515, 5634.492895639412, 5672.801451949162, 5707.914381284284, 5747.62640428375, 5758.884822303572, 5627.803026894039, 5639.661620302754, 5708.686022268774, 5719.19931209631, 5722.141678748179, 6229.781957631507, 6304.148939893102, 6312.3372045934975, 6373.808785736389, 6416.582922110095, 6718.529702920798, 6835.555688880207, 6848.828779280508, 6868.862622747517, 6922.203946546111, 6881.793546193622, 6932.0, 6937.063496427968, 6943.166819981635, 6943.409810166142, 6369.044643303495, 6392.584482008701, 6435.8391040223905, 6457.709403076826, 6463.903041454382, 7034.331738484836, 7045.978333141666, 7116.181639111465, 7138.46461515378, 7140.892886606705, 6641.33586641332, 6749.898751518731, 6760.898586521206, 6773.62745049027, 6786.524943254023, 6841.555298905555, 6858.485613579001, 6898.862022759517, 6922.7923162305215, 6945.89581156283, 6808.0, 6830.248672646005, 6901.482388820758, 6934.375906168469, 6967.616781077089, 6864.176298844171, 6869.347411995853, 6877.449804015667, 6960.234374218832, 6965.756198533027, 4921.729304888266, 4967.652264341461, 4994.40067191932, 5022.221555658879, 5057.140286151363, 4526.909461810746, 4566.771661416916, 4592.0, 4603.49361570227, 4624.468186158635, 6132.63204207749, 6183.876322473615, 6223.439890409885, 6292.4966002719675, 6295.559310848196, 6741.1236539249585, 6795.422389096883, 6845.0, 6912.239653638096, 6932.0988271524375, 6163.3528479509905, 6196.287426945869, 6197.876042479125, 6236.719347629368, 6245.781397651061, 5471.644343117685, 5484.808031718871, 5555.416681248492, 5560.388357280696, 5565.805196818092, 6904.516683832184, 6916.896246556305, 6917.031615573824, 6918.481113916477, 6918.8962165567555, 6771.01820236063, 6776.7628133015105, 6780.898286525608, 6783.152105986752, 6784.118064651662, 6808.251092379934, 6874.896876546756, 6877.243503214542, 6890.276520965226, 6923.584584924921, 6751.93248067513, 6798.796036118924, 6830.829229269308, 6850.520463567503, 6881.243063262937, 6844.418224450778, 6896.4827637926355, 6898.482613803884, 6972.51192416526, 6972.825679358057, 6505.121808555974, 6600.0, 6613.470922326299, 6685.298043705341, 6694.765683201259, 6510.21877374718, 6535.934640653722, 6548.345165483413, 6556.114924485324, 6592.044153597797, 6510.902336465051, 6515.2833188348295, 6584.868302634016, 6586.012098185119, 6589.077529145828, 6622.867542649216, 6654.800355989328, 6660.167479065117, 6714.362135597111, 6718.093057437183, 6400.615963042233, 6414.679266036773, 6469.676516174267, 6509.479241660751, 6516.446102081376], "value": 6197.968659852215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [949.1931857920769, 958.5973890965794, 996.8504724291355, 1008.6822650864978, 1011.802698473797, 42440.15119697608, 44092.33861492072, 44314.77842610786, 44451.333230001495, 44569.33146002812, 41028.38457423141, 41052.58947410525, 41194.176116477654, 41259.58740412595, 41285.7935710321, 7335.88996165058, 7498.4001279897475, 7499.47503674743, 7504.099508059041, 7506.399488040971, 7341.119065712122, 7397.81505462365, 7434.9256507434775, 7441.144268409148, 7441.739539116132, 7421.0723659542555, 8054.758357249262, 8224.177582241759, 8231.629576669035, 8234.794130146734, 8117.594120293991, 8168.591570421485, 8184.304334131606, 8185.631646575918, 8190.918090819075, 7843.960780196063, 8067.878981815277, 8071.31393831525, 8143.0, 8218.178182181735, 41369.37945930813, 41546.79226603878, 41682.1663566727, 41729.0, 41878.1624367514, 3806.9619303806885, 3848.076461649212, 3848.9615103848882, 3852.191039881628, 3857.691384689246, 33069.17327066843, 33108.6689133108, 33119.6688033119, 33147.6685233147, 33219.169520761934, 5408.594355423359, 5419.729013549346, 5426.375966763833, 5451.318585176853, 5475.972620136914, 219324.0, 220482.0, 220578.0, 223795.0, 238330.0, 51366.0, 51472.22791658128, 51761.48238517567, 51832.48167518352, 51862.481375185766, 54377.45622543803, 54467.0, 54680.45319546833, 54684.0, 54765.452345476035, 16073.598160046093, 16239.0, 16258.349666013228, 16275.674486510203, 16521.504354869372, 13426.187464692142, 13498.52755153565, 13531.932340338431, 13639.045266831226, 13661.795073073912, 14355.497557585435, 14370.425182992773, 14394.42422303117, 14426.278686065654, 14533.927330363493, 5827.339208325607, 5881.264841894763, 5921.792737254262, 5985.910211346833, 5993.250843644544, 1106.4301884529473, 1119.3787447966456, 1119.6864877834219, 1125.4372813593204, 1130.197559732599, 25341.493170136495, 25534.0, 25541.106061287766, 25732.742672573033, 25913.481730365664, 1199.4182821331647, 1205.6383085074617, 1212.805951047828, 1216.5681183179843, 1222.370479203211, 13607.795883061763, 13613.931930340483, 13662.385192666354, 13788.379522921492, 14056.71886562263, 294224.0, 296258.0, 296483.0, 297026.0, 298128.0, 58183.0, 58421.70789146027, 58449.415505845245, 58532.70733646305, 58565.707171464725, 1520.6046427928818, 1528.4192007037254, 1544.4825983295498, 1554.4015554011787, 1570.9057456552644, 25256.87371563204, 25328.620070699322, 25393.49213015766, 25513.23460296194, 25605.743942560337, 40956.18087638171, 41015.58984410237, 41052.384214237405, 41092.58907410887, 41153.38269926013, 240986.0, 242190.0, 243093.0, 243278.0, 243390.0, 34333.313333733684, 38574.42138367983, 38894.6110538891, 39133.0, 39152.0, 50111.74944125256, 50639.49360506348, 50783.0, 51057.74471127621, 51252.0, 34767.30465390729, 34995.0, 35013.47479787856, 35441.46837797383, 35740.82129589335, 37082.25835483329, 37087.81456092702, 37199.07002324964, 37272.254554908206, 37409.25181496409, 42737.786311068245, 43896.34155487606, 43974.0, 44045.77977110094, 44128.77935610301, 35253.82373088118, 35323.82338088293, 35591.11022224362, 35668.82165589155, 36265.45601816028, 26905.461890762468, 26922.29023551878, 26969.325766855985, 27035.324116897234, 27184.184474465797], "value": 18707.191310064693, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.1783360626273, 574.4198359656746, 581.6248519704787, 597.4891467795035, 598.2462097756827, 23295.534089318204, 23981.880090599523, 24023.0, 24042.879785601068, 24051.759482405214, 25073.874630626866, 26080.739192608067, 26305.21084367467, 26630.0, 26638.73361266387, 6572.21133463985, 6694.297098804628, 6728.192616885956, 6729.528932974694, 6731.798046058601, 6435.420812126908, 6589.637569933651, 6695.665216739168, 6698.531102822804, 6705.731770729165, 6661.600303981752, 6664.966675166617, 6667.366600172976, 6667.566608170478, 6671.466282697373, 6410.134631824737, 6647.634380109068, 6654.833629159286, 6658.0, 6661.600303981752, 6186.474149697281, 6198.34917333678, 6200.131981522591, 6200.162977997958, 6201.224846894138, 29695.70304296951, 30155.396892062035, 30163.849180754176, 30193.396132077454, 30231.0, 2276.8975396107217, 2297.4945511987335, 2301.8849057547054, 2310.688057112302, 2310.745817960023, 19866.900665496723, 20308.0, 20817.479563010893, 20842.5831483371, 20898.0, 2361.409647588103, 2384.4873352229356, 2437.97562024381, 2448.5959816630234, 2463.8028957683523, 143576.28211858874, 147295.0, 150328.2483587586, 152669.0, 161353.0, 15350.539483815503, 15477.458288959833, 15595.688086238211, 15992.200389980455, 16009.439669611507, 24918.875405622857, 25030.0, 25042.0, 25054.12310569158, 25065.87467062653, 10451.581936722598, 10595.788084238273, 10650.147988160928, 10651.84022239667, 10818.29681070728, 9818.0, 9822.65620703272, 9833.901660983442, 9908.25688073383, 9929.80140397188, 9250.95374523123, 9446.952765236267, 9472.242220622335, 9501.952490237505, 9861.901380986243, 2629.4872499862345, 2654.2037388783674, 2704.553748631493, 2714.6335244742104, 2738.3564862257354, 1007.3653598233067, 1038.0864838941684, 1039.2673165418362, 1040.78143589847, 1056.0759335581367, 20648.58702825935, 20823.0, 20888.268910588056, 21116.47208819792, 21220.89389553073, 1079.4764539198482, 1081.426843772786, 1097.5609756097624, 1098.6758906122714, 1099.8900109988938, 11144.219904606762, 11357.0, 11360.715982100513, 11565.0, 11607.883921160848, 620450.0, 626279.0, 629668.0, 632253.0, 643268.0, 126770.36614816867, 127692.36153819357, 128394.0, 129098.35450822685, 129540.3522982379, 1105.8341248812742, 1162.337467643435, 1215.070471089617, 1226.1294480918468, 1270.4219580091037, 21476.570468590227, 21707.674384884554, 21865.453363666034, 21937.67093493631, 22150.446238844157, 60659.39340606538, 61630.91845407622, 62104.689476552325, 62494.0, 64114.0, 77995.22004780106, 78154.0, 78912.0, 79365.60317198378, 81411.0, 14727.631809204855, 15103.546893592771, 15185.0, 15196.772048419061, 15202.46791362297, 23053.0, 23432.531349373257, 23436.414089647213, 23580.528389432457, 23811.404714881573, 19480.123394447, 19556.21775128979, 19616.705749414057, 19715.0, 19820.90089549543, 16496.505104846874, 16549.58626034359, 17283.654326913642, 17553.297868085134, 17984.190711417756, 19149.0, 19730.0, 19873.205071796965, 20163.89918050459, 20180.0, 14662.926685366505, 14773.778393323893, 14820.7776883349, 14855.702885942437, 14873.85126148768, 19358.0, 19366.709499357803, 19465.4160375189, 19504.34213091903, 19571.315003974792], "value": 12640.761351286465, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.851965543618, 1448.5591777854315, 1473.587395529251, 1476.557032890133, 1477.6896851661152, 13852.515161969333, 14514.274286285683, 14550.054246473985, 16776.245068971893, 17378.13109344531, 17000.234989425506, 17241.827581724177, 17255.223514941827, 17255.91372043138, 17293.740593891103, 2731.617573539706, 2822.209781261245, 2839.630847989769, 2867.068202834077, 2870.153304775086, 2871.8994835180774, 2904.6950070242638, 2914.591957126004, 2917.781166412517, 2942.7351538361545, 2677.6786785585755, 2731.6721993360825, 2735.753782159605, 2781.5410457274525, 2837.7162283771663, 2824.7740180785486, 2838.3897462045657, 2849.715028497144, 2855.728705772948, 2858.5997960285576, 2744.8353098814137, 2750.972490275092, 2770.1827960751625, 2772.625695531118, 2774.375765452777, 16190.190490475547, 16543.83456165435, 16579.751303730452, 16586.58533536659, 16603.169841507875, 8115.0, 8496.277816385615, 8501.234888860032, 8506.191911768372, 8542.658293668246, 5585.525230355424, 5712.80005199816, 5764.798232061858, 5841.211436456109, 5865.120231965197, 2011.0950072467301, 2072.849568489488, 2081.7814129516487, 2116.386247988082, 2142.4108370198164, 29785.55321670155, 30062.0, 30071.69928300733, 30093.0, 30096.54855177152, 6997.265287144878, 7002.299770023059, 7239.674214660353, 7252.927470725331, 7265.273472652692, 19288.421347359603, 19291.710624340645, 19363.806361936484, 19376.903115484612, 19384.903075484814, 8416.368772342099, 8606.26846718036, 8629.52537610425, 8630.611622477003, 8635.568221589021, 7651.961740191264, 7662.0, 7773.494722842997, 7889.053313602404, 7893.1712170222445, 5666.546676265889, 5698.1196205253, 5727.570432217601, 5736.110902810071, 5753.568482363839, 1620.894641848276, 1652.3803573659877, 1661.1279078483892, 1663.5924198571427, 1681.9831801682071, 943.9716808495756, 961.038961038961, 968.2931460034215, 972.6401231544373, 973.5181085362649, 8426.789330266793, 8453.112423195476, 8479.576021198915, 8525.403221774417, 8540.14598540141, 779.1546172403006, 784.2980532423448, 790.237420888845, 801.338895411288, 804.2962407893093, 5660.179274005246, 5683.119116536944, 5792.781701548606, 5874.765009399662, 5893.528517718572, 24051.879740601184, 24881.0, 24909.875450622632, 24920.252392428258, 25252.621210681853, 24793.5041299173, 24841.0, 24859.254222373358, 24994.50010999806, 25091.498170036863, 11584.131190160771, 12199.75600488003, 12247.69380765488, 12273.140880138304, 12384.31886246247, 9408.43549387039, 9483.336166468282, 9486.762830929281, 9512.95243523778, 9516.333856630246, 20214.494637634176, 20594.0, 20594.176232950515, 21084.367468975954, 21468.0, 21635.0, 21656.133754649636, 21699.240526582125, 21983.450413739785, 22356.77643223547, 12018.75962480763, 12111.273323600612, 12213.206141600767, 12218.0, 12238.816417753918, 15192.392304307705, 15507.844921551092, 15520.456784012506, 15521.37914483408, 15523.922380388025, 11282.94358528202, 11307.0, 11412.942935285602, 11429.371384573928, 11619.709507262049, 10870.402127883042, 11013.118950483782, 11086.61196858106, 11276.9436152822, 11428.942855286003, 10659.307145035547, 10842.0, 10885.619003334845, 10898.400587967737, 10909.34543927367, 8122.878156827534, 8235.50586964784, 8240.670373185245, 8281.54451505149, 8337.874931876147, 8023.398245131521, 8133.308668763221, 8139.430239883151, 8212.630431630472, 8213.342932565261], "value": 7172.081358941078, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.42293996314197, 547.5865721380359, 547.6850810783799, 555.3141869790805, 557.0391075394945, 6198.287196972349, 6237.37626237376, 6312.222622046296, 6313.558050936436, 6402.615843049421, 6170.568060235785, 6208.951046154758, 6236.625802451856, 6268.749250029993, 6332.683365831713, 5159.871003224921, 5168.198929165967, 5173.5085166909275, 5176.948230517703, 5206.167013277876, 4866.345442549533, 5006.148954677696, 5105.106606343894, 5146.202338637517, 5195.584353251749, 4953.269627505723, 4963.478834722357, 4966.826161084363, 5009.649324547283, 5017.422996355411, 4935.555799978001, 4966.2054071348775, 4984.501549845023, 4989.451160372356, 5026.29631851541, 4421.0, 4472.105578884236, 4492.977535112336, 4515.63874890008, 4538.1377538267625, 6066.211392518989, 6067.605605635619, 6142.293636231846, 6191.709067644955, 6237.87524249517, 5842.97078514609, 5899.203607512974, 5936.228290322274, 5952.7023648817385, 5961.403859614047, 6684.0, 6772.966135169342, 6781.627010514419, 6813.965930170367, 6834.624095674736, 5051.0, 5082.923756143624, 5095.541401273866, 5101.82143624975, 5123.3339665843205, 6698.263191048981, 6927.0, 6954.860902781916, 6978.232394436557, 7034.788956331318, 5140.228965655182, 5166.689998600096, 5182.144946083854, 5217.791288348423, 5234.0, 5276.8390953990065, 5280.970210808932, 5288.127458969227, 5309.33633295838, 5309.389420216725, 5848.356680765112, 5867.090600956859, 5873.559483038789, 5904.468597826216, 5961.7615295387695, 6718.044060613314, 6720.563163394364, 6749.646478021648, 6780.93219067803, 6783.38949494538, 1817.4547635709234, 1831.7527133837027, 1832.1480511562188, 1833.5599456130699, 1837.9523671507113, 1230.8921970226868, 1272.63729836996, 1293.6765808547864, 1298.6753311672082, 1307.1242267680677, 6087.726052327655, 6102.664353460514, 6103.816885493442, 6119.571629985947, 6152.569320147636, 6394.680265986682, 6723.7646682365885, 6766.188057433041, 6835.359513716771, 6946.756863509753, 5957.761689532457, 6022.97609406402, 6056.454919057306, 6078.0, 6141.263048434127, 3558.3061303045656, 3581.212133330663, 3585.9779962710504, 3593.8742144025355, 3615.783053016775, 6909.930900691029, 6921.584704917721, 6951.131108611424, 6958.652067396712, 7019.543729657556, 6891.862162756717, 6927.203371612229, 6946.305369463114, 6965.303469653096, 6990.790276291719, 6842.144731908512, 6894.689738961659, 6913.930860691429, 6947.722091116501, 6968.860622787516, 6771.119754432089, 6774.8645027100165, 6853.348931851466, 6862.622555759282, 6870.416014638811, 6758.7296508139125, 6826.429921118071, 6826.658667066726, 6852.177738671192, 6864.725410983705, 6929.89605155913, 7000.929990700028, 7014.2635023323855, 7024.192217895007, 7054.0, 5213.608979326642, 5231.189165679326, 5241.9737901310245, 5253.290805741176, 5275.604329675367, 6584.703688333941, 6611.611561572124, 6816.727330906709, 6826.385625293748, 6925.722971081101, 6451.419372256331, 6505.73977040932, 6513.576617519846, 6544.9672751635935, 6619.7683081093855, 6444.806655800334, 6467.773627923, 6517.185351831021, 6553.344665533408, 6557.442617377576, 6252.843678908063, 6380.808575742735, 6428.389303016206, 6546.247181573989, 6576.80269591913, 6487.610743355413, 6545.770898018546, 6597.736090556324, 6608.240052394036, 6692.966535167293, 6402.743890244338, 6407.295197528175, 6445.51753096781, 6475.255345635313, 6485.837854053686], "value": 5189.286999310813, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [972.810301991112, 1000.5947591225553, 1007.1640538353167, 1013.4780587997183, 1029.7734498410352, 20267.08798104085, 20689.586208275825, 20714.067866946003, 20730.170793168254, 20731.896340518313, 18827.341043063494, 18836.246550137977, 18855.811441885577, 19112.522186945334, 19336.323228686997, 1015.9441230732307, 1046.1682962045193, 1046.4140081554315, 1046.5081411736471, 1047.2669131607847, 1028.3058935218735, 1031.4997226345217, 1051.6844946516053, 1057.323313079626, 1061.47987486132, 1136.3806725334712, 1142.817149256115, 1143.9885601144008, 1146.403869987606, 1146.8050431426636, 1059.7403636109125, 1084.3331351219022, 1131.835883796849, 1134.909207263421, 1135.829625556165, 802.5265093594807, 809.9878501822477, 811.7929927868415, 812.3420029775841, 814.5967745965785, 21200.363989080353, 21403.67894481584, 21404.357869263946, 21421.785782142135, 21423.785762142335, 23201.419964500852, 23913.0, 23992.0, 23999.760002399926, 24017.0, 18615.0, 18641.62716745671, 18736.62526749471, 18741.34405295808, 18762.906185468986, 4007.8998025049314, 4032.1330913853367, 4034.394840773878, 4035.1122752994293, 4037.717359784787, 160039.0, 160707.0, 163318.0, 163665.0, 166772.16613916974, 17942.641147176982, 17992.190351434216, 18285.90857045733, 18457.0, 18463.446096617125, 34577.30845383078, 34637.0, 34931.4760278596, 35034.82482587622, 35390.0, 8640.654373825102, 8687.522186279815, 8722.781930451663, 8746.56267186638, 8766.079561645935, 4435.534268901783, 4606.631469482433, 4677.883052923704, 4693.647976401783, 4732.763361831963, 6122.724477398528, 6169.383061693795, 6208.813735587849, 6224.0, 6250.531210159256, 1076.1928553584812, 1077.7197928538637, 1122.270524159303, 1126.0878688262533, 1127.644791890552, 736.6243215959792, 739.4343327354578, 755.5051441305953, 761.0942977856333, 767.9577623230664, 11986.64040078799, 12017.759644807054, 12204.0, 12239.082068844695, 12466.81299780504, 738.8189893476133, 741.7218543046357, 746.6042997211487, 750.8047907544078, 759.2483441392981, 5571.052921003439, 5582.0, 5631.605787594829, 5670.5463562914865, 5693.259876216148, 158321.0, 158451.0, 158578.20710896372, 160384.0, 165898.0, 136534.0, 140093.0, 140202.0, 142702.0, 143701.0, 115599.0, 118991.0, 119148.40425797815, 119256.0, 120145.39927300482, 10511.526981285708, 10562.0, 10624.309419887682, 10683.94658026705, 10748.89251107479, 56561.0, 56906.43093569177, 56968.715156423954, 57723.71138144282, 58026.7098664504, 73335.0, 74677.6266118666, 74846.0, 75266.24733752811, 76845.23154768381, 16577.17114144264, 16814.0, 16815.66368672595, 16837.91581042087, 16930.32278708838, 17887.0, 18417.907910460362, 18700.81299186991, 18776.24895004239, 18923.621527569096, 15522.146281954148, 15569.84430155684, 15590.14254216029, 15763.0, 15823.762643560125, 15940.601484962968, 16117.838821611635, 16218.513444596681, 16386.262618181972, 16457.753133703245, 16036.839631603534, 16172.110533920277, 16253.349866005228, 16384.918075409547, 16388.590285242964, 13017.349132543146, 13218.537351192663, 13232.13991090576, 13423.328833558478, 13471.797923030967, 12799.48802047945, 12832.935835320764, 13001.634828342887, 13116.540921067717, 13179.14335568185], "value": 9813.676496572207, "unit": "score"}]}, {"extraOptions": ["e10s", "stylo_disabled"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1378.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 106.0, 105.0, 106.0, 108.0, 107.0, 107.0, 106.0, 106.0], "value": 106.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 114.0, 111.0, 141.0, 115.0, 112.0, 114.0, 118.0, 112.0], "value": 115.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 162.0, 161.0, 162.0, 161.0, 164.0, 166.0, 163.0], "value": 162.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 73.0, 78.0, 75.0, 72.0, 72.0, 73.0, 74.0, 74.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 76.0, 76.0, 78.0, 78.0, 77.0, 81.0, 75.0, 77.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 110.0, 114.0, 113.0, 113.0, 115.0, 117.0, 116.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 149.0, 150.0, 152.0, 152.0, 151.0, 148.0, 148.0, 150.0], "value": 150.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 93.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 45.0, 45.0, 45.0, 46.0, 46.0, 46.0, 45.0, 45.0, 45.0], "value": 45.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 37.0, 38.0, 37.0, 37.0, 49.0, 37.0, 36.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 72.0, 74.0, 75.0, 71.0, 72.0, 71.0, 78.0, 76.0, 71.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 104.0, 105.0, 111.0, 106.0, 104.0, 109.0, 105.0, 108.0, 112.0], "value": 107.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 151.0, 152.0, 159.0, 152.0, 152.0, 167.0, 159.0, 160.0, 156.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 60.0, 61.0, 63.0, 59.0, 59.0, 60.0, 60.0, 67.0], "value": 61.5, "unit": "score"}]}]} 04:06:51 INFO - Return code: 0 04:06:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:06:51 INFO - # TBPL SUCCESS # 04:06:51 INFO - Running post-action listener: _package_coverage_data 04:06:51 INFO - Running post-action listener: _resource_record_post_action 04:06:51 INFO - [mozharness: 2017-09-13 11:06:51.204480Z] Finished run-tests step (success) 04:06:51 INFO - Running post-run listener: _resource_record_post_run 04:06:51 INFO - Total resource usage - Wall time: 1183s; CPU: 13.0%; Read bytes: 0; Write bytes: 289202176; Read time: 0; Write time: 194664 04:06:51 INFO - TinderboxPrint: CPU usage
13.0% 04:06:51 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 04:06:51 INFO - TinderboxPrint: I/O write bytes / time
289,202,176 / 194,664 04:06:51 INFO - TinderboxPrint: CPU idle
8,199.5 (86.9%) 04:06:51 INFO - TinderboxPrint: CPU user
1,184.4 (12.6%) 04:06:51 INFO - TinderboxPrint: Swap in / out
0 / 0 04:06:51 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 12451840; Read time: 0; Write time: 2852 04:06:51 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:06:51 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 0; Write bytes: 276750336; Read time: 0; Write time: 191812 04:06:51 INFO - Running post-run listener: _upload_blobber_files 04:06:51 INFO - Blob upload gear active. 04:06:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:06:51 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:06:51 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:06:51 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:06:52 INFO - (blobuploader) - INFO - Open directory for files ... 04:06:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:06:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:06:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:06:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:06:59 INFO - (blobuploader) - INFO - Done attempting. 04:06:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:06:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:07:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:07:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:07:00 INFO - (blobuploader) - INFO - Done attempting. 04:07:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_errorsummary.log ... 04:07:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:07:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:07:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_errorsummary.log: uploaded 04:07:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:07:01 INFO - (blobuploader) - INFO - Done attempting. 04:07:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-stylo-disabled-e10s_raw.log ... 04:07:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:07:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:07:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-stylo-disabled-e10s_raw.log: uploaded 04:07:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:07:02 INFO - (blobuploader) - INFO - Done attempting. 04:07:02 INFO - (blobuploader) - INFO - Iteration through files over. 04:07:02 INFO - Return code: 0 04:07:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:07:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:07:02 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6a851c3160157a1dc06adf786de00169bcfbf4cde7377d24a13385a149b2fafad551a9f7b4af5548f4f8594dbe4758202933bd77dfc0521628fd5c6f1ce2b3d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/914b537946b10e389446ef3d61abd55e780205e8821f94a2d2380cab2a1dfbf7c81a5722747950055131cde1cb90c7ab67fdf786619af66bdd5ca48ded4668d9", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa74e6ab97de2121c539037a435b57b7022fdffb0b68691e15eaa4c09bdba1bfc3c88ad8228569dee8bcad97c59412e7af3a2bfc5e4f7541be0351958c758fe1", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61aaf49cc64048d8b60d0baf6f57ddb4ede2d1031bf0ce14431d483ac260409e6436f40947231696724e137f816b38cc6bd8d4116cdccd4c96a6926f564ad56a"} 04:07:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:07:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:07:02 INFO - Contents: 04:07:02 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6a851c3160157a1dc06adf786de00169bcfbf4cde7377d24a13385a149b2fafad551a9f7b4af5548f4f8594dbe4758202933bd77dfc0521628fd5c6f1ce2b3d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/914b537946b10e389446ef3d61abd55e780205e8821f94a2d2380cab2a1dfbf7c81a5722747950055131cde1cb90c7ab67fdf786619af66bdd5ca48ded4668d9", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa74e6ab97de2121c539037a435b57b7022fdffb0b68691e15eaa4c09bdba1bfc3c88ad8228569dee8bcad97c59412e7af3a2bfc5e4f7541be0351958c758fe1", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61aaf49cc64048d8b60d0baf6f57ddb4ede2d1031bf0ce14431d483ac260409e6436f40947231696724e137f816b38cc6bd8d4116cdccd4c96a6926f564ad56a"} 04:07:02 INFO - Running post-run listener: copy_logs_to_upload_dir 04:07:02 INFO - Copying logs to upload dir... 04:07:02 INFO - mkdir: /builds/slave/test/build/upload/logs 04:07:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.881818 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-09-13 04:07:02.384006) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 04:07:02.387469) ========= 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=438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6a851c3160157a1dc06adf786de00169bcfbf4cde7377d24a13385a149b2fafad551a9f7b4af5548f4f8594dbe4758202933bd77dfc0521628fd5c6f1ce2b3d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/914b537946b10e389446ef3d61abd55e780205e8821f94a2d2380cab2a1dfbf7c81a5722747950055131cde1cb90c7ab67fdf786619af66bdd5ca48ded4668d9", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa74e6ab97de2121c539037a435b57b7022fdffb0b68691e15eaa4c09bdba1bfc3c88ad8228569dee8bcad97c59412e7af3a2bfc5e4f7541be0351958c758fe1", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61aaf49cc64048d8b60d0baf6f57ddb4ede2d1031bf0ce14431d483ac260409e6436f40947231696724e137f816b38cc6bd8d4116cdccd4c96a6926f564ad56a"} build_url:https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011437 build_url: 'https://queue.taskcluster.net/v1/task/UIAOECxVTHSi9bdMrSDP9w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6a851c3160157a1dc06adf786de00169bcfbf4cde7377d24a13385a149b2fafad551a9f7b4af5548f4f8594dbe4758202933bd77dfc0521628fd5c6f1ce2b3d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/914b537946b10e389446ef3d61abd55e780205e8821f94a2d2380cab2a1dfbf7c81a5722747950055131cde1cb90c7ab67fdf786619af66bdd5ca48ded4668d9", "dromaeojs-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aa74e6ab97de2121c539037a435b57b7022fdffb0b68691e15eaa4c09bdba1bfc3c88ad8228569dee8bcad97c59412e7af3a2bfc5e4f7541be0351958c758fe1", "dromaeojs-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61aaf49cc64048d8b60d0baf6f57ddb4ede2d1031bf0ce14431d483ac260409e6436f40947231696724e137f816b38cc6bd8d4116cdccd4c96a6926f564ad56a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 04:07:02.418833) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 04:07:02.419143) ========= 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=438111f3d31ce799eff2119d000001bc-1505298488.955915-558750836 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004777 ========= master_lag: 0.11 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 04:07:02.537655) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 04:07:02.537995) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 04:07:02.538428) ========= ========= Total master_lag: 0.21 =========