builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-089 starttime: 1506596930.53 results: success (0) revision: 76a26ef7c493311c170ae83eb0c1d6592a21396d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 04:08:50.534401) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 04:08:50.534860) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 04:08:50.535133) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 04:08:50.550013) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 04:08:50.550344) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-28 04:08:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 21.4M=0.001s 2017-09-28 04:08:50 (21.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131104 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 04:08:50.698468) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 04:08:50.698780) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017298 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 04:08:50.748452) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-28 04:08:50.748782) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 76a26ef7c493311c170ae83eb0c1d6592a21396d --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 76a26ef7c493311c170ae83eb0c1d6592a21396d --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=8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-28 04:08:50,801 truncating revision to first 12 chars 2017-09-28 04:08:50,801 Setting DEBUG logging. 2017-09-28 04:08:50,801 attempt 1/10 2017-09-28 04:08:50,801 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/76a26ef7c493?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-28 04:08:51,955 unpacking tar archive at: mozilla-central-76a26ef7c493/testing/mozharness/ program finished with exit code 0 elapsedTime=1.456322 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-28 04:08:52.221953) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 04:08:52.222330) ========= script_repo_revision: 76a26ef7c493311c170ae83eb0c1d6592a21396d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 04:08:52.222862) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 04:08:52.223131) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 04:08:52.236879) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-09-28 04:08:52.237177) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:08:52 INFO - MultiFileLogger online at 20170928 04:08:52 in /builds/slave/test 04:08:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:08:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:08:52 INFO - {'append_to_log': False, 04:08:52 INFO - 'base_work_dir': '/builds/slave/test', 04:08:52 INFO - 'blob_upload_branch': 'Firefox', 04:08:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:08:52 INFO - 'branch': 'Firefox', 04:08:52 INFO - 'buildbot_json_path': 'buildprops.json', 04:08:52 INFO - 'code_coverage': False, 04:08:52 INFO - 'config_files': ('talos/linux_config.py',), 04:08:52 INFO - 'default_actions': ('clobber', 04:08:52 INFO - 'read-buildbot-config', 04:08:52 INFO - 'download-and-extract', 04:08:52 INFO - 'populate-webroot', 04:08:52 INFO - 'create-virtualenv', 04:08:52 INFO - 'install', 04:08:52 INFO - 'setup-mitmproxy', 04:08:52 INFO - 'run-tests'), 04:08:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:08:52 INFO - 'disable_ccov_upload': False, 04:08:52 INFO - 'disable_stylo': False, 04:08:52 INFO - 'download_minidump_stackwalk': True, 04:08:52 INFO - 'download_symbols': 'ondemand', 04:08:52 INFO - 'enable_stylo': False, 04:08:52 INFO - 'enable_webrender': False, 04:08:52 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:08:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:08:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:08:52 INFO - 'gecko_profile': False, 04:08:52 INFO - 'gecko_profile_interval': 0, 04:08:52 INFO - 'installer_path': 'installer.exe', 04:08:52 INFO - 'log_level': 'info', 04:08:52 INFO - 'log_name': 'talos', 04:08:52 INFO - 'log_to_console': True, 04:08:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:08:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:08:52 INFO - 'opt_config_files': (), 04:08:52 INFO - 'pip_index': False, 04:08:52 INFO - 'suite': 'dromaeojs-e10s', 04:08:52 INFO - 'system_bits': '32', 04:08:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:08:52 INFO - 'title': 'talos-linux64-ix-089', 04:08:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:08:52 INFO - 'use_talos_json': True, 04:08:52 INFO - 'verify': 'False', 04:08:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:08:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:08:52 INFO - 'work_dir': 'build'} 04:08:52 INFO - [mozharness: 2017-09-28 11:08:52.429769Z] Running clobber step. 04:08:52 INFO - Running pre-action listener: _resource_record_pre_action 04:08:52 INFO - Running main action method: clobber 04:08:52 INFO - rmtree: /builds/slave/test/build 04:08:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:08:53 INFO - Running post-action listener: _resource_record_post_action 04:08:53 INFO - [mozharness: 2017-09-28 11:08:53.971613Z] Finished clobber step (success) 04:08:53 INFO - [mozharness: 2017-09-28 11:08:53.971710Z] Running read-buildbot-config step. 04:08:53 INFO - Running pre-action listener: _resource_record_pre_action 04:08:53 INFO - Running main action method: read_buildbot_config 04:08:53 INFO - Using buildbot properties: 04:08:53 INFO - { 04:08:53 INFO - "project": "", 04:08:53 INFO - "product": "firefox", 04:08:53 INFO - "who": "archaeopteryx@coole-files.de", 04:08:53 INFO - "installer_path": "public/build/target.tar.bz2", 04:08:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:08:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:08:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 04:08:53 INFO - "stage_platform": "linux64-pgo", 04:08:53 INFO - "basedir": "/builds/slave/test", 04:08:53 INFO - "buildnumber": 214, 04:08:53 INFO - "platform": "ubuntu64_hw", 04:08:53 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:08:53 INFO - "slavebuilddir": "test", 04:08:53 INFO - "taskId": "QJf5PFYUReKkdYTpo1LUww", 04:08:53 INFO - "branch": "mozilla-central", 04:08:53 INFO - "script_repo_revision": "production", 04:08:53 INFO - "revision": "76a26ef7c493311c170ae83eb0c1d6592a21396d", 04:08:53 INFO - "slavename": "talos-linux64-ix-089", 04:08:53 INFO - "repo_path": "mozilla-central" 04:08:53 INFO - } 04:08:53 INFO - Finding installer, test and symbols from parent task. 04:08:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QJf5PFYUReKkdYTpo1LUww'}, attempt #1 04:08:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QJf5PFYUReKkdYTpo1LUww'}, attempt #1 04:08:54 INFO - Task dependencies: Dy6UL1toQc2jv9OegjbnVg 04:08:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg'}, attempt #1 04:08:55 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.tar.bz2 04:08:55 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.test_packages.json 04:08:55 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.crashreporter-symbols.zip 04:08:55 INFO - Running post-action listener: _resource_record_post_action 04:08:55 INFO - [mozharness: 2017-09-28 11:08:55.299624Z] Finished read-buildbot-config step (success) 04:08:55 INFO - [mozharness: 2017-09-28 11:08:55.299844Z] Running download-and-extract step. 04:08:55 INFO - Running pre-action listener: _resource_record_pre_action 04:08:55 INFO - Running main action method: download_and_extract 04:08:55 INFO - mkdir: /builds/slave/test/build/tests 04:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:08:55 INFO - trying https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.test_packages.json 04:08:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:08:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:08:56 INFO - Downloaded 989 bytes. 04:08:56 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:08:56 INFO - Using the following test package requirements: 04:08:56 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:08:56 INFO - u'common': [u'target.common.tests.zip'], 04:08:56 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:08:56 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:08:56 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:08:56 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:08:56 INFO - u'mozbase': [u'target.common.tests.zip'], 04:08:56 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:08:56 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:08:56 INFO - u'web-platform': [u'target.common.tests.zip', 04:08:56 INFO - u'target.web-platform.tests.tar.gz'], 04:08:56 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:08:56 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 04:08:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.common.tests.zip 04:08:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:08:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.common.tests.zip into memory 04:08:57 INFO - Content-Length response header: 55825740 04:08:57 INFO - Bytes received: 55825740 04:09:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.talos.tests.zip 04:09:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:09:08 INFO - Fetch https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.talos.tests.zip into memory 04:09:09 INFO - Content-Length response header: 14521162 04:09:09 INFO - Bytes received: 14521162 04:09:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:09 INFO - trying https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.tar.bz2 04:09:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:09:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:09:10 INFO - Downloaded 62280178 bytes. 04:09:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.tar.bz2 04:09:11 INFO - mkdir: /builds/slave/test/properties 04:09:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:09:11 INFO - Writing to file /builds/slave/test/properties/build_url 04:09:11 INFO - Contents: 04:09:11 INFO - build_url:https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.tar.bz2 04:09:11 INFO - Running post-action listener: _resource_record_post_action 04:09:11 INFO - Running post-action listener: find_tests_for_verification 04:09:11 INFO - Running post-action listener: set_extra_try_arguments 04:09:11 INFO - [mozharness: 2017-09-28 11:09:11.222048Z] Finished download-and-extract step (success) 04:09:11 INFO - [mozharness: 2017-09-28 11:09:11.222279Z] Running populate-webroot step. 04:09:11 INFO - Running pre-action listener: _resource_record_pre_action 04:09:11 INFO - Running main action method: populate_webroot 04:09:11 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:09:11 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:11 INFO - 'tests': ['tresize', 04:09:11 INFO - 'tcanvasmark']}, 04:09:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:09:11 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:11 INFO - 'tests': ['dromaeo_css', 04:09:11 INFO - 'kraken']}, 04:09:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:09:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:09:11 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:11 INFO - 'talos_options': ['--disable-stylo'], 04:09:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:09:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:09:11 INFO - 'tests': ['damp', 'tps']}, 04:09:11 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:11 INFO - 'talos_options': ['--disable-stylo'], 04:09:11 INFO - 'tests': ['damp', 'tps']}, 04:09:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:09:11 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:11 INFO - 'tests': ['dromaeo_dom']}, 04:09:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:09:11 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:11 INFO - 'tests': ['basic_compositor_video', 04:09:11 INFO - 'glvideo']}, 04:09:11 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:09:11 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:09:11 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:11 INFO - 'talos_options': ['--disable-stylo'], 04:09:11 INFO - 'tests': ['ts_paint_webext', 04:09:11 INFO - 'tp5o_webext']}, 04:09:11 INFO - 'other-e10s': {'tests': ['a11yr', 04:09:11 INFO - 'ts_paint', 04:09:11 INFO - 'tpaint', 04:09:11 INFO - 'sessionrestore', 04:09:11 INFO - 'sessionrestore_many_windows', 04:09:11 INFO - 'sessionrestore_no_auto_restore', 04:09:11 INFO - 'tabpaint', 04:09:11 INFO - 'cpstartup']}, 04:09:11 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:11 INFO - 'tests': ['a11yr', 04:09:11 INFO - 'ts_paint', 04:09:11 INFO - 'tpaint', 04:09:11 INFO - 'sessionrestore', 04:09:11 INFO - 'sessionrestore_many_windows', 04:09:11 INFO - 'sessionrestore_no_auto_restore', 04:09:11 INFO - 'tabpaint', 04:09:11 INFO - 'cpstartup']}, 04:09:11 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 04:09:11 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 04:09:11 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:11 INFO - 'tests': ['perf_reftest_singletons']}, 04:09:11 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:11 INFO - 'tests': ['perf_reftest']}, 04:09:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:09:11 INFO - 'tsvgr_opacity', 04:09:11 INFO - 'tart', 04:09:11 INFO - 'tscrollx', 04:09:11 INFO - 'tsvg_static']}, 04:09:11 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:11 INFO - 'tests': ['tsvgx', 04:09:11 INFO - 'tsvgr_opacity', 04:09:11 INFO - 'tart', 04:09:11 INFO - 'tscrollx', 04:09:11 INFO - 'tsvg_static']}, 04:09:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:09:11 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:11 INFO - 'talos_options': ['--disable-stylo'], 04:09:11 INFO - 'tests': ['tp5o']}, 04:09:11 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:09:11 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:09:11 INFO - 'talos_options': ['--mitmproxy', 04:09:11 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:09:11 INFO - '--firstNonBlankPaint'], 04:09:11 INFO - 'tests': ['tp6_google', 04:09:11 INFO - 'tp6_youtube', 04:09:11 INFO - 'tp6_amazon', 04:09:11 INFO - 'tp6_facebook']}, 04:09:11 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:09:11 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:09:11 INFO - 'talos_options': ['--disable-stylo', 04:09:11 INFO - '--mitmproxy', 04:09:11 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:09:11 INFO - '--firstNonBlankPaint'], 04:09:11 INFO - 'tests': ['tp6_google', 04:09:11 INFO - 'tp6_youtube', 04:09:11 INFO - 'tp6_amazon', 04:09:11 INFO - 'tp6_facebook']}, 04:09:11 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:09:11 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:09:11 INFO - 'talos_options': ['--stylo-threads=1', 04:09:11 INFO - '--mitmproxy', 04:09:11 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:09:11 INFO - '--firstNonBlankPaint'], 04:09:11 INFO - 'tests': ['tp6_google', 04:09:11 INFO - 'tp6_youtube', 04:09:11 INFO - 'tp6_amazon', 04:09:11 INFO - 'tp6_facebook']}, 04:09:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:09:11 INFO - 'talos_options': ['--xperf_path', 04:09:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:09:11 INFO - 'tests': ['tp5n']}, 04:09:11 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:11 INFO - 'talos_options': ['--disable-stylo', 04:09:11 INFO - '--xperf_path', 04:09:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:09:11 INFO - 'tests': ['tp5n']}}} 04:09:11 INFO - Running post-action listener: _resource_record_post_action 04:09:11 INFO - [mozharness: 2017-09-28 11:09:11.242923Z] Finished populate-webroot step (success) 04:09:11 INFO - [mozharness: 2017-09-28 11:09:11.243013Z] Running create-virtualenv step. 04:09:11 INFO - Running pre-action listener: _resource_record_pre_action 04:09:11 INFO - Running main action method: create_virtualenv 04:09:11 INFO - Creating virtualenv /builds/slave/test/build/venv 04:09:11 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:09:11 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:09:11 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:09:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:09:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:09:11 INFO - Installing distribute.............................................................................................................................................................................................done. 04:09:12 INFO - Installing pip...............done. 04:09:12 INFO - Return code: 0 04:09:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:09:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:09:12 INFO - Reading from file tmpfile_stdout 04:09:12 INFO - Output received: 04:09:12 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 04:09:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:12 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 0x21751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dfd60>, '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 0x24e0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e0ef0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:09:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:09:12 INFO - Using env: {'DISPLAY': ':0', 04:09:12 INFO - 'HOME': '/home/cltbld', 04:09:12 INFO - 'LANG': 'en_US.UTF-8', 04:09:12 INFO - 'LANGUAGE': 'en_US:en', 04:09:12 INFO - 'LOGNAME': 'cltbld', 04:09:12 INFO - 'MAIL': '/var/mail/cltbld', 04:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:12 INFO - 'MOZ_NO_REMOTE': '1', 04:09:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:12 INFO - 'NO_EM_RESTART': '1', 04:09:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:12 INFO - 'PWD': '/builds/slave/test', 04:09:12 INFO - 'SHELL': '/bin/bash', 04:09:12 INFO - 'SHLVL': '1', 04:09:12 INFO - 'TERM': 'linux', 04:09:12 INFO - 'TMOUT': '86400', 04:09:12 INFO - 'USER': 'cltbld', 04:09:12 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 04:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:12 INFO - '_': '/tools/buildbot/bin/python'} 04:09:13 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:09:13 INFO - Downloading/unpacking pip>=1.5 04:09:13 INFO - Running setup.py egg_info for package pip 04:09:13 INFO - warning: no files found matching 'pip/cacert.pem' 04:09:13 INFO - warning: no files found matching '*.html' under directory 'docs' 04:09:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:09:13 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:09:13 INFO - Installing collected packages: pip 04:09:13 INFO - Found existing installation: pip 1.1 04:09:13 INFO - Uninstalling pip: 04:09:13 INFO - Successfully uninstalled pip 04:09:13 INFO - Running setup.py install for pip 04:09:13 INFO - warning: no files found matching 'pip/cacert.pem' 04:09:13 INFO - warning: no files found matching '*.html' under directory 'docs' 04:09:13 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:09:13 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:09:13 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:09:13 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:09:13 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:09:13 INFO - Successfully installed pip 04:09:13 INFO - Cleaning up... 04:09:13 INFO - Return code: 0 04:09:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:13 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 0x21751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dfd60>, '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 0x24e0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e0ef0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:09:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:09:13 INFO - Using env: {'DISPLAY': ':0', 04:09:13 INFO - 'HOME': '/home/cltbld', 04:09:13 INFO - 'LANG': 'en_US.UTF-8', 04:09:13 INFO - 'LANGUAGE': 'en_US:en', 04:09:13 INFO - 'LOGNAME': 'cltbld', 04:09:13 INFO - 'MAIL': '/var/mail/cltbld', 04:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:13 INFO - 'MOZ_NO_REMOTE': '1', 04:09:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:13 INFO - 'NO_EM_RESTART': '1', 04:09:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:13 INFO - 'PWD': '/builds/slave/test', 04:09:13 INFO - 'SHELL': '/bin/bash', 04:09:13 INFO - 'SHLVL': '1', 04:09:13 INFO - 'TERM': 'linux', 04:09:13 INFO - 'TMOUT': '86400', 04:09:13 INFO - 'USER': 'cltbld', 04:09:13 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 04:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:13 INFO - '_': '/tools/buildbot/bin/python'} 04:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:13 INFO - Downloading/unpacking psutil>=3.1.1 04:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:09:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:09:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:09:14 INFO - Installing collected packages: psutil 04:09:14 INFO - Running setup.py install for psutil 04:09:15 INFO - building 'psutil._psutil_linux' extension 04:09:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:09:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:09:15 INFO - building 'psutil._psutil_posix' extension 04:09:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:09:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:09:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:09:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:09:15 INFO - Successfully installed psutil 04:09:15 INFO - Cleaning up... 04:09:15 INFO - Return code: 0 04:09:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dfd60>, '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 0x24e0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e0ef0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:09:15 INFO - Using env: {'DISPLAY': ':0', 04:09:15 INFO - 'HOME': '/home/cltbld', 04:09:15 INFO - 'LANG': 'en_US.UTF-8', 04:09:15 INFO - 'LANGUAGE': 'en_US:en', 04:09:15 INFO - 'LOGNAME': 'cltbld', 04:09:15 INFO - 'MAIL': '/var/mail/cltbld', 04:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:15 INFO - 'MOZ_NO_REMOTE': '1', 04:09:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:15 INFO - 'NO_EM_RESTART': '1', 04:09:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:15 INFO - 'PWD': '/builds/slave/test', 04:09:15 INFO - 'SHELL': '/bin/bash', 04:09:15 INFO - 'SHLVL': '1', 04:09:15 INFO - 'TERM': 'linux', 04:09:15 INFO - 'TMOUT': '86400', 04:09:15 INFO - 'USER': 'cltbld', 04:09:15 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 04:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:15 INFO - '_': '/tools/buildbot/bin/python'} 04:09:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:09:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:09:16 INFO - Installing collected packages: mozsystemmonitor 04:09:16 INFO - Running setup.py install for mozsystemmonitor 04:09:17 INFO - Successfully installed mozsystemmonitor 04:09:17 INFO - Cleaning up... 04:09:17 INFO - Return code: 0 04:09:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:17 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 0x21751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dfd60>, '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 0x24e0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e0ef0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:09:17 INFO - Using env: {'DISPLAY': ':0', 04:09:17 INFO - 'HOME': '/home/cltbld', 04:09:17 INFO - 'LANG': 'en_US.UTF-8', 04:09:17 INFO - 'LANGUAGE': 'en_US:en', 04:09:17 INFO - 'LOGNAME': 'cltbld', 04:09:17 INFO - 'MAIL': '/var/mail/cltbld', 04:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:17 INFO - 'MOZ_NO_REMOTE': '1', 04:09:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:17 INFO - 'NO_EM_RESTART': '1', 04:09:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:17 INFO - 'PWD': '/builds/slave/test', 04:09:17 INFO - 'SHELL': '/bin/bash', 04:09:17 INFO - 'SHLVL': '1', 04:09:17 INFO - 'TERM': 'linux', 04:09:17 INFO - 'TMOUT': '86400', 04:09:17 INFO - 'USER': 'cltbld', 04:09:17 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 04:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:17 INFO - '_': '/tools/buildbot/bin/python'} 04:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:18 INFO - Downloading/unpacking jsonschema==2.5.1 04:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:09:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:20 INFO - Downloading functools32-3.2.3-2.tar.gz 04:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:09:20 INFO - warning: no files found matching '*.txt' 04:09:20 INFO - no previously-included directories found matching 'build' 04:09:20 INFO - no previously-included directories found matching 'dist' 04:09:20 INFO - no previously-included directories found matching '.git*' 04:09:20 INFO - Installing collected packages: jsonschema, functools32 04:09:20 INFO - Running setup.py install for functools32 04:09:20 INFO - warning: no files found matching '*.txt' 04:09:20 INFO - no previously-included directories found matching 'build' 04:09:20 INFO - no previously-included directories found matching 'dist' 04:09:20 INFO - no previously-included directories found matching '.git*' 04:09:20 INFO - Successfully installed jsonschema functools32 04:09:20 INFO - Cleaning up... 04:09:20 INFO - Return code: 0 04:09:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:20 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 0x21751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dfd60>, '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 0x24e0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e0ef0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:09:20 INFO - Using env: {'DISPLAY': ':0', 04:09:20 INFO - 'HOME': '/home/cltbld', 04:09:20 INFO - 'LANG': 'en_US.UTF-8', 04:09:20 INFO - 'LANGUAGE': 'en_US:en', 04:09:20 INFO - 'LOGNAME': 'cltbld', 04:09:20 INFO - 'MAIL': '/var/mail/cltbld', 04:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:20 INFO - 'MOZ_NO_REMOTE': '1', 04:09:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:20 INFO - 'NO_EM_RESTART': '1', 04:09:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:20 INFO - 'PWD': '/builds/slave/test', 04:09:20 INFO - 'SHELL': '/bin/bash', 04:09:20 INFO - 'SHLVL': '1', 04:09:20 INFO - 'TERM': 'linux', 04:09:20 INFO - 'TMOUT': '86400', 04:09:20 INFO - 'USER': 'cltbld', 04:09:20 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 04:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:20 INFO - '_': '/tools/buildbot/bin/python'} 04:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:09:20 INFO - Cleaning up... 04:09:20 INFO - Return code: 0 04:09:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:20 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 0x21751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dfd60>, '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 0x24e0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e0ef0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:09:20 INFO - Using env: {'DISPLAY': ':0', 04:09:20 INFO - 'HOME': '/home/cltbld', 04:09:20 INFO - 'LANG': 'en_US.UTF-8', 04:09:20 INFO - 'LANGUAGE': 'en_US:en', 04:09:20 INFO - 'LOGNAME': 'cltbld', 04:09:20 INFO - 'MAIL': '/var/mail/cltbld', 04:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:20 INFO - 'MOZ_NO_REMOTE': '1', 04:09:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:20 INFO - 'NO_EM_RESTART': '1', 04:09:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:20 INFO - 'PWD': '/builds/slave/test', 04:09:20 INFO - 'SHELL': '/bin/bash', 04:09:20 INFO - 'SHLVL': '1', 04:09:20 INFO - 'TERM': 'linux', 04:09:20 INFO - 'TMOUT': '86400', 04:09:20 INFO - 'USER': 'cltbld', 04:09:20 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 04:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:20 INFO - '_': '/tools/buildbot/bin/python'} 04:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:20 INFO - Downloading/unpacking blobuploader==1.2.4 04:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:21 INFO - Downloading blobuploader-1.2.4.tar.gz 04:09:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:09:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:09:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:22 INFO - Downloading docopt-0.6.1.tar.gz 04:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:09:22 INFO - Installing collected packages: blobuploader, requests, docopt 04:09:22 INFO - Running setup.py install for blobuploader 04:09:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:09:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:09:22 INFO - Running setup.py install for requests 04:09:22 INFO - Running setup.py install for docopt 04:09:23 INFO - Successfully installed blobuploader requests docopt 04:09:23 INFO - Cleaning up... 04:09:23 INFO - Return code: 0 04:09:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:23 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 0x21751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dfd60>, '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 0x24e0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e0ef0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:09:23 INFO - Using env: {'DISPLAY': ':0', 04:09:23 INFO - 'HOME': '/home/cltbld', 04:09:23 INFO - 'LANG': 'en_US.UTF-8', 04:09:23 INFO - 'LANGUAGE': 'en_US:en', 04:09:23 INFO - 'LOGNAME': 'cltbld', 04:09:23 INFO - 'MAIL': '/var/mail/cltbld', 04:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:23 INFO - 'MOZ_NO_REMOTE': '1', 04:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:23 INFO - 'NO_EM_RESTART': '1', 04:09:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:23 INFO - 'PWD': '/builds/slave/test', 04:09:23 INFO - 'SHELL': '/bin/bash', 04:09:23 INFO - 'SHLVL': '1', 04:09:23 INFO - 'TERM': 'linux', 04:09:23 INFO - 'TMOUT': '86400', 04:09:23 INFO - 'USER': 'cltbld', 04:09:23 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 04:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:23 INFO - '_': '/tools/buildbot/bin/python'} 04:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:09:23 INFO - Running setup.py (path:/tmp/pip-CnZ5TB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:09:23 INFO - Running setup.py (path:/tmp/pip-Gz8kMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:09:23 INFO - Running setup.py (path:/tmp/pip-JGS5TI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:09:23 INFO - Running setup.py (path:/tmp/pip-w5hJ3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:09:23 INFO - Running setup.py (path:/tmp/pip-EhfgwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:09:23 INFO - Running setup.py (path:/tmp/pip-7iARfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:09:23 INFO - Running setup.py (path:/tmp/pip-WhWBvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:09:23 INFO - Running setup.py (path:/tmp/pip-zChj4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:09:23 INFO - Running setup.py (path:/tmp/pip-WoAAhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:09:23 INFO - Running setup.py (path:/tmp/pip-pJ_IPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:09:24 INFO - Running setup.py (path:/tmp/pip-tWtyx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:09:24 INFO - Running setup.py (path:/tmp/pip-qet2CC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:09:24 INFO - Running setup.py (path:/tmp/pip-n7IRbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:09:24 INFO - Running setup.py (path:/tmp/pip-4Tk3QB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:24 INFO - Running setup.py (path:/tmp/pip-pCjACu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:09:24 INFO - Running setup.py (path:/tmp/pip-CIySu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:09:24 INFO - Running setup.py (path:/tmp/pip-irnElb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:09:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:09:24 INFO - Running setup.py install for manifestparser 04:09:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:09:24 INFO - Running setup.py install for mozcrash 04:09:24 INFO - Running setup.py install for mozdebug 04:09:24 INFO - Running setup.py install for mozdevice 04:09:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:09:24 INFO - Running setup.py install for mozfile 04:09:25 INFO - Running setup.py install for mozhttpd 04:09:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:09:25 INFO - Running setup.py install for mozinfo 04:09:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:09:25 INFO - Running setup.py install for mozInstall 04:09:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:09:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:09:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:09:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:09:25 INFO - Running setup.py install for mozleak 04:09:25 INFO - Running setup.py install for mozlog 04:09:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:09:25 INFO - Running setup.py install for moznetwork 04:09:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:09:25 INFO - Running setup.py install for mozprocess 04:09:25 INFO - Running setup.py install for mozprofile 04:09:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:09:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:09:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:09:25 INFO - Running setup.py install for mozrunner 04:09:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:09:25 INFO - Running setup.py install for mozscreenshot 04:09:26 INFO - Running setup.py install for moztest 04:09:26 INFO - Running setup.py install for mozversion 04:09:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:09:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:09:26 INFO - Cleaning up... 04:09:26 INFO - Return code: 0 04:09:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:26 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 0x21751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dfd60>, '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 0x24e0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e0ef0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:09:26 INFO - Using env: {'DISPLAY': ':0', 04:09:26 INFO - 'HOME': '/home/cltbld', 04:09:26 INFO - 'LANG': 'en_US.UTF-8', 04:09:26 INFO - 'LANGUAGE': 'en_US:en', 04:09:26 INFO - 'LOGNAME': 'cltbld', 04:09:26 INFO - 'MAIL': '/var/mail/cltbld', 04:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:26 INFO - 'MOZ_NO_REMOTE': '1', 04:09:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:26 INFO - 'NO_EM_RESTART': '1', 04:09:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:26 INFO - 'PWD': '/builds/slave/test', 04:09:26 INFO - 'SHELL': '/bin/bash', 04:09:26 INFO - 'SHLVL': '1', 04:09:26 INFO - 'TERM': 'linux', 04:09:26 INFO - 'TMOUT': '86400', 04:09:26 INFO - 'USER': 'cltbld', 04:09:26 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 04:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:26 INFO - '_': '/tools/buildbot/bin/python'} 04:09:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:09:26 INFO - Running setup.py (path:/tmp/pip-ZJxcj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:09:26 INFO - Running setup.py (path:/tmp/pip-_tQAFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:09:26 INFO - Running setup.py (path:/tmp/pip-CpKKze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:09:26 INFO - Running setup.py (path:/tmp/pip-7NpDd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:09:26 INFO - Running setup.py (path:/tmp/pip-SEysp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:09:26 INFO - Running setup.py (path:/tmp/pip-Nej9Zz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:09:26 INFO - Running setup.py (path:/tmp/pip-59TTf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:09:26 INFO - Running setup.py (path:/tmp/pip-0xT_zE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:09:27 INFO - Running setup.py (path:/tmp/pip-FTU4Ic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:09:27 INFO - Running setup.py (path:/tmp/pip-e8yjTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:09:27 INFO - Running setup.py (path:/tmp/pip-1kKnDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:09:27 INFO - Running setup.py (path:/tmp/pip-QQjKXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:09:27 INFO - Running setup.py (path:/tmp/pip-GJi0S9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:09:27 INFO - Running setup.py (path:/tmp/pip-SywZGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:27 INFO - Running setup.py (path:/tmp/pip-6fQUQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:09:27 INFO - Running setup.py (path:/tmp/pip-S3h8cC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:09:27 INFO - Running setup.py (path:/tmp/pip-RYZbuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:09:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:28 INFO - Downloading blessings-1.6.tar.gz 04:09:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:09:28 INFO - Installing collected packages: blessings 04:09:28 INFO - Running setup.py install for blessings 04:09:29 INFO - Successfully installed blessings 04:09:29 INFO - Cleaning up... 04:09:29 INFO - Return code: 0 04:09:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:09:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:09:29 INFO - Reading from file tmpfile_stdout 04:09:29 INFO - Current package versions: 04:09:29 INFO - argparse == 1.2.1 04:09:29 INFO - blessings == 1.6 04:09:29 INFO - blobuploader == 1.2.4 04:09:29 INFO - docopt == 0.6.1 04:09:29 INFO - functools32 == 3.2.3-2 04:09:29 INFO - jsonschema == 2.5.1 04:09:29 INFO - manifestparser == 1.1 04:09:29 INFO - mozInstall == 1.13 04:09:29 INFO - mozcrash == 1.0 04:09:29 INFO - mozdebug == 0.1 04:09:29 INFO - mozdevice == 0.51 04:09:29 INFO - mozfile == 1.2 04:09:29 INFO - mozhttpd == 0.7 04:09:29 INFO - mozinfo == 0.10 04:09:29 INFO - mozleak == 0.1 04:09:29 INFO - mozlog == 3.5 04:09:29 INFO - moznetwork == 0.27 04:09:29 INFO - mozprocess == 0.25 04:09:29 INFO - mozprofile == 0.28 04:09:29 INFO - mozrunner == 6.13 04:09:29 INFO - mozscreenshot == 0.1 04:09:29 INFO - mozsystemmonitor == 0.3 04:09:29 INFO - moztest == 0.8 04:09:29 INFO - mozversion == 1.4 04:09:29 INFO - psutil == 3.1.1 04:09:29 INFO - requests == 1.2.3 04:09:29 INFO - wsgiref == 0.1.2 04:09:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:09: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')]} 04:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09: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')]} 04:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:29 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 0x21751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dfd60>, '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 0x24e0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e0ef0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:09:29 INFO - Using env: {'DISPLAY': ':0', 04:09:29 INFO - 'HOME': '/home/cltbld', 04:09:29 INFO - 'LANG': 'en_US.UTF-8', 04:09:29 INFO - 'LANGUAGE': 'en_US:en', 04:09:29 INFO - 'LOGNAME': 'cltbld', 04:09:29 INFO - 'MAIL': '/var/mail/cltbld', 04:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:29 INFO - 'MOZ_NO_REMOTE': '1', 04:09:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:29 INFO - 'NO_EM_RESTART': '1', 04:09:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:29 INFO - 'PWD': '/builds/slave/test', 04:09:29 INFO - 'SHELL': '/bin/bash', 04:09:29 INFO - 'SHLVL': '1', 04:09:29 INFO - 'TERM': 'linux', 04:09:29 INFO - 'TMOUT': '86400', 04:09:29 INFO - 'USER': 'cltbld', 04:09:29 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 04:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:29 INFO - '_': '/tools/buildbot/bin/python'} 04:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:09:29 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:09:30 INFO - Installing collected packages: simplejson 04:09:30 INFO - Running setup.py install for simplejson 04:09:30 INFO - building 'simplejson._speedups' extension 04:09:30 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 04:09:31 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 04:09:31 INFO - Successfully installed simplejson 04:09:31 INFO - Cleaning up... 04:09:31 INFO - Return code: 0 04:09:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:31 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 0x21751f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x233db70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e29c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dfd60>, '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 0x24e0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e0ef0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:09:31 INFO - Using env: {'DISPLAY': ':0', 04:09:31 INFO - 'HOME': '/home/cltbld', 04:09:31 INFO - 'LANG': 'en_US.UTF-8', 04:09:31 INFO - 'LANGUAGE': 'en_US:en', 04:09:31 INFO - 'LOGNAME': 'cltbld', 04:09:31 INFO - 'MAIL': '/var/mail/cltbld', 04:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:31 INFO - 'MOZ_NO_REMOTE': '1', 04:09:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:31 INFO - 'NO_EM_RESTART': '1', 04:09:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:31 INFO - 'PWD': '/builds/slave/test', 04:09:31 INFO - 'SHELL': '/bin/bash', 04:09:31 INFO - 'SHLVL': '1', 04:09:31 INFO - 'TERM': 'linux', 04:09:31 INFO - 'TMOUT': '86400', 04:09:31 INFO - 'USER': 'cltbld', 04:09:31 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 04:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:31 INFO - '_': '/tools/buildbot/bin/python'} 04:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:09:31 INFO - Cleaning up... 04:09:31 INFO - Return code: 0 04:09:31 INFO - Running post-action listener: _resource_record_post_action 04:09:31 INFO - Running post-action listener: _start_resource_monitoring 04:09:31 INFO - Starting resource monitoring. 04:09:31 INFO - [mozharness: 2017-09-28 11:09:31.981530Z] Finished create-virtualenv step (success) 04:09:31 INFO - [mozharness: 2017-09-28 11:09:31.981998Z] Running install step. 04:09:31 INFO - Running pre-action listener: _resource_record_pre_action 04:09:31 INFO - Running main action method: install 04:09:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:09:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:09:32 INFO - Reading from file tmpfile_stdout 04:09:32 INFO - Detecting whether we're running mozinstall >=1.0... 04:09:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:09:32 INFO - Reading from file tmpfile_stdout 04:09:32 INFO - Output received: 04:09:32 INFO - Usage: mozinstall [options] installer 04:09:32 INFO - Options: 04:09:32 INFO - -h, --help show this help message and exit 04:09:32 INFO - -d DEST, --destination=DEST 04:09:32 INFO - Directory to install application into. [default: 04:09:32 INFO - "/builds/slave/test"] 04:09:32 INFO - --app=APP Application being installed. [default: firefox] 04:09:32 INFO - mkdir: /builds/slave/test/build/application 04:09:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:09:49 INFO - Reading from file tmpfile_stdout 04:09:49 INFO - Output received: 04:09:49 INFO - /builds/slave/test/build/application/firefox/firefox 04:09:49 INFO - Running post-action listener: _resource_record_post_action 04:09:49 INFO - [mozharness: 2017-09-28 11:09:49.941602Z] Finished install step (success) 04:09:49 INFO - [mozharness: 2017-09-28 11:09:49.941852Z] Running setup-mitmproxy step. 04:09:49 INFO - Running pre-action listener: _resource_record_pre_action 04:09:49 INFO - Running main action method: setup_mitmproxy 04:09:49 INFO - Skipping: mitmproxy is not required 04:09:49 INFO - Running post-action listener: _resource_record_post_action 04:09:49 INFO - [mozharness: 2017-09-28 11:09:49.942978Z] Finished setup-mitmproxy step (success) 04:09:49 INFO - [mozharness: 2017-09-28 11:09:49.943215Z] Running run-tests step. 04:09:49 INFO - Running pre-action listener: _resource_record_pre_action 04:09:49 INFO - Running pre-action listener: _set_gcov_prefix 04:09:49 INFO - Running main action method: run_tests 04:09:49 WARNING - Try message not found. 04:09:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:09:49 INFO - Python 2.7.3 04:09:49 INFO - Return code: 0 04:09:49 INFO - grabbing minidump binary from tooltool 04:09: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')]} 04:09:49 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 0x24dfd60>, '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 0x24e0a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e0ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:09:49 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:09:49 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:09:49 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:09:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:09:50 INFO - Return code: 0 04:09:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:09:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:09:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:09:50 INFO - ENV: RUST_BACKTRACE is now full 04:09:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:09:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-089 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:09:50 INFO - Using env: {'DISPLAY': ':0', 04:09:50 INFO - 'HOME': '/home/cltbld', 04:09:50 INFO - 'LANG': 'en_US.UTF-8', 04:09:50 INFO - 'LANGUAGE': 'en_US:en', 04:09:50 INFO - 'LOGNAME': 'cltbld', 04:09:50 INFO - 'MAIL': '/var/mail/cltbld', 04:09:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:09:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:50 INFO - 'MOZ_NO_REMOTE': '1', 04:09:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:09:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:50 INFO - 'NO_EM_RESTART': '1', 04:09:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:50 INFO - 'PWD': '/builds/slave/test', 04:09:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:09:50 INFO - 'RUST_BACKTRACE': 'full', 04:09:50 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:09:50 INFO - 'SHELL': '/bin/bash', 04:09:50 INFO - 'SHLVL': '1', 04:09:50 INFO - 'TERM': 'linux', 04:09:50 INFO - 'TMOUT': '86400', 04:09:50 INFO - 'USER': 'cltbld', 04:09:50 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 04:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:50 INFO - '_': '/tools/buildbot/bin/python'} 04:09:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:09:50 INFO - mozversion application_buildid: 20170928094443 04:09:50 INFO - mozversion application_changeset: 76a26ef7c493311c170ae83eb0c1d6592a21396d 04:09:50 INFO - mozversion application_display_name: Nightly 04:09:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:09:50 INFO - mozversion application_name: Firefox 04:09:50 INFO - mozversion application_remotingname: firefox 04:09:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:09:50 INFO - mozversion application_vendor: Mozilla 04:09:50 INFO - mozversion application_version: 58.0a1 04:09:50 INFO - mozversion platform_buildid: 20170928094443 04:09:50 INFO - mozversion platform_changeset: 76a26ef7c493311c170ae83eb0c1d6592a21396d 04:09:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:09:50 INFO - mozversion platform_version: 58.0a1 04:09:50 INFO - using testdate: 1506596990 04:09:50 INFO - actual date: 1506596990 04:09:50 INFO - starting webserver on 'localhost:35607' 04:09:50 INFO - SUITE-START | Running 2 tests 04:09:50 INFO - TEST-START | dromaeo_css 04:09:50 INFO - Initialising browser for dromaeo_css test... 04:09:50 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35607/getInfo.html -profile /tmp/tmp0k8WJc/profile 04:09:50 INFO - TEST-INFO | started process 21712 (/builds/slave/test/build/application/firefox/firefox http://localhost:35607/getInfo.html) 04:09:56 INFO - TEST-INFO | 21712: exit 0 04:09:56 INFO - Browser initialized. 04:09:56 INFO - Running cycle 1/1 for dromaeo_css test... 04:09:56 INFO - Using env: {'DISPLAY': ':0', 04:09:56 INFO - 'HOME': '/home/cltbld', 04:09:56 INFO - 'JSGC_DISABLE_POISONING': '1', 04:09:56 INFO - 'LANG': 'en_US.UTF-8', 04:09:56 INFO - 'LANGUAGE': 'en_US:en', 04:09:56 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:09:56 INFO - 'LOGNAME': 'cltbld', 04:09:56 INFO - 'MAIL': '/var/mail/cltbld', 04:09:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:09:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:09:56 INFO - 'MOZ_CRASHREPORTER': '1', 04:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:56 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:09:56 INFO - 'MOZ_NO_REMOTE': '1', 04:09:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:09:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:56 INFO - 'NO_EM_RESTART': '1', 04:09:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:56 INFO - 'PWD': '/builds/slave/test', 04:09:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:09:56 INFO - 'RUST_BACKTRACE': 'full', 04:09:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:09:56 INFO - 'SHELL': '/bin/bash', 04:09:56 INFO - 'SHLVL': '1', 04:09:56 INFO - 'TERM': 'linux', 04:09:56 INFO - 'TMOUT': '86400', 04:09:56 INFO - 'USER': 'cltbld', 04:09:56 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 04:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:56 INFO - '_': '/tools/buildbot/bin/python'} 04:09:56 INFO - TEST-INFO | started process 21949 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0k8WJc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:09:57 INFO - PID 21949 | 04:09:57 INFO - PID 21949 | (/builds/slave/test/build/application/firefox/firefox:21999): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:09:57 INFO - PID 21949 | 04:10:07 INFO - PID 21949 | RSS: Main: 144375808 04:10:07 INFO - PID 21949 | 04:13:05 INFO - PID 21949 | Cycle 1(1): loaded http://localhost:35607/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35607/tests/dromaeo/cssquery-ext.html) 04:13:06 INFO - PID 21949 | RSS: Main: 142499840 04:13:06 INFO - PID 21949 | 04:16:07 INFO - PID 21949 | Cycle 1(1): loaded http://localhost:35607/tests/dromaeo/cssquery-ext.html (next: http://localhost:35607/tests/dromaeo/cssquery-jquery.html) 04:16:08 INFO - PID 21949 | RSS: Main: 143237120 04:16:08 INFO - PID 21949 | 04:19:09 INFO - PID 21949 | Cycle 1(1): loaded http://localhost:35607/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35607/tests/dromaeo/cssquery-mootools.html) 04:19:10 INFO - PID 21949 | RSS: Main: 144596992 04:19:10 INFO - PID 21949 | 04:22:11 INFO - PID 21949 | Cycle 1(1): loaded http://localhost:35607/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35607/tests/dromaeo/cssquery-prototype.html) 04:22:12 INFO - PID 21949 | RSS: Main: 144625664 04:22:12 INFO - PID 21949 | 04:25:15 INFO - PID 21949 | Cycle 1(1): loaded http://localhost:35607/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35607/tests/dromaeo/cssquery-yui.html) 04:25:16 INFO - PID 21949 | RSS: Main: 147550208 04:25:16 INFO - PID 21949 | 04:28:17 INFO - PID 21949 | Cycle 1(1): loaded http://localhost:35607/tests/dromaeo/cssquery-yui.html (next: http://localhost:35607/tests/dromaeo/cssquery-dojo.html) 04:28:17 INFO - PID 21949 | RSS: Main: 148787200 04:28:17 INFO - PID 21949 | 04:28:17 INFO - PID 21949 | __start_tp_report 04:28:17 INFO - PID 21949 | _x_x_mozilla_page_load 04:28:17 INFO - PID 21949 | _x_x_mozilla_page_load_details 04:28:17 INFO - PID 21949 | |i|pagename|runs| 04:28:17 INFO - PID 21949 | |0;dojo.html;3901.7658940463575;3905.589913059129;3922.490076290082;3935.744176628518;3945.28984782739;6158.876822463548;6241.719122639481;6273.404026617475;6289.779857704982;6326.810195694131;6283.654399008053;6284.528660350469;6289.968550157243;6314.305426403092;6319.873602527947;5635.6618602883755;5637.774489020434;5658.151277308406;5661.179129026289;5722.942770572304;5714.628549144313;5755.597108202427;5772;5775.422457754212;5793;5695.316562012565;5712.6286791358425;5721.513671337942;5784.479396854283;5825.213596164506;5233.607479439057;5550.417206193332;5638.548916086703;5653.349864765563;5738.741756620921;5668.121441176624;5671.489565939044;5726.198332233491;5753.769849206027;5754.367019627837;6209.316975132731;6289.685515724242;6306.968465157691;6340.968295158541;6348.079528468346;6795.762148324785;6828.146481689789;6833.145856767904;6851.862962740767;6855.5543889647015;6797.524173307821;6873.828154296132;6874.725010999554;6885.380315771605;6897.517173797836;6228.657423841687;6289.465395441393;6298.496120310364;6371.968140159316;6429.22849258092;7034;7034.8944765828555;7111.822204444827;7116.430685545144;7133.001379806831;6746.730130794714;6755.324467553303;6818.727250910008;6836.316368363224;6845.383915447634;6744.966275168593;6809.591424514545;6862.656867156622;6869.107016087876;6948.27043160471;6715.6977935993;6761.425278851254;6766.898496522556;6787.524873258824;6787.524873258923;6649.301823308483;6798.184217893884;6820.658967051628;6824.556403833735;6829.965850170717;4876.878078048077;4879.34128892595;4946.579540739005;4947.752612369367;4967.776450059756;4432.401625780542;4480.641548676098;4483.708558943724;4522.728636281834;4546.02260513991;6299.6535190564955;6309.211348581427;6318.873622527523;6323.52573556985;6323.683815809283;6776.9322306777285;6784.151981002375;6785;6818.693158807866;6863.931360686429;6183.258009038944;6243.969744992116;6255.186825712719;6333.524985626096;6340.492760579143;5525.861853453615;5579.9163012554845;5580.525655319343;5583.218349431002;5605.775768969277;7057.647117644097;7119.4660400470175;7175.210726820045;7176.856462870714;7202.567845929261;6716.496262780213;6749.392554670103;6779.72881084751;6798.422134118655;6908.1364829396325;7041;7044.15470143586;7071.681774320271;7075;7088.610126443095;6765.594064356154;6817.829554261183;6838.418734407631;6848.315168483112;6872;6744.932550674431;6791.252962174256;6854.9657251713425;6895.172579290517;6923.446124310144;6581.934180658323;6639.634820084942;6640.169978752656;6651.700673469609;6671.5997040177745;6590.176227971504;6590.868182636416;6625.204975402982;6672.466202703869;6700.865982680417;6474.384933431316;6485.156929599122;6553.967230163818;6568.408843204134;6581.276059633532;6601.174853143357;6672.633005184762;6677.2655007948115;6721.126253587198;6726.764563240263;6397.264314603694;6443.387878151568;6484.70818813164;6516.51126165549;6517.250516190698 04:28:17 INFO - PID 21949 | |1;ext.html;923.6767131503974;951.7810903492201;964.9179819715324;991.1921783746244;1010.0354161775505;45418.77290613546;45929;46046.76976615112;46209.53790462094;46350.304745428846;43120;43293.13413731723;43375;43512;43527;7582.545047297166;7677.2322767723335;7681.193474685134;7689.154193038761;7718.073831140271;7572.545647261154;7668.808279792994;7669;7683.884741728879;7688.461807673465;8627.698030568932;8790.208881200691;8794.956025219864;8801.427907186044;8820.911790882104;8660.30717542595;8668.566571671423;8727.650893964234;8733.170348816884;8744.825103497958;8390.958045209796;8564.657413703444;8584.098669639661;8588.141185881424;8708.521031343274;41835.163296733896;41984.1603167938;42096.57903420957;42122;42304.78847605773;3778.962210377916;3786.734928555002;3821.5987321331136;3822.4266360045935;3823.5220597425323;33946;34020.659793401996;34043.82978085094;34047.6595234047;34138.829305853564;5428.647137936061;5437.34751829779;5457.345118585756;5465.207544906006;5494.423085575997;238942;239193;239389;240105;241311;55274.44725552773;55368;55377.723111384184;55609;55822;58928.41071589229;59033;59140.704296478245;59194;59251.407485925454;16641.251143698562;16652.250648720594;16727.66544669124;16776.161191940355;16890.071046092336;13351.933240333736;13402.396892139877;13455.865441345657;13474.730505390033;13520.18878867271;15377.846221537642;15629.14039727826;15682;15696.21518924072;15731.528054158394;6097.542684298695;6130.29501607312;6197.504199664016;6318.4313411793155;6327.462165715966;1090.5637744901944;1095.222392101601;1100.49927283086;1103.9724006899892;1104.2380757277454;26260;26760.464790704078;26896.193114206606;27080.59379109315;27151.728482715316;1192.6064398748565;1194.6714653470365;1202.1765090912752;1207.4747484844122;1216.707990082374;13795.862041379458;13888.930555347159;14005.929970350084;14160.716785664228;14201.573952781433;307251;309285;309544;310254;310827;63936.68031659812;64399.67800161063;64529.67735161388;64541;64576;1455.9344829482698;1524.7103050420606;1536.4084827341553;1540.8151021877447;1545.590418539088;25761.613575796004;26059.348516287246;26159.476810463304;26248;26268.343291417867;43467.347989780814;43576;43645.781771090944;43911.78044109759;43962.34056489091;252278;253331;253542;254238;254472;39210.41184382179;41667.58332416637;44421.33367999547;44567.10865782731;44658.3301250488;53236;54605.726971364886;54657.45342546524;54871;54972.72513637566;38889.41665874958;39151.021224469616;39279.80360098181;39460.60539394569;39720.205595888496;38394.80802595969;38847.805760971016;38883.416748749354;39028;39366;45906.770466147456;46023.76988115038;46338.53661463477;46446;46484;37209.813950930074;37829.43255851219;37932.24135517219;38094.428583571826;38375;28145.718542815128;28190.577141342485;28377;28386.85806570954;28438.43123137484 04:28:17 INFO - PID 21949 | |2;jquery.html;615.4983688294041;633.5913335898343;639.4500729372742;648.6918713611035;657.5200103924137;22597.77402225977;23431.531369372602;24103.15638952637;24167.39581510461;24353.512929741395;25001.874990625067;26307.736922630767;26325;26352.209433716973;26368;6423.421892029728;6566.212054553436;6575.0466182403525;6575.4082132608055;6593;6618.93381066188;6850.691718872663;6868.7252509899545;6881.862362752742;6886.586804791728;6854.965725171367;6860.451163906875;6863.279355667658;6868.278830722752;6874;6399.232092148949;6662.233843108056;6663.36698013688;6673.065770792093;6857.314268573153;6191.9690401547705;6195.132681424604;6235.657038862861;6272.341404152589;6278.215223097113;30257;30862;30922.226944326572;31032.844835775675;31064.378712425852;2164.296603603835;2191.123550579765;2211.9447013824624;2213.6236839737285;2224.9666255006186;20083.89958050215;20795;20898.37304880856;20959.68560471594;20967.16131354744;2456.4472993576483;2495.6007038873695;2533.8479691218586;2615.594582839663;2629.4346715456077;145132.27433862764;152011;158074;167266.99999999878;167621;16102.19489025544;16578.253978570752;16790.664186716196;17008.57478563046;17655.73516397255;26631.733682663315;26727.19818405451;26732;26743.598846017325;26746.598801018;10395.740106497247;10573.629922952814;10634.361938283728;10723.356598604109;10808;9304.162625363615;9444.952775236217;9494.003129671422;9556.85664715016;9570.856437153448;9958.900410995942;9998.500074996222;10106.595736170471;10140.543675534618;10318.43248621318;2905.636795400575;2954.985225073861;3000.2499375156212;3030.4696678081245;3080.613723824288;1032.8554002439664;1045.1429827541342;1073.8550295710136;1078.5308390850005;1083.6532309660834;20978.47553811167;21426.142954281964;21589.67615485769;21645.67531487029;21826.454338641663;1114.793763153819;1116.2242241641982;1116.263266244275;1119.5130118398522;1126.5155982927256;11620.825687614693;11817.14160083552;11876.10929180315;11953.103517236243;12013.519459221534;624601;633362;637447;640901;667413;128875;134930;135007;137170;138839.30580347034;1220.0849362977767;1237.3998610673816;1261.2684642907093;1262.665393670678;1264.987350126487;18557.35049273269;18984.43046708601;19020.144093515548;19064.42806715801;19079.236830526625;61534.38465615286;62541;62595.6870215646;63476;64316;76570;76891;77641;78081.60959195168;79980;15420.306086226372;15696;15714.449994250605;15770.526884193492;15819.209039547746;23437.179698710464;23472.061117555106;23593.88203058974;23794.88102559476;23841.403964901016;19055.333063342718;19064;19082.14130364165;19238.23047078101;19265.5183620405;17293.65412691764;17318.913405432893;17358.652826943642;18168.818311817242;18192.45422637323;19552.413427597196;19700.50748731293;19721.605567888848;19723.11245993905;19924.402267931986;15365.078095314317;15499.14754688503;15511.84488155104;15554.222288885736;15573.532794016197;18653.813461865208;19111.331103411314;19174.13716382738;19232.134553944827;19244.518887027378 04:28:17 INFO - PID 21949 | |3;mootools.html;1438.9352479138438;1461.371610207611;1478.8890833187513;1481.548127821014;1505.036776463064;16838.747418788713;17489.475315740518;17527.948323100605;17557.64884702305;17567.560810979732;16972.74540881888;17079.743803842954;17120.31518739249;17206;17214.82785172148;2799.2721892308045;2868.340281735202;2870.167651381097;2873.612062371584;2877.0505733108025;2706.4316493536285;2800.719928007193;2802.1313392848283;2803.2010876900085;2809.3117186289287;2730.426610411816;2782.9721702782917;2784.1090850927712;2784.2482529716963;2784.6797618273954;2602.531544322021;2640.788736901043;2641.8679066046716;2643.0088716731225;2643.524165650182;2569.498947705198;2611.673540807399;2619.122593931035;2619.174959887648;2621.3839747659285;15900.443484478103;16278;16309.429169979112;16315.918420407941;16340.918295408566;7932.920670793276;8204.589770511451;8204.876926846102;8207.835843283101;8224.300934420582;5236.319278493771;5338.706371149585;5560.360558535739;5594.9720251398885;5597.74810133545;2015.314792970397;2017.5157962088992;2087.895605219748;2136.508603021314;2162.697222388867;28210;29100;30725.846370768002;31782.04653860365;31798.523022154684;7355.080614923135;7405.222451642608;7428.257174282637;7484.438667099989;7592.354649854714;22541.43646408853;22559.88720056422;22627.547449051257;22690.432739181324;22764.772352276595;8305.626246818907;8353.707620233385;8420.11588783169;8606.78483037929;8629.093945135795;7460.948239234254;7589.8861517077285;7616.9238307616215;7642;7650.273224043707;5996.2504686914135;6189.294328560971;6198.752049918043;6226.937730622636;6506.154199953976;1630.6575619120117;1702.1574320711327;1833.9358122465649;1885.5191926059042;1889.5653999580175;941.4586612697726;964.9421034737938;970.8301047316692;970.9077637624414;979.6669132494986;8087.503811794837;8192.139825318374;8216.09622941476;8278.958605206935;8350.33197344211;1046.4035499765214;1056.730533713898;1068.4283908109123;1086.266769930294;1097.160672085855;4672.2057250267535;4688.156131896223;4777.259524773665;4793.688410253322;4835.927461088087;25094.874525627256;25551.361215969748;25748.8712556436;25752.356191095372;25857.353566160997;24802.751972480404;25016.87491562567;25551.872240638677;25598.616020759702;25605.743942560337;12082.697932551757;12792.6801829955;12984.220946743226;13017.349132543335;13032.934835325952;8965.641374344954;8976.506292153994;9037.141471560191;9074.773130671521;9118.635254589743;21150.68273975861;21195.78804211938;21362.679559806926;21394;21543.13827446947;21934.232301869357;22414.551708966057;22550.661740073585;22585;22632.886835566376;12783.23300601967;13018.15382000167;13025.283609401575;13025.413856376676;13025.544105956245;15421.84578154249;15682.529524114294;15741.291641875914;15812.762808558111;15841.128737919526;11970.341631210018;12088.51645934188;12098.75802483963;12434.440450179583;12609.684757881127;12390.62828115158;12451.875481245072;12454.564090256797;12802.615921522369;12890.935545322214;10319.94840025825;10342.689719308433;10378.325408848401;10514.789704206027;10529.73675658084;7758.030246219223;7771.145174030965;7911.406644501801;7973.641186146753;8005.039395272372;7642.4650274480255;7742.1483636798785;7750.379969602305;7759.262870027338;7847.646855891613 04:28:17 INFO - PID 21949 | |4;prototype.html;565.6634302589958;572.5591294703079;581.7440326256444;582.9766809327626;585.6544638663188;5993.730282137303;6065.454109130183;6084.452399284069;6113.938860611393;6120.540959428038;5985.371535988713;6045.455908968192;6058.091286307056;6075.635461872291;6122.387761223876;5030.84907452776;5039.546440820325;5041.697498150104;5076.44159142494;5096.311997880294;4754.405699287589;4913.066517361708;4942.827001054964;5042.773075211624;5054.545090941814;4894.804207831682;4907.239377896414;4912.48418916014;4921.335619691332;4926.778294976717;4853.733044682541;4872.731999740013;4901.264810278469;4917;4921.286413470062;4454.13144436835;4480.215962206634;4481.215787237221;4491.797869095899;4501.774911254424;6011.098335249748;6011.158437818709;6032.185654936615;6056.757729690807;6099.146119543268;5751.900556296921;5759.510441612426;5769.88460230793;5796.3913789052385;5839.182514447981;6469.126667899867;6472.255690595594;6477.22273327199;6503.122078519386;6521.47828173745;4728.983268597268;5005.924911126336;5012.1479348510475;5014.473480284591;5017.874553136165;6568.160914943824;6572.112764776742;6573.375529324707;6603.372679595431;6688.531802773857;5117.462666420047;5137.537621614073;5149.150390185578;5166.225066240094;5171.224316352502;5247.396549396868;5253.369595648546;5261.579073674096;5275.709835959059;5278.762455689503;5775.660857352779;5778.191053252548;5786.363500014995;5824.941750582525;5830.475257226785;6508.414242718178;6526.379993900572;6541.443977261891;6590.110335104795;6594.967025164843;1788.7674602301618;1809.5928416106271;1811.9275228990693;1820.7359932809668;1823.2433540080983;1227.128738595607;1286.2057429885258;1286.787680032784;1300.9414576344088;1301.8348578022806;5906.274980016056;5995.670238136944;5995.910061349084;5999.400059993965;6021.668808215591;6240.906386404207;6367.904481432783;6377.266614339317;6527.314631963575;6531.832061450193;5805.303363596396;5873.383294754075;5893.44012318829;5917.3371982608;5956.255468066492;3398.09950363154;3440.8795692150657;3448.2758620689433;3467.5318831957866;3471.149568355769;6713.597184168965;6744.527883048139;6763.661816909135;6811.829704257334;6825.215100263434;6668.133142691516;6672.599644021374;6706.295838936841;6708.396244338033;6732.831679207961;6744.460443164437;6802.319768023158;6825.191324299065;6858.451323894076;6871.6564171791215;6488.221413430229;6568.80293591193;6578.618490116937;6620.801375958729;6626.105475760711;6317.747290108528;6357.841053973688;6390.808275751735;6410.551261411656;6550.639714815731;6809.727610895508;6848.931510684829;6866.592348568632;6869.793906182823;6878.58728476293;5102.540771330598;5170.5346518813485;5174.120399532013;5182.948170518246;5206.791728330976;6221.377862213742;6394.4884409246215;6453.677316134268;6535.215774107138;6567.507436942153;6376.107344971615;6379.712912918837;6405.295417504164;6405.679716014274;6415.081138082255;6329.398707122816;6330.525210609315;6336.683165841781;6353;6377.840553986002;6247.406496382836;6254.8123556293385;6267.592606480564;6270.122182894307;6309.7160627770945;6407.935920640921;6439.388258115471;6454.903176452451;6473;6496.740130394731;6183.907241391293;6275.811725648238;6276.686165691606;6283.308836027942;6311 04:28:17 INFO - PID 21949 | |5;yui.html;957.3729207369175;978.7406337320612;986.955586998585;1000.0849222960991;1012.4684540616175;18826.529336766587;21202.25792097277;21288.787112128874;21337;21364.038618262177;20432.591348173028;21135.788642113574;21225.150993960262;21772.12911483539;22053.558928821454;1074.6507385099835;1083.0252772504768;1086.7337502311946;1090.1006669497656;1092.5738961804902;1111.372074777752;1114.654457118296;1114.9331040137577;1117.4412793603199;1119.311623351641;1157.536985205916;1158.5134243617701;1159.1248607301475;1159.1711925972927;1165.702745799824;1145.4673576786793;1148.3856136966683;1152.7579208366253;1154.3881742676394;1159.2001518951938;843.2495079379357;843.6498852976008;843.7173546861748;843.7215718812777;851.1063383447356;18353.90823045876;19220.71168932467;19399.03004849752;19419.514512137168;19488.610227795507;23101.422464438354;23201.071957121865;23489.88255058714;23533.76466235333;23640.1725939593;17876.463706088838;18459.538511537186;18631.441056768184;18642.44072677822;18769.90615046916;3831.4635950966886;3839.6736277416453;3854.1713531590563;3855.8843234703004;3861.092643228839;159022;159736.20131899382;160330;161717;162969;17867.910660446876;17899.910500447415;17975.550611234823;18128.45614631563;18250.178741956377;34454.65545344514;34880.30239395198;34990.30019399649;35000.474992875126;35241;9110.681126160553;9228.35401521887;9241.907580924104;9280.303977201736;9286.628534858664;4415.668824838086;4506.233940230103;4529.116822219701;4529.68292219548;4530.07133537624;6216.347283535254;6254.9687251563455;6298.590591611531;6347.904781428282;6371.108044873721;1091.49245600796;1101.366714139373;1121.966341009771;1125.729824842032;1126.3579759536985;701.4563713122371;712.9465290103159;716.9533980291264;729.4601994524014;746.2425637977457;13181.868181318256;13198.60404187876;13290.867091329157;13326.200427974352;13698.65753356174;752.1012390193666;754.3851760814919;759.0777205694997;759.5746382026083;763.7594157840263;6293;6400.359964003563;6401.967990160019;6437.549371543947;6454.1609590752905;156980;157812;159007;160537;160945.19527402287;137543;137737;137853;138036.30981845027;143896;117583;119534;119922.40038799925;120261;121250.39374803069;11915.344656044;12024.458899349374;12177.208481448677;12254.264744115382;12440.19138755978;57056.42943570677;58357.708211458674;59108.40891591201;59667.40332596619;60061.699691501264;77438.61280693786;77824;78539;79184.20815791769;79619;16986.235619397405;17213.827861721722;17546.473605791845;17594.208260628046;17608.38370657021;17577.209025593624;18026.72959905576;23778.286651400485;25322.493550129264;25425.364365891;21728.67406988865;22251.22120725767;22577;22749.31752047441;22954.54090918139;20672.793272067087;20697.17211311591;21122.366329010154;22445.43886402853;22507.21224757191;21647.13411463524;21665.241716539844;22320.88839555792;22466.663000055338;23100.768992309862;13490.730185396042;14394.568162955127;14847.480338188112;15029.173395463355;15068.773968390702;16721.495065444167;17082.402115925885;17105.743413848555;17116.486505404857;17199.22603482821 04:28:17 INFO - PID 21949 | __end_tp_report 04:28:17 INFO - PID 21949 | __start_cc_report 04:28:17 INFO - PID 21949 | _x_x_mozilla_cycle_collect,250 04:28:17 INFO - PID 21949 | __end_cc_report 04:28:17 INFO - PID 21949 | __startTimestamp1506598097808__endTimestamp 04:28:17 INFO - PID 21949 | 04:28:17 INFO - PID 21949 | ------- Summary: start ------- 04:28:17 INFO - PID 21949 | Number of tests: 6 04:28:17 INFO - PID 21949 | 04:28:17 INFO - PID 21949 | [#0] dojo.html Cycles:165 Average:6289.24 Median:6553.97 stddev:748.20 (11.4%) stddev-sans-first:726.68 04:28:17 INFO - PID 21949 | Values: 3901.8 3905.6 3922.5 3935.7 3945.3 6158.9 6241.7 6273.4 6289.8 6326.8 6283.7 6284.5 6290.0 6314.3 6319.9 5635.7 5637.8 5658.2 5661.2 5722.9 5714.6 5755.6 5772.0 5775.4 5793.0 5695.3 5712.6 5721.5 5784.5 5825.2 5233.6 5550.4 5638.5 5653.3 5738.7 5668.1 5671.5 5726.2 5753.8 5754.4 6209.3 6289.7 6307.0 6341.0 6348.1 6795.8 6828.1 6833.1 6851.9 6855.6 6797.5 6873.8 6874.7 6885.4 6897.5 6228.7 6289.5 6298.5 6372.0 6429.2 7034.0 7034.9 7111.8 7116.4 7133.0 6746.7 6755.3 6818.7 6836.3 6845.4 6745.0 6809.6 6862.7 6869.1 6948.3 6715.7 6761.4 6766.9 6787.5 6787.5 6649.3 6798.2 6820.7 6824.6 6830.0 4876.9 4879.3 4946.6 4947.8 4967.8 4432.4 4480.6 4483.7 4522.7 4546.0 6299.7 6309.2 6318.9 6323.5 6323.7 6776.9 6784.2 6785.0 6818.7 6863.9 6183.3 6244.0 6255.2 6333.5 6340.5 5525.9 5579.9 5580.5 5583.2 5605.8 7057.6 7119.5 7175.2 7176.9 7202.6 6716.5 6749.4 6779.7 6798.4 6908.1 7041.0 7044.2 7071.7 7075.0 7088.6 6765.6 6817.8 6838.4 6848.3 6872.0 6744.9 6791.3 6855.0 6895.2 6923.4 6581.9 6639.6 6640.2 6651.7 6671.6 6590.2 6590.9 6625.2 6672.5 6700.9 6474.4 6485.2 6554.0 6568.4 6581.3 6601.2 6672.6 6677.3 6721.1 6726.8 6397.3 6443.4 6484.7 6516.5 6517.3 04:28:17 INFO - PID 21949 | 04:28:17 INFO - PID 21949 | [#1] ext.html Cycles:180 Average:45926.56 Median:39590.41 stddev:70145.78 (177.2%) stddev-sans-first:70261.16 04:28:17 INFO - PID 21949 | Values: 923.7 951.8 964.9 991.2 1010.0 45418.8 45929.0 46046.8 46209.5 46350.3 43120.0 43293.1 43375.0 43512.0 43527.0 7582.5 7677.2 7681.2 7689.2 7718.1 7572.5 7668.8 7669.0 7683.9 7688.5 8627.7 8790.2 8795.0 8801.4 8820.9 8660.3 8668.6 8727.7 8733.2 8744.8 8391.0 8564.7 8584.1 8588.1 8708.5 41835.2 41984.2 42096.6 42122.0 42304.8 3779.0 3786.7 3821.6 3822.4 3823.5 33946.0 34020.7 34043.8 34047.7 34138.8 5428.6 5437.3 5457.3 5465.2 5494.4 238942.0 239193.0 239389.0 240105.0 241311.0 55274.4 55368.0 55377.7 55609.0 55822.0 58928.4 59033.0 59140.7 59194.0 59251.4 16641.3 16652.3 16727.7 16776.2 16890.1 13351.9 13402.4 13455.9 13474.7 13520.2 15377.8 15629.1 15682.0 15696.2 15731.5 6097.5 6130.3 6197.5 6318.4 6327.5 1090.6 1095.2 1100.5 1104.0 1104.2 26260.0 26760.5 26896.2 27080.6 27151.7 1192.6 1194.7 1202.2 1207.5 1216.7 13795.9 13888.9 14005.9 14160.7 14201.6 307251.0 309285.0 309544.0 310254.0 310827.0 63936.7 64399.7 64529.7 64541.0 64576.0 1455.9 1524.7 1536.4 1540.8 1545.6 25761.6 26059.3 26159.5 26248.0 26268.3 43467.3 43576.0 43645.8 43911.8 43962.3 252278.0 253331.0 253542.0 254238.0 254472.0 39210.4 41667.6 44421.3 44567.1 44658.3 53236.0 54605.7 54657.5 54871.0 54972.7 38889.4 39151.0 39279.8 39460.6 39720.2 38394.8 38847.8 38883.4 39028.0 39366.0 45906.8 46023.8 46338.5 46446.0 46484.0 37209.8 37829.4 37932.2 38094.4 38375.0 28145.7 28190.6 28377.0 28386.9 28438.4 04:28:17 INFO - PID 21949 | 04:28:17 INFO - PID 21949 | [#2] jquery.html Cycles:180 Average:41033.96 Median:2212.78 stddev:107152.42 (4842.4%) stddev-sans-first:107410.03 04:28:17 INFO - PID 21949 | Values: 615.5 633.6 639.5 648.7 657.5 22597.8 23431.5 24103.2 24167.4 24353.5 25001.9 26307.7 26325.0 26352.2 26368.0 6423.4 6566.2 6575.0 6575.4 6593.0 6618.9 6850.7 6868.7 6881.9 6886.6 6855.0 6860.5 6863.3 6868.3 6874.0 6399.2 6662.2 6663.4 6673.1 6857.3 6192.0 6195.1 6235.7 6272.3 6278.2 30257.0 30862.0 30922.2 31032.8 31064.4 2164.3 2191.1 2211.9 2213.6 2225.0 20083.9 20795.0 20898.4 20959.7 20967.2 2456.4 2495.6 2533.8 2615.6 2629.4 145132.3 152011.0 158074.0 167267.0 167621.0 16102.2 16578.3 16790.7 17008.6 17655.7 26631.7 26727.2 26732.0 26743.6 26746.6 10395.7 10573.6 10634.4 10723.4 10808.0 9304.2 9445.0 9494.0 9556.9 9570.9 9958.9 9998.5 10106.6 10140.5 10318.4 2905.6 2955.0 3000.2 3030.5 3080.6 1032.9 1045.1 1073.9 1078.5 1083.7 20978.5 21426.1 21589.7 21645.7 21826.5 1114.8 1116.2 1116.3 1119.5 1126.5 11620.8 11817.1 11876.1 11953.1 12013.5 624601.0 633362.0 637447.0 640901.0 667413.0 128875.0 134930.0 135007.0 137170.0 138839.3 1220.1 1237.4 1261.3 1262.7 1265.0 18557.4 18984.4 19020.1 19064.4 19079.2 61534.4 62541.0 62595.7 63476.0 64316.0 76570.0 76891.0 77641.0 78081.6 79980.0 15420.3 15696.0 15714.4 15770.5 15819.2 23437.2 23472.1 23593.9 23794.9 23841.4 19055.3 19064.0 19082.1 19238.2 19265.5 17293.7 17318.9 17358.7 18168.8 18192.5 19552.4 19700.5 19721.6 19723.1 19924.4 15365.1 15499.1 15511.8 15554.2 15573.5 18653.8 19111.3 19174.1 19232.1 19244.5 04:28:17 INFO - PID 21949 | 04:28:17 INFO - PID 21949 | [#3] mootools.html Cycles:180 Average:10475.64 Median:14229.94 stddev:7937.77 (55.8%) stddev-sans-first:7931.00 04:28:17 INFO - PID 21949 | Values: 1438.9 1461.4 1478.9 1481.5 1505.0 16838.7 17489.5 17527.9 17557.6 17567.6 16972.7 17079.7 17120.3 17206.0 17214.8 2799.3 2868.3 2870.2 2873.6 2877.1 2706.4 2800.7 2802.1 2803.2 2809.3 2730.4 2783.0 2784.1 2784.2 2784.7 2602.5 2640.8 2641.9 2643.0 2643.5 2569.5 2611.7 2619.1 2619.2 2621.4 15900.4 16278.0 16309.4 16315.9 16340.9 7932.9 8204.6 8204.9 8207.8 8224.3 5236.3 5338.7 5560.4 5595.0 5597.7 2015.3 2017.5 2087.9 2136.5 2162.7 28210.0 29100.0 30725.8 31782.0 31798.5 7355.1 7405.2 7428.3 7484.4 7592.4 22541.4 22559.9 22627.5 22690.4 22764.8 8305.6 8353.7 8420.1 8606.8 8629.1 7460.9 7589.9 7616.9 7642.0 7650.3 5996.3 6189.3 6198.8 6226.9 6506.2 1630.7 1702.2 1833.9 1885.5 1889.6 941.5 964.9 970.8 970.9 979.7 8087.5 8192.1 8216.1 8279.0 8350.3 1046.4 1056.7 1068.4 1086.3 1097.2 4672.2 4688.2 4777.3 4793.7 4835.9 25094.9 25551.4 25748.9 25752.4 25857.4 24802.8 25016.9 25551.9 25598.6 25605.7 12082.7 12792.7 12984.2 13017.3 13032.9 8965.6 8976.5 9037.1 9074.8 9118.6 21150.7 21195.8 21362.7 21394.0 21543.1 21934.2 22414.6 22550.7 22585.0 22632.9 12783.2 13018.2 13025.3 13025.4 13025.5 15421.8 15682.5 15741.3 15812.8 15841.1 11970.3 12088.5 12098.8 12434.4 12609.7 12390.6 12451.9 12454.6 12802.6 12890.9 10319.9 10342.7 10378.3 10514.8 10529.7 7758.0 7771.1 7911.4 7973.6 8005.0 7642.5 7742.1 7750.4 7759.3 7847.6 04:28:17 INFO - PID 21949 | 04:28:17 INFO - PID 21949 | [#4] prototype.html Cycles:180 Average:5497.51 Median:6051.11 stddev:1508.85 (24.9%) stddev-sans-first:1466.98 04:28:17 INFO - PID 21949 | Values: 565.7 572.6 581.7 583.0 585.7 5993.7 6065.5 6084.5 6113.9 6120.5 5985.4 6045.5 6058.1 6075.6 6122.4 5030.8 5039.5 5041.7 5076.4 5096.3 4754.4 4913.1 4942.8 5042.8 5054.5 4894.8 4907.2 4912.5 4921.3 4926.8 4853.7 4872.7 4901.3 4917.0 4921.3 4454.1 4480.2 4481.2 4491.8 4501.8 6011.1 6011.2 6032.2 6056.8 6099.1 5751.9 5759.5 5769.9 5796.4 5839.2 6469.1 6472.3 6477.2 6503.1 6521.5 4729.0 5005.9 5012.1 5014.5 5017.9 6568.2 6572.1 6573.4 6603.4 6688.5 5117.5 5137.5 5149.2 5166.2 5171.2 5247.4 5253.4 5261.6 5275.7 5278.8 5775.7 5778.2 5786.4 5824.9 5830.5 6508.4 6526.4 6541.4 6590.1 6595.0 1788.8 1809.6 1811.9 1820.7 1823.2 1227.1 1286.2 1286.8 1300.9 1301.8 5906.3 5995.7 5995.9 5999.4 6021.7 6240.9 6367.9 6377.3 6527.3 6531.8 5805.3 5873.4 5893.4 5917.3 5956.3 3398.1 3440.9 3448.3 3467.5 3471.1 6713.6 6744.5 6763.7 6811.8 6825.2 6668.1 6672.6 6706.3 6708.4 6732.8 6744.5 6802.3 6825.2 6858.5 6871.7 6488.2 6568.8 6578.6 6620.8 6626.1 6317.7 6357.8 6390.8 6410.6 6550.6 6809.7 6848.9 6866.6 6869.8 6878.6 5102.5 5170.5 5174.1 5182.9 5206.8 6221.4 6394.5 6453.7 6535.2 6567.5 6376.1 6379.7 6405.3 6405.7 6415.1 6329.4 6330.5 6336.7 6353.0 6377.8 6247.4 6254.8 6267.6 6270.1 6309.7 6407.9 6439.4 6454.9 6473.0 6496.7 6183.9 6275.8 6276.7 6283.3 6311.0 04:28:17 INFO - PID 21949 | 04:28:17 INFO - PID 21949 | [#5] yui.html Cycles:180 Average:29831.65 Median:20552.69 stddev:44026.12 (214.2%) stddev-sans-first:44096.24 04:28:17 INFO - PID 21949 | Values: 957.4 978.7 987.0 1000.1 1012.5 18826.5 21202.3 21288.8 21337.0 21364.0 20432.6 21135.8 21225.2 21772.1 22053.6 1074.7 1083.0 1086.7 1090.1 1092.6 1111.4 1114.7 1114.9 1117.4 1119.3 1157.5 1158.5 1159.1 1159.2 1165.7 1145.5 1148.4 1152.8 1154.4 1159.2 843.2 843.6 843.7 843.7 851.1 18353.9 19220.7 19399.0 19419.5 19488.6 23101.4 23201.1 23489.9 23533.8 23640.2 17876.5 18459.5 18631.4 18642.4 18769.9 3831.5 3839.7 3854.2 3855.9 3861.1 159022.0 159736.2 160330.0 161717.0 162969.0 17867.9 17899.9 17975.6 18128.5 18250.2 34454.7 34880.3 34990.3 35000.5 35241.0 9110.7 9228.4 9241.9 9280.3 9286.6 4415.7 4506.2 4529.1 4529.7 4530.1 6216.3 6255.0 6298.6 6347.9 6371.1 1091.5 1101.4 1122.0 1125.7 1126.4 701.5 712.9 717.0 729.5 746.2 13181.9 13198.6 13290.9 13326.2 13698.7 752.1 754.4 759.1 759.6 763.8 6293.0 6400.4 6402.0 6437.5 6454.2 156980.0 157812.0 159007.0 160537.0 160945.2 137543.0 137737.0 137853.0 138036.3 143896.0 117583.0 119534.0 119922.4 120261.0 121250.4 11915.3 12024.5 12177.2 12254.3 12440.2 57056.4 58357.7 59108.4 59667.4 60061.7 77438.6 77824.0 78539.0 79184.2 79619.0 16986.2 17213.8 17546.5 17594.2 17608.4 17577.2 18026.7 23778.3 25322.5 25425.4 21728.7 22251.2 22577.0 22749.3 22954.5 20672.8 20697.2 21122.4 22445.4 22507.2 21647.1 21665.2 22320.9 22466.7 23100.8 13490.7 14394.6 14847.5 15029.2 15068.8 16721.5 17082.4 17105.7 17116.5 17199.2 04:28:17 INFO - PID 21949 | -------- Summary: end -------- 04:28:17 INFO - PID 21949 | 04:28:18 INFO - TEST-INFO | 21949: exit 0 04:28:18 INFO - TEST-OK | dromaeo_css | took 1108027ms 04:28:18 INFO - TEST-START | kraken 04:28:18 INFO - Initialising browser for kraken test... 04:28:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:35607/getInfo.html -profile /tmp/tmpcw2rY0/profile 04:28:18 INFO - TEST-INFO | started process 22319 (/builds/slave/test/build/application/firefox/firefox http://localhost:35607/getInfo.html) 04:28:23 INFO - TEST-INFO | 22319: exit 0 04:28:23 INFO - Browser initialized. 04:28:23 INFO - Running cycle 1/1 for kraken test... 04:28:23 INFO - Using env: {'DISPLAY': ':0', 04:28:23 INFO - 'HOME': '/home/cltbld', 04:28:23 INFO - 'JSGC_DISABLE_POISONING': '1', 04:28:23 INFO - 'LANG': 'en_US.UTF-8', 04:28:23 INFO - 'LANGUAGE': 'en_US:en', 04:28:23 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:28:23 INFO - 'LOGNAME': 'cltbld', 04:28:23 INFO - 'MAIL': '/var/mail/cltbld', 04:28:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:28:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:28:23 INFO - 'MOZ_CRASHREPORTER': '1', 04:28:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:23 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:28:23 INFO - 'MOZ_NO_REMOTE': '1', 04:28:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:28:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:23 INFO - 'NO_EM_RESTART': '1', 04:28:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:23 INFO - 'PWD': '/builds/slave/test', 04:28:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:28:23 INFO - 'RUST_BACKTRACE': 'full', 04:28:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:28:23 INFO - 'SHELL': '/bin/bash', 04:28:23 INFO - 'SHLVL': '1', 04:28:23 INFO - 'TERM': 'linux', 04:28:23 INFO - 'TMOUT': '86400', 04:28:23 INFO - 'USER': 'cltbld', 04:28:23 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319', 04:28:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:23 INFO - '_': '/tools/buildbot/bin/python'} 04:28:23 INFO - TEST-INFO | started process 22556 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcw2rY0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:28:24 INFO - PID 22556 | 04:28:24 INFO - PID 22556 | (/builds/slave/test/build/application/firefox/firefox:22607): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:28:24 INFO - PID 22556 | 04:28:34 INFO - PID 22556 | RSS: Main: 144916480 04:28:34 INFO - PID 22556 | 04:28:37 INFO - PID 22556 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35607/tests/kraken/driver.html?testName=audio-beat-detection) 04:28:38 INFO - PID 22556 | RSS: Main: 151130112 04:28:38 INFO - PID 22556 | 04:28:40 INFO - PID 22556 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35607/tests/kraken/driver.html?testName=audio-dft) 04:28:41 INFO - PID 22556 | RSS: Main: 151371776 04:28:41 INFO - PID 22556 | 04:28:44 INFO - PID 22556 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35607/tests/kraken/driver.html?testName=audio-fft) 04:28:45 INFO - PID 22556 | RSS: Main: 147402752 04:28:45 INFO - PID 22556 | 04:28:47 INFO - PID 22556 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35607/tests/kraken/driver.html?testName=audio-oscillator) 04:28:47 INFO - PID 22556 | RSS: Main: 148963328 04:28:47 INFO - PID 22556 | 04:28:49 INFO - PID 22556 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35607/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:28:50 INFO - PID 22556 | RSS: Main: 148492288 04:28:50 INFO - PID 22556 | 04:28:53 INFO - PID 22556 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35607/tests/kraken/driver.html?testName=imaging-darkroom) 04:28:54 INFO - PID 22556 | RSS: Main: 148733952 04:28:54 INFO - PID 22556 | 04:28:57 INFO - PID 22556 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35607/tests/kraken/driver.html?testName=imaging-desaturate) 04:28:58 INFO - PID 22556 | RSS: Main: 150167552 04:28:58 INFO - PID 22556 | 04:29:01 INFO - PID 22556 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35607/tests/kraken/driver.html?testName=json-parse-financial) 04:29:01 INFO - PID 22556 | RSS: Main: 152018944 04:29:01 INFO - PID 22556 | 04:29:03 INFO - PID 22556 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35607/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:29:03 INFO - PID 22556 | RSS: Main: 151367680 04:29:03 INFO - PID 22556 | 04:29:05 INFO - PID 22556 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:29:06 INFO - PID 22556 | RSS: Main: 151220224 04:29:06 INFO - PID 22556 | 04:29:08 INFO - PID 22556 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:29:08 INFO - PID 22556 | RSS: Main: 148258816 04:29:08 INFO - PID 22556 | 04:29:11 INFO - PID 22556 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:29:11 INFO - PID 22556 | RSS: Main: 148267008 04:29:11 INFO - PID 22556 | 04:29:14 INFO - PID 22556 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:29:15 INFO - PID 22556 | RSS: Main: 148652032 04:29:15 INFO - PID 22556 | 04:29:16 INFO - PID 22556 | Cycle 1(1): loaded http://localhost:35607/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35607/tests/kraken/driver.html?testName=ai-astar) 04:29:16 INFO - PID 22556 | RSS: Main: 150495232 04:29:16 INFO - PID 22556 | 04:29:16 INFO - PID 22556 | __start_tp_report 04:29:16 INFO - PID 22556 | _x_x_mozilla_page_load 04:29:16 INFO - PID 22556 | _x_x_mozilla_page_load_details 04:29:16 INFO - PID 22556 | |i|pagename|runs| 04:29:16 INFO - PID 22556 | |0;ai-astar;109;104;105;105;105;106;110;107;105;104 04:29:16 INFO - PID 22556 | |1;audio-beat-detection;113;117;112;113;114;143;113;115;116;112 04:29:16 INFO - PID 22556 | |2;audio-dft;163;161;162;162;162;166;162;162;163;163 04:29:16 INFO - PID 22556 | |3;audio-fft;72;75;73;75;75;74;73;77;73;73 04:29:16 INFO - PID 22556 | |4;audio-oscillator;78;80;82;78;77;78;78;78;77;79 04:29:16 INFO - PID 22556 | |5;imaging-gaussian-blur;114;113;113;113;114;114;114;115;114;114 04:29:16 INFO - PID 22556 | |6;imaging-darkroom;150;150;149;149;149;149;149;151;150;152 04:29:16 INFO - PID 22556 | |7;imaging-desaturate;94;94;93;94;95;94;94;95;94;97 04:29:16 INFO - PID 22556 | |8;json-parse-financial;43;43;44;46;44;43;43;43;44;43 04:29:16 INFO - PID 22556 | |9;json-stringify-tinderbox;39;39;39;38;39;39;39;39;38;40 04:29:16 INFO - PID 22556 | |10;stanford-crypto-aes;75;75;69;69;72;70;69;73;68;69 04:29:16 INFO - PID 22556 | |11;stanford-crypto-ccm;102;102;107;111;109;107;109;102;104;109 04:29:16 INFO - PID 22556 | |12;stanford-crypto-pbkdf2;158;155;151;152;148;154;149;154;154;148 04:29:16 INFO - PID 22556 | |13;stanford-crypto-sha256-iterative;60;58;58;61;61;68;60;62;62;58 04:29:16 INFO - PID 22556 | __end_tp_report 04:29:16 INFO - PID 22556 | __start_cc_report 04:29:16 INFO - PID 22556 | _x_x_mozilla_cycle_collect,696 04:29:16 INFO - PID 22556 | __end_cc_report 04:29:16 INFO - PID 22556 | __startTimestamp1506598156982__endTimestamp 04:29:16 INFO - PID 22556 | 04:29:16 INFO - PID 22556 | ------- Summary: start ------- 04:29:16 INFO - PID 22556 | Number of tests: 14 04:29:16 INFO - PID 22556 | 04:29:16 INFO - PID 22556 | [#0] ai-astar Cycles:10 Average:106.00 Median:105.50 stddev:2.05 (1.9%) stddev-sans-first:1.87 04:29:16 INFO - PID 22556 | Values: 109.0 104.0 105.0 105.0 105.0 106.0 110.0 107.0 105.0 104.0 04:29:16 INFO - PID 22556 | 04:29:16 INFO - PID 22556 | [#1] audio-beat-detection Cycles:10 Average:116.80 Median:114.50 stddev:9.35 (8.2%) stddev-sans-first:9.82 04:29:16 INFO - PID 22556 | Values: 113.0 117.0 112.0 113.0 114.0 143.0 113.0 115.0 116.0 112.0 04:29:16 INFO - PID 22556 | 04:29:17 INFO - PID 22556 | [#2] audio-dft Cycles:10 Average:162.60 Median:162.50 stddev:1.35 (0.8%) stddev-sans-first:1.42 04:29:17 INFO - PID 22556 | Values: 163.0 161.0 162.0 162.0 162.0 166.0 162.0 162.0 163.0 163.0 04:29:17 INFO - PID 22556 | 04:29:17 INFO - PID 22556 | [#3] audio-fft Cycles:10 Average:74.00 Median:74.50 stddev:1.49 (2.0%) stddev-sans-first:1.39 04:29:17 INFO - PID 22556 | Values: 72.0 75.0 73.0 75.0 75.0 74.0 73.0 77.0 73.0 73.0 04:29:17 INFO - PID 22556 | 04:29:17 INFO - PID 22556 | [#4] audio-oscillator Cycles:10 Average:78.50 Median:78.00 stddev:1.51 (1.9%) stddev-sans-first:1.59 04:29:17 INFO - PID 22556 | Values: 78.0 80.0 82.0 78.0 77.0 78.0 78.0 78.0 77.0 79.0 04:29:17 INFO - PID 22556 | 04:29:17 INFO - PID 22556 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 04:29:17 INFO - PID 22556 | Values: 114.0 113.0 113.0 113.0 114.0 114.0 114.0 115.0 114.0 114.0 04:29:17 INFO - PID 22556 | 04:29:17 INFO - PID 22556 | [#6] imaging-darkroom Cycles:10 Average:149.80 Median:150.00 stddev:1.03 (0.7%) stddev-sans-first:1.09 04:29:17 INFO - PID 22556 | Values: 150.0 150.0 149.0 149.0 149.0 149.0 149.0 151.0 150.0 152.0 04:29:17 INFO - PID 22556 | 04:29:17 INFO - PID 22556 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.00 stddev:1.07 (1.1%) stddev-sans-first:1.13 04:29:17 INFO - PID 22556 | Values: 94.0 94.0 93.0 94.0 95.0 94.0 94.0 95.0 94.0 97.0 04:29:17 INFO - PID 22556 | 04:29:17 INFO - PID 22556 | [#8] json-parse-financial Cycles:10 Average:43.60 Median:43.50 stddev:0.97 (2.2%) stddev-sans-first:1.00 04:29:17 INFO - PID 22556 | Values: 43.0 43.0 44.0 46.0 44.0 43.0 43.0 43.0 44.0 43.0 04:29:17 INFO - PID 22556 | 04:29:17 INFO - PID 22556 | [#9] json-stringify-tinderbox Cycles:10 Average:38.90 Median:39.00 stddev:0.57 (1.5%) stddev-sans-first:0.60 04:29:17 INFO - PID 22556 | Values: 39.0 39.0 39.0 38.0 39.0 39.0 39.0 39.0 38.0 40.0 04:29:17 INFO - PID 22556 | 04:29:17 INFO - PID 22556 | [#10] stanford-crypto-aes Cycles:10 Average:70.90 Median:71.00 stddev:2.64 (3.7%) stddev-sans-first:2.35 04:29:17 INFO - PID 22556 | Values: 75.0 75.0 69.0 69.0 72.0 70.0 69.0 73.0 68.0 69.0 04:29:17 INFO - PID 22556 | 04:29:17 INFO - PID 22556 | [#11] stanford-crypto-ccm Cycles:10 Average:106.20 Median:108.00 stddev:3.43 (3.2%) stddev-sans-first:3.28 04:29:17 INFO - PID 22556 | Values: 102.0 102.0 107.0 111.0 109.0 107.0 109.0 102.0 104.0 109.0 04:29:17 INFO - PID 22556 | 04:29:17 INFO - PID 22556 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.30 Median:154.00 stddev:3.30 (2.1%) stddev-sans-first:2.78 04:29:17 INFO - PID 22556 | Values: 158.0 155.0 151.0 152.0 148.0 154.0 149.0 154.0 154.0 148.0 04:29:17 INFO - PID 22556 | 04:29:17 INFO - PID 22556 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.80 Median:61.00 stddev:2.97 (4.9%) stddev-sans-first:3.14 04:29:17 INFO - PID 22556 | Values: 60.0 58.0 58.0 61.0 61.0 68.0 60.0 62.0 62.0 58.0 04:29:17 INFO - PID 22556 | -------- Summary: end -------- 04:29:17 INFO - PID 22556 | 04:29:17 INFO - PID 22556 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:29:17 INFO - TEST-INFO | 22556: exit 0 04:29:17 INFO - TEST-OK | kraken | took 59331ms 04:29:17 INFO - SUITE-END | took 1167s 04:29:18 INFO - Completed test suite (00:19:27) 04:29:18 INFO - javascript benchmark 04:29:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9186.856717664892, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3901.7658940463575, 3905.589913059129, 3922.490076290082, 3935.744176628518, 3945.28984782739, 6158.876822463548, 6241.719122639481, 6273.404026617475, 6289.779857704982, 6326.810195694131, 6283.654399008053, 6284.528660350469, 6289.968550157243, 6314.305426403092, 6319.873602527947, 5635.6618602883755, 5637.774489020434, 5658.151277308406, 5661.179129026289, 5722.942770572304, 5714.628549144313, 5755.597108202427, 5772.0, 5775.422457754212, 5793.0, 5695.316562012565, 5712.6286791358425, 5721.513671337942, 5784.479396854283, 5825.213596164506, 5233.607479439057, 5550.417206193332, 5638.548916086703, 5653.349864765563, 5738.741756620921, 5668.121441176624, 5671.489565939044, 5726.198332233491, 5753.769849206027, 5754.367019627837, 6209.316975132731, 6289.685515724242, 6306.968465157691, 6340.968295158541, 6348.079528468346, 6795.762148324785, 6828.146481689789, 6833.145856767904, 6851.862962740767, 6855.5543889647015, 6797.524173307821, 6873.828154296132, 6874.725010999554, 6885.380315771605, 6897.517173797836, 6228.657423841687, 6289.465395441393, 6298.496120310364, 6371.968140159316, 6429.22849258092, 7034.0, 7034.8944765828555, 7111.822204444827, 7116.430685545144, 7133.001379806831, 6746.730130794714, 6755.324467553303, 6818.727250910008, 6836.316368363224, 6845.383915447634, 6744.966275168593, 6809.591424514545, 6862.656867156622, 6869.107016087876, 6948.27043160471, 6715.6977935993, 6761.425278851254, 6766.898496522556, 6787.524873258824, 6787.524873258923, 6649.301823308483, 6798.184217893884, 6820.658967051628, 6824.556403833735, 6829.965850170717, 4876.878078048077, 4879.34128892595, 4946.579540739005, 4947.752612369367, 4967.776450059756, 4432.401625780542, 4480.641548676098, 4483.708558943724, 4522.728636281834, 4546.02260513991, 6299.6535190564955, 6309.211348581427, 6318.873622527523, 6323.52573556985, 6323.683815809283, 6776.9322306777285, 6784.151981002375, 6785.0, 6818.693158807866, 6863.931360686429, 6183.258009038944, 6243.969744992116, 6255.186825712719, 6333.524985626096, 6340.492760579143, 5525.861853453615, 5579.9163012554845, 5580.525655319343, 5583.218349431002, 5605.775768969277, 7057.647117644097, 7119.4660400470175, 7175.210726820045, 7176.856462870714, 7202.567845929261, 6716.496262780213, 6749.392554670103, 6779.72881084751, 6798.422134118655, 6908.1364829396325, 7041.0, 7044.15470143586, 7071.681774320271, 7075.0, 7088.610126443095, 6765.594064356154, 6817.829554261183, 6838.418734407631, 6848.315168483112, 6872.0, 6744.932550674431, 6791.252962174256, 6854.9657251713425, 6895.172579290517, 6923.446124310144, 6581.934180658323, 6639.634820084942, 6640.169978752656, 6651.700673469609, 6671.5997040177745, 6590.176227971504, 6590.868182636416, 6625.204975402982, 6672.466202703869, 6700.865982680417, 6474.384933431316, 6485.156929599122, 6553.967230163818, 6568.408843204134, 6581.276059633532, 6601.174853143357, 6672.633005184762, 6677.2655007948115, 6721.126253587198, 6726.764563240263, 6397.264314603694, 6443.387878151568, 6484.70818813164, 6516.51126165549, 6517.250516190698], "value": 6238.364861705118, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [923.6767131503974, 951.7810903492201, 964.9179819715324, 991.1921783746244, 1010.0354161775505, 45418.77290613546, 45929.0, 46046.76976615112, 46209.53790462094, 46350.304745428846, 43120.0, 43293.13413731723, 43375.0, 43512.0, 43527.0, 7582.545047297166, 7677.2322767723335, 7681.193474685134, 7689.154193038761, 7718.073831140271, 7572.545647261154, 7668.808279792994, 7669.0, 7683.884741728879, 7688.461807673465, 8627.698030568932, 8790.208881200691, 8794.956025219864, 8801.427907186044, 8820.911790882104, 8660.30717542595, 8668.566571671423, 8727.650893964234, 8733.170348816884, 8744.825103497958, 8390.958045209796, 8564.657413703444, 8584.098669639661, 8588.141185881424, 8708.521031343274, 41835.163296733896, 41984.1603167938, 42096.57903420957, 42122.0, 42304.78847605773, 3778.962210377916, 3786.734928555002, 3821.5987321331136, 3822.4266360045935, 3823.5220597425323, 33946.0, 34020.659793401996, 34043.82978085094, 34047.6595234047, 34138.829305853564, 5428.647137936061, 5437.34751829779, 5457.345118585756, 5465.207544906006, 5494.423085575997, 238942.0, 239193.0, 239389.0, 240105.0, 241311.0, 55274.44725552773, 55368.0, 55377.723111384184, 55609.0, 55822.0, 58928.41071589229, 59033.0, 59140.704296478245, 59194.0, 59251.407485925454, 16641.251143698562, 16652.250648720594, 16727.66544669124, 16776.161191940355, 16890.071046092336, 13351.933240333736, 13402.396892139877, 13455.865441345657, 13474.730505390033, 13520.18878867271, 15377.846221537642, 15629.14039727826, 15682.0, 15696.21518924072, 15731.528054158394, 6097.542684298695, 6130.29501607312, 6197.504199664016, 6318.4313411793155, 6327.462165715966, 1090.5637744901944, 1095.222392101601, 1100.49927283086, 1103.9724006899892, 1104.2380757277454, 26260.0, 26760.464790704078, 26896.193114206606, 27080.59379109315, 27151.728482715316, 1192.6064398748565, 1194.6714653470365, 1202.1765090912752, 1207.4747484844122, 1216.707990082374, 13795.862041379458, 13888.930555347159, 14005.929970350084, 14160.716785664228, 14201.573952781433, 307251.0, 309285.0, 309544.0, 310254.0, 310827.0, 63936.68031659812, 64399.67800161063, 64529.67735161388, 64541.0, 64576.0, 1455.9344829482698, 1524.7103050420606, 1536.4084827341553, 1540.8151021877447, 1545.590418539088, 25761.613575796004, 26059.348516287246, 26159.476810463304, 26248.0, 26268.343291417867, 43467.347989780814, 43576.0, 43645.781771090944, 43911.78044109759, 43962.34056489091, 252278.0, 253331.0, 253542.0, 254238.0, 254472.0, 39210.41184382179, 41667.58332416637, 44421.33367999547, 44567.10865782731, 44658.3301250488, 53236.0, 54605.726971364886, 54657.45342546524, 54871.0, 54972.72513637566, 38889.41665874958, 39151.021224469616, 39279.80360098181, 39460.60539394569, 39720.205595888496, 38394.80802595969, 38847.805760971016, 38883.416748749354, 39028.0, 39366.0, 45906.770466147456, 46023.76988115038, 46338.53661463477, 46446.0, 46484.0, 37209.813950930074, 37829.43255851219, 37932.24135517219, 38094.428583571826, 38375.0, 28145.718542815128, 28190.577141342485, 28377.0, 28386.85806570954, 28438.43123137484], "value": 19532.882931955566, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [615.4983688294041, 633.5913335898343, 639.4500729372742, 648.6918713611035, 657.5200103924137, 22597.77402225977, 23431.531369372602, 24103.15638952637, 24167.39581510461, 24353.512929741395, 25001.874990625067, 26307.736922630767, 26325.0, 26352.209433716973, 26368.0, 6423.421892029728, 6566.212054553436, 6575.0466182403525, 6575.4082132608055, 6593.0, 6618.93381066188, 6850.691718872663, 6868.7252509899545, 6881.862362752742, 6886.586804791728, 6854.965725171367, 6860.451163906875, 6863.279355667658, 6868.278830722752, 6874.0, 6399.232092148949, 6662.233843108056, 6663.36698013688, 6673.065770792093, 6857.314268573153, 6191.9690401547705, 6195.132681424604, 6235.657038862861, 6272.341404152589, 6278.215223097113, 30257.0, 30862.0, 30922.226944326572, 31032.844835775675, 31064.378712425852, 2164.296603603835, 2191.123550579765, 2211.9447013824624, 2213.6236839737285, 2224.9666255006186, 20083.89958050215, 20795.0, 20898.37304880856, 20959.68560471594, 20967.16131354744, 2456.4472993576483, 2495.6007038873695, 2533.8479691218586, 2615.594582839663, 2629.4346715456077, 145132.27433862764, 152011.0, 158074.0, 167266.99999999878, 167621.0, 16102.19489025544, 16578.253978570752, 16790.664186716196, 17008.57478563046, 17655.73516397255, 26631.733682663315, 26727.19818405451, 26732.0, 26743.598846017325, 26746.598801018, 10395.740106497247, 10573.629922952814, 10634.361938283728, 10723.356598604109, 10808.0, 9304.162625363615, 9444.952775236217, 9494.003129671422, 9556.85664715016, 9570.856437153448, 9958.900410995942, 9998.500074996222, 10106.595736170471, 10140.543675534618, 10318.43248621318, 2905.636795400575, 2954.985225073861, 3000.2499375156212, 3030.4696678081245, 3080.613723824288, 1032.8554002439664, 1045.1429827541342, 1073.8550295710136, 1078.5308390850005, 1083.6532309660834, 20978.47553811167, 21426.142954281964, 21589.67615485769, 21645.67531487029, 21826.454338641663, 1114.793763153819, 1116.2242241641982, 1116.263266244275, 1119.5130118398522, 1126.5155982927256, 11620.825687614693, 11817.14160083552, 11876.10929180315, 11953.103517236243, 12013.519459221534, 624601.0, 633362.0, 637447.0, 640901.0, 667413.0, 128875.0, 134930.0, 135007.0, 137170.0, 138839.30580347034, 1220.0849362977767, 1237.3998610673816, 1261.2684642907093, 1262.665393670678, 1264.987350126487, 18557.35049273269, 18984.43046708601, 19020.144093515548, 19064.42806715801, 19079.236830526625, 61534.38465615286, 62541.0, 62595.6870215646, 63476.0, 64316.0, 76570.0, 76891.0, 77641.0, 78081.60959195168, 79980.0, 15420.306086226372, 15696.0, 15714.449994250605, 15770.526884193492, 15819.209039547746, 23437.179698710464, 23472.061117555106, 23593.88203058974, 23794.88102559476, 23841.403964901016, 19055.333063342718, 19064.0, 19082.14130364165, 19238.23047078101, 19265.5183620405, 17293.65412691764, 17318.913405432893, 17358.652826943642, 18168.818311817242, 18192.45422637323, 19552.413427597196, 19700.50748731293, 19721.605567888848, 19723.11245993905, 19924.402267931986, 15365.078095314317, 15499.14754688503, 15511.84488155104, 15554.222288885736, 15573.532794016197, 18653.813461865208, 19111.331103411314, 19174.13716382738, 19232.134553944827, 19244.518887027378], "value": 12865.041849006204, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1438.9352479138438, 1461.371610207611, 1478.8890833187513, 1481.548127821014, 1505.036776463064, 16838.747418788713, 17489.475315740518, 17527.948323100605, 17557.64884702305, 17567.560810979732, 16972.74540881888, 17079.743803842954, 17120.31518739249, 17206.0, 17214.82785172148, 2799.2721892308045, 2868.340281735202, 2870.167651381097, 2873.612062371584, 2877.0505733108025, 2706.4316493536285, 2800.719928007193, 2802.1313392848283, 2803.2010876900085, 2809.3117186289287, 2730.426610411816, 2782.9721702782917, 2784.1090850927712, 2784.2482529716963, 2784.6797618273954, 2602.531544322021, 2640.788736901043, 2641.8679066046716, 2643.0088716731225, 2643.524165650182, 2569.498947705198, 2611.673540807399, 2619.122593931035, 2619.174959887648, 2621.3839747659285, 15900.443484478103, 16278.0, 16309.429169979112, 16315.918420407941, 16340.918295408566, 7932.920670793276, 8204.589770511451, 8204.876926846102, 8207.835843283101, 8224.300934420582, 5236.319278493771, 5338.706371149585, 5560.360558535739, 5594.9720251398885, 5597.74810133545, 2015.314792970397, 2017.5157962088992, 2087.895605219748, 2136.508603021314, 2162.697222388867, 28210.0, 29100.0, 30725.846370768002, 31782.04653860365, 31798.523022154684, 7355.080614923135, 7405.222451642608, 7428.257174282637, 7484.438667099989, 7592.354649854714, 22541.43646408853, 22559.88720056422, 22627.547449051257, 22690.432739181324, 22764.772352276595, 8305.626246818907, 8353.707620233385, 8420.11588783169, 8606.78483037929, 8629.093945135795, 7460.948239234254, 7589.8861517077285, 7616.9238307616215, 7642.0, 7650.273224043707, 5996.2504686914135, 6189.294328560971, 6198.752049918043, 6226.937730622636, 6506.154199953976, 1630.6575619120117, 1702.1574320711327, 1833.9358122465649, 1885.5191926059042, 1889.5653999580175, 941.4586612697726, 964.9421034737938, 970.8301047316692, 970.9077637624414, 979.6669132494986, 8087.503811794837, 8192.139825318374, 8216.09622941476, 8278.958605206935, 8350.33197344211, 1046.4035499765214, 1056.730533713898, 1068.4283908109123, 1086.266769930294, 1097.160672085855, 4672.2057250267535, 4688.156131896223, 4777.259524773665, 4793.688410253322, 4835.927461088087, 25094.874525627256, 25551.361215969748, 25748.8712556436, 25752.356191095372, 25857.353566160997, 24802.751972480404, 25016.87491562567, 25551.872240638677, 25598.616020759702, 25605.743942560337, 12082.697932551757, 12792.6801829955, 12984.220946743226, 13017.349132543335, 13032.934835325952, 8965.641374344954, 8976.506292153994, 9037.141471560191, 9074.773130671521, 9118.635254589743, 21150.68273975861, 21195.78804211938, 21362.679559806926, 21394.0, 21543.13827446947, 21934.232301869357, 22414.551708966057, 22550.661740073585, 22585.0, 22632.886835566376, 12783.23300601967, 13018.15382000167, 13025.283609401575, 13025.413856376676, 13025.544105956245, 15421.84578154249, 15682.529524114294, 15741.291641875914, 15812.762808558111, 15841.128737919526, 11970.341631210018, 12088.51645934188, 12098.75802483963, 12434.440450179583, 12609.684757881127, 12390.62828115158, 12451.875481245072, 12454.564090256797, 12802.615921522369, 12890.935545322214, 10319.94840025825, 10342.689719308433, 10378.325408848401, 10514.789704206027, 10529.73675658084, 7758.030246219223, 7771.145174030965, 7911.406644501801, 7973.641186146753, 8005.039395272372, 7642.4650274480255, 7742.1483636798785, 7750.379969602305, 7759.262870027338, 7847.646855891613], "value": 7275.530066455932, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.6634302589958, 572.5591294703079, 581.7440326256444, 582.9766809327626, 585.6544638663188, 5993.730282137303, 6065.454109130183, 6084.452399284069, 6113.938860611393, 6120.540959428038, 5985.371535988713, 6045.455908968192, 6058.091286307056, 6075.635461872291, 6122.387761223876, 5030.84907452776, 5039.546440820325, 5041.697498150104, 5076.44159142494, 5096.311997880294, 4754.405699287589, 4913.066517361708, 4942.827001054964, 5042.773075211624, 5054.545090941814, 4894.804207831682, 4907.239377896414, 4912.48418916014, 4921.335619691332, 4926.778294976717, 4853.733044682541, 4872.731999740013, 4901.264810278469, 4917.0, 4921.286413470062, 4454.13144436835, 4480.215962206634, 4481.215787237221, 4491.797869095899, 4501.774911254424, 6011.098335249748, 6011.158437818709, 6032.185654936615, 6056.757729690807, 6099.146119543268, 5751.900556296921, 5759.510441612426, 5769.88460230793, 5796.3913789052385, 5839.182514447981, 6469.126667899867, 6472.255690595594, 6477.22273327199, 6503.122078519386, 6521.47828173745, 4728.983268597268, 5005.924911126336, 5012.1479348510475, 5014.473480284591, 5017.874553136165, 6568.160914943824, 6572.112764776742, 6573.375529324707, 6603.372679595431, 6688.531802773857, 5117.462666420047, 5137.537621614073, 5149.150390185578, 5166.225066240094, 5171.224316352502, 5247.396549396868, 5253.369595648546, 5261.579073674096, 5275.709835959059, 5278.762455689503, 5775.660857352779, 5778.191053252548, 5786.363500014995, 5824.941750582525, 5830.475257226785, 6508.414242718178, 6526.379993900572, 6541.443977261891, 6590.110335104795, 6594.967025164843, 1788.7674602301618, 1809.5928416106271, 1811.9275228990693, 1820.7359932809668, 1823.2433540080983, 1227.128738595607, 1286.2057429885258, 1286.787680032784, 1300.9414576344088, 1301.8348578022806, 5906.274980016056, 5995.670238136944, 5995.910061349084, 5999.400059993965, 6021.668808215591, 6240.906386404207, 6367.904481432783, 6377.266614339317, 6527.314631963575, 6531.832061450193, 5805.303363596396, 5873.383294754075, 5893.44012318829, 5917.3371982608, 5956.255468066492, 3398.09950363154, 3440.8795692150657, 3448.2758620689433, 3467.5318831957866, 3471.149568355769, 6713.597184168965, 6744.527883048139, 6763.661816909135, 6811.829704257334, 6825.215100263434, 6668.133142691516, 6672.599644021374, 6706.295838936841, 6708.396244338033, 6732.831679207961, 6744.460443164437, 6802.319768023158, 6825.191324299065, 6858.451323894076, 6871.6564171791215, 6488.221413430229, 6568.80293591193, 6578.618490116937, 6620.801375958729, 6626.105475760711, 6317.747290108528, 6357.841053973688, 6390.808275751735, 6410.551261411656, 6550.639714815731, 6809.727610895508, 6848.931510684829, 6866.592348568632, 6869.793906182823, 6878.58728476293, 5102.540771330598, 5170.5346518813485, 5174.120399532013, 5182.948170518246, 5206.791728330976, 6221.377862213742, 6394.4884409246215, 6453.677316134268, 6535.215774107138, 6567.507436942153, 6376.107344971615, 6379.712912918837, 6405.295417504164, 6405.679716014274, 6415.081138082255, 6329.398707122816, 6330.525210609315, 6336.683165841781, 6353.0, 6377.840553986002, 6247.406496382836, 6254.8123556293385, 6267.592606480564, 6270.122182894307, 6309.7160627770945, 6407.935920640921, 6439.388258115471, 6454.903176452451, 6473.0, 6496.740130394731, 6183.907241391293, 6275.811725648238, 6276.686165691606, 6283.308836027942, 6311.0], "value": 5070.872116536719, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [957.3729207369175, 978.7406337320612, 986.955586998585, 1000.0849222960991, 1012.4684540616175, 18826.529336766587, 21202.25792097277, 21288.787112128874, 21337.0, 21364.038618262177, 20432.591348173028, 21135.788642113574, 21225.150993960262, 21772.12911483539, 22053.558928821454, 1074.6507385099835, 1083.0252772504768, 1086.7337502311946, 1090.1006669497656, 1092.5738961804902, 1111.372074777752, 1114.654457118296, 1114.9331040137577, 1117.4412793603199, 1119.311623351641, 1157.536985205916, 1158.5134243617701, 1159.1248607301475, 1159.1711925972927, 1165.702745799824, 1145.4673576786793, 1148.3856136966683, 1152.7579208366253, 1154.3881742676394, 1159.2001518951938, 843.2495079379357, 843.6498852976008, 843.7173546861748, 843.7215718812777, 851.1063383447356, 18353.90823045876, 19220.71168932467, 19399.03004849752, 19419.514512137168, 19488.610227795507, 23101.422464438354, 23201.071957121865, 23489.88255058714, 23533.76466235333, 23640.1725939593, 17876.463706088838, 18459.538511537186, 18631.441056768184, 18642.44072677822, 18769.90615046916, 3831.4635950966886, 3839.6736277416453, 3854.1713531590563, 3855.8843234703004, 3861.092643228839, 159022.0, 159736.20131899382, 160330.0, 161717.0, 162969.0, 17867.910660446876, 17899.910500447415, 17975.550611234823, 18128.45614631563, 18250.178741956377, 34454.65545344514, 34880.30239395198, 34990.30019399649, 35000.474992875126, 35241.0, 9110.681126160553, 9228.35401521887, 9241.907580924104, 9280.303977201736, 9286.628534858664, 4415.668824838086, 4506.233940230103, 4529.116822219701, 4529.68292219548, 4530.07133537624, 6216.347283535254, 6254.9687251563455, 6298.590591611531, 6347.904781428282, 6371.108044873721, 1091.49245600796, 1101.366714139373, 1121.966341009771, 1125.729824842032, 1126.3579759536985, 701.4563713122371, 712.9465290103159, 716.9533980291264, 729.4601994524014, 746.2425637977457, 13181.868181318256, 13198.60404187876, 13290.867091329157, 13326.200427974352, 13698.65753356174, 752.1012390193666, 754.3851760814919, 759.0777205694997, 759.5746382026083, 763.7594157840263, 6293.0, 6400.359964003563, 6401.967990160019, 6437.549371543947, 6454.1609590752905, 156980.0, 157812.0, 159007.0, 160537.0, 160945.19527402287, 137543.0, 137737.0, 137853.0, 138036.30981845027, 143896.0, 117583.0, 119534.0, 119922.40038799925, 120261.0, 121250.39374803069, 11915.344656044, 12024.458899349374, 12177.208481448677, 12254.264744115382, 12440.19138755978, 57056.42943570677, 58357.708211458674, 59108.40891591201, 59667.40332596619, 60061.699691501264, 77438.61280693786, 77824.0, 78539.0, 79184.20815791769, 79619.0, 16986.235619397405, 17213.827861721722, 17546.473605791845, 17594.208260628046, 17608.38370657021, 17577.209025593624, 18026.72959905576, 23778.286651400485, 25322.493550129264, 25425.364365891, 21728.67406988865, 22251.22120725767, 22577.0, 22749.31752047441, 22954.54090918139, 20672.793272067087, 20697.17211311591, 21122.366329010154, 22445.43886402853, 22507.21224757191, 21647.13411463524, 21665.241716539844, 22320.88839555792, 22466.663000055338, 23100.768992309862, 13490.730185396042, 14394.568162955127, 14847.480338188112, 15029.173395463355, 15068.773968390702, 16721.495065444167, 17082.402115925885, 17105.743413848555, 17116.486505404857, 17199.22603482821], "value": 10393.862220058429, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1368.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 104.0, 105.0, 105.0, 105.0, 106.0, 110.0, 107.0, 105.0, 104.0], "value": 106.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 112.0, 113.0, 114.0, 143.0, 113.0, 115.0, 116.0, 112.0], "value": 116.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 162.0, 162.0, 166.0, 162.0, 162.0, 163.0, 163.0], "value": 162.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 73.0, 75.0, 75.0, 74.0, 73.0, 77.0, 73.0, 73.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 82.0, 78.0, 77.0, 78.0, 78.0, 78.0, 77.0, 79.0], "value": 78.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 114.0, 114.0, 114.0, 115.0, 114.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 149.0, 149.0, 149.0, 149.0, 149.0, 151.0, 150.0, 152.0], "value": 149.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 97.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 43.0, 44.0, 46.0, 44.0, 43.0, 43.0, 43.0, 44.0, 43.0], "value": 43.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 39.0, 38.0, 39.0, 39.0, 39.0, 39.0, 38.0, 40.0], "value": 38.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 69.0, 69.0, 72.0, 70.0, 69.0, 73.0, 68.0, 69.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 102.0, 107.0, 111.0, 109.0, 107.0, 109.0, 102.0, 104.0, 109.0], "value": 106.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 155.0, 151.0, 152.0, 148.0, 154.0, 149.0, 154.0, 154.0, 148.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 58.0, 61.0, 61.0, 68.0, 60.0, 62.0, 62.0, 58.0], "value": 60.8, "unit": "score"}]}]} 04:29:18 INFO - Return code: 0 04:29:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:29:18 INFO - # TBPL SUCCESS # 04:29:18 INFO - Running post-action listener: _package_coverage_data 04:29:18 INFO - Running post-action listener: _resource_record_post_action 04:29:18 INFO - [mozharness: 2017-09-28 11:29:18.279789Z] Finished run-tests step (success) 04:29:18 INFO - Running post-run listener: _resource_record_post_run 04:29:18 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 337502208; Read time: 16; Write time: 201960 04:29:18 INFO - TinderboxPrint: CPU usage
13.1% 04:29:18 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 04:29:18 INFO - TinderboxPrint: I/O write bytes / time
337,502,208 / 201,960 04:29:18 INFO - TinderboxPrint: CPU idle
8,220.4 (86.9%) 04:29:18 INFO - TinderboxPrint: CPU user
1,187.7 (12.6%) 04:29:18 INFO - TinderboxPrint: Swap in / out
0 / 0 04:29:18 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13348864; Read time: 0; Write time: 2252 04:29:18 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:29:18 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 4096; Write bytes: 324153344; Read time: 16; Write time: 199708 04:29:18 INFO - Running post-run listener: _upload_blobber_files 04:29:18 INFO - Blob upload gear active. 04:29:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:29:18 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:29:18 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:29:18 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:29:19 INFO - (blobuploader) - INFO - Open directory for files ... 04:29:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:29:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:29:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:20 INFO - (blobuploader) - INFO - Done attempting. 04:29:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:29:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:29:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:21 INFO - (blobuploader) - INFO - Done attempting. 04:29:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:29:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:29:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:22 INFO - (blobuploader) - INFO - Done attempting. 04:29:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:29:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:29:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:29 INFO - (blobuploader) - INFO - Done attempting. 04:29:29 INFO - (blobuploader) - INFO - Iteration through files over. 04:29:29 INFO - Return code: 0 04:29:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:29:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:29:29 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4217e5a81a8b6033d3cc6a756c17f355902813288775977a56cae27ce1d28af8dc1f34444d2f5234def5e81bd705b12912ce46b7ad19052e2bc15f684ea7be91", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3d575b450c3c568d93cb2fc89b25ccaf72dc5f7db18e39b32a715e34e7ef0ef05a046196332ddf9ee86341205e4ec3430cc39a15bc0673aa8cf6c7b98f2131c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6715afac53745fd1be2f2ec15abc2493b2ab4c6081cec079c9b2b639cb515e1d967731a8ca315e21cb69d911a3bf87f50e7e3f414ef0276c965faa36500680d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/936046d51c538a5bbf8570d4dc0506a5cd6fec26faeb92ff4f56c6c1306886c96d746120ca1d185d6b2a8b5194559ec5f4c4434bc77c6df9036f8219353b912c"} 04:29:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:29:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:29:29 INFO - Contents: 04:29:29 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4217e5a81a8b6033d3cc6a756c17f355902813288775977a56cae27ce1d28af8dc1f34444d2f5234def5e81bd705b12912ce46b7ad19052e2bc15f684ea7be91", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3d575b450c3c568d93cb2fc89b25ccaf72dc5f7db18e39b32a715e34e7ef0ef05a046196332ddf9ee86341205e4ec3430cc39a15bc0673aa8cf6c7b98f2131c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6715afac53745fd1be2f2ec15abc2493b2ab4c6081cec079c9b2b639cb515e1d967731a8ca315e21cb69d911a3bf87f50e7e3f414ef0276c965faa36500680d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/936046d51c538a5bbf8570d4dc0506a5cd6fec26faeb92ff4f56c6c1306886c96d746120ca1d185d6b2a8b5194559ec5f4c4434bc77c6df9036f8219353b912c"} 04:29:29 INFO - Running post-run listener: copy_logs_to_upload_dir 04:29:29 INFO - Copying logs to upload dir... 04:29:29 INFO - mkdir: /builds/slave/test/build/upload/logs 04:29:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.919344 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-09-28 04:29:29.188328) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 04:29:29.191915) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319 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/4217e5a81a8b6033d3cc6a756c17f355902813288775977a56cae27ce1d28af8dc1f34444d2f5234def5e81bd705b12912ce46b7ad19052e2bc15f684ea7be91", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3d575b450c3c568d93cb2fc89b25ccaf72dc5f7db18e39b32a715e34e7ef0ef05a046196332ddf9ee86341205e4ec3430cc39a15bc0673aa8cf6c7b98f2131c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6715afac53745fd1be2f2ec15abc2493b2ab4c6081cec079c9b2b639cb515e1d967731a8ca315e21cb69d911a3bf87f50e7e3f414ef0276c965faa36500680d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/936046d51c538a5bbf8570d4dc0506a5cd6fec26faeb92ff4f56c6c1306886c96d746120ca1d185d6b2a8b5194559ec5f4c4434bc77c6df9036f8219353b912c"} build_url:https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011238 build_url: 'https://queue.taskcluster.net/v1/task/Dy6UL1toQc2jv9OegjbnVg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4217e5a81a8b6033d3cc6a756c17f355902813288775977a56cae27ce1d28af8dc1f34444d2f5234def5e81bd705b12912ce46b7ad19052e2bc15f684ea7be91", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f3d575b450c3c568d93cb2fc89b25ccaf72dc5f7db18e39b32a715e34e7ef0ef05a046196332ddf9ee86341205e4ec3430cc39a15bc0673aa8cf6c7b98f2131c", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6715afac53745fd1be2f2ec15abc2493b2ab4c6081cec079c9b2b639cb515e1d967731a8ca315e21cb69d911a3bf87f50e7e3f414ef0276c965faa36500680d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/936046d51c538a5bbf8570d4dc0506a5cd6fec26faeb92ff4f56c6c1306886c96d746120ca1d185d6b2a8b5194559ec5f4c4434bc77c6df9036f8219353b912c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 04:29:29.220581) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 04:29:29.220916) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1506596929.719494-882967319 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004500 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 04:29:29.302661) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 04:29:29.302986) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 04:29:29.303325) ========= ========= Total master_lag: 0.19 =========