builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-069 starttime: 1504521487.32 results: success (0) revision: 632e42dca494ec3d90b70325d9c359f80cb3f38a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 03:38:07.322939) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 03:38:07.323419) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 03:38:07.323694) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 03:38:07.342187) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:38:07.342644) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-04 03:38:07-- 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% 46.5M=0s 2017-09-04 03:38:07 (46.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.395630 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:38:07.755355) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:38:07.755693) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016305 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:38:07.808784) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:38:07.809117) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 632e42dca494ec3d90b70325d9c359f80cb3f38a --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 632e42dca494ec3d90b70325d9c359f80cb3f38a --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=05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-04 03:38:07,698 truncating revision to first 12 chars 2017-09-04 03:38:07,698 Setting DEBUG logging. 2017-09-04 03:38:07,699 attempt 1/10 2017-09-04 03:38:07,699 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/632e42dca494?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-04 03:38:08,151 unpacking tar archive at: mozilla-central-632e42dca494/testing/mozharness/ program finished with exit code 0 elapsedTime=0.795210 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:38:08.623609) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 03:38:08.627015) ========= script_repo_revision: 632e42dca494ec3d90b70325d9c359f80cb3f38a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 03:38:08.627452) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 03:38:08.627722) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 03:38:08.641422) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-04 03:38:08.641854) ========= /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=05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:38:08 INFO - MultiFileLogger online at 20170904 03:38:08 in /builds/slave/test 03:38:08 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 03:38:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:38:08 INFO - {'append_to_log': False, 03:38:08 INFO - 'base_work_dir': '/builds/slave/test', 03:38:08 INFO - 'blob_upload_branch': 'Firefox', 03:38:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:38:08 INFO - 'branch': 'Firefox', 03:38:08 INFO - 'buildbot_json_path': 'buildprops.json', 03:38:08 INFO - 'code_coverage': False, 03:38:08 INFO - 'config_files': ('talos/linux_config.py',), 03:38:08 INFO - 'default_actions': ('clobber', 03:38:08 INFO - 'read-buildbot-config', 03:38:08 INFO - 'download-and-extract', 03:38:08 INFO - 'populate-webroot', 03:38:08 INFO - 'create-virtualenv', 03:38:08 INFO - 'install', 03:38:08 INFO - 'setup-mitmproxy', 03:38:08 INFO - 'run-tests'), 03:38:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:38:08 INFO - 'disable_ccov_upload': False, 03:38:08 INFO - 'disable_stylo': False, 03:38:08 INFO - 'download_minidump_stackwalk': True, 03:38:08 INFO - 'download_symbols': 'ondemand', 03:38:08 INFO - 'enable_stylo': False, 03:38:08 INFO - 'enable_webrender': False, 03:38:08 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:38:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:38:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:38:08 INFO - 'gecko_profile': False, 03:38:08 INFO - 'gecko_profile_interval': 0, 03:38:08 INFO - 'installer_path': 'installer.exe', 03:38:08 INFO - 'log_level': 'info', 03:38:08 INFO - 'log_name': 'talos', 03:38:08 INFO - 'log_to_console': True, 03:38:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:38:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:38:08 INFO - 'opt_config_files': (), 03:38:08 INFO - 'pip_index': False, 03:38:08 INFO - 'suite': 'dromaeojs-e10s', 03:38:08 INFO - 'system_bits': '32', 03:38:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:38:08 INFO - 'title': 'talos-linux64-ix-069', 03:38:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:38:08 INFO - 'use_talos_json': True, 03:38:08 INFO - 'verify': 'False', 03:38:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:38:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:38:08 INFO - 'work_dir': 'build'} 03:38:08 INFO - [mozharness: 2017-09-04 10:38:08.676841Z] Running clobber step. 03:38:08 INFO - Running pre-action listener: _resource_record_pre_action 03:38:08 INFO - Running main action method: clobber 03:38:08 INFO - rmtree: /builds/slave/test/build 03:38:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:38:09 INFO - Running post-action listener: _resource_record_post_action 03:38:09 INFO - [mozharness: 2017-09-04 10:38:09.733653Z] Finished clobber step (success) 03:38:09 INFO - [mozharness: 2017-09-04 10:38:09.733749Z] Running read-buildbot-config step. 03:38:09 INFO - Running pre-action listener: _resource_record_pre_action 03:38:09 INFO - Running main action method: read_buildbot_config 03:38:09 INFO - Using buildbot properties: 03:38:09 INFO - { 03:38:09 INFO - "project": "", 03:38:09 INFO - "product": "firefox", 03:38:09 INFO - "who": "archaeopteryx@coole-files.de", 03:38:09 INFO - "installer_path": "public/build/target.tar.bz2", 03:38:09 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:38:09 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:38:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 03:38:09 INFO - "stage_platform": "linux64-pgo", 03:38:09 INFO - "basedir": "/builds/slave/test", 03:38:09 INFO - "buildnumber": 139, 03:38:09 INFO - "platform": "ubuntu64_hw", 03:38:09 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:38:09 INFO - "slavebuilddir": "test", 03:38:09 INFO - "taskId": "OV2i8mcmQGyEbhDo0mC6Rg", 03:38:09 INFO - "branch": "mozilla-central", 03:38:09 INFO - "script_repo_revision": "production", 03:38:09 INFO - "revision": "632e42dca494ec3d90b70325d9c359f80cb3f38a", 03:38:09 INFO - "slavename": "talos-linux64-ix-069", 03:38:09 INFO - "repo_path": "mozilla-central" 03:38:09 INFO - } 03:38:09 INFO - Finding installer, test and symbols from parent task. 03:38:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OV2i8mcmQGyEbhDo0mC6Rg'}, attempt #1 03:38:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OV2i8mcmQGyEbhDo0mC6Rg'}, attempt #1 03:38:10 INFO - Task dependencies: LR3txbuMQR2w2pQpYudBzw 03:38:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw'}, attempt #1 03:38:11 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.tar.bz2 03:38:11 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.test_packages.json 03:38:11 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.crashreporter-symbols.zip 03:38:11 INFO - Running post-action listener: _resource_record_post_action 03:38:11 INFO - [mozharness: 2017-09-04 10:38:11.078642Z] Finished read-buildbot-config step (success) 03:38:11 INFO - [mozharness: 2017-09-04 10:38:11.078835Z] Running download-and-extract step. 03:38:11 INFO - Running pre-action listener: _resource_record_pre_action 03:38:11 INFO - Running main action method: download_and_extract 03:38:11 INFO - mkdir: /builds/slave/test/build/tests 03:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:11 INFO - trying https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.test_packages.json 03:38:11 INFO - Downloading https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:38:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:38:11 INFO - Downloaded 989 bytes. 03:38:11 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:38:11 INFO - Using the following test package requirements: 03:38:11 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:38:11 INFO - u'common': [u'target.common.tests.zip'], 03:38:11 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:38:11 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:38:11 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:38:11 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:38:11 INFO - u'mozbase': [u'target.common.tests.zip'], 03:38:11 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:38:11 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:38:11 INFO - u'web-platform': [u'target.common.tests.zip', 03:38:11 INFO - u'target.web-platform.tests.tar.gz'], 03:38:11 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:38:11 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:38:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.common.tests.zip 03:38:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:38:11 INFO - Fetch https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.common.tests.zip into memory 03:38:12 INFO - Content-Length response header: 55682776 03:38:12 INFO - Bytes received: 55682776 03:38:23 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:38:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.common.tests.zip 03:38:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:38:23 INFO - Fetch https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.common.tests.zip into memory 03:38:24 INFO - Content-Length response header: 55682776 03:38:24 INFO - Bytes received: 55682776 03:38:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.talos.tests.zip 03:38:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:38:37 INFO - Fetch https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.talos.tests.zip into memory 03:38:38 INFO - Content-Length response header: 14496650 03:38:38 INFO - Bytes received: 14496650 03:38:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:39 INFO - trying https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.tar.bz2 03:38:39 INFO - Downloading https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:38:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:38:40 INFO - Downloaded 63578603 bytes. 03:38:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.tar.bz2 03:38:40 INFO - mkdir: /builds/slave/test/properties 03:38:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:38:40 INFO - Writing to file /builds/slave/test/properties/build_url 03:38:40 INFO - Contents: 03:38:40 INFO - build_url:https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.tar.bz2 03:38:40 INFO - Running post-action listener: _resource_record_post_action 03:38:40 INFO - Running post-action listener: find_tests_for_verification 03:38:40 INFO - Running post-action listener: set_extra_try_arguments 03:38:40 INFO - [mozharness: 2017-09-04 10:38:40.584962Z] Finished download-and-extract step (success) 03:38:40 INFO - [mozharness: 2017-09-04 10:38:40.585058Z] Running populate-webroot step. 03:38:40 INFO - Running pre-action listener: _resource_record_pre_action 03:38:40 INFO - Running main action method: populate_webroot 03:38:40 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:38:40 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:38:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:38:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:38:40 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:38:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:38:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:38:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:38:40 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:38:40 INFO - 'talos_options': ['--enable-stylo'], 03:38:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:38:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:38:40 INFO - 'tests': ['damp', 'tps']}, 03:38:40 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:38:40 INFO - 'talos_options': ['--enable-stylo'], 03:38:40 INFO - 'tests': ['damp', 'tps']}, 03:38:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:38:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:38:40 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:38:40 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:38:40 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:38:40 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:38:40 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:38:40 INFO - 'talos_options': ['--enable-stylo'], 03:38:40 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:38:40 INFO - 'other-e10s': {'tests': ['a11yr', 03:38:40 INFO - 'ts_paint', 03:38:40 INFO - 'tpaint', 03:38:40 INFO - 'sessionrestore', 03:38:40 INFO - 'sessionrestore_many_windows', 03:38:40 INFO - 'sessionrestore_no_auto_restore', 03:38:40 INFO - 'tabpaint']}, 03:38:40 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:38:40 INFO - 'tests': ['a11yr', 03:38:40 INFO - 'ts_paint', 03:38:40 INFO - 'tpaint', 03:38:40 INFO - 'sessionrestore', 03:38:40 INFO - 'sessionrestore_many_windows', 03:38:40 INFO - 'sessionrestore_no_auto_restore', 03:38:40 INFO - 'tabpaint']}, 03:38:40 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:38:40 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:38:40 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:38:40 INFO - 'tests': ['bloom_basic_singleton']}, 03:38:40 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:38:40 INFO - 'tests': ['bloom_basic']}, 03:38:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:38:40 INFO - 'tsvgr_opacity', 03:38:40 INFO - 'tart', 03:38:40 INFO - 'tscrollx', 03:38:40 INFO - 'tsvg_static']}, 03:38:40 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:38:40 INFO - 'tests': ['tsvgx', 03:38:40 INFO - 'tsvgr_opacity', 03:38:40 INFO - 'tart', 03:38:40 INFO - 'tscrollx', 03:38:40 INFO - 'tsvg_static']}, 03:38:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:38:40 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:38:40 INFO - 'talos_options': ['--enable-stylo'], 03:38:40 INFO - 'tests': ['tp5o']}, 03:38:40 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:38:40 INFO - 'talos_options': ['--mitmproxy', 03:38:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:38:40 INFO - '--firstNonBlankPaint'], 03:38:40 INFO - 'tests': ['tp6_google', 03:38:40 INFO - 'tp6_youtube', 03:38:40 INFO - 'tp6_amazon', 03:38:40 INFO - 'tp6_facebook']}, 03:38:40 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:38:40 INFO - 'talos_options': ['--enable-stylo', 03:38:40 INFO - '--mitmproxy', 03:38:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:38:40 INFO - '--firstNonBlankPaint'], 03:38:40 INFO - 'tests': ['tp6_google', 03:38:40 INFO - 'tp6_youtube', 03:38:40 INFO - 'tp6_amazon', 03:38:40 INFO - 'tp6_facebook']}, 03:38:40 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:38:40 INFO - 'talos_options': ['--enable-stylo', 03:38:40 INFO - '--stylo-threads=1', 03:38:40 INFO - '--mitmproxy', 03:38:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:38:40 INFO - '--firstNonBlankPaint'], 03:38:40 INFO - 'tests': ['tp6_google', 03:38:40 INFO - 'tp6_youtube', 03:38:40 INFO - 'tp6_amazon', 03:38:40 INFO - 'tp6_facebook']}, 03:38:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:38:40 INFO - 'talos_options': ['--xperf_path', 03:38:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:38:40 INFO - 'tests': ['tp5n']}, 03:38:40 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:38:40 INFO - 'talos_options': ['--enable-stylo', 03:38:40 INFO - '--xperf_path', 03:38:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:38:40 INFO - 'tests': ['tp5n']}}} 03:38:40 INFO - Running post-action listener: _resource_record_post_action 03:38:40 INFO - [mozharness: 2017-09-04 10:38:40.599303Z] Finished populate-webroot step (success) 03:38:40 INFO - [mozharness: 2017-09-04 10:38:40.599391Z] Running create-virtualenv step. 03:38:40 INFO - Running pre-action listener: _resource_record_pre_action 03:38:40 INFO - Running main action method: create_virtualenv 03:38:40 INFO - Creating virtualenv /builds/slave/test/build/venv 03:38:40 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:38:40 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:38:40 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:38:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:38:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:38:41 INFO - Installing distribute.............................................................................................................................................................................................done. 03:38:41 INFO - Installing pip...............done. 03:38:41 INFO - Return code: 0 03:38:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:38:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:38:41 INFO - Reading from file tmpfile_stdout 03:38:41 INFO - Output received: 03:38:41 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:38:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:38:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:41 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 0xdb61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1122a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1126090>, '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 0x1126d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1120f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:38:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:38:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:38:41 INFO - Using env: {'DISPLAY': ':0', 03:38:41 INFO - 'HOME': '/home/cltbld', 03:38:41 INFO - 'LANG': 'en_US.UTF-8', 03:38:41 INFO - 'LANGUAGE': 'en_US:en', 03:38:41 INFO - 'LOGNAME': 'cltbld', 03:38:41 INFO - 'MAIL': '/var/mail/cltbld', 03:38:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:41 INFO - 'MOZ_NO_REMOTE': '1', 03:38:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:41 INFO - 'NO_EM_RESTART': '1', 03:38:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:41 INFO - 'PWD': '/builds/slave/test', 03:38:41 INFO - 'SHELL': '/bin/bash', 03:38:41 INFO - 'SHLVL': '1', 03:38:41 INFO - 'TERM': 'linux', 03:38:41 INFO - 'TMOUT': '86400', 03:38:41 INFO - 'USER': 'cltbld', 03:38:41 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 03:38:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:41 INFO - '_': '/tools/buildbot/bin/python'} 03:38:42 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:38:42 INFO - Downloading/unpacking pip>=1.5 03:38:42 INFO - Running setup.py egg_info for package pip 03:38:42 INFO - warning: no files found matching 'pip/cacert.pem' 03:38:42 INFO - warning: no files found matching '*.html' under directory 'docs' 03:38:42 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:38:42 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:38:42 INFO - Installing collected packages: pip 03:38:42 INFO - Found existing installation: pip 1.1 03:38:42 INFO - Uninstalling pip: 03:38:42 INFO - Successfully uninstalled pip 03:38:42 INFO - Running setup.py install for pip 03:38:42 INFO - warning: no files found matching 'pip/cacert.pem' 03:38:42 INFO - warning: no files found matching '*.html' under directory 'docs' 03:38:42 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:38:42 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:38:42 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:38:42 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:38:42 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:38:42 INFO - Successfully installed pip 03:38:42 INFO - Cleaning up... 03:38:42 INFO - Return code: 0 03:38:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:38:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:42 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 0xdb61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1122a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1126090>, '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 0x1126d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1120f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:38:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:38:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:38:42 INFO - Using env: {'DISPLAY': ':0', 03:38:42 INFO - 'HOME': '/home/cltbld', 03:38:42 INFO - 'LANG': 'en_US.UTF-8', 03:38:42 INFO - 'LANGUAGE': 'en_US:en', 03:38:42 INFO - 'LOGNAME': 'cltbld', 03:38:42 INFO - 'MAIL': '/var/mail/cltbld', 03:38:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:42 INFO - 'MOZ_NO_REMOTE': '1', 03:38:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:42 INFO - 'NO_EM_RESTART': '1', 03:38:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:42 INFO - 'PWD': '/builds/slave/test', 03:38:42 INFO - 'SHELL': '/bin/bash', 03:38:42 INFO - 'SHLVL': '1', 03:38:42 INFO - 'TERM': 'linux', 03:38:42 INFO - 'TMOUT': '86400', 03:38:42 INFO - 'USER': 'cltbld', 03:38:42 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 03:38:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:42 INFO - '_': '/tools/buildbot/bin/python'} 03:38:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:42 INFO - Downloading/unpacking psutil>=3.1.1 03:38:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:38:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:38:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:38:44 INFO - Installing collected packages: psutil 03:38:44 INFO - Running setup.py install for psutil 03:38:44 INFO - building 'psutil._psutil_linux' extension 03:38:44 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:38:44 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:38:44 INFO - building 'psutil._psutil_posix' extension 03:38:44 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:38:44 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:38:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:38:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:38:44 INFO - Successfully installed psutil 03:38:44 INFO - Cleaning up... 03:38:44 INFO - Return code: 0 03:38:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:38:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdb61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1122a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1126090>, '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 0x1126d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1120f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:38:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:38:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:38:44 INFO - Using env: {'DISPLAY': ':0', 03:38:44 INFO - 'HOME': '/home/cltbld', 03:38:44 INFO - 'LANG': 'en_US.UTF-8', 03:38:44 INFO - 'LANGUAGE': 'en_US:en', 03:38:44 INFO - 'LOGNAME': 'cltbld', 03:38:44 INFO - 'MAIL': '/var/mail/cltbld', 03:38:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:44 INFO - 'MOZ_NO_REMOTE': '1', 03:38:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:44 INFO - 'NO_EM_RESTART': '1', 03:38:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:44 INFO - 'PWD': '/builds/slave/test', 03:38:44 INFO - 'SHELL': '/bin/bash', 03:38:44 INFO - 'SHLVL': '1', 03:38:44 INFO - 'TERM': 'linux', 03:38:44 INFO - 'TMOUT': '86400', 03:38:44 INFO - 'USER': 'cltbld', 03:38:44 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 03:38:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:44 INFO - '_': '/tools/buildbot/bin/python'} 03:38:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:38:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:38:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:38:46 INFO - Installing collected packages: mozsystemmonitor 03:38:46 INFO - Running setup.py install for mozsystemmonitor 03:38:46 INFO - Successfully installed mozsystemmonitor 03:38:46 INFO - Cleaning up... 03:38:46 INFO - Return code: 0 03:38:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:38:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xdb61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1122a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1126090>, '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 0x1126d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1120f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:38:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:38:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:38:46 INFO - Using env: {'DISPLAY': ':0', 03:38:46 INFO - 'HOME': '/home/cltbld', 03:38:46 INFO - 'LANG': 'en_US.UTF-8', 03:38:46 INFO - 'LANGUAGE': 'en_US:en', 03:38:46 INFO - 'LOGNAME': 'cltbld', 03:38:46 INFO - 'MAIL': '/var/mail/cltbld', 03:38:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:46 INFO - 'MOZ_NO_REMOTE': '1', 03:38:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:46 INFO - 'NO_EM_RESTART': '1', 03:38:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:46 INFO - 'PWD': '/builds/slave/test', 03:38:46 INFO - 'SHELL': '/bin/bash', 03:38:46 INFO - 'SHLVL': '1', 03:38:46 INFO - 'TERM': 'linux', 03:38:46 INFO - 'TMOUT': '86400', 03:38:46 INFO - 'USER': 'cltbld', 03:38:46 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 03:38:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:46 INFO - '_': '/tools/buildbot/bin/python'} 03:38:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:46 INFO - Downloading/unpacking jsonschema==2.5.1 03:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:38:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:47 INFO - Downloading functools32-3.2.3-2.tar.gz 03:38:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:38:47 INFO - warning: no files found matching '*.txt' 03:38:47 INFO - no previously-included directories found matching 'build' 03:38:47 INFO - no previously-included directories found matching 'dist' 03:38:47 INFO - no previously-included directories found matching '.git*' 03:38:47 INFO - Installing collected packages: jsonschema, functools32 03:38:47 INFO - Running setup.py install for functools32 03:38:47 INFO - warning: no files found matching '*.txt' 03:38:47 INFO - no previously-included directories found matching 'build' 03:38:47 INFO - no previously-included directories found matching 'dist' 03:38:47 INFO - no previously-included directories found matching '.git*' 03:38:47 INFO - Successfully installed jsonschema functools32 03:38:47 INFO - Cleaning up... 03:38:48 INFO - Return code: 0 03:38:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:38:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xdb61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1122a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1126090>, '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 0x1126d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1120f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:38:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:38:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:38:48 INFO - Using env: {'DISPLAY': ':0', 03:38:48 INFO - 'HOME': '/home/cltbld', 03:38:48 INFO - 'LANG': 'en_US.UTF-8', 03:38:48 INFO - 'LANGUAGE': 'en_US:en', 03:38:48 INFO - 'LOGNAME': 'cltbld', 03:38:48 INFO - 'MAIL': '/var/mail/cltbld', 03:38:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:48 INFO - 'MOZ_NO_REMOTE': '1', 03:38:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:48 INFO - 'NO_EM_RESTART': '1', 03:38:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:48 INFO - 'PWD': '/builds/slave/test', 03:38:48 INFO - 'SHELL': '/bin/bash', 03:38:48 INFO - 'SHLVL': '1', 03:38:48 INFO - 'TERM': 'linux', 03:38:48 INFO - 'TMOUT': '86400', 03:38:48 INFO - 'USER': 'cltbld', 03:38:48 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 03:38:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:48 INFO - '_': '/tools/buildbot/bin/python'} 03:38:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:38:48 INFO - Cleaning up... 03:38:48 INFO - Return code: 0 03:38:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:38:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xdb61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1122a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1126090>, '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 0x1126d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1120f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:38:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:38:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:38:48 INFO - Using env: {'DISPLAY': ':0', 03:38:48 INFO - 'HOME': '/home/cltbld', 03:38:48 INFO - 'LANG': 'en_US.UTF-8', 03:38:48 INFO - 'LANGUAGE': 'en_US:en', 03:38:48 INFO - 'LOGNAME': 'cltbld', 03:38:48 INFO - 'MAIL': '/var/mail/cltbld', 03:38:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:48 INFO - 'MOZ_NO_REMOTE': '1', 03:38:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:48 INFO - 'NO_EM_RESTART': '1', 03:38:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:48 INFO - 'PWD': '/builds/slave/test', 03:38:48 INFO - 'SHELL': '/bin/bash', 03:38:48 INFO - 'SHLVL': '1', 03:38:48 INFO - 'TERM': 'linux', 03:38:48 INFO - 'TMOUT': '86400', 03:38:48 INFO - 'USER': 'cltbld', 03:38:48 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 03:38:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:48 INFO - '_': '/tools/buildbot/bin/python'} 03:38:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:48 INFO - Downloading/unpacking blobuploader==1.2.4 03:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:49 INFO - Downloading blobuploader-1.2.4.tar.gz 03:38:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:38:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:38:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:50 INFO - Downloading docopt-0.6.1.tar.gz 03:38:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:38:50 INFO - Installing collected packages: blobuploader, requests, docopt 03:38:50 INFO - Running setup.py install for blobuploader 03:38:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:38:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:38:50 INFO - Running setup.py install for requests 03:38:50 INFO - Running setup.py install for docopt 03:38:50 INFO - Successfully installed blobuploader requests docopt 03:38:50 INFO - Cleaning up... 03:38:50 INFO - Return code: 0 03:38:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:50 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 0xdb61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1122a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1126090>, '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 0x1126d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1120f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:38:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:38:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:38:50 INFO - Using env: {'DISPLAY': ':0', 03:38:50 INFO - 'HOME': '/home/cltbld', 03:38:50 INFO - 'LANG': 'en_US.UTF-8', 03:38:50 INFO - 'LANGUAGE': 'en_US:en', 03:38:50 INFO - 'LOGNAME': 'cltbld', 03:38:50 INFO - 'MAIL': '/var/mail/cltbld', 03:38:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:50 INFO - 'MOZ_NO_REMOTE': '1', 03:38:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:50 INFO - 'NO_EM_RESTART': '1', 03:38:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:50 INFO - 'PWD': '/builds/slave/test', 03:38:50 INFO - 'SHELL': '/bin/bash', 03:38:50 INFO - 'SHLVL': '1', 03:38:50 INFO - 'TERM': 'linux', 03:38:50 INFO - 'TMOUT': '86400', 03:38:50 INFO - 'USER': 'cltbld', 03:38:50 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 03:38:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:50 INFO - '_': '/tools/buildbot/bin/python'} 03:38:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:38:50 INFO - Running setup.py (path:/tmp/pip-EZ9vZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:38:51 INFO - Running setup.py (path:/tmp/pip-ulBpV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:38:51 INFO - Running setup.py (path:/tmp/pip-vAbflT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:38:51 INFO - Running setup.py (path:/tmp/pip-eJgp72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:38:51 INFO - Running setup.py (path:/tmp/pip-XLtmkj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:38:51 INFO - Running setup.py (path:/tmp/pip-2fxiJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:38:51 INFO - Running setup.py (path:/tmp/pip-plLHGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:38:51 INFO - Running setup.py (path:/tmp/pip-RysiH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:38:51 INFO - Running setup.py (path:/tmp/pip-wbOZee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:38:51 INFO - Running setup.py (path:/tmp/pip-uMygXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:38:51 INFO - Running setup.py (path:/tmp/pip-vObpxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:38:51 INFO - Running setup.py (path:/tmp/pip-nooFMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:38:51 INFO - Running setup.py (path:/tmp/pip-HKdnQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:38:51 INFO - Running setup.py (path:/tmp/pip-KWkb9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:51 INFO - Running setup.py (path:/tmp/pip-Ld9xLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:38:51 INFO - Running setup.py (path:/tmp/pip-xkqnSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:38:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:38:52 INFO - Running setup.py (path:/tmp/pip-4amz0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:38:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:38:52 INFO - Running setup.py install for manifestparser 03:38:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:38:52 INFO - Running setup.py install for mozcrash 03:38:52 INFO - Running setup.py install for mozdebug 03:38:52 INFO - Running setup.py install for mozdevice 03:38:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:38:52 INFO - Running setup.py install for mozfile 03:38:52 INFO - Running setup.py install for mozhttpd 03:38:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:38:52 INFO - Running setup.py install for mozinfo 03:38:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:38:52 INFO - Running setup.py install for mozInstall 03:38:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:38:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:38:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:38:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:38:52 INFO - Running setup.py install for mozleak 03:38:52 INFO - Running setup.py install for mozlog 03:38:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:38:53 INFO - Running setup.py install for moznetwork 03:38:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:38:53 INFO - Running setup.py install for mozprocess 03:38:53 INFO - Running setup.py install for mozprofile 03:38:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:38:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:38:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:38:53 INFO - Running setup.py install for mozrunner 03:38:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:38:53 INFO - Running setup.py install for mozscreenshot 03:38:53 INFO - Running setup.py install for moztest 03:38:53 INFO - Running setup.py install for mozversion 03:38:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:38:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:38:53 INFO - Cleaning up... 03:38:53 INFO - Return code: 0 03:38:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:38:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:53 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 0xdb61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1122a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1126090>, '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 0x1126d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1120f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:38:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:38:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:38:53 INFO - Using env: {'DISPLAY': ':0', 03:38:53 INFO - 'HOME': '/home/cltbld', 03:38:53 INFO - 'LANG': 'en_US.UTF-8', 03:38:53 INFO - 'LANGUAGE': 'en_US:en', 03:38:53 INFO - 'LOGNAME': 'cltbld', 03:38:53 INFO - 'MAIL': '/var/mail/cltbld', 03:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:53 INFO - 'MOZ_NO_REMOTE': '1', 03:38:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:53 INFO - 'NO_EM_RESTART': '1', 03:38:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:53 INFO - 'PWD': '/builds/slave/test', 03:38:53 INFO - 'SHELL': '/bin/bash', 03:38:53 INFO - 'SHLVL': '1', 03:38:53 INFO - 'TERM': 'linux', 03:38:53 INFO - 'TMOUT': '86400', 03:38:53 INFO - 'USER': 'cltbld', 03:38:53 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 03:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:53 INFO - '_': '/tools/buildbot/bin/python'} 03:38:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:38:54 INFO - Running setup.py (path:/tmp/pip-gUt7IJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:38:54 INFO - Running setup.py (path:/tmp/pip-sE3SPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:38:54 INFO - Running setup.py (path:/tmp/pip-ApLD3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:38:54 INFO - Running setup.py (path:/tmp/pip-OpvT42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:38:54 INFO - Running setup.py (path:/tmp/pip-VTo4YI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:38:54 INFO - Running setup.py (path:/tmp/pip-CvELjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:38:54 INFO - Running setup.py (path:/tmp/pip-XlF4vF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:38:54 INFO - Running setup.py (path:/tmp/pip-qgjGDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:38:54 INFO - Running setup.py (path:/tmp/pip-IJncyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:38:54 INFO - Running setup.py (path:/tmp/pip-mtsnCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:38:54 INFO - Running setup.py (path:/tmp/pip-CdnMpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:38:54 INFO - Running setup.py (path:/tmp/pip-XnPjMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:38:54 INFO - Running setup.py (path:/tmp/pip-kJtiey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:38:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:38:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:38:55 INFO - Running setup.py (path:/tmp/pip-cM9RPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:38:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:55 INFO - Running setup.py (path:/tmp/pip-yBeGmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:38:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:38:55 INFO - Running setup.py (path:/tmp/pip-wIrCGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:38:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:38:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:38:55 INFO - Running setup.py (path:/tmp/pip-6EQke9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:38:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:38:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:38:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:38:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:38:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:38:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:38:55 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:38:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:56 INFO - Downloading blessings-1.6.tar.gz 03:38:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:38:56 INFO - Installing collected packages: blessings 03:38:56 INFO - Running setup.py install for blessings 03:38:56 INFO - Successfully installed blessings 03:38:56 INFO - Cleaning up... 03:38:56 INFO - Return code: 0 03:38:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:38:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:38:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:38:56 INFO - Reading from file tmpfile_stdout 03:38:56 INFO - Current package versions: 03:38:56 INFO - argparse == 1.2.1 03:38:56 INFO - blessings == 1.6 03:38:56 INFO - blobuploader == 1.2.4 03:38:56 INFO - docopt == 0.6.1 03:38:56 INFO - functools32 == 3.2.3-2 03:38:56 INFO - jsonschema == 2.5.1 03:38:56 INFO - manifestparser == 1.1 03:38:56 INFO - mozInstall == 1.13 03:38:56 INFO - mozcrash == 1.0 03:38:56 INFO - mozdebug == 0.1 03:38:56 INFO - mozdevice == 0.51 03:38:56 INFO - mozfile == 1.2 03:38:56 INFO - mozhttpd == 0.7 03:38:56 INFO - mozinfo == 0.10 03:38:56 INFO - mozleak == 0.1 03:38:56 INFO - mozlog == 3.5 03:38:56 INFO - moznetwork == 0.27 03:38:56 INFO - mozprocess == 0.25 03:38:56 INFO - mozprofile == 0.28 03:38:56 INFO - mozrunner == 6.13 03:38:56 INFO - mozscreenshot == 0.1 03:38:56 INFO - mozsystemmonitor == 0.3 03:38:56 INFO - moztest == 0.8 03:38:56 INFO - mozversion == 1.4 03:38:56 INFO - psutil == 3.1.1 03:38:56 INFO - requests == 1.2.3 03:38:56 INFO - wsgiref == 0.1.2 03:38:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:56 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 0xdb61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1122a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1126090>, '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 0x1126d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1120f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:38:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:38:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:38:56 INFO - Using env: {'DISPLAY': ':0', 03:38:56 INFO - 'HOME': '/home/cltbld', 03:38:56 INFO - 'LANG': 'en_US.UTF-8', 03:38:56 INFO - 'LANGUAGE': 'en_US:en', 03:38:56 INFO - 'LOGNAME': 'cltbld', 03:38:56 INFO - 'MAIL': '/var/mail/cltbld', 03:38:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:56 INFO - 'MOZ_NO_REMOTE': '1', 03:38:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:56 INFO - 'NO_EM_RESTART': '1', 03:38:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:56 INFO - 'PWD': '/builds/slave/test', 03:38:56 INFO - 'SHELL': '/bin/bash', 03:38:56 INFO - 'SHLVL': '1', 03:38:56 INFO - 'TERM': 'linux', 03:38:56 INFO - 'TMOUT': '86400', 03:38:56 INFO - 'USER': 'cltbld', 03:38:56 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 03:38:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:56 INFO - '_': '/tools/buildbot/bin/python'} 03:38:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:38:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:38:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:38:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:38:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:38:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:38:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:38:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:38:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:38:57 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:38:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:38:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:38:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:38:58 INFO - Installing collected packages: simplejson 03:38:58 INFO - Running setup.py install for simplejson 03:38:58 INFO - building 'simplejson._speedups' extension 03:38:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:38:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 03:38:59 INFO - Successfully installed simplejson 03:38:59 INFO - Cleaning up... 03:38:59 INFO - Return code: 0 03:38:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:38:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:38:59 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 0xdb61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1122a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1126090>, '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 0x1126d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1120f90>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:38:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:38:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:38:59 INFO - Using env: {'DISPLAY': ':0', 03:38:59 INFO - 'HOME': '/home/cltbld', 03:38:59 INFO - 'LANG': 'en_US.UTF-8', 03:38:59 INFO - 'LANGUAGE': 'en_US:en', 03:38:59 INFO - 'LOGNAME': 'cltbld', 03:38:59 INFO - 'MAIL': '/var/mail/cltbld', 03:38:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:59 INFO - 'MOZ_NO_REMOTE': '1', 03:38:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:59 INFO - 'NO_EM_RESTART': '1', 03:38:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:59 INFO - 'PWD': '/builds/slave/test', 03:38:59 INFO - 'SHELL': '/bin/bash', 03:38:59 INFO - 'SHLVL': '1', 03:38:59 INFO - 'TERM': 'linux', 03:38:59 INFO - 'TMOUT': '86400', 03:38:59 INFO - 'USER': 'cltbld', 03:38:59 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 03:38:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:59 INFO - '_': '/tools/buildbot/bin/python'} 03:38:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:38:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:38:59 INFO - Cleaning up... 03:38:59 INFO - Return code: 0 03:38:59 INFO - Running post-action listener: _resource_record_post_action 03:38:59 INFO - Running post-action listener: _start_resource_monitoring 03:38:59 INFO - Starting resource monitoring. 03:38:59 INFO - [mozharness: 2017-09-04 10:38:59.553895Z] Finished create-virtualenv step (success) 03:38:59 INFO - [mozharness: 2017-09-04 10:38:59.554416Z] Running install step. 03:38:59 INFO - Running pre-action listener: _resource_record_pre_action 03:38:59 INFO - Running main action method: install 03:38:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:38:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:38:59 INFO - Reading from file tmpfile_stdout 03:38:59 INFO - Detecting whether we're running mozinstall >=1.0... 03:38:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:38:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:38:59 INFO - Reading from file tmpfile_stdout 03:38:59 INFO - Output received: 03:38:59 INFO - Usage: mozinstall [options] installer 03:38:59 INFO - Options: 03:38:59 INFO - -h, --help show this help message and exit 03:38:59 INFO - -d DEST, --destination=DEST 03:38:59 INFO - Directory to install application into. [default: 03:38:59 INFO - "/builds/slave/test"] 03:38:59 INFO - --app=APP Application being installed. [default: firefox] 03:38:59 INFO - mkdir: /builds/slave/test/build/application 03:38:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:38:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:39:16 INFO - Reading from file tmpfile_stdout 03:39:16 INFO - Output received: 03:39:16 INFO - /builds/slave/test/build/application/firefox/firefox 03:39:16 INFO - Running post-action listener: _resource_record_post_action 03:39:16 INFO - [mozharness: 2017-09-04 10:39:16.281164Z] Finished install step (success) 03:39:16 INFO - [mozharness: 2017-09-04 10:39:16.281501Z] Running setup-mitmproxy step. 03:39:16 INFO - Running pre-action listener: _resource_record_pre_action 03:39:16 INFO - Running main action method: setup_mitmproxy 03:39:16 INFO - Skipping: mitmproxy is not required 03:39:16 INFO - Running post-action listener: _resource_record_post_action 03:39:16 INFO - [mozharness: 2017-09-04 10:39:16.283099Z] Finished setup-mitmproxy step (success) 03:39:16 INFO - [mozharness: 2017-09-04 10:39:16.283407Z] Running run-tests step. 03:39:16 INFO - Running pre-action listener: _resource_record_pre_action 03:39:16 INFO - Running pre-action listener: _set_gcov_prefix 03:39:16 INFO - Running main action method: run_tests 03:39:16 WARNING - Try message not found. 03:39:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:39:16 INFO - Python 2.7.3 03:39:16 INFO - Return code: 0 03:39:16 INFO - grabbing minidump binary from tooltool 03:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:39:16 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 0x1126090>, '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 0x1126d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1120f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:39:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:39:16 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:39:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:39:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:39:16 INFO - Return code: 0 03:39:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:39:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:39:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:39:16 INFO - ENV: RUST_BACKTRACE is now full 03:39:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:39:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:39:16 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/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:39:16 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/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-069 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:39:16 INFO - Using env: {'DISPLAY': ':0', 03:39:16 INFO - 'HOME': '/home/cltbld', 03:39:16 INFO - 'LANG': 'en_US.UTF-8', 03:39:16 INFO - 'LANGUAGE': 'en_US:en', 03:39:16 INFO - 'LOGNAME': 'cltbld', 03:39:16 INFO - 'MAIL': '/var/mail/cltbld', 03:39:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:39:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:39:16 INFO - 'MOZ_NO_REMOTE': '1', 03:39:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:39:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:39:16 INFO - 'NO_EM_RESTART': '1', 03:39:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:39:16 INFO - 'PWD': '/builds/slave/test', 03:39:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:39:16 INFO - 'RUST_BACKTRACE': 'full', 03:39:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:39:16 INFO - 'SHELL': '/bin/bash', 03:39:16 INFO - 'SHLVL': '1', 03:39:16 INFO - 'TERM': 'linux', 03:39:16 INFO - 'TMOUT': '86400', 03:39:16 INFO - 'USER': 'cltbld', 03:39:16 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 03:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:39:16 INFO - '_': '/tools/buildbot/bin/python'} 03:39:16 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/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:39:16 INFO - mozversion application_buildid: 20170904091258 03:39:16 INFO - mozversion application_changeset: 632e42dca494ec3d90b70325d9c359f80cb3f38a 03:39:16 INFO - mozversion application_display_name: Nightly 03:39:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:39:16 INFO - mozversion application_name: Firefox 03:39:16 INFO - mozversion application_remotingname: firefox 03:39:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:39:16 INFO - mozversion application_vendor: Mozilla 03:39:16 INFO - mozversion application_version: 57.0a1 03:39:16 INFO - mozversion platform_buildid: 20170904091258 03:39:16 INFO - mozversion platform_changeset: 632e42dca494ec3d90b70325d9c359f80cb3f38a 03:39:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:39:16 INFO - mozversion platform_version: 57.0a1 03:39:16 INFO - using testdate: 1504521556 03:39:16 INFO - actual date: 1504521556 03:39:16 INFO - starting webserver on 'localhost:58827' 03:39:16 INFO - SUITE-START | Running 2 tests 03:39:16 INFO - TEST-START | dromaeo_css 03:39:16 INFO - Initialising browser for dromaeo_css test... 03:39:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58827/getInfo.html -profile /tmp/tmpYsPiQs/profile 03:39:16 INFO - TEST-INFO | started process 4910 (/builds/slave/test/build/application/firefox/firefox http://localhost:58827/getInfo.html) 03:39:21 INFO - TEST-INFO | 4910: exit 0 03:39:21 INFO - Browser initialized. 03:39:21 INFO - Running cycle 1/1 for dromaeo_css test... 03:39:21 INFO - Using env: {'DISPLAY': ':0', 03:39:21 INFO - 'HOME': '/home/cltbld', 03:39:21 INFO - 'JSGC_DISABLE_POISONING': '1', 03:39:21 INFO - 'LANG': 'en_US.UTF-8', 03:39:21 INFO - 'LANGUAGE': 'en_US:en', 03:39:21 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:39:21 INFO - 'LOGNAME': 'cltbld', 03:39:21 INFO - 'MAIL': '/var/mail/cltbld', 03:39:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:39:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:39:21 INFO - 'MOZ_CRASHREPORTER': '1', 03:39:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:39:21 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:39:21 INFO - 'MOZ_NO_REMOTE': '1', 03:39:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:39:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:39:21 INFO - 'NO_EM_RESTART': '1', 03:39:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:39:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:39:21 INFO - 'PWD': '/builds/slave/test', 03:39:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:39:21 INFO - 'RUST_BACKTRACE': 'full', 03:39:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:39:21 INFO - 'SHELL': '/bin/bash', 03:39:21 INFO - 'SHLVL': '1', 03:39:21 INFO - 'TERM': 'linux', 03:39:21 INFO - 'TMOUT': '86400', 03:39:21 INFO - 'USER': 'cltbld', 03:39:21 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 03:39:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:39:21 INFO - '_': '/tools/buildbot/bin/python'} 03:39:21 INFO - TEST-INFO | started process 5092 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYsPiQs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:39:22 INFO - PID 5092 | 03:39:22 INFO - PID 5092 | (/builds/slave/test/build/application/firefox/firefox:5145): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:39:22 INFO - PID 5092 | 03:39:33 INFO - PID 5092 | RSS: Main: 162947072 03:39:33 INFO - PID 5092 | 03:42:31 INFO - PID 5092 | Cycle 1(1): loaded http://localhost:58827/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58827/tests/dromaeo/cssquery-ext.html) 03:42:33 INFO - PID 5092 | RSS: Main: 165421056 03:42:33 INFO - PID 5092 | 03:45:34 INFO - PID 5092 | Cycle 1(1): loaded http://localhost:58827/tests/dromaeo/cssquery-ext.html (next: http://localhost:58827/tests/dromaeo/cssquery-jquery.html) 03:45:35 INFO - PID 5092 | RSS: Main: 161640448 03:45:35 INFO - PID 5092 | 03:48:37 INFO - PID 5092 | Cycle 1(1): loaded http://localhost:58827/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58827/tests/dromaeo/cssquery-mootools.html) 03:48:37 INFO - PID 5092 | RSS: Main: 164069376 03:48:37 INFO - PID 5092 | 03:51:39 INFO - PID 5092 | Cycle 1(1): loaded http://localhost:58827/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58827/tests/dromaeo/cssquery-prototype.html) 03:51:39 INFO - PID 5092 | RSS: Main: 162476032 03:51:39 INFO - PID 5092 | 03:54:43 INFO - PID 5092 | Cycle 1(1): loaded http://localhost:58827/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58827/tests/dromaeo/cssquery-yui.html) 03:54:44 INFO - PID 5092 | RSS: Main: 163827712 03:54:44 INFO - PID 5092 | 03:57:45 INFO - PID 5092 | Cycle 1(1): loaded http://localhost:58827/tests/dromaeo/cssquery-yui.html (next: http://localhost:58827/tests/dromaeo/cssquery-dojo.html) 03:57:45 INFO - PID 5092 | RSS: Main: 166338560 03:57:45 INFO - PID 5092 | 03:57:45 INFO - PID 5092 | __start_tp_report 03:57:45 INFO - PID 5092 | _x_x_mozilla_page_load 03:57:45 INFO - PID 5092 | _x_x_mozilla_page_load_details 03:57:45 INFO - PID 5092 | |i|pagename|runs| 03:57:45 INFO - PID 5092 | |0;dojo.html;3863.4784304118944;3863.7488563243405;3879.2241551689663;3909.394043923191;3944.9348675857536;6796.796096117112;6876.621785801779;6879.828004299895;6917.654117294141;6922.826929326763;6625.900611490832;6736.562123461971;6757.32426757324;6765.323467653232;6796.796096117112;6083.634981901078;6108.664023478706;6136;6141.140240366352;6149.3235744068115;6126.877462450749;6162.952298109332;6191.473724733403;6234.906476402857;6276.1840960675045;6164.96917515414;6188.504919606421;6205.131281620555;6259.405356491126;6270.153529273535;6087.360827113178;6159.846003849895;6173.753049878;6189.473894718954;6260.280067792194;5873.85315367115;6092.634441933498;6096.359882212349;6162.291336496271;6205.906911396332;6879.2432832388395;6898.206706228748;6900.171979362509;7006.124234470692;7018.613976231305;7256.6734496947765;7320.158181809107;7349.742759003409;7355.595442250674;7479.289467500579;7418.591977441239;7482.363999060087;7569.583672897988;7595.126560445564;7608.885866712004;6929.133858267716;6943.375096241312;6996.790096297119;7022.332878376648;7029.859402811966;7523.022007139037;7652.081750189902;7681.3854891608535;7756.961215193888;7760.728374506978;7211.242819503981;7293.8905916411295;7300.926990730131;7303.233160518175;7310.817229569197;7313.414926805843;7386.852262954711;7413.1104267488245;7423.072115985502;7501.062367204099;7236.095488063992;7271.963640181765;7296.233895440902;7322.267773222635;7330.7067717291775;7285.453590980697;7296.8175795604475;7311.561306321638;7362.079740032496;7383.741569045165;6203.348648391944;6213.161223234896;6246.3753624637175;6262.311145774052;6308.369163083655;5407.594430417735;5497.890042199134;5498.532624726943;5552.472515111058;5565.1374037024325;6299.937000629935;6340.587861788969;6358.968205159037;6384.7126879290545;6385.329540398191;7323.597202153933;7325.963370183115;7353.595552244571;7357.889631655529;7382.889256661155;6352.301246862841;6384.553081284265;6392.232932048184;6393.360663933569;6395.20059992501;6561.639109849004;6636.137302150786;6667;6668.866622667617;6670.433013193836;7194.892076618855;7263.237360077222;7280.344768970712;7291.30732580404;7308.123025237006;7272.345488906024;7391.482596218213;7394.519356241827;7483.70065197386;7497.662605182671;7076.893846592202;7091.149062112373;7128.108986376703;7167.641617919187;7181.82045448847;7191.0292110564405;7257.927420725725;7296.270372962661;7297.63511824396;7308.8538229234055;7412.999245102058;7448.404127669665;7527.247275272429;7528.435367347581;7551.848963020599;6979.755708550177;7057;7081.114860642419;7178.030965819757;7178.60517671533;7078.929210707827;7124.679389427384;7127.964360178166;7128.964355178191;7132.072830531998;6830.93169068303;6836.316368363124;6908.689108990007;6931.618760968196;7069.434445244266;7185.245549217466;7200.53196542244;7202.423806095605;7233.710651573878;7244.673989670583;6952.930470695431;6984.441244700311;7049.330313620198;7049.929500705132;7068.9293107070325 03:57:45 INFO - PID 5092 | |1;ext.html;884.5311984648134;929.9023602521735;959.1942768074816;993.6025589764098;993.7863359377737;41245.3813192802;41756.37365439521;41793.79103104488;41837.581624183746;41842.16315673688;39646.20707585847;39980.60019399805;39988.20023599533;39989.40015899764;40036.59963400372;7297.927020729804;7407.185209626937;7408.296211859892;7410.147832999221;7416.740414085508;7234.059572255599;7275.708971641128;7278.09023872016;7312.159101703292;7811.570363629998;8085.757427277162;8117.837643247131;8125.146859579748;8139.430239883211;8146.26683598476;7928.9603551982445;8037.879431808527;8105.1084380718075;8105.918940810605;8149.307308878752;7879.251471110236;7949.562774047425;7954.681812727484;7955.085165206017;7956.602169891482;38464.423033654515;38591.228175436336;38672.41991370104;38713.41929871054;38809.417858732144;3698.4637227601847;3728.1984373359587;3729.9067523311865;3730.4404339349044;3731.8134093295225;32063.19842003945;32119.357612847612;32177.1955701107;32189.51715724266;32214;5439.728013599344;5459.672419654833;5469.972650136724;5475.561955043627;5548.805791797308;204848;208377;209177;210456;219130.90434547883;48897.26654100191;49019;49330.013399732525;49529.752351238014;49600.751996239786;53366;53372;53464.73267633637;53489.1976620351;53491.465085349424;13972.790408143886;14082.577522674335;14108.435662573382;14110.788338174936;14159.362828672734;11205.103591712643;11207.775844483065;11210.943945280385;11216.887831121585;11218.831717524243;14298.213598251989;14405.85594144066;14426.85573144255;14534.854651453561;14633;5602.38373778884;5654.46282603152;5697.088465845445;5713.542916566665;5719.7712091516705;1081.7025318037445;1085.1427372375892;1088.9401082940356;1107.1253709569512;1108.0747575774158;25555.87222063878;25644.871775641;25663;25764.613530797054;25784.226473205836;1179.3454632678945;1181.4624345922584;1187.9643610691694;1193.6359410379916;1197.1739499745147;13323.249762422476;13444.865551344361;13468.259245741578;13655.317234138254;13698;254012;259011;259298;260055;260864;55291.170632440546;55617.72191139018;55721.44278557163;55730.72134639301;55801.162982555295;1462.8683418492174;1492.5223928342825;1502.4440956846038;1503.180766482248;1513.1450730337324;25169;25561.616575751752;25622.615660765478;25752;25829.483410332065;40643.390349144196;40838.79580602078;40892.1821563573;40920.795396022826;40930.79534602308;216397.91801040893;220855;221666;223009;223776;35125.824370877985;36892;38429.231415372094;38912;39049.414258785575;48350.5164948346;49026.26460603022;49375.50624493709;49969;49983.00033999373;36448;36800.815995919846;36811.44782828313;37118.44322335221;37510.8124459376;36056.819715901256;36226;36249.27501450009;36652.63347366492;37250.8137459311;41991.7900410496;43287.783561082;43535;43679.344809828515;43721.56278437175;35057;35333.46999795057;35344.82327588346;35385.64614353824;36258.09354766152;26645.733542664326;27009.729902701507;27301.590476142475;27341.453170936868;27583.86208068947 03:57:45 INFO - PID 5092 | |2;jquery.html;572.7565784541569;588.9057750759877;597.2295738497338;605.9214598015534;615.1326629452471;22653.09387624495;23112.884435577842;23316.300510984656;23397.883010584967;23410.64884026739;26357;26868.865655671743;27058.864705676493;27064.188074357753;27086.051988180418;6644.335566443353;6766.627835469048;6767.42476889465;6788.864222715542;6793;5833.154192642064;6366.554341196118;6374.553781235315;6376.2667293261175;6781.966090169542;6323.715432805512;6327.5570710050315;6348.619082855021;6348.714307856135;6349.555531112801;6314.210723659543;6347.777827776029;6363.363663633646;6365;6365.2998170201245;5944.851378715523;5980.581359304851;5986.07215881539;5995.490383317423;6001.039833626643;29562;29727.554086688717;30596.38807223843;31098.689013109804;31236.282004489745;2250.7411647660565;2273.59075366434;2273.7498875123724;2273.818094552432;2273.9204127855446;20739.170433182655;21372.5725485491;21401.571968560696;21440.571188576298;21463;2449.8162637802056;2488.365466805953;2553.961690574643;2591.1449221756775;2601.3626661467874;150579;156143;156321;156402;156546;15743.68512629741;16205.918970405073;16703.582410439594;16746.665066698843;16984.49046528606;26313.73686263151;26329.341766455993;26329.868350658126;26375;26392.8680356597;9750.902490974999;9776.657816976514;9878.753031174278;9995.151381856136;10052.296339256181;6677.565958212701;8656.394052416397;8670.349723770742;8682.348823838238;8773.517456539952;8910.955445222733;9993.550290236957;10045;10065.496725163712;10163.339382940085;2929.384829185895;2942.3379739558427;2985.7462115719973;2994.7454466370605;3002.879884804627;1056.8678915135608;1060.1465820014807;1072.104792498257;1075.510642657589;1082.6860210539014;26151;26558.539280339766;27455;27554.17337479879;27589.31026724348;1111.7998760223238;1112.855328807266;1116.4361997191365;1128.5937062657438;1133.348324713293;12002.399880005965;12299.508019679113;12383.504659813687;12540.184887982252;12549.74900502003;688673;693142;694458;724840;734918;132475.33762331126;134131.32934335267;135197;135367.32316338355;135754.32122839324;1293.275765571283;1319.4722111155615;1341.155072304442;1345.4056942522946;1348.4201793229006;19464.902675486534;19988.30040948618;20075;20085;20642.380728578166;65027;65257.34742652513;66799.33200667931;66869.66565167143;67787.3221267781;82519;83669.58165209135;84135.579322103;84634.15365846263;85694.57152714196;15971.920140399223;16305.755413668567;16384.918075409547;16388.5083447492;16436.917815410845;24995.250142495755;25180.11869584556;25181.62227566551;25508.362290942874;25637.10270140536;20174.394768156977;20431.8978405107;20505;20619.381418557467;20631.896840516303;18049.09754512243;18086.366977156264;18482.353117640818;18511.444656660322;18883.811161888756;20653.58692826105;20779;20972.895135524224;21015;21279.148834046464;16027.439039634024;16061.839381606034;16106.758398623795;16110.838891610934;16242;19958.800411995693;20036.298729544396;20080.497987549843;20217.797822021592;20251.59496810026 03:57:45 INFO - PID 5092 | |3;mootools.html;1448.8188976377953;1506.8719158871495;1512.5916002679278;1528.0755143138401;1534.4859472076857;16421.753673694904;16571.08859012754;17166.141692915367;17176.914115429438;17198.828011719896;17334.393296234604;17520.561985950357;17533.736993945102;17549.21028553715;17553.29786808526;2895.1314605618336;2934.838583877886;2937.397833444139;2938.1038783171125;2939.265183704074;2790.1629511146675;2827.5899994500787;2837.9716202837913;2902.8983985560403;2903.7241462061174;2835.31952331441;2838.3755573773738;2860.4994126027964;2863.513202755526;2875.309925617847;2800.201942446393;2807.9578806317922;2818.070036887822;2820.675622303441;2825.0959692898177;2767.9169624911283;2780.6802217745017;2796.9791026275466;2802.327441414046;2802.8598570071413;16553.83446165535;16778.077205753678;16802.41191558302;16870.915645421694;16888.408905688364;8504.149585041447;8747;8751.124887511261;8752.824943501095;8759.386842920998;5297.417284098746;5590.91613625796;5637.661740295554;5663.348714897757;5735.541156707495;1946.1242440901651;1967.8917659528863;1980.9504762380911;1995.1720036184965;2007.8192962633434;30270.24324391886;30400;30440.391192176034;30485;30501.694983050107;7253.165885923081;7361.153467351216;7404.222556631476;7474.103107627011;7503.5497870127965;19066.80933190678;19088.141033653243;19103.52241193981;19114.904425477784;19127;8367.288780453608;8515.659373625109;8519.57402129891;8523.488590684456;8544.871826922601;7702.037245344332;7819.765407037798;7851.685932562747;7874.527528348317;7951.642176102089;5071.123684236856;5270.866763645758;5485.533729632946;5495.807646732425;5526.392096869341;1508.9622759431102;1583.247957220311;1601.6716573102485;1614.4833653230853;1619.6841615885025;854.0690647194588;857.1043259793531;864.7492227254025;876.4784952952983;878.2315473960285;8186.631601577943;8203.138670439637;8292.958535207285;8469.872951905727;8489.320854331638;1009.4498498318287;1019.7603563162651;1020.637673625867;1020.7907378987438;1022.1311884897926;4799.256115302133;5514.889702206014;5552.250446189793;5704;5801.7099145042575;24151.396215094763;24491.510169796504;25117.62323565148;25168.748312517007;25273.74726252714;23805;24375.878120609283;24447.75552244455;24449.633255501183;25014.37464063413;12642.241465511916;12642.873571264354;12990;13009.674758131123;13053.934730326288;8334.249367936105;8673.739787806377;8804.911950880665;8904.777380565538;8995.325350598861;20547.38357849267;20628.587428251652;21155.62488438308;21513.569728605653;21732.239371621916;22019.339419817432;22065;22177;22488.32535023952;23131.768682313636;13107.803382949454;13437.798433023709;13441.260730659907;13445.126066805626;13453.798193026916;15442.84557154459;15495.612609684847;15555.377784888931;15625.531234062997;15698.450554230547;12309.076819238413;12324.075694322783;12423.440945157308;12624.684382890502;12694.746105077662;11176.217664763388;11290.661280161608;11310.773784524099;11438.370889601152;11580.594679186188;9235.538223088954;9419.952900235456;9420.670276540288;9426.104520070583;9427.010163932688;7178.174509931425;7184.712611495482;7207.171175314906;7235.421166306577;7287.489875708649;7429.368503677248;7436.475522517762;7439.628018599156;7442.7395041173295;7534.736284230025 03:57:45 INFO - PID 5092 | |4;prototype.html;536.2519285596593;549.9742979343525;570.6119838509813;571.8198767388275;574.5977815529128;6673.632950187738;6727.697253623586;6770.729170833167;6778.152730908637;6800.557963732366;6581.440577550902;6650.978511224019;6693.247794066218;6719.697613607387;6720.092787473702;5440.292761940952;5478.726063696819;5480.506754392104;5486.231927530149;5509.504144626982;5204.063268611649;5292.603054770889;5366.221897824813;5388.757505912224;5392.245085687987;5353.9196912046355;5369.677819330833;5387.811426600069;5404.891902161954;5439.428859969706;5363.846772943797;5366.436524164965;5423.755930983115;5452.781888724447;5492.670439773627;5080;5083.440821509668;5096.439391666913;5137.535802296365;5147.588192944556;6441.516886233551;6474.773382931575;6511.316311787289;6554.967225163891;6688.93311066888;6395.14540783177;6429.485641148697;6433.9678301608665;6438.967805160991;6451.516136289797;7104.761999969993;7121.857562848766;7130.964345178293;7154.964225178893;7176.533525320838;5542.8337149885565;5648.43515648436;5653.660780353192;5674.914876276859;5693.886122277573;7108.182559005677;7234;7243.89134162988;7437.665305061285;7442.553446793209;5857.560682948796;5858.794942177004;5901.704914754245;5943.375945525744;5952.404759524013;5920.763169473173;5953.464188222994;6034.48706859913;6053.909191362133;6083.69581520931;6332.746690132343;6467.482601391877;6500.5449618526245;6503.187101612299;6532.346765323429;7089.929100708927;7130.821729456805;7160.248173941765;7161.9641901790155;7164.713411463483;2072.77199508054;2092.937211883646;2121.4060063182333;2133.370655656585;2135.0605733477532;1476.365162979929;1532.8926975111635;1534.7621118726615;1541.8072740907387;1562.8827837912202;6013.338532761392;6017.578769486182;6027.849303767353;6197.287311959092;6238.65687387189;6965.616891070938;6984.196817365966;7014.473914456436;7061.964690176516;7168.426525877917;6043.8791224175275;6180.351063138396;6204.565680402328;6207.317195108534;6230.719617617127;3817.828197731109;3839.3856982883162;3842.7310088293552;3851.268259030775;3854.094287842355;7050.330218629222;7057.92942070583;7068.823279418056;7080.468964827659;7088.503804733619;7401.777946661608;7430.702771889064;7468.21583733711;7482.363999060032;7506.24937506245;6862.348076932683;7011.964940175267;7024.8595028098125;7088.539244949062;7090.539114957614;7058.682359293742;7074.816332650225;7111.53775004623;7139.394605724076;7180.748673796392;6997.580145191101;7149.820279653799;7152.392046676091;7189.964050179925;7196.820079498055;6848.623325717133;6938.895916561155;6947.61788101639;7002.474814389043;7020.157581090302;5888.852778680567;5901.350851406256;5907.261592300963;5944.554158438048;5949.3455719871645;6888.345607167311;6980.476464265302;7013.789586312419;7066.434685225273;7247.746328878464;6831;6839.794806155824;6842.93157068423;6901.792946211622;6902.896456553256;6834.428081541373;6836.829079273058;6841.247462778991;6903.1716194057035;6932.826679332855;6814.420774234246;6821.624810635462;6829.931700683128;6862.656867156522;6909.654517274217;6871.5189936703955;6933.306669333027;6966;6970.201240323934;6998.675629076148;6727.730890764315;6738.326167383419;6759.5606285591275;6810.965945170243;6839.760608378683 03:57:45 INFO - PID 5092 | |5;yui.html;952.8284908716428;961.8842143113988;988.6391467114505;992.503748125937;1013.3514550687561;17077.914610426946;17595.73606395902;17596.560085997855;17703.468895933143;19318.903405482954;18713.719294210598;18740.53148671284;18746.25014999402;18751.15619797109;18794.436166915013;761.4441457735857;1019.3476175247847;1055.1242468750957;1055.9208059395537;1056.7780766039102;963.2005435488528;1042.6455005298235;1057.8730552333732;1058.2856571813995;1062.1715062251455;1129.5481807277072;1131.2929419113054;1131.3438205840594;1135.432283858071;1135.5741596901162;1120.7646394257215;1124.454639499842;1124.6907100547332;1124.7356871135278;1125.5047778977264;828.888100106484;831.7255305749088;832.7168762620678;832.7501749475133;832.950022998616;16640.251188696537;16827.91586042074;17792.288308467647;17986.19062142207;18024;22277.220297289678;22455.663165052374;22557.210497632503;22636.660450093263;22646.66030009551;17107.401240956508;17198.91400543002;17268.74096888535;17368.218430170673;17475;3913.706472014582;3929.4695216146024;3933.2920074386598;3943.369060950234;3949.8025098744947;142560;142846.28576857154;142906;144313;147129;16280.75578866318;16344.67310653804;16842.578935526464;16844.74732879008;16845.241964111643;31432.214194645316;32324;32529;32605.510917336258;32638;8888.422252553562;9123.635054597742;9127.726168214964;9178.082191780903;9225.53872306382;4195.622393984495;4385.824567017347;4622.884427889329;4684.4378674559275;4776.283557466339;5622.212890195376;5703.657780533098;5704.771809127588;5713.371529131792;5734;1183.8520184976878;1198.6823491774687;1202.2786328203008;1215.228330010432;1226.2152222577558;724.807925899637;738.9482736208414;749.8800191969259;760.4828716472852;763.560952452331;13296;13329.200247985153;13522.391492382669;13990.790138147935;14167.716645667235;716.4733920568378;726.0561270348526;730.1347902735275;733.2813842434444;734.988975165373;6410.391012853772;6728.966355168192;6742.5280230383405;6854.2117656469145;7025.121859767529;128976;131856;132199;132970.33514832365;134140.32929835288;117027.41486292685;119134;126069;128071.71928280784;128341.35829320793;98358;98573;99602;99668.50165749124;102285;13774.65563360924;14076.929615351857;14096.78854817158;14146.575602731935;14329.283535823375;48971.265431019274;49094.26358604695;49948.50051499438;50231.74884125556;50482.74758626183;65511.34488655243;65665.67167164324;66897.3310266891;67026.32973670201;67836.98244526237;18955.431337059912;19814.801851981294;20051.29820456277;20066.799332006492;20083.598328033066;20832.062557185265;21378.465538361666;21392.251271205434;21579.2447264345;22251.888740556195;20764.06561704757;22066.779332207116;22073.227437039626;22108.88945555262;22120.336389908327;17915.73126403079;18022.729659055385;18089.45731628053;18674.906625466785;20071.29750458727;19614.313499027467;20088.59822803565;20155.69766453475;20233.494162646053;20241.392758217276;14425.85574144287;14668.413263469341;14689.338979746151;14838.529232552075;15255.16937967451;15051.473198438001;15148.772768408704;15532.456364027204;15797.21013949275;15967.920160399588 03:57:45 INFO - PID 5092 | __end_tp_report 03:57:45 INFO - PID 5092 | __start_cc_report 03:57:45 INFO - PID 5092 | _x_x_mozilla_cycle_collect,351 03:57:45 INFO - PID 5092 | __end_cc_report 03:57:45 INFO - PID 5092 | __startTimestamp1504522665966__endTimestamp 03:57:45 INFO - PID 5092 | 03:57:45 INFO - PID 5092 | ------- Summary: start ------- 03:57:45 INFO - PID 5092 | Number of tests: 6 03:57:45 INFO - PID 5092 | 03:57:45 INFO - PID 5092 | [#0] dojo.html Cycles:165 Average:6803.49 Median:7049.33 stddev:739.03 (10.5%) stddev-sans-first:704.39 03:57:45 INFO - PID 5092 | Values: 3863.5 3863.7 3879.2 3909.4 3944.9 6796.8 6876.6 6879.8 6917.7 6922.8 6625.9 6736.6 6757.3 6765.3 6796.8 6083.6 6108.7 6136.0 6141.1 6149.3 6126.9 6163.0 6191.5 6234.9 6276.2 6165.0 6188.5 6205.1 6259.4 6270.2 6087.4 6159.8 6173.8 6189.5 6260.3 5873.9 6092.6 6096.4 6162.3 6205.9 6879.2 6898.2 6900.2 7006.1 7018.6 7256.7 7320.2 7349.7 7355.6 7479.3 7418.6 7482.4 7569.6 7595.1 7608.9 6929.1 6943.4 6996.8 7022.3 7029.9 7523.0 7652.1 7681.4 7757.0 7760.7 7211.2 7293.9 7300.9 7303.2 7310.8 7313.4 7386.9 7413.1 7423.1 7501.1 7236.1 7272.0 7296.2 7322.3 7330.7 7285.5 7296.8 7311.6 7362.1 7383.7 6203.3 6213.2 6246.4 6262.3 6308.4 5407.6 5497.9 5498.5 5552.5 5565.1 6299.9 6340.6 6359.0 6384.7 6385.3 7323.6 7326.0 7353.6 7357.9 7382.9 6352.3 6384.6 6392.2 6393.4 6395.2 6561.6 6636.1 6667.0 6668.9 6670.4 7194.9 7263.2 7280.3 7291.3 7308.1 7272.3 7391.5 7394.5 7483.7 7497.7 7076.9 7091.1 7128.1 7167.6 7181.8 7191.0 7257.9 7296.3 7297.6 7308.9 7413.0 7448.4 7527.2 7528.4 7551.8 6979.8 7057.0 7081.1 7178.0 7178.6 7078.9 7124.7 7128.0 7129.0 7132.1 6830.9 6836.3 6908.7 6931.6 7069.4 7185.2 7200.5 7202.4 7233.7 7244.7 6952.9 6984.4 7049.3 7049.9 7068.9 03:57:45 INFO - PID 5092 | 03:57:45 INFO - PID 5092 | [#1] ext.html Cycles:180 Average:40866.61 Median:20621.31 stddev:59963.91 (290.8%) stddev-sans-first:60056.97 03:57:45 INFO - PID 5092 | Values: 884.5 929.9 959.2 993.6 993.8 41245.4 41756.4 41793.8 41837.6 41842.2 39646.2 39980.6 39988.2 39989.4 40036.6 7297.9 7407.2 7408.3 7410.1 7416.7 7234.1 7275.7 7278.1 7312.2 7811.6 8085.8 8117.8 8125.1 8139.4 8146.3 7929.0 8037.9 8105.1 8105.9 8149.3 7879.3 7949.6 7954.7 7955.1 7956.6 38464.4 38591.2 38672.4 38713.4 38809.4 3698.5 3728.2 3729.9 3730.4 3731.8 32063.2 32119.4 32177.2 32189.5 32214.0 5439.7 5459.7 5470.0 5475.6 5548.8 204848.0 208377.0 209177.0 210456.0 219130.9 48897.3 49019.0 49330.0 49529.8 49600.8 53366.0 53372.0 53464.7 53489.2 53491.5 13972.8 14082.6 14108.4 14110.8 14159.4 11205.1 11207.8 11210.9 11216.9 11218.8 14298.2 14405.9 14426.9 14534.9 14633.0 5602.4 5654.5 5697.1 5713.5 5719.8 1081.7 1085.1 1088.9 1107.1 1108.1 25555.9 25644.9 25663.0 25764.6 25784.2 1179.3 1181.5 1188.0 1193.6 1197.2 13323.2 13444.9 13468.3 13655.3 13698.0 254012.0 259011.0 259298.0 260055.0 260864.0 55291.2 55617.7 55721.4 55730.7 55801.2 1462.9 1492.5 1502.4 1503.2 1513.1 25169.0 25561.6 25622.6 25752.0 25829.5 40643.4 40838.8 40892.2 40920.8 40930.8 216397.9 220855.0 221666.0 223009.0 223776.0 35125.8 36892.0 38429.2 38912.0 39049.4 48350.5 49026.3 49375.5 49969.0 49983.0 36448.0 36800.8 36811.4 37118.4 37510.8 36056.8 36226.0 36249.3 36652.6 37250.8 41991.8 43287.8 43535.0 43679.3 43721.6 35057.0 35333.5 35344.8 35385.6 36258.1 26645.7 27009.7 27301.6 27341.5 27583.9 03:57:45 INFO - PID 5092 | 03:57:45 INFO - PID 5092 | [#2] jquery.html Cycles:180 Average:43357.44 Median:13741.81 stddev:117547.36 (855.4%) stddev-sans-first:117833.21 03:57:45 INFO - PID 5092 | Values: 572.8 588.9 597.2 605.9 615.1 22653.1 23112.9 23316.3 23397.9 23410.6 26357.0 26868.9 27058.9 27064.2 27086.1 6644.3 6766.6 6767.4 6788.9 6793.0 5833.2 6366.6 6374.6 6376.3 6782.0 6323.7 6327.6 6348.6 6348.7 6349.6 6314.2 6347.8 6363.4 6365.0 6365.3 5944.9 5980.6 5986.1 5995.5 6001.0 29562.0 29727.6 30596.4 31098.7 31236.3 2250.7 2273.6 2273.7 2273.8 2273.9 20739.2 21372.6 21401.6 21440.6 21463.0 2449.8 2488.4 2554.0 2591.1 2601.4 150579.0 156143.0 156321.0 156402.0 156546.0 15743.7 16205.9 16703.6 16746.7 16984.5 26313.7 26329.3 26329.9 26375.0 26392.9 9750.9 9776.7 9878.8 9995.2 10052.3 6677.6 8656.4 8670.3 8682.3 8773.5 8911.0 9993.6 10045.0 10065.5 10163.3 2929.4 2942.3 2985.7 2994.7 3002.9 1056.9 1060.1 1072.1 1075.5 1082.7 26151.0 26558.5 27455.0 27554.2 27589.3 1111.8 1112.9 1116.4 1128.6 1133.3 12002.4 12299.5 12383.5 12540.2 12549.7 688673.0 693142.0 694458.0 724840.0 734918.0 132475.3 134131.3 135197.0 135367.3 135754.3 1293.3 1319.5 1341.2 1345.4 1348.4 19464.9 19988.3 20075.0 20085.0 20642.4 65027.0 65257.3 66799.3 66869.7 67787.3 82519.0 83669.6 84135.6 84634.2 85694.6 15971.9 16305.8 16384.9 16388.5 16436.9 24995.3 25180.1 25181.6 25508.4 25637.1 20174.4 20431.9 20505.0 20619.4 20631.9 18049.1 18086.4 18482.4 18511.4 18883.8 20653.6 20779.0 20972.9 21015.0 21279.1 16027.4 16061.8 16106.8 16110.8 16242.0 19958.8 20036.3 20080.5 20217.8 20251.6 03:57:45 INFO - PID 5092 | 03:57:45 INFO - PID 5092 | [#3] mootools.html Cycles:180 Average:10285.88 Median:2802.59 stddev:7721.17 (275.5%) stddev-sans-first:7714.29 03:57:45 INFO - PID 5092 | Values: 1448.8 1506.9 1512.6 1528.1 1534.5 16421.8 16571.1 17166.1 17176.9 17198.8 17334.4 17520.6 17533.7 17549.2 17553.3 2895.1 2934.8 2937.4 2938.1 2939.3 2790.2 2827.6 2838.0 2902.9 2903.7 2835.3 2838.4 2860.5 2863.5 2875.3 2800.2 2808.0 2818.1 2820.7 2825.1 2767.9 2780.7 2797.0 2802.3 2802.9 16553.8 16778.1 16802.4 16870.9 16888.4 8504.1 8747.0 8751.1 8752.8 8759.4 5297.4 5590.9 5637.7 5663.3 5735.5 1946.1 1967.9 1981.0 1995.2 2007.8 30270.2 30400.0 30440.4 30485.0 30501.7 7253.2 7361.2 7404.2 7474.1 7503.5 19066.8 19088.1 19103.5 19114.9 19127.0 8367.3 8515.7 8519.6 8523.5 8544.9 7702.0 7819.8 7851.7 7874.5 7951.6 5071.1 5270.9 5485.5 5495.8 5526.4 1509.0 1583.2 1601.7 1614.5 1619.7 854.1 857.1 864.7 876.5 878.2 8186.6 8203.1 8293.0 8469.9 8489.3 1009.4 1019.8 1020.6 1020.8 1022.1 4799.3 5514.9 5552.3 5704.0 5801.7 24151.4 24491.5 25117.6 25168.7 25273.7 23805.0 24375.9 24447.8 24449.6 25014.4 12642.2 12642.9 12990.0 13009.7 13053.9 8334.2 8673.7 8804.9 8904.8 8995.3 20547.4 20628.6 21155.6 21513.6 21732.2 22019.3 22065.0 22177.0 22488.3 23131.8 13107.8 13437.8 13441.3 13445.1 13453.8 15442.8 15495.6 15555.4 15625.5 15698.5 12309.1 12324.1 12423.4 12624.7 12694.7 11176.2 11290.7 11310.8 11438.4 11580.6 9235.5 9420.0 9420.7 9426.1 9427.0 7178.2 7184.7 7207.2 7235.4 7287.5 7429.4 7436.5 7439.6 7442.7 7534.7 03:57:45 INFO - PID 5092 | 03:57:45 INFO - PID 5092 | [#4] prototype.html Cycles:180 Average:6001.95 Median:6662.31 stddev:1598.44 (24.0%) stddev-sans-first:1549.39 03:57:45 INFO - PID 5092 | Values: 536.3 550.0 570.6 571.8 574.6 6673.6 6727.7 6770.7 6778.2 6800.6 6581.4 6651.0 6693.2 6719.7 6720.1 5440.3 5478.7 5480.5 5486.2 5509.5 5204.1 5292.6 5366.2 5388.8 5392.2 5353.9 5369.7 5387.8 5404.9 5439.4 5363.8 5366.4 5423.8 5452.8 5492.7 5080.0 5083.4 5096.4 5137.5 5147.6 6441.5 6474.8 6511.3 6555.0 6688.9 6395.1 6429.5 6434.0 6439.0 6451.5 7104.8 7121.9 7131.0 7155.0 7176.5 5542.8 5648.4 5653.7 5674.9 5693.9 7108.2 7234.0 7243.9 7437.7 7442.6 5857.6 5858.8 5901.7 5943.4 5952.4 5920.8 5953.5 6034.5 6053.9 6083.7 6332.7 6467.5 6500.5 6503.2 6532.3 7089.9 7130.8 7160.2 7162.0 7164.7 2072.8 2092.9 2121.4 2133.4 2135.1 1476.4 1532.9 1534.8 1541.8 1562.9 6013.3 6017.6 6027.8 6197.3 6238.7 6965.6 6984.2 7014.5 7062.0 7168.4 6043.9 6180.4 6204.6 6207.3 6230.7 3817.8 3839.4 3842.7 3851.3 3854.1 7050.3 7057.9 7068.8 7080.5 7088.5 7401.8 7430.7 7468.2 7482.4 7506.2 6862.3 7012.0 7024.9 7088.5 7090.5 7058.7 7074.8 7111.5 7139.4 7180.7 6997.6 7149.8 7152.4 7190.0 7196.8 6848.6 6938.9 6947.6 7002.5 7020.2 5888.9 5901.4 5907.3 5944.6 5949.3 6888.3 6980.5 7013.8 7066.4 7247.7 6831.0 6839.8 6842.9 6901.8 6902.9 6834.4 6836.8 6841.2 6903.2 6932.8 6814.4 6821.6 6829.9 6862.7 6909.7 6871.5 6933.3 6966.0 6970.2 6998.7 6727.7 6738.3 6759.6 6811.0 6839.8 03:57:45 INFO - PID 5092 | 03:57:45 INFO - PID 5092 | [#5] yui.html Cycles:180 Average:26585.77 Median:19714.56 stddev:37790.55 (191.7%) stddev-sans-first:37847.55 03:57:45 INFO - PID 5092 | Values: 952.8 961.9 988.6 992.5 1013.4 17077.9 17595.7 17596.6 17703.5 19318.9 18713.7 18740.5 18746.3 18751.2 18794.4 761.4 1019.3 1055.1 1055.9 1056.8 963.2 1042.6 1057.9 1058.3 1062.2 1129.5 1131.3 1131.3 1135.4 1135.6 1120.8 1124.5 1124.7 1124.7 1125.5 828.9 831.7 832.7 832.8 833.0 16640.3 16827.9 17792.3 17986.2 18024.0 22277.2 22455.7 22557.2 22636.7 22646.7 17107.4 17198.9 17268.7 17368.2 17475.0 3913.7 3929.5 3933.3 3943.4 3949.8 142560.0 142846.3 142906.0 144313.0 147129.0 16280.8 16344.7 16842.6 16844.7 16845.2 31432.2 32324.0 32529.0 32605.5 32638.0 8888.4 9123.6 9127.7 9178.1 9225.5 4195.6 4385.8 4622.9 4684.4 4776.3 5622.2 5703.7 5704.8 5713.4 5734.0 1183.9 1198.7 1202.3 1215.2 1226.2 724.8 738.9 749.9 760.5 763.6 13296.0 13329.2 13522.4 13990.8 14167.7 716.5 726.1 730.1 733.3 735.0 6410.4 6729.0 6742.5 6854.2 7025.1 128976.0 131856.0 132199.0 132970.3 134140.3 117027.4 119134.0 126069.0 128071.7 128341.4 98358.0 98573.0 99602.0 99668.5 102285.0 13774.7 14076.9 14096.8 14146.6 14329.3 48971.3 49094.3 49948.5 50231.7 50482.7 65511.3 65665.7 66897.3 67026.3 67837.0 18955.4 19814.8 20051.3 20066.8 20083.6 20832.1 21378.5 21392.3 21579.2 22251.9 20764.1 22066.8 22073.2 22108.9 22120.3 17915.7 18022.7 18089.5 18674.9 20071.3 19614.3 20088.6 20155.7 20233.5 20241.4 14425.9 14668.4 14689.3 14838.5 15255.2 15051.5 15148.8 15532.5 15797.2 15967.9 03:57:45 INFO - PID 5092 | -------- Summary: end -------- 03:57:45 INFO - PID 5092 | 03:57:46 INFO - TEST-INFO | 5092: exit 0 03:57:46 INFO - TEST-OK | dromaeo_css | took 1110013ms 03:57:46 INFO - TEST-START | kraken 03:57:46 INFO - Initialising browser for kraken test... 03:57:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58827/getInfo.html -profile /tmp/tmpPMc_gk/profile 03:57:46 INFO - TEST-INFO | started process 5466 (/builds/slave/test/build/application/firefox/firefox http://localhost:58827/getInfo.html) 03:57:52 INFO - TEST-INFO | 5466: exit 0 03:57:52 INFO - Browser initialized. 03:57:52 INFO - Running cycle 1/1 for kraken test... 03:57:52 INFO - Using env: {'DISPLAY': ':0', 03:57:52 INFO - 'HOME': '/home/cltbld', 03:57:52 INFO - 'JSGC_DISABLE_POISONING': '1', 03:57:52 INFO - 'LANG': 'en_US.UTF-8', 03:57:52 INFO - 'LANGUAGE': 'en_US:en', 03:57:52 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:57:52 INFO - 'LOGNAME': 'cltbld', 03:57:52 INFO - 'MAIL': '/var/mail/cltbld', 03:57:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:57:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:57:52 INFO - 'MOZ_CRASHREPORTER': '1', 03:57:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:52 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:57:52 INFO - 'MOZ_NO_REMOTE': '1', 03:57:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:57:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:57:52 INFO - 'NO_EM_RESTART': '1', 03:57:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:57:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:52 INFO - 'PWD': '/builds/slave/test', 03:57:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:57:52 INFO - 'RUST_BACKTRACE': 'full', 03:57:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:57:52 INFO - 'SHELL': '/bin/bash', 03:57:52 INFO - 'SHLVL': '1', 03:57:52 INFO - 'TERM': 'linux', 03:57:52 INFO - 'TMOUT': '86400', 03:57:52 INFO - 'USER': 'cltbld', 03:57:52 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632', 03:57:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:52 INFO - '_': '/tools/buildbot/bin/python'} 03:57:52 INFO - TEST-INFO | started process 5651 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPMc_gk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:57:53 INFO - PID 5651 | 03:57:53 INFO - PID 5651 | (/builds/slave/test/build/application/firefox/firefox:5704): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:57:53 INFO - PID 5651 | 03:58:03 INFO - PID 5651 | RSS: Main: 157859840 03:58:03 INFO - PID 5651 | 03:58:05 INFO - PID 5651 | Cycle 1(1): loaded http://localhost:58827/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58827/tests/kraken/driver.html?testName=audio-beat-detection) 03:58:06 INFO - PID 5651 | RSS: Main: 162308096 03:58:06 INFO - PID 5651 | 03:58:09 INFO - PID 5651 | Cycle 1(1): loaded http://localhost:58827/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58827/tests/kraken/driver.html?testName=audio-dft) 03:58:10 INFO - PID 5651 | RSS: Main: 166948864 03:58:10 INFO - PID 5651 | 03:58:12 INFO - PID 5651 | Cycle 1(1): loaded http://localhost:58827/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58827/tests/kraken/driver.html?testName=audio-fft) 03:58:13 INFO - PID 5651 | RSS: Main: 160481280 03:58:13 INFO - PID 5651 | 03:58:15 INFO - PID 5651 | Cycle 1(1): loaded http://localhost:58827/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58827/tests/kraken/driver.html?testName=audio-oscillator) 03:58:16 INFO - PID 5651 | RSS: Main: 161230848 03:58:16 INFO - PID 5651 | 03:58:18 INFO - PID 5651 | Cycle 1(1): loaded http://localhost:58827/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58827/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:58:18 INFO - PID 5651 | RSS: Main: 161669120 03:58:18 INFO - PID 5651 | 03:58:21 INFO - PID 5651 | Cycle 1(1): loaded http://localhost:58827/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58827/tests/kraken/driver.html?testName=imaging-darkroom) 03:58:22 INFO - PID 5651 | RSS: Main: 161124352 03:58:22 INFO - PID 5651 | 03:58:25 INFO - PID 5651 | Cycle 1(1): loaded http://localhost:58827/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58827/tests/kraken/driver.html?testName=imaging-desaturate) 03:58:26 INFO - PID 5651 | RSS: Main: 162893824 03:58:26 INFO - PID 5651 | 03:58:29 INFO - PID 5651 | Cycle 1(1): loaded http://localhost:58827/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58827/tests/kraken/driver.html?testName=json-parse-financial) 03:58:30 INFO - PID 5651 | RSS: Main: 162889728 03:58:30 INFO - PID 5651 | 03:58:31 INFO - PID 5651 | Cycle 1(1): loaded http://localhost:58827/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58827/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:58:32 INFO - PID 5651 | RSS: Main: 162271232 03:58:32 INFO - PID 5651 | 03:58:33 INFO - PID 5651 | Cycle 1(1): loaded http://localhost:58827/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58827/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:58:34 INFO - PID 5651 | RSS: Main: 162627584 03:58:34 INFO - PID 5651 | 03:58:36 INFO - PID 5651 | Cycle 1(1): loaded http://localhost:58827/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58827/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:58:36 INFO - PID 5651 | RSS: Main: 162553856 03:58:36 INFO - PID 5651 | 03:58:39 INFO - PID 5651 | Cycle 1(1): loaded http://localhost:58827/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58827/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:58:40 INFO - PID 5651 | RSS: Main: 162582528 03:58:40 INFO - PID 5651 | 03:58:42 INFO - PID 5651 | Cycle 1(1): loaded http://localhost:58827/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58827/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:58:43 INFO - PID 5651 | RSS: Main: 162762752 03:58:43 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | Cycle 1(1): loaded http://localhost:58827/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58827/tests/kraken/driver.html?testName=ai-astar) 03:58:45 INFO - PID 5651 | RSS: Main: 164831232 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | __start_tp_report 03:58:45 INFO - PID 5651 | _x_x_mozilla_page_load 03:58:45 INFO - PID 5651 | _x_x_mozilla_page_load_details 03:58:45 INFO - PID 5651 | |i|pagename|runs| 03:58:45 INFO - PID 5651 | |0;ai-astar;97;107;98;106;107;106;107;99;105;108 03:58:45 INFO - PID 5651 | |1;audio-beat-detection;142;117;154;171;116;138;139;113;171;117 03:58:45 INFO - PID 5651 | |2;audio-dft;164;162;164;163;161;162;162;162;161;162 03:58:45 INFO - PID 5651 | |3;audio-fft;74;77;74;183;75;73;73;72;181;73 03:58:45 INFO - PID 5651 | |4;audio-oscillator;78;77;127;79;78;77;120;78;78;132 03:58:45 INFO - PID 5651 | |5;imaging-gaussian-blur;114;113;114;114;114;114;117;114;114;113 03:58:45 INFO - PID 5651 | |6;imaging-darkroom;151;149;150;150;150;149;151;150;151;150 03:58:45 INFO - PID 5651 | |7;imaging-desaturate;94;94;94;94;94;95;94;95;96;94 03:58:45 INFO - PID 5651 | |8;json-parse-financial;50;45;45;47;44;46;44;49;44;46 03:58:45 INFO - PID 5651 | |9;json-stringify-tinderbox;40;41;40;39;40;39;39;41;40;41 03:58:45 INFO - PID 5651 | |10;stanford-crypto-aes;71;73;71;77;71;75;72;72;73;71 03:58:45 INFO - PID 5651 | |11;stanford-crypto-ccm;109;109;112;108;110;114;116;124;114;151 03:58:45 INFO - PID 5651 | |12;stanford-crypto-pbkdf2;159;161;172;151;158;155;157;164;162;153 03:58:45 INFO - PID 5651 | |13;stanford-crypto-sha256-iterative;63;60;60;62;61;59;60;59;59;58 03:58:45 INFO - PID 5651 | __end_tp_report 03:58:45 INFO - PID 5651 | __start_cc_report 03:58:45 INFO - PID 5651 | _x_x_mozilla_cycle_collect,908 03:58:45 INFO - PID 5651 | __end_cc_report 03:58:45 INFO - PID 5651 | __startTimestamp1504522725462__endTimestamp 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | ------- Summary: start ------- 03:58:45 INFO - PID 5651 | Number of tests: 14 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | [#0] ai-astar Cycles:10 Average:104.00 Median:107.50 stddev:4.24 (3.9%) stddev-sans-first:3.67 03:58:45 INFO - PID 5651 | Values: 97.0 107.0 98.0 106.0 107.0 106.0 107.0 99.0 105.0 108.0 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:140.50 stddev:22.22 (15.8%) stddev-sans-first:23.51 03:58:45 INFO - PID 5651 | Values: 142.0 117.0 154.0 171.0 116.0 138.0 139.0 113.0 171.0 117.0 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.00 stddev:1.06 (0.7%) stddev-sans-first:0.93 03:58:45 INFO - PID 5651 | Values: 164.0 162.0 164.0 163.0 161.0 162.0 162.0 162.0 161.0 162.0 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | [#3] audio-fft Cycles:10 Average:95.50 Median:73.50 stddev:45.61 (62.1%) stddev-sans-first:47.71 03:58:45 INFO - PID 5651 | Values: 74.0 77.0 74.0 183.0 75.0 73.0 73.0 72.0 181.0 73.0 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | [#4] audio-oscillator Cycles:10 Average:92.40 Median:78.00 stddev:23.59 (30.2%) stddev-sans-first:24.44 03:58:45 INFO - PID 5651 | Values: 78.0 77.0 127.0 79.0 78.0 77.0 120.0 78.0 78.0 132.0 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:1.10 (1.0%) stddev-sans-first:1.17 03:58:45 INFO - PID 5651 | Values: 114.0 113.0 114.0 114.0 114.0 114.0 117.0 114.0 114.0 113.0 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | [#6] imaging-darkroom Cycles:10 Average:150.10 Median:150.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 03:58:45 INFO - PID 5651 | Values: 151.0 149.0 150.0 150.0 150.0 149.0 151.0 150.0 151.0 150.0 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 03:58:45 INFO - PID 5651 | Values: 94.0 94.0 94.0 94.0 94.0 95.0 94.0 95.0 96.0 94.0 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | [#8] json-parse-financial Cycles:10 Average:46.00 Median:46.00 stddev:2.11 (4.6%) stddev-sans-first:1.67 03:58:45 INFO - PID 5651 | Values: 50.0 45.0 45.0 47.0 44.0 46.0 44.0 49.0 44.0 46.0 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | [#9] json-stringify-tinderbox Cycles:10 Average:40.00 Median:40.00 stddev:0.82 (2.0%) stddev-sans-first:0.87 03:58:45 INFO - PID 5651 | Values: 40.0 41.0 40.0 39.0 40.0 39.0 39.0 41.0 40.0 41.0 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | [#10] stanford-crypto-aes Cycles:10 Average:72.60 Median:72.50 stddev:2.01 (2.8%) stddev-sans-first:2.05 03:58:45 INFO - PID 5651 | Values: 71.0 73.0 71.0 77.0 71.0 75.0 72.0 72.0 73.0 71.0 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | [#11] stanford-crypto-ccm Cycles:10 Average:116.70 Median:114.00 stddev:12.94 (11.3%) stddev-sans-first:13.42 03:58:45 INFO - PID 5651 | Values: 109.0 109.0 112.0 108.0 110.0 114.0 116.0 124.0 114.0 151.0 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.20 Median:160.00 stddev:6.03 (3.8%) stddev-sans-first:6.40 03:58:45 INFO - PID 5651 | Values: 159.0 161.0 172.0 151.0 158.0 155.0 157.0 164.0 162.0 153.0 03:58:45 INFO - PID 5651 | 03:58:45 INFO - PID 5651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.10 Median:60.00 stddev:1.52 (2.5%) stddev-sans-first:1.20 03:58:45 INFO - PID 5651 | Values: 63.0 60.0 60.0 62.0 61.0 59.0 60.0 59.0 59.0 58.0 03:58:45 INFO - PID 5651 | -------- Summary: end -------- 03:58:45 INFO - PID 5651 | 03:58:46 INFO - PID 5651 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:58:46 INFO - TEST-INFO | 5651: exit 0 03:58:46 INFO - TEST-OK | kraken | took 59702ms 03:58:46 INFO - SUITE-END | took 1169s 03:58:46 INFO - Completed test suite (00:19:29) 03:58:46 INFO - javascript benchmark 03:58:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9249.928442042463, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3863.4784304118944, 3863.7488563243405, 3879.2241551689663, 3909.394043923191, 3944.9348675857536, 6796.796096117112, 6876.621785801779, 6879.828004299895, 6917.654117294141, 6922.826929326763, 6625.900611490832, 6736.562123461971, 6757.32426757324, 6765.323467653232, 6796.796096117112, 6083.634981901078, 6108.664023478706, 6136.0, 6141.140240366352, 6149.3235744068115, 6126.877462450749, 6162.952298109332, 6191.473724733403, 6234.906476402857, 6276.1840960675045, 6164.96917515414, 6188.504919606421, 6205.131281620555, 6259.405356491126, 6270.153529273535, 6087.360827113178, 6159.846003849895, 6173.753049878, 6189.473894718954, 6260.280067792194, 5873.85315367115, 6092.634441933498, 6096.359882212349, 6162.291336496271, 6205.906911396332, 6879.2432832388395, 6898.206706228748, 6900.171979362509, 7006.124234470692, 7018.613976231305, 7256.6734496947765, 7320.158181809107, 7349.742759003409, 7355.595442250674, 7479.289467500579, 7418.591977441239, 7482.363999060087, 7569.583672897988, 7595.126560445564, 7608.885866712004, 6929.133858267716, 6943.375096241312, 6996.790096297119, 7022.332878376648, 7029.859402811966, 7523.022007139037, 7652.081750189902, 7681.3854891608535, 7756.961215193888, 7760.728374506978, 7211.242819503981, 7293.8905916411295, 7300.926990730131, 7303.233160518175, 7310.817229569197, 7313.414926805843, 7386.852262954711, 7413.1104267488245, 7423.072115985502, 7501.062367204099, 7236.095488063992, 7271.963640181765, 7296.233895440902, 7322.267773222635, 7330.7067717291775, 7285.453590980697, 7296.8175795604475, 7311.561306321638, 7362.079740032496, 7383.741569045165, 6203.348648391944, 6213.161223234896, 6246.3753624637175, 6262.311145774052, 6308.369163083655, 5407.594430417735, 5497.890042199134, 5498.532624726943, 5552.472515111058, 5565.1374037024325, 6299.937000629935, 6340.587861788969, 6358.968205159037, 6384.7126879290545, 6385.329540398191, 7323.597202153933, 7325.963370183115, 7353.595552244571, 7357.889631655529, 7382.889256661155, 6352.301246862841, 6384.553081284265, 6392.232932048184, 6393.360663933569, 6395.20059992501, 6561.639109849004, 6636.137302150786, 6667.0, 6668.866622667617, 6670.433013193836, 7194.892076618855, 7263.237360077222, 7280.344768970712, 7291.30732580404, 7308.123025237006, 7272.345488906024, 7391.482596218213, 7394.519356241827, 7483.70065197386, 7497.662605182671, 7076.893846592202, 7091.149062112373, 7128.108986376703, 7167.641617919187, 7181.82045448847, 7191.0292110564405, 7257.927420725725, 7296.270372962661, 7297.63511824396, 7308.8538229234055, 7412.999245102058, 7448.404127669665, 7527.247275272429, 7528.435367347581, 7551.848963020599, 6979.755708550177, 7057.0, 7081.114860642419, 7178.030965819757, 7178.60517671533, 7078.929210707827, 7124.679389427384, 7127.964360178166, 7128.964355178191, 7132.072830531998, 6830.93169068303, 6836.316368363124, 6908.689108990007, 6931.618760968196, 7069.434445244266, 7185.245549217466, 7200.53196542244, 7202.423806095605, 7233.710651573878, 7244.673989670583, 6952.930470695431, 6984.441244700311, 7049.330313620198, 7049.929500705132, 7068.9293107070325], "value": 6755.588454021844, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [884.5311984648134, 929.9023602521735, 959.1942768074816, 993.6025589764098, 993.7863359377737, 41245.3813192802, 41756.37365439521, 41793.79103104488, 41837.581624183746, 41842.16315673688, 39646.20707585847, 39980.60019399805, 39988.20023599533, 39989.40015899764, 40036.59963400372, 7297.927020729804, 7407.185209626937, 7408.296211859892, 7410.147832999221, 7416.740414085508, 7234.059572255599, 7275.708971641128, 7278.09023872016, 7312.159101703292, 7811.570363629998, 8085.757427277162, 8117.837643247131, 8125.146859579748, 8139.430239883211, 8146.26683598476, 7928.9603551982445, 8037.879431808527, 8105.1084380718075, 8105.918940810605, 8149.307308878752, 7879.251471110236, 7949.562774047425, 7954.681812727484, 7955.085165206017, 7956.602169891482, 38464.423033654515, 38591.228175436336, 38672.41991370104, 38713.41929871054, 38809.417858732144, 3698.4637227601847, 3728.1984373359587, 3729.9067523311865, 3730.4404339349044, 3731.8134093295225, 32063.19842003945, 32119.357612847612, 32177.1955701107, 32189.51715724266, 32214.0, 5439.728013599344, 5459.672419654833, 5469.972650136724, 5475.561955043627, 5548.805791797308, 204848.0, 208377.0, 209177.0, 210456.0, 219130.90434547883, 48897.26654100191, 49019.0, 49330.013399732525, 49529.752351238014, 49600.751996239786, 53366.0, 53372.0, 53464.73267633637, 53489.1976620351, 53491.465085349424, 13972.790408143886, 14082.577522674335, 14108.435662573382, 14110.788338174936, 14159.362828672734, 11205.103591712643, 11207.775844483065, 11210.943945280385, 11216.887831121585, 11218.831717524243, 14298.213598251989, 14405.85594144066, 14426.85573144255, 14534.854651453561, 14633.0, 5602.38373778884, 5654.46282603152, 5697.088465845445, 5713.542916566665, 5719.7712091516705, 1081.7025318037445, 1085.1427372375892, 1088.9401082940356, 1107.1253709569512, 1108.0747575774158, 25555.87222063878, 25644.871775641, 25663.0, 25764.613530797054, 25784.226473205836, 1179.3454632678945, 1181.4624345922584, 1187.9643610691694, 1193.6359410379916, 1197.1739499745147, 13323.249762422476, 13444.865551344361, 13468.259245741578, 13655.317234138254, 13698.0, 254012.0, 259011.0, 259298.0, 260055.0, 260864.0, 55291.170632440546, 55617.72191139018, 55721.44278557163, 55730.72134639301, 55801.162982555295, 1462.8683418492174, 1492.5223928342825, 1502.4440956846038, 1503.180766482248, 1513.1450730337324, 25169.0, 25561.616575751752, 25622.615660765478, 25752.0, 25829.483410332065, 40643.390349144196, 40838.79580602078, 40892.1821563573, 40920.795396022826, 40930.79534602308, 216397.91801040893, 220855.0, 221666.0, 223009.0, 223776.0, 35125.824370877985, 36892.0, 38429.231415372094, 38912.0, 39049.414258785575, 48350.5164948346, 49026.26460603022, 49375.50624493709, 49969.0, 49983.00033999373, 36448.0, 36800.815995919846, 36811.44782828313, 37118.44322335221, 37510.8124459376, 36056.819715901256, 36226.0, 36249.27501450009, 36652.63347366492, 37250.8137459311, 41991.7900410496, 43287.783561082, 43535.0, 43679.344809828515, 43721.56278437175, 35057.0, 35333.46999795057, 35344.82327588346, 35385.64614353824, 36258.09354766152, 26645.733542664326, 27009.729902701507, 27301.590476142475, 27341.453170936868, 27583.86208068947], "value": 18109.55707254138, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.7565784541569, 588.9057750759877, 597.2295738497338, 605.9214598015534, 615.1326629452471, 22653.09387624495, 23112.884435577842, 23316.300510984656, 23397.883010584967, 23410.64884026739, 26357.0, 26868.865655671743, 27058.864705676493, 27064.188074357753, 27086.051988180418, 6644.335566443353, 6766.627835469048, 6767.42476889465, 6788.864222715542, 6793.0, 5833.154192642064, 6366.554341196118, 6374.553781235315, 6376.2667293261175, 6781.966090169542, 6323.715432805512, 6327.5570710050315, 6348.619082855021, 6348.714307856135, 6349.555531112801, 6314.210723659543, 6347.777827776029, 6363.363663633646, 6365.0, 6365.2998170201245, 5944.851378715523, 5980.581359304851, 5986.07215881539, 5995.490383317423, 6001.039833626643, 29562.0, 29727.554086688717, 30596.38807223843, 31098.689013109804, 31236.282004489745, 2250.7411647660565, 2273.59075366434, 2273.7498875123724, 2273.818094552432, 2273.9204127855446, 20739.170433182655, 21372.5725485491, 21401.571968560696, 21440.571188576298, 21463.0, 2449.8162637802056, 2488.365466805953, 2553.961690574643, 2591.1449221756775, 2601.3626661467874, 150579.0, 156143.0, 156321.0, 156402.0, 156546.0, 15743.68512629741, 16205.918970405073, 16703.582410439594, 16746.665066698843, 16984.49046528606, 26313.73686263151, 26329.341766455993, 26329.868350658126, 26375.0, 26392.8680356597, 9750.902490974999, 9776.657816976514, 9878.753031174278, 9995.151381856136, 10052.296339256181, 6677.565958212701, 8656.394052416397, 8670.349723770742, 8682.348823838238, 8773.517456539952, 8910.955445222733, 9993.550290236957, 10045.0, 10065.496725163712, 10163.339382940085, 2929.384829185895, 2942.3379739558427, 2985.7462115719973, 2994.7454466370605, 3002.879884804627, 1056.8678915135608, 1060.1465820014807, 1072.104792498257, 1075.510642657589, 1082.6860210539014, 26151.0, 26558.539280339766, 27455.0, 27554.17337479879, 27589.31026724348, 1111.7998760223238, 1112.855328807266, 1116.4361997191365, 1128.5937062657438, 1133.348324713293, 12002.399880005965, 12299.508019679113, 12383.504659813687, 12540.184887982252, 12549.74900502003, 688673.0, 693142.0, 694458.0, 724840.0, 734918.0, 132475.33762331126, 134131.32934335267, 135197.0, 135367.32316338355, 135754.32122839324, 1293.275765571283, 1319.4722111155615, 1341.155072304442, 1345.4056942522946, 1348.4201793229006, 19464.902675486534, 19988.30040948618, 20075.0, 20085.0, 20642.380728578166, 65027.0, 65257.34742652513, 66799.33200667931, 66869.66565167143, 67787.3221267781, 82519.0, 83669.58165209135, 84135.579322103, 84634.15365846263, 85694.57152714196, 15971.920140399223, 16305.755413668567, 16384.918075409547, 16388.5083447492, 16436.917815410845, 24995.250142495755, 25180.11869584556, 25181.62227566551, 25508.362290942874, 25637.10270140536, 20174.394768156977, 20431.8978405107, 20505.0, 20619.381418557467, 20631.896840516303, 18049.09754512243, 18086.366977156264, 18482.353117640818, 18511.444656660322, 18883.811161888756, 20653.58692826105, 20779.0, 20972.895135524224, 21015.0, 21279.148834046464, 16027.439039634024, 16061.839381606034, 16106.758398623795, 16110.838891610934, 16242.0, 19958.800411995693, 20036.298729544396, 20080.497987549843, 20217.797822021592, 20251.59496810026], "value": 13033.449992337126, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.8188976377953, 1506.8719158871495, 1512.5916002679278, 1528.0755143138401, 1534.4859472076857, 16421.753673694904, 16571.08859012754, 17166.141692915367, 17176.914115429438, 17198.828011719896, 17334.393296234604, 17520.561985950357, 17533.736993945102, 17549.21028553715, 17553.29786808526, 2895.1314605618336, 2934.838583877886, 2937.397833444139, 2938.1038783171125, 2939.265183704074, 2790.1629511146675, 2827.5899994500787, 2837.9716202837913, 2902.8983985560403, 2903.7241462061174, 2835.31952331441, 2838.3755573773738, 2860.4994126027964, 2863.513202755526, 2875.309925617847, 2800.201942446393, 2807.9578806317922, 2818.070036887822, 2820.675622303441, 2825.0959692898177, 2767.9169624911283, 2780.6802217745017, 2796.9791026275466, 2802.327441414046, 2802.8598570071413, 16553.83446165535, 16778.077205753678, 16802.41191558302, 16870.915645421694, 16888.408905688364, 8504.149585041447, 8747.0, 8751.124887511261, 8752.824943501095, 8759.386842920998, 5297.417284098746, 5590.91613625796, 5637.661740295554, 5663.348714897757, 5735.541156707495, 1946.1242440901651, 1967.8917659528863, 1980.9504762380911, 1995.1720036184965, 2007.8192962633434, 30270.24324391886, 30400.0, 30440.391192176034, 30485.0, 30501.694983050107, 7253.165885923081, 7361.153467351216, 7404.222556631476, 7474.103107627011, 7503.5497870127965, 19066.80933190678, 19088.141033653243, 19103.52241193981, 19114.904425477784, 19127.0, 8367.288780453608, 8515.659373625109, 8519.57402129891, 8523.488590684456, 8544.871826922601, 7702.037245344332, 7819.765407037798, 7851.685932562747, 7874.527528348317, 7951.642176102089, 5071.123684236856, 5270.866763645758, 5485.533729632946, 5495.807646732425, 5526.392096869341, 1508.9622759431102, 1583.247957220311, 1601.6716573102485, 1614.4833653230853, 1619.6841615885025, 854.0690647194588, 857.1043259793531, 864.7492227254025, 876.4784952952983, 878.2315473960285, 8186.631601577943, 8203.138670439637, 8292.958535207285, 8469.872951905727, 8489.320854331638, 1009.4498498318287, 1019.7603563162651, 1020.637673625867, 1020.7907378987438, 1022.1311884897926, 4799.256115302133, 5514.889702206014, 5552.250446189793, 5704.0, 5801.7099145042575, 24151.396215094763, 24491.510169796504, 25117.62323565148, 25168.748312517007, 25273.74726252714, 23805.0, 24375.878120609283, 24447.75552244455, 24449.633255501183, 25014.37464063413, 12642.241465511916, 12642.873571264354, 12990.0, 13009.674758131123, 13053.934730326288, 8334.249367936105, 8673.739787806377, 8804.911950880665, 8904.777380565538, 8995.325350598861, 20547.38357849267, 20628.587428251652, 21155.62488438308, 21513.569728605653, 21732.239371621916, 22019.339419817432, 22065.0, 22177.0, 22488.32535023952, 23131.768682313636, 13107.803382949454, 13437.798433023709, 13441.260730659907, 13445.126066805626, 13453.798193026916, 15442.84557154459, 15495.612609684847, 15555.377784888931, 15625.531234062997, 15698.450554230547, 12309.076819238413, 12324.075694322783, 12423.440945157308, 12624.684382890502, 12694.746105077662, 11176.217664763388, 11290.661280161608, 11310.773784524099, 11438.370889601152, 11580.594679186188, 9235.538223088954, 9419.952900235456, 9420.670276540288, 9426.104520070583, 9427.010163932688, 7178.174509931425, 7184.712611495482, 7207.171175314906, 7235.421166306577, 7287.489875708649, 7429.368503677248, 7436.475522517762, 7439.628018599156, 7442.7395041173295, 7534.736284230025], "value": 7172.437325490957, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [536.2519285596593, 549.9742979343525, 570.6119838509813, 571.8198767388275, 574.5977815529128, 6673.632950187738, 6727.697253623586, 6770.729170833167, 6778.152730908637, 6800.557963732366, 6581.440577550902, 6650.978511224019, 6693.247794066218, 6719.697613607387, 6720.092787473702, 5440.292761940952, 5478.726063696819, 5480.506754392104, 5486.231927530149, 5509.504144626982, 5204.063268611649, 5292.603054770889, 5366.221897824813, 5388.757505912224, 5392.245085687987, 5353.9196912046355, 5369.677819330833, 5387.811426600069, 5404.891902161954, 5439.428859969706, 5363.846772943797, 5366.436524164965, 5423.755930983115, 5452.781888724447, 5492.670439773627, 5080.0, 5083.440821509668, 5096.439391666913, 5137.535802296365, 5147.588192944556, 6441.516886233551, 6474.773382931575, 6511.316311787289, 6554.967225163891, 6688.93311066888, 6395.14540783177, 6429.485641148697, 6433.9678301608665, 6438.967805160991, 6451.516136289797, 7104.761999969993, 7121.857562848766, 7130.964345178293, 7154.964225178893, 7176.533525320838, 5542.8337149885565, 5648.43515648436, 5653.660780353192, 5674.914876276859, 5693.886122277573, 7108.182559005677, 7234.0, 7243.89134162988, 7437.665305061285, 7442.553446793209, 5857.560682948796, 5858.794942177004, 5901.704914754245, 5943.375945525744, 5952.404759524013, 5920.763169473173, 5953.464188222994, 6034.48706859913, 6053.909191362133, 6083.69581520931, 6332.746690132343, 6467.482601391877, 6500.5449618526245, 6503.187101612299, 6532.346765323429, 7089.929100708927, 7130.821729456805, 7160.248173941765, 7161.9641901790155, 7164.713411463483, 2072.77199508054, 2092.937211883646, 2121.4060063182333, 2133.370655656585, 2135.0605733477532, 1476.365162979929, 1532.8926975111635, 1534.7621118726615, 1541.8072740907387, 1562.8827837912202, 6013.338532761392, 6017.578769486182, 6027.849303767353, 6197.287311959092, 6238.65687387189, 6965.616891070938, 6984.196817365966, 7014.473914456436, 7061.964690176516, 7168.426525877917, 6043.8791224175275, 6180.351063138396, 6204.565680402328, 6207.317195108534, 6230.719617617127, 3817.828197731109, 3839.3856982883162, 3842.7310088293552, 3851.268259030775, 3854.094287842355, 7050.330218629222, 7057.92942070583, 7068.823279418056, 7080.468964827659, 7088.503804733619, 7401.777946661608, 7430.702771889064, 7468.21583733711, 7482.363999060032, 7506.24937506245, 6862.348076932683, 7011.964940175267, 7024.8595028098125, 7088.539244949062, 7090.539114957614, 7058.682359293742, 7074.816332650225, 7111.53775004623, 7139.394605724076, 7180.748673796392, 6997.580145191101, 7149.820279653799, 7152.392046676091, 7189.964050179925, 7196.820079498055, 6848.623325717133, 6938.895916561155, 6947.61788101639, 7002.474814389043, 7020.157581090302, 5888.852778680567, 5901.350851406256, 5907.261592300963, 5944.554158438048, 5949.3455719871645, 6888.345607167311, 6980.476464265302, 7013.789586312419, 7066.434685225273, 7247.746328878464, 6831.0, 6839.794806155824, 6842.93157068423, 6901.792946211622, 6902.896456553256, 6834.428081541373, 6836.829079273058, 6841.247462778991, 6903.1716194057035, 6932.826679332855, 6814.420774234246, 6821.624810635462, 6829.931700683128, 6862.656867156522, 6909.654517274217, 6871.5189936703955, 6933.306669333027, 6966.0, 6970.201240323934, 6998.675629076148, 6727.730890764315, 6738.326167383419, 6759.5606285591275, 6810.965945170243, 6839.760608378683], "value": 5545.652232377524, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [952.8284908716428, 961.8842143113988, 988.6391467114505, 992.503748125937, 1013.3514550687561, 17077.914610426946, 17595.73606395902, 17596.560085997855, 17703.468895933143, 19318.903405482954, 18713.719294210598, 18740.53148671284, 18746.25014999402, 18751.15619797109, 18794.436166915013, 761.4441457735857, 1019.3476175247847, 1055.1242468750957, 1055.9208059395537, 1056.7780766039102, 963.2005435488528, 1042.6455005298235, 1057.8730552333732, 1058.2856571813995, 1062.1715062251455, 1129.5481807277072, 1131.2929419113054, 1131.3438205840594, 1135.432283858071, 1135.5741596901162, 1120.7646394257215, 1124.454639499842, 1124.6907100547332, 1124.7356871135278, 1125.5047778977264, 828.888100106484, 831.7255305749088, 832.7168762620678, 832.7501749475133, 832.950022998616, 16640.251188696537, 16827.91586042074, 17792.288308467647, 17986.19062142207, 18024.0, 22277.220297289678, 22455.663165052374, 22557.210497632503, 22636.660450093263, 22646.66030009551, 17107.401240956508, 17198.91400543002, 17268.74096888535, 17368.218430170673, 17475.0, 3913.706472014582, 3929.4695216146024, 3933.2920074386598, 3943.369060950234, 3949.8025098744947, 142560.0, 142846.28576857154, 142906.0, 144313.0, 147129.0, 16280.75578866318, 16344.67310653804, 16842.578935526464, 16844.74732879008, 16845.241964111643, 31432.214194645316, 32324.0, 32529.0, 32605.510917336258, 32638.0, 8888.422252553562, 9123.635054597742, 9127.726168214964, 9178.082191780903, 9225.53872306382, 4195.622393984495, 4385.824567017347, 4622.884427889329, 4684.4378674559275, 4776.283557466339, 5622.212890195376, 5703.657780533098, 5704.771809127588, 5713.371529131792, 5734.0, 1183.8520184976878, 1198.6823491774687, 1202.2786328203008, 1215.228330010432, 1226.2152222577558, 724.807925899637, 738.9482736208414, 749.8800191969259, 760.4828716472852, 763.560952452331, 13296.0, 13329.200247985153, 13522.391492382669, 13990.790138147935, 14167.716645667235, 716.4733920568378, 726.0561270348526, 730.1347902735275, 733.2813842434444, 734.988975165373, 6410.391012853772, 6728.966355168192, 6742.5280230383405, 6854.2117656469145, 7025.121859767529, 128976.0, 131856.0, 132199.0, 132970.33514832365, 134140.32929835288, 117027.41486292685, 119134.0, 126069.0, 128071.71928280784, 128341.35829320793, 98358.0, 98573.0, 99602.0, 99668.50165749124, 102285.0, 13774.65563360924, 14076.929615351857, 14096.78854817158, 14146.575602731935, 14329.283535823375, 48971.265431019274, 49094.26358604695, 49948.50051499438, 50231.74884125556, 50482.74758626183, 65511.34488655243, 65665.67167164324, 66897.3310266891, 67026.32973670201, 67836.98244526237, 18955.431337059912, 19814.801851981294, 20051.29820456277, 20066.799332006492, 20083.598328033066, 20832.062557185265, 21378.465538361666, 21392.251271205434, 21579.2447264345, 22251.888740556195, 20764.06561704757, 22066.779332207116, 22073.227437039626, 22108.88945555262, 22120.336389908327, 17915.73126403079, 18022.729659055385, 18089.45731628053, 18674.906625466785, 20071.29750458727, 19614.313499027467, 20088.59822803565, 20155.69766453475, 20233.494162646053, 20241.392758217276, 14425.85574144287, 14668.413263469341, 14689.338979746151, 14838.529232552075, 15255.16937967451, 15051.473198438001, 15148.772768408704, 15532.456364027204, 15797.21013949275, 15967.920160399588], "value": 9875.46884785872, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1445.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 107.0, 98.0, 106.0, 107.0, 106.0, 107.0, 99.0, 105.0, 108.0], "value": 104.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 117.0, 154.0, 171.0, 116.0, 138.0, 139.0, 113.0, 171.0, 117.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 164.0, 163.0, 161.0, 162.0, 162.0, 162.0, 161.0, 162.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 74.0, 183.0, 75.0, 73.0, 73.0, 72.0, 181.0, 73.0], "value": 95.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 127.0, 79.0, 78.0, 77.0, 120.0, 78.0, 78.0, 132.0], "value": 92.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 117.0, 114.0, 114.0, 113.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 149.0, 150.0, 150.0, 150.0, 149.0, 151.0, 150.0, 151.0, 150.0], "value": 150.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 96.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 45.0, 45.0, 47.0, 44.0, 46.0, 44.0, 49.0, 44.0, 46.0], "value": 46.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [40.0, 41.0, 40.0, 39.0, 40.0, 39.0, 39.0, 41.0, 40.0, 41.0], "value": 40.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 71.0, 77.0, 71.0, 75.0, 72.0, 72.0, 73.0, 71.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 109.0, 112.0, 108.0, 110.0, 114.0, 116.0, 124.0, 114.0, 151.0], "value": 116.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 172.0, 151.0, 158.0, 155.0, 157.0, 164.0, 162.0, 153.0], "value": 159.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 60.0, 62.0, 61.0, 59.0, 60.0, 59.0, 59.0, 58.0], "value": 60.1, "unit": "score"}]}]} 03:58:46 INFO - Return code: 0 03:58:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:58:46 INFO - # TBPL SUCCESS # 03:58:46 INFO - Running post-action listener: _package_coverage_data 03:58:46 INFO - Running post-action listener: _resource_record_post_action 03:58:46 INFO - [mozharness: 2017-09-04 10:58:46.766279Z] Finished run-tests step (success) 03:58:46 INFO - Running post-run listener: _resource_record_post_run 03:58:46 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 12288; Write bytes: 401969152; Read time: 392; Write time: 468720 03:58:46 INFO - TinderboxPrint: CPU usage
13.1% 03:58:46 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 392 03:58:46 INFO - TinderboxPrint: I/O write bytes / time
401,969,152 / 468,720 03:58:46 INFO - TinderboxPrint: CPU idle
8,226.6 (86.9%) 03:58:46 INFO - TinderboxPrint: CPU user
1,187.8 (12.5%) 03:58:46 INFO - TinderboxPrint: Swap in / out
0 / 0 03:58:46 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 120389632; Read time: 36; Write time: 156444 03:58:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:58:46 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 8192; Write bytes: 281145344; Read time: 356; Write time: 312240 03:58:47 INFO - Running post-run listener: _upload_blobber_files 03:58:47 INFO - Blob upload gear active. 03:58:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:58:47 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 03:58:47 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'] 03:58:47 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 03:58:47 INFO - (blobuploader) - INFO - Open directory for files ... 03:58:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:58:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:58:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:48 INFO - (blobuploader) - INFO - Done attempting. 03:58:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:58:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:58:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:55 INFO - (blobuploader) - INFO - Done attempting. 03:58:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:58:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:58:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:56 INFO - (blobuploader) - INFO - Done attempting. 03:58:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:58:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:58:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:58:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:58:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:58:57 INFO - (blobuploader) - INFO - Done attempting. 03:58:57 INFO - (blobuploader) - INFO - Iteration through files over. 03:58:57 INFO - Return code: 0 03:58:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:58:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:58:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7dfc3925465340af451a45a030fa5024f7d5ebaca6c1e36cc478d8cca40caffa8675840b7dc056c5876350bb36e2c2716839d29a6dc6139a6ed2b0d23282a283", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25afea5f8424ce66d8be75407884be05eacb60dae18e1a9cd52f90c8fee50b6613e125130fb9898d1e34d87e1348d7edfc3e313dc9dbf64f30e53848e7f24c65", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c252df30e2dac92b73baadf73d229b66180fbab35741013b2624a9e83957dd914ef0f1eb5d405b141337ec4f6dac895661e376e34ebfdc9b7735385817e17ed", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5fbdcdaa554a42bcfbd495e0e36386701559b5c5175292e1bfc48f3b0f68e070184d95a9113890badd4f85f95bc60111decf932fdc5545beb61d4b490a4872f"} 03:58:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:58:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:58:57 INFO - Contents: 03:58:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7dfc3925465340af451a45a030fa5024f7d5ebaca6c1e36cc478d8cca40caffa8675840b7dc056c5876350bb36e2c2716839d29a6dc6139a6ed2b0d23282a283", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25afea5f8424ce66d8be75407884be05eacb60dae18e1a9cd52f90c8fee50b6613e125130fb9898d1e34d87e1348d7edfc3e313dc9dbf64f30e53848e7f24c65", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c252df30e2dac92b73baadf73d229b66180fbab35741013b2624a9e83957dd914ef0f1eb5d405b141337ec4f6dac895661e376e34ebfdc9b7735385817e17ed", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5fbdcdaa554a42bcfbd495e0e36386701559b5c5175292e1bfc48f3b0f68e070184d95a9113890badd4f85f95bc60111decf932fdc5545beb61d4b490a4872f"} 03:58:57 INFO - Running post-run listener: copy_logs_to_upload_dir 03:58:57 INFO - Copying logs to upload dir... 03:58:57 INFO - mkdir: /builds/slave/test/build/upload/logs 03:58:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.165635 ========= master_lag: 0.22 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-04 03:58:58.028719) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 03:58:58.036221) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7dfc3925465340af451a45a030fa5024f7d5ebaca6c1e36cc478d8cca40caffa8675840b7dc056c5876350bb36e2c2716839d29a6dc6139a6ed2b0d23282a283", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25afea5f8424ce66d8be75407884be05eacb60dae18e1a9cd52f90c8fee50b6613e125130fb9898d1e34d87e1348d7edfc3e313dc9dbf64f30e53848e7f24c65", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c252df30e2dac92b73baadf73d229b66180fbab35741013b2624a9e83957dd914ef0f1eb5d405b141337ec4f6dac895661e376e34ebfdc9b7735385817e17ed", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5fbdcdaa554a42bcfbd495e0e36386701559b5c5175292e1bfc48f3b0f68e070184d95a9113890badd4f85f95bc60111decf932fdc5545beb61d4b490a4872f"} build_url:https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011375 build_url: 'https://queue.taskcluster.net/v1/task/LR3txbuMQR2w2pQpYudBzw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7dfc3925465340af451a45a030fa5024f7d5ebaca6c1e36cc478d8cca40caffa8675840b7dc056c5876350bb36e2c2716839d29a6dc6139a6ed2b0d23282a283", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25afea5f8424ce66d8be75407884be05eacb60dae18e1a9cd52f90c8fee50b6613e125130fb9898d1e34d87e1348d7edfc3e313dc9dbf64f30e53848e7f24c65", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c252df30e2dac92b73baadf73d229b66180fbab35741013b2624a9e83957dd914ef0f1eb5d405b141337ec4f6dac895661e376e34ebfdc9b7735385817e17ed", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5fbdcdaa554a42bcfbd495e0e36386701559b5c5175292e1bfc48f3b0f68e070184d95a9113890badd4f85f95bc60111decf932fdc5545beb61d4b490a4872f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 03:58:58.071420) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:58:58.071752) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1504521486.101291-1577290632 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004505 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:58:58.151522) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 03:58:58.154857) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 03:58:58.155131) ========= ========= Total master_lag: 0.39 =========