builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-032 starttime: 1504087208.61 results: success (0) revision: ae9e6b6d31321f119f1e07823f93137f58771377 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-30 03:00:08.609728) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-30 03:00:08.610205) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-30 03:00:08.610494) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-30 03:00:08.669300) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:00:08.669753) ========= 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=745a4373a2a16df560f216e000000264-1504084707.132134-1014938893 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-30 03:00:08-- 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% 34.2M=0s 2017-08-30 03:00:08 (34.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.094573 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:00:08.796251) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:00:08.796811) ========= 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=745a4373a2a16df560f216e000000264-1504084707.132134-1014938893 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016708 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:00:08.852386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:00:08.852753) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ae9e6b6d31321f119f1e07823f93137f58771377 --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 ae9e6b6d31321f119f1e07823f93137f58771377 --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=745a4373a2a16df560f216e000000264-1504084707.132134-1014938893 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-30 03:00:08,892 truncating revision to first 12 chars 2017-08-30 03:00:08,892 Setting DEBUG logging. 2017-08-30 03:00:08,892 attempt 1/10 2017-08-30 03:00:08,892 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ae9e6b6d3132?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-30 03:00:09,455 unpacking tar archive at: mozilla-central-ae9e6b6d3132/testing/mozharness/ program finished with exit code 0 elapsedTime=0.950483 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:00:09.822458) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-30 03:00:09.822895) ========= script_repo_revision: ae9e6b6d31321f119f1e07823f93137f58771377 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-30 03:00:09.823314) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-30 03:00:09.823595) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-30 03:00:09.839611) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-08-30 03:00:09.840017) ========= /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=745a4373a2a16df560f216e000000264-1504084707.132134-1014938893 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:00:10 INFO - MultiFileLogger online at 20170830 03:00:10 in /builds/slave/test 03:00:10 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:00:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:00:10 INFO - {'append_to_log': False, 03:00:10 INFO - 'base_work_dir': '/builds/slave/test', 03:00:10 INFO - 'blob_upload_branch': 'Firefox', 03:00:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:00:10 INFO - 'branch': 'Firefox', 03:00:10 INFO - 'buildbot_json_path': 'buildprops.json', 03:00:10 INFO - 'code_coverage': False, 03:00:10 INFO - 'config_files': ('talos/linux_config.py',), 03:00:10 INFO - 'default_actions': ('clobber', 03:00:10 INFO - 'read-buildbot-config', 03:00:10 INFO - 'download-and-extract', 03:00:10 INFO - 'populate-webroot', 03:00:10 INFO - 'create-virtualenv', 03:00:10 INFO - 'install', 03:00:10 INFO - 'setup-mitmproxy', 03:00:10 INFO - 'run-tests'), 03:00:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:00:10 INFO - 'disable_ccov_upload': False, 03:00:10 INFO - 'disable_stylo': False, 03:00:10 INFO - 'download_minidump_stackwalk': True, 03:00:10 INFO - 'download_symbols': 'ondemand', 03:00:10 INFO - 'enable_stylo': False, 03:00:10 INFO - 'enable_webrender': False, 03:00:10 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:00:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:00:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:00:10 INFO - 'gecko_profile': False, 03:00:10 INFO - 'gecko_profile_interval': 0, 03:00:10 INFO - 'installer_path': 'installer.exe', 03:00:10 INFO - 'log_level': 'info', 03:00:10 INFO - 'log_name': 'talos', 03:00:10 INFO - 'log_to_console': True, 03:00:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:00:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:00:10 INFO - 'opt_config_files': (), 03:00:10 INFO - 'pip_index': False, 03:00:10 INFO - 'suite': 'dromaeojs-e10s', 03:00:10 INFO - 'system_bits': '32', 03:00:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:00:10 INFO - 'title': 'talos-linux64-ix-032', 03:00:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:00:10 INFO - 'use_talos_json': True, 03:00:10 INFO - 'verify': 'False', 03:00:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:00:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:00:10 INFO - 'work_dir': 'build'} 03:00:10 INFO - [mozharness: 2017-08-30 10:00:10.024336Z] Running clobber step. 03:00:10 INFO - Running pre-action listener: _resource_record_pre_action 03:00:10 INFO - Running main action method: clobber 03:00:10 INFO - rmtree: /builds/slave/test/build 03:00:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:00:11 INFO - Running post-action listener: _resource_record_post_action 03:00:11 INFO - [mozharness: 2017-08-30 10:00:11.546448Z] Finished clobber step (success) 03:00:11 INFO - [mozharness: 2017-08-30 10:00:11.546545Z] Running read-buildbot-config step. 03:00:11 INFO - Running pre-action listener: _resource_record_pre_action 03:00:11 INFO - Running main action method: read_buildbot_config 03:00:11 INFO - Using buildbot properties: 03:00:11 INFO - { 03:00:11 INFO - "project": "", 03:00:11 INFO - "product": "firefox", 03:00:11 INFO - "who": "archaeopteryx@coole-files.de", 03:00:11 INFO - "installer_path": "public/build/target.tar.bz2", 03:00:11 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:00:11 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:00:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 03:00:11 INFO - "stage_platform": "linux64-pgo", 03:00:11 INFO - "basedir": "/builds/slave/test", 03:00:11 INFO - "buildnumber": 130, 03:00:11 INFO - "platform": "ubuntu64_hw", 03:00:11 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:00:11 INFO - "slavebuilddir": "test", 03:00:11 INFO - "taskId": "W8Ora31CSviArTciE8SE9A", 03:00:11 INFO - "branch": "mozilla-central", 03:00:11 INFO - "script_repo_revision": "production", 03:00:11 INFO - "revision": "ae9e6b6d31321f119f1e07823f93137f58771377", 03:00:11 INFO - "slavename": "talos-linux64-ix-032", 03:00:11 INFO - "repo_path": "mozilla-central" 03:00:11 INFO - } 03:00:11 INFO - Finding installer, test and symbols from parent task. 03:00:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W8Ora31CSviArTciE8SE9A'}, attempt #1 03:00:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W8Ora31CSviArTciE8SE9A'}, attempt #1 03:00:12 INFO - Task dependencies: agGxgv-nSMeSLvEC4QRiKA 03:00:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA'}, attempt #1 03:00:12 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.tar.bz2 03:00:12 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.test_packages.json 03:00:12 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.crashreporter-symbols.zip 03:00:12 INFO - Running post-action listener: _resource_record_post_action 03:00:12 INFO - [mozharness: 2017-08-30 10:00:12.906347Z] Finished read-buildbot-config step (success) 03:00:12 INFO - [mozharness: 2017-08-30 10:00:12.906567Z] Running download-and-extract step. 03:00:12 INFO - Running pre-action listener: _resource_record_pre_action 03:00:12 INFO - Running main action method: download_and_extract 03:00:12 INFO - mkdir: /builds/slave/test/build/tests 03:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:12 INFO - trying https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.test_packages.json 03:00:12 INFO - Downloading https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:00:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:00:13 INFO - Downloaded 989 bytes. 03:00:13 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:00:13 INFO - Using the following test package requirements: 03:00:13 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:00:13 INFO - u'common': [u'target.common.tests.zip'], 03:00:13 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:00:13 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:00:13 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:00:13 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:00:13 INFO - u'mozbase': [u'target.common.tests.zip'], 03:00:13 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:00:13 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:00:13 INFO - u'web-platform': [u'target.common.tests.zip', 03:00:13 INFO - u'target.web-platform.tests.tar.gz'], 03:00:13 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:00:13 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:00:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.common.tests.zip 03:00:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:00:13 INFO - Fetch https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.common.tests.zip into memory 03:00:14 INFO - Content-Length response header: 55591583 03:00:14 INFO - Bytes received: 55591583 03:00:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:00:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.common.tests.zip 03:00:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:00:25 INFO - Fetch https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.common.tests.zip into memory 03:00:26 INFO - Content-Length response header: 55591583 03:00:26 INFO - Bytes received: 55591583 03:00:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.talos.tests.zip 03:00:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:00:38 INFO - Fetch https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.talos.tests.zip into memory 03:00:39 INFO - Content-Length response header: 14496610 03:00:39 INFO - Bytes received: 14496610 03:00: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:00:39 INFO - trying https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.tar.bz2 03:00:39 INFO - Downloading https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:00:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:00:40 INFO - Downloaded 63424312 bytes. 03:00:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.tar.bz2 03:00:40 INFO - mkdir: /builds/slave/test/properties 03:00:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:00:40 INFO - Writing to file /builds/slave/test/properties/build_url 03:00:40 INFO - Contents: 03:00:40 INFO - build_url:https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.tar.bz2 03:00:40 INFO - Running post-action listener: _resource_record_post_action 03:00:40 INFO - Running post-action listener: find_tests_for_verification 03:00:40 INFO - Running post-action listener: set_extra_try_arguments 03:00:40 INFO - [mozharness: 2017-08-30 10:00:40.431819Z] Finished download-and-extract step (success) 03:00:40 INFO - [mozharness: 2017-08-30 10:00:40.431913Z] Running populate-webroot step. 03:00:40 INFO - Running pre-action listener: _resource_record_pre_action 03:00:40 INFO - Running main action method: populate_webroot 03:00:40 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:00:40 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:00:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:00:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:00:40 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:00:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:00:40 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:00:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:40 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:00:40 INFO - 'talos_options': ['--enable-stylo'], 03:00:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:40 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:00:40 INFO - 'tests': ['damp', 'tps']}, 03:00:40 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:00:40 INFO - 'talos_options': ['--enable-stylo'], 03:00:40 INFO - 'tests': ['damp', 'tps']}, 03:00:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:00:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:00:40 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:00:40 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:00:40 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:00:40 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:00:40 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:00:40 INFO - 'talos_options': ['--enable-stylo'], 03:00:40 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:00:40 INFO - 'other-e10s': {'tests': ['a11yr', 03:00:40 INFO - 'ts_paint', 03:00:40 INFO - 'tpaint', 03:00:40 INFO - 'sessionrestore', 03:00:40 INFO - 'sessionrestore_many_windows', 03:00:40 INFO - 'sessionrestore_no_auto_restore', 03:00:40 INFO - 'tabpaint']}, 03:00:40 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:00:40 INFO - 'tests': ['a11yr', 03:00:40 INFO - 'ts_paint', 03:00:40 INFO - 'tpaint', 03:00:40 INFO - 'sessionrestore', 03:00:40 INFO - 'sessionrestore_many_windows', 03:00:40 INFO - 'sessionrestore_no_auto_restore', 03:00:40 INFO - 'tabpaint']}, 03:00:40 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:00:40 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:00:40 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:00:40 INFO - 'tests': ['bloom_basic_singleton']}, 03:00:40 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:00:40 INFO - 'tests': ['bloom_basic']}, 03:00:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:00:40 INFO - 'tsvgr_opacity', 03:00:40 INFO - 'tart', 03:00:40 INFO - 'tscrollx', 03:00:40 INFO - 'tsvg_static']}, 03:00:40 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:00:40 INFO - 'tests': ['tsvgx', 03:00:40 INFO - 'tsvgr_opacity', 03:00:40 INFO - 'tart', 03:00:40 INFO - 'tscrollx', 03:00:40 INFO - 'tsvg_static']}, 03:00:40 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:00:40 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:00:40 INFO - 'talos_options': ['--enable-stylo'], 03:00:40 INFO - 'tests': ['tp5o']}, 03:00:40 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:00:40 INFO - 'talos_options': ['--mitmproxy', 03:00:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:00:40 INFO - '--firstNonBlankPaint'], 03:00:40 INFO - 'tests': ['tp6_google', 03:00:40 INFO - 'tp6_youtube', 03:00:40 INFO - 'tp6_amazon', 03:00:40 INFO - 'tp6_facebook']}, 03:00:40 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:00:40 INFO - 'talos_options': ['--enable-stylo', 03:00:40 INFO - '--mitmproxy', 03:00:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:00:40 INFO - '--firstNonBlankPaint'], 03:00:40 INFO - 'tests': ['tp6_google', 03:00:40 INFO - 'tp6_youtube', 03:00:40 INFO - 'tp6_amazon', 03:00:40 INFO - 'tp6_facebook']}, 03:00:40 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:00:40 INFO - 'talos_options': ['--enable-stylo', 03:00:40 INFO - '--stylo-threads=1', 03:00:40 INFO - '--mitmproxy', 03:00:40 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:00:40 INFO - '--firstNonBlankPaint'], 03:00:40 INFO - 'tests': ['tp6_google', 03:00:40 INFO - 'tp6_youtube', 03:00:40 INFO - 'tp6_amazon', 03:00:40 INFO - 'tp6_facebook']}, 03:00:40 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:00:40 INFO - 'talos_options': ['--xperf_path', 03:00:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:40 INFO - 'tests': ['tp5n']}, 03:00:40 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:00:40 INFO - 'talos_options': ['--enable-stylo', 03:00:40 INFO - '--xperf_path', 03:00:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:40 INFO - 'tests': ['tp5n']}}} 03:00:40 INFO - Running post-action listener: _resource_record_post_action 03:00:40 INFO - [mozharness: 2017-08-30 10:00:40.446064Z] Finished populate-webroot step (success) 03:00:40 INFO - [mozharness: 2017-08-30 10:00:40.446152Z] Running create-virtualenv step. 03:00:40 INFO - Running pre-action listener: _resource_record_pre_action 03:00:40 INFO - Running main action method: create_virtualenv 03:00:40 INFO - Creating virtualenv /builds/slave/test/build/venv 03:00:40 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:00:40 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:00:40 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:00:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:00:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:00:41 INFO - Installing distribute.............................................................................................................................................................................................done. 03:00:41 INFO - Installing pip...............done. 03:00:41 INFO - Return code: 0 03:00:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:00:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:00:41 INFO - Reading from file tmpfile_stdout 03:00:41 INFO - Output received: 03:00: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:00:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:00: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:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00: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:00:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00: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 0x1b911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efd580>, '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 0x1efe480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efe910>, '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': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', '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:00: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:00: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:00:41 INFO - Using env: {'DISPLAY': ':0', 03:00:41 INFO - 'HOME': '/home/cltbld', 03:00:41 INFO - 'LANG': 'en_US.UTF-8', 03:00:41 INFO - 'LANGUAGE': 'en_US:en', 03:00:41 INFO - 'LOGNAME': 'cltbld', 03:00:41 INFO - 'MAIL': '/var/mail/cltbld', 03:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:41 INFO - 'MOZ_NO_REMOTE': '1', 03:00:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:41 INFO - 'NO_EM_RESTART': '1', 03:00:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:41 INFO - 'PWD': '/builds/slave/test', 03:00:41 INFO - 'SHELL': '/bin/bash', 03:00:41 INFO - 'SHLVL': '1', 03:00:41 INFO - 'TERM': 'linux', 03:00:41 INFO - 'TMOUT': '86400', 03:00:41 INFO - 'USER': 'cltbld', 03:00:41 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', 03:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:41 INFO - '_': '/tools/buildbot/bin/python'} 03:00:42 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:00:42 INFO - Downloading/unpacking pip>=1.5 03:00:42 INFO - Running setup.py egg_info for package pip 03:00:42 INFO - warning: no files found matching 'pip/cacert.pem' 03:00:42 INFO - warning: no files found matching '*.html' under directory 'docs' 03:00:42 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:00:42 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:00:42 INFO - Installing collected packages: pip 03:00:42 INFO - Found existing installation: pip 1.1 03:00:42 INFO - Uninstalling pip: 03:00:42 INFO - Successfully uninstalled pip 03:00:42 INFO - Running setup.py install for pip 03:00:42 INFO - warning: no files found matching 'pip/cacert.pem' 03:00:42 INFO - warning: no files found matching '*.html' under directory 'docs' 03:00:42 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:00:42 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:00:42 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:00:42 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:00:42 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:00:42 INFO - Successfully installed pip 03:00:42 INFO - Cleaning up... 03:00:42 INFO - Return code: 0 03:00:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00: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 0x1b911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efd580>, '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 0x1efe480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efe910>, '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': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', '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:00: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:00: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:00:42 INFO - Using env: {'DISPLAY': ':0', 03:00:42 INFO - 'HOME': '/home/cltbld', 03:00:42 INFO - 'LANG': 'en_US.UTF-8', 03:00:42 INFO - 'LANGUAGE': 'en_US:en', 03:00:42 INFO - 'LOGNAME': 'cltbld', 03:00:42 INFO - 'MAIL': '/var/mail/cltbld', 03:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:42 INFO - 'MOZ_NO_REMOTE': '1', 03:00:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:42 INFO - 'NO_EM_RESTART': '1', 03:00:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:42 INFO - 'PWD': '/builds/slave/test', 03:00:42 INFO - 'SHELL': '/bin/bash', 03:00:42 INFO - 'SHLVL': '1', 03:00:42 INFO - 'TERM': 'linux', 03:00:42 INFO - 'TMOUT': '86400', 03:00:42 INFO - 'USER': 'cltbld', 03:00:42 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', 03:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:42 INFO - '_': '/tools/buildbot/bin/python'} 03:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:42 INFO - Downloading/unpacking psutil>=3.1.1 03:00: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:00: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:00: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:00: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:00:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:00:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:00:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:00:44 INFO - Installing collected packages: psutil 03:00:44 INFO - Running setup.py install for psutil 03:00:44 INFO - building 'psutil._psutil_linux' extension 03:00: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:00: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:00:44 INFO - building 'psutil._psutil_posix' extension 03:00: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:00: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:00:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:00:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:00:44 INFO - Successfully installed psutil 03:00:44 INFO - Cleaning up... 03:00:44 INFO - Return code: 0 03:00:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:00:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efd580>, '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 0x1efe480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efe910>, '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': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', '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:00:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:00:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:00:44 INFO - Using env: {'DISPLAY': ':0', 03:00:44 INFO - 'HOME': '/home/cltbld', 03:00:44 INFO - 'LANG': 'en_US.UTF-8', 03:00:44 INFO - 'LANGUAGE': 'en_US:en', 03:00:44 INFO - 'LOGNAME': 'cltbld', 03:00:44 INFO - 'MAIL': '/var/mail/cltbld', 03:00:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:44 INFO - 'MOZ_NO_REMOTE': '1', 03:00:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:44 INFO - 'NO_EM_RESTART': '1', 03:00:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:44 INFO - 'PWD': '/builds/slave/test', 03:00:44 INFO - 'SHELL': '/bin/bash', 03:00:44 INFO - 'SHLVL': '1', 03:00:44 INFO - 'TERM': 'linux', 03:00:44 INFO - 'TMOUT': '86400', 03:00:44 INFO - 'USER': 'cltbld', 03:00:44 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', 03:00:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:44 INFO - '_': '/tools/buildbot/bin/python'} 03:00:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:00: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:00: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:00: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:00: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:00:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:00:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:00:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:00:45 INFO - Installing collected packages: mozsystemmonitor 03:00:45 INFO - Running setup.py install for mozsystemmonitor 03:00:46 INFO - Successfully installed mozsystemmonitor 03:00:46 INFO - Cleaning up... 03:00:46 INFO - Return code: 0 03:00:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:00: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:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00: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:00:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00: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 0x1b911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efd580>, '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 0x1efe480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efe910>, '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': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', '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:00: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:00: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:00:46 INFO - Using env: {'DISPLAY': ':0', 03:00:46 INFO - 'HOME': '/home/cltbld', 03:00:46 INFO - 'LANG': 'en_US.UTF-8', 03:00:46 INFO - 'LANGUAGE': 'en_US:en', 03:00:46 INFO - 'LOGNAME': 'cltbld', 03:00:46 INFO - 'MAIL': '/var/mail/cltbld', 03:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:46 INFO - 'MOZ_NO_REMOTE': '1', 03:00:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:46 INFO - 'NO_EM_RESTART': '1', 03:00:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:46 INFO - 'PWD': '/builds/slave/test', 03:00:46 INFO - 'SHELL': '/bin/bash', 03:00:46 INFO - 'SHLVL': '1', 03:00:46 INFO - 'TERM': 'linux', 03:00:46 INFO - 'TMOUT': '86400', 03:00:46 INFO - 'USER': 'cltbld', 03:00:46 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', 03:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:46 INFO - '_': '/tools/buildbot/bin/python'} 03:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:46 INFO - Downloading/unpacking jsonschema==2.5.1 03:00: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:00: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:00: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:00: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:00:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:00:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:00: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:00: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:00: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:00: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:00:47 INFO - Downloading functools32-3.2.3-2.tar.gz 03:00:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:00:47 INFO - warning: no files found matching '*.txt' 03:00:47 INFO - no previously-included directories found matching 'build' 03:00:47 INFO - no previously-included directories found matching 'dist' 03:00:47 INFO - no previously-included directories found matching '.git*' 03:00:47 INFO - Installing collected packages: jsonschema, functools32 03:00:47 INFO - Running setup.py install for functools32 03:00:47 INFO - warning: no files found matching '*.txt' 03:00:47 INFO - no previously-included directories found matching 'build' 03:00:47 INFO - no previously-included directories found matching 'dist' 03:00:47 INFO - no previously-included directories found matching '.git*' 03:00:47 INFO - Successfully installed jsonschema functools32 03:00:47 INFO - Cleaning up... 03:00:47 INFO - Return code: 0 03:00:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:00:47 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:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:47 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:00:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:47 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 0x1b911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efd580>, '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 0x1efe480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efe910>, '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': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', '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:00:47 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:00:47 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:00:47 INFO - Using env: {'DISPLAY': ':0', 03:00:47 INFO - 'HOME': '/home/cltbld', 03:00:47 INFO - 'LANG': 'en_US.UTF-8', 03:00:47 INFO - 'LANGUAGE': 'en_US:en', 03:00:47 INFO - 'LOGNAME': 'cltbld', 03:00:47 INFO - 'MAIL': '/var/mail/cltbld', 03:00:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:47 INFO - 'MOZ_NO_REMOTE': '1', 03:00:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:47 INFO - 'NO_EM_RESTART': '1', 03:00:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:47 INFO - 'PWD': '/builds/slave/test', 03:00:47 INFO - 'SHELL': '/bin/bash', 03:00:47 INFO - 'SHLVL': '1', 03:00:47 INFO - 'TERM': 'linux', 03:00:47 INFO - 'TMOUT': '86400', 03:00:47 INFO - 'USER': 'cltbld', 03:00:47 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', 03:00:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:47 INFO - '_': '/tools/buildbot/bin/python'} 03:00:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:00:47 INFO - Cleaning up... 03:00:47 INFO - Return code: 0 03:00:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:00:47 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:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:47 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:00:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:47 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 0x1b911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efd580>, '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 0x1efe480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efe910>, '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': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', '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:00:47 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:00:47 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:00:47 INFO - Using env: {'DISPLAY': ':0', 03:00:47 INFO - 'HOME': '/home/cltbld', 03:00:47 INFO - 'LANG': 'en_US.UTF-8', 03:00:47 INFO - 'LANGUAGE': 'en_US:en', 03:00:47 INFO - 'LOGNAME': 'cltbld', 03:00:47 INFO - 'MAIL': '/var/mail/cltbld', 03:00:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:47 INFO - 'MOZ_NO_REMOTE': '1', 03:00:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:47 INFO - 'NO_EM_RESTART': '1', 03:00:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:47 INFO - 'PWD': '/builds/slave/test', 03:00:47 INFO - 'SHELL': '/bin/bash', 03:00:47 INFO - 'SHLVL': '1', 03:00:47 INFO - 'TERM': 'linux', 03:00:47 INFO - 'TMOUT': '86400', 03:00:47 INFO - 'USER': 'cltbld', 03:00:47 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', 03:00:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:47 INFO - '_': '/tools/buildbot/bin/python'} 03:00:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:48 INFO - Downloading/unpacking blobuploader==1.2.4 03:00: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:00: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:00: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:00: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:00:49 INFO - Downloading blobuploader-1.2.4.tar.gz 03:00:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:00:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:00: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:00: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:00: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:00: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:00:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:00:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:00: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:00: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:00: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:00: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:00:49 INFO - Downloading docopt-0.6.1.tar.gz 03:00:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:00:49 INFO - Installing collected packages: blobuploader, requests, docopt 03:00:49 INFO - Running setup.py install for blobuploader 03:00:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:00:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:00:49 INFO - Running setup.py install for requests 03:00:50 INFO - Running setup.py install for docopt 03:00:50 INFO - Successfully installed blobuploader requests docopt 03:00:50 INFO - Cleaning up... 03:00:50 INFO - Return code: 0 03:00:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00: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 0x1b911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efd580>, '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 0x1efe480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efe910>, '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': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', '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:00: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:00: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:00:50 INFO - Using env: {'DISPLAY': ':0', 03:00:50 INFO - 'HOME': '/home/cltbld', 03:00:50 INFO - 'LANG': 'en_US.UTF-8', 03:00:50 INFO - 'LANGUAGE': 'en_US:en', 03:00:50 INFO - 'LOGNAME': 'cltbld', 03:00:50 INFO - 'MAIL': '/var/mail/cltbld', 03:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:50 INFO - 'MOZ_NO_REMOTE': '1', 03:00:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:50 INFO - 'NO_EM_RESTART': '1', 03:00:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:50 INFO - 'PWD': '/builds/slave/test', 03:00:50 INFO - 'SHELL': '/bin/bash', 03:00:50 INFO - 'SHLVL': '1', 03:00:50 INFO - 'TERM': 'linux', 03:00:50 INFO - 'TMOUT': '86400', 03:00:50 INFO - 'USER': 'cltbld', 03:00:50 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', 03:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:50 INFO - '_': '/tools/buildbot/bin/python'} 03:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:00:50 INFO - Running setup.py (path:/tmp/pip-esuZ7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:00:50 INFO - Running setup.py (path:/tmp/pip-CuwICF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:00:50 INFO - Running setup.py (path:/tmp/pip-AVFxJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:00:50 INFO - Running setup.py (path:/tmp/pip-e8UV8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:00:50 INFO - Running setup.py (path:/tmp/pip-nzHRld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:00:51 INFO - Running setup.py (path:/tmp/pip-kQj6AB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:00:51 INFO - Running setup.py (path:/tmp/pip-Gg0QrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:00:51 INFO - Running setup.py (path:/tmp/pip-8ozazB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:00:51 INFO - Running setup.py (path:/tmp/pip-vWfVlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:00:51 INFO - Running setup.py (path:/tmp/pip-mLhaur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:00:51 INFO - Running setup.py (path:/tmp/pip-tFW95A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:00:51 INFO - Running setup.py (path:/tmp/pip-MMMmsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:00:51 INFO - Running setup.py (path:/tmp/pip-8uwgIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:00:51 INFO - Running setup.py (path:/tmp/pip-a9J8r6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:51 INFO - Running setup.py (path:/tmp/pip-AorlFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:00:51 INFO - Running setup.py (path:/tmp/pip-MFR2bA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:00:51 INFO - Running setup.py (path:/tmp/pip-RZSs5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:00:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:00:51 INFO - Running setup.py install for manifestparser 03:00:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:00:51 INFO - Running setup.py install for mozcrash 03:00:52 INFO - Running setup.py install for mozdebug 03:00:52 INFO - Running setup.py install for mozdevice 03:00:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:00:52 INFO - Running setup.py install for mozfile 03:00:52 INFO - Running setup.py install for mozhttpd 03:00:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:00:52 INFO - Running setup.py install for mozinfo 03:00:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:00:52 INFO - Running setup.py install for mozInstall 03:00:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:00:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:00:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:00:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:00:52 INFO - Running setup.py install for mozleak 03:00:52 INFO - Running setup.py install for mozlog 03:00:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:00:52 INFO - Running setup.py install for moznetwork 03:00:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:00:52 INFO - Running setup.py install for mozprocess 03:00:53 INFO - Running setup.py install for mozprofile 03:00:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:00:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:00:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:00:53 INFO - Running setup.py install for mozrunner 03:00:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:00:53 INFO - Running setup.py install for mozscreenshot 03:00:53 INFO - Running setup.py install for moztest 03:00:53 INFO - Running setup.py install for mozversion 03:00:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:00:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:00:53 INFO - Cleaning up... 03:00:53 INFO - Return code: 0 03:00:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00: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 0x1b911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efd580>, '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 0x1efe480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efe910>, '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': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', '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:00: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:00: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:00:53 INFO - Using env: {'DISPLAY': ':0', 03:00:53 INFO - 'HOME': '/home/cltbld', 03:00:53 INFO - 'LANG': 'en_US.UTF-8', 03:00:53 INFO - 'LANGUAGE': 'en_US:en', 03:00:53 INFO - 'LOGNAME': 'cltbld', 03:00:53 INFO - 'MAIL': '/var/mail/cltbld', 03:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:53 INFO - 'MOZ_NO_REMOTE': '1', 03:00:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:53 INFO - 'NO_EM_RESTART': '1', 03:00:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:53 INFO - 'PWD': '/builds/slave/test', 03:00:53 INFO - 'SHELL': '/bin/bash', 03:00:53 INFO - 'SHLVL': '1', 03:00:53 INFO - 'TERM': 'linux', 03:00:53 INFO - 'TMOUT': '86400', 03:00:53 INFO - 'USER': 'cltbld', 03:00:53 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', 03:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:53 INFO - '_': '/tools/buildbot/bin/python'} 03:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:00:53 INFO - Running setup.py (path:/tmp/pip-NnzNMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:00:53 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:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:00:53 INFO - Running setup.py (path:/tmp/pip-cd8yI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:00: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:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:00:54 INFO - Running setup.py (path:/tmp/pip-TDXvKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:00: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:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:00:54 INFO - Running setup.py (path:/tmp/pip-VwwJrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:00: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:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:00:54 INFO - Running setup.py (path:/tmp/pip-4ZJa_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:00: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:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:00:54 INFO - Running setup.py (path:/tmp/pip-WVvtE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:00: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:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:00:54 INFO - Running setup.py (path:/tmp/pip-MR211I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:00: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:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:00:54 INFO - Running setup.py (path:/tmp/pip-2IaI1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:00: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:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:00:54 INFO - Running setup.py (path:/tmp/pip-Q69YFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:00: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:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:00:54 INFO - Running setup.py (path:/tmp/pip-x4lgGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:00: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:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:00:54 INFO - Running setup.py (path:/tmp/pip-VdjDyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:00: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:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:00:54 INFO - Running setup.py (path:/tmp/pip-GKcNqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:00: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:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:00:54 INFO - Running setup.py (path:/tmp/pip-sd9_Sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:00: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:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:00:54 INFO - Running setup.py (path:/tmp/pip-zIvs5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:00:54 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:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:54 INFO - Running setup.py (path:/tmp/pip-NhjSwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:00:54 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:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:00:54 INFO - Running setup.py (path:/tmp/pip-cVCnz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:00: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:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:00:55 INFO - Running setup.py (path:/tmp/pip-HIAzYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:00: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:00: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:00: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:00: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:00: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:00: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:00: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:00:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:56 INFO - Downloading blessings-1.6.tar.gz 03:00:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:00:56 INFO - Installing collected packages: blessings 03:00:56 INFO - Running setup.py install for blessings 03:00:56 INFO - Successfully installed blessings 03:00:56 INFO - Cleaning up... 03:00:56 INFO - Return code: 0 03:00:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:00:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:00:56 INFO - Reading from file tmpfile_stdout 03:00:56 INFO - Current package versions: 03:00:56 INFO - argparse == 1.2.1 03:00:56 INFO - blessings == 1.6 03:00:56 INFO - blobuploader == 1.2.4 03:00:56 INFO - docopt == 0.6.1 03:00:56 INFO - functools32 == 3.2.3-2 03:00:56 INFO - jsonschema == 2.5.1 03:00:56 INFO - manifestparser == 1.1 03:00:56 INFO - mozInstall == 1.13 03:00:56 INFO - mozcrash == 1.0 03:00:56 INFO - mozdebug == 0.1 03:00:56 INFO - mozdevice == 0.51 03:00:56 INFO - mozfile == 1.2 03:00:56 INFO - mozhttpd == 0.7 03:00:56 INFO - mozinfo == 0.10 03:00:56 INFO - mozleak == 0.1 03:00:56 INFO - mozlog == 3.5 03:00:56 INFO - moznetwork == 0.27 03:00:56 INFO - mozprocess == 0.25 03:00:56 INFO - mozprofile == 0.28 03:00:56 INFO - mozrunner == 6.13 03:00:56 INFO - mozscreenshot == 0.1 03:00:56 INFO - mozsystemmonitor == 0.3 03:00:56 INFO - moztest == 0.8 03:00:56 INFO - mozversion == 1.4 03:00:56 INFO - psutil == 3.1.1 03:00:56 INFO - requests == 1.2.3 03:00:56 INFO - wsgiref == 0.1.2 03:00:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:00: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:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00: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:00:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00: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 0x1b911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efd580>, '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 0x1efe480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efe910>, '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': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', '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:00: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:00: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:00:56 INFO - Using env: {'DISPLAY': ':0', 03:00:56 INFO - 'HOME': '/home/cltbld', 03:00:56 INFO - 'LANG': 'en_US.UTF-8', 03:00:56 INFO - 'LANGUAGE': 'en_US:en', 03:00:56 INFO - 'LOGNAME': 'cltbld', 03:00:56 INFO - 'MAIL': '/var/mail/cltbld', 03:00:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:56 INFO - 'MOZ_NO_REMOTE': '1', 03:00:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:56 INFO - 'NO_EM_RESTART': '1', 03:00:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:56 INFO - 'PWD': '/builds/slave/test', 03:00:56 INFO - 'SHELL': '/bin/bash', 03:00:56 INFO - 'SHLVL': '1', 03:00:56 INFO - 'TERM': 'linux', 03:00:56 INFO - 'TMOUT': '86400', 03:00:56 INFO - 'USER': 'cltbld', 03:00:56 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', 03:00:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:56 INFO - '_': '/tools/buildbot/bin/python'} 03:00:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:56 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:00:56 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:00:56 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:00:56 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:00:56 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:00:56 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:00:56 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:00:56 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:00:56 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:00:56 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:00: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:00: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:00:58 INFO - Installing collected packages: simplejson 03:00:58 INFO - Running setup.py install for simplejson 03:00:58 INFO - building 'simplejson._speedups' extension 03:00: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:00: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:00:59 INFO - Successfully installed simplejson 03:00:59 INFO - Cleaning up... 03:00:59 INFO - Return code: 0 03:00:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:00: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:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00: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:00:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00: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 0x1b911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f00b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efd580>, '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 0x1efe480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efe910>, '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': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', '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:00: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:00: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:00:59 INFO - Using env: {'DISPLAY': ':0', 03:00:59 INFO - 'HOME': '/home/cltbld', 03:00:59 INFO - 'LANG': 'en_US.UTF-8', 03:00:59 INFO - 'LANGUAGE': 'en_US:en', 03:00:59 INFO - 'LOGNAME': 'cltbld', 03:00:59 INFO - 'MAIL': '/var/mail/cltbld', 03:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:59 INFO - 'MOZ_NO_REMOTE': '1', 03:00:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:59 INFO - 'NO_EM_RESTART': '1', 03:00:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:00:59 INFO - 'PWD': '/builds/slave/test', 03:00:59 INFO - 'SHELL': '/bin/bash', 03:00:59 INFO - 'SHLVL': '1', 03:00:59 INFO - 'TERM': 'linux', 03:00:59 INFO - 'TMOUT': '86400', 03:00:59 INFO - 'USER': 'cltbld', 03:00:59 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', 03:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:59 INFO - '_': '/tools/buildbot/bin/python'} 03:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:00:59 INFO - Cleaning up... 03:00:59 INFO - Return code: 0 03:00:59 INFO - Running post-action listener: _resource_record_post_action 03:00:59 INFO - Running post-action listener: _start_resource_monitoring 03:00:59 INFO - Starting resource monitoring. 03:00:59 INFO - [mozharness: 2017-08-30 10:00:59.465680Z] Finished create-virtualenv step (success) 03:00:59 INFO - [mozharness: 2017-08-30 10:00:59.466153Z] Running install step. 03:00:59 INFO - Running pre-action listener: _resource_record_pre_action 03:00:59 INFO - Running main action method: install 03:00:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:00:59 INFO - Reading from file tmpfile_stdout 03:00:59 INFO - Detecting whether we're running mozinstall >=1.0... 03:00:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:00:59 INFO - Reading from file tmpfile_stdout 03:00:59 INFO - Output received: 03:00:59 INFO - Usage: mozinstall [options] installer 03:00:59 INFO - Options: 03:00:59 INFO - -h, --help show this help message and exit 03:00:59 INFO - -d DEST, --destination=DEST 03:00:59 INFO - Directory to install application into. [default: 03:00:59 INFO - "/builds/slave/test"] 03:00:59 INFO - --app=APP Application being installed. [default: firefox] 03:00:59 INFO - mkdir: /builds/slave/test/build/application 03:00: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:00: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:01:16 INFO - Reading from file tmpfile_stdout 03:01:16 INFO - Output received: 03:01:16 INFO - /builds/slave/test/build/application/firefox/firefox 03:01:16 INFO - Running post-action listener: _resource_record_post_action 03:01:16 INFO - [mozharness: 2017-08-30 10:01:16.157934Z] Finished install step (success) 03:01:16 INFO - [mozharness: 2017-08-30 10:01:16.158088Z] Running setup-mitmproxy step. 03:01:16 INFO - Running pre-action listener: _resource_record_pre_action 03:01:16 INFO - Running main action method: setup_mitmproxy 03:01:16 INFO - Skipping: mitmproxy is not required 03:01:16 INFO - Running post-action listener: _resource_record_post_action 03:01:16 INFO - [mozharness: 2017-08-30 10:01:16.159159Z] Finished setup-mitmproxy step (success) 03:01:16 INFO - [mozharness: 2017-08-30 10:01:16.159378Z] Running run-tests step. 03:01:16 INFO - Running pre-action listener: _resource_record_pre_action 03:01:16 INFO - Running pre-action listener: _set_gcov_prefix 03:01:16 INFO - Running main action method: run_tests 03:01:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:01:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:01:16 INFO - Python 2.7.3 03:01:16 INFO - Return code: 0 03:01:16 INFO - grabbing minidump binary from tooltool 03:01: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:01: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 0x1efd580>, '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 0x1efe480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1efe910>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:01: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:01: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:01: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:01:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:01:16 INFO - Return code: 0 03:01:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:01:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:01:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:01:16 INFO - ENV: RUST_BACKTRACE is now full 03:01:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:01:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:01: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/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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:01: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/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-032 --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:01:16 INFO - Using env: {'DISPLAY': ':0', 03:01:16 INFO - 'HOME': '/home/cltbld', 03:01:16 INFO - 'LANG': 'en_US.UTF-8', 03:01:16 INFO - 'LANGUAGE': 'en_US:en', 03:01:16 INFO - 'LOGNAME': 'cltbld', 03:01:16 INFO - 'MAIL': '/var/mail/cltbld', 03:01:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:01:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:16 INFO - 'MOZ_NO_REMOTE': '1', 03:01:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:01:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:16 INFO - 'NO_EM_RESTART': '1', 03:01:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:16 INFO - 'PWD': '/builds/slave/test', 03:01:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:01:16 INFO - 'RUST_BACKTRACE': 'full', 03:01:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:01:16 INFO - 'SHELL': '/bin/bash', 03:01:16 INFO - 'SHLVL': '1', 03:01:16 INFO - 'TERM': 'linux', 03:01:16 INFO - 'TMOUT': '86400', 03:01:16 INFO - 'USER': 'cltbld', 03:01:16 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', 03:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:16 INFO - '_': '/tools/buildbot/bin/python'} 03:01: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/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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:01:16 INFO - mozversion application_buildid: 20170830084509 03:01:16 INFO - mozversion application_changeset: ae9e6b6d31321f119f1e07823f93137f58771377 03:01:16 INFO - mozversion application_display_name: Nightly 03:01:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:01:16 INFO - mozversion application_name: Firefox 03:01:16 INFO - mozversion application_remotingname: firefox 03:01:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:01:16 INFO - mozversion application_vendor: Mozilla 03:01:16 INFO - mozversion application_version: 57.0a1 03:01:16 INFO - mozversion platform_buildid: 20170830084509 03:01:16 INFO - mozversion platform_changeset: ae9e6b6d31321f119f1e07823f93137f58771377 03:01:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:01:16 INFO - mozversion platform_version: 57.0a1 03:01:16 INFO - using testdate: 1504087276 03:01:16 INFO - actual date: 1504087276 03:01:16 INFO - starting webserver on 'localhost:52272' 03:01:16 INFO - SUITE-START | Running 2 tests 03:01:16 INFO - TEST-START | dromaeo_css 03:01:16 INFO - Initialising browser for dromaeo_css test... 03:01:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52272/getInfo.html -profile /tmp/tmp4fzWuT/profile 03:01:16 INFO - TEST-INFO | started process 7479 (/builds/slave/test/build/application/firefox/firefox http://localhost:52272/getInfo.html) 03:01:21 INFO - TEST-INFO | 7479: exit 0 03:01:21 INFO - Browser initialized. 03:01:21 INFO - Running cycle 1/1 for dromaeo_css test... 03:01:21 INFO - Using env: {'DISPLAY': ':0', 03:01:21 INFO - 'HOME': '/home/cltbld', 03:01:21 INFO - 'JSGC_DISABLE_POISONING': '1', 03:01:21 INFO - 'LANG': 'en_US.UTF-8', 03:01:21 INFO - 'LANGUAGE': 'en_US:en', 03:01:21 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:01:21 INFO - 'LOGNAME': 'cltbld', 03:01:21 INFO - 'MAIL': '/var/mail/cltbld', 03:01:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:01:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:01:21 INFO - 'MOZ_CRASHREPORTER': '1', 03:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:21 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:01:21 INFO - 'MOZ_NO_REMOTE': '1', 03:01:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:01:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:21 INFO - 'NO_EM_RESTART': '1', 03:01:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:01:21 INFO - 'PWD': '/builds/slave/test', 03:01:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:01:21 INFO - 'RUST_BACKTRACE': 'full', 03:01:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:01:21 INFO - 'SHELL': '/bin/bash', 03:01:21 INFO - 'SHLVL': '1', 03:01:21 INFO - 'TERM': 'linux', 03:01:21 INFO - 'TMOUT': '86400', 03:01:21 INFO - 'USER': 'cltbld', 03:01:21 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', 03:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:21 INFO - '_': '/tools/buildbot/bin/python'} 03:01:21 INFO - TEST-INFO | started process 7665 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4fzWuT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:01:22 INFO - PID 7665 | 03:01:22 INFO - PID 7665 | (/builds/slave/test/build/application/firefox/firefox:7717): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:01:22 INFO - PID 7665 | 03:01:33 INFO - PID 7665 | RSS: Main: 163495936 03:01:33 INFO - PID 7665 | 03:04:32 INFO - PID 7665 | Cycle 1(1): loaded http://localhost:52272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52272/tests/dromaeo/cssquery-ext.html) 03:04:33 INFO - PID 7665 | RSS: Main: 165744640 03:04:33 INFO - PID 7665 | 03:07:34 INFO - PID 7665 | Cycle 1(1): loaded http://localhost:52272/tests/dromaeo/cssquery-ext.html (next: http://localhost:52272/tests/dromaeo/cssquery-jquery.html) 03:07:35 INFO - PID 7665 | RSS: Main: 162123776 03:07:35 INFO - PID 7665 | 03:10:37 INFO - PID 7665 | Cycle 1(1): loaded http://localhost:52272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52272/tests/dromaeo/cssquery-mootools.html) 03:10:37 INFO - PID 7665 | RSS: Main: 164352000 03:10:37 INFO - PID 7665 | 03:13:39 INFO - PID 7665 | Cycle 1(1): loaded http://localhost:52272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52272/tests/dromaeo/cssquery-prototype.html) 03:13:39 INFO - PID 7665 | RSS: Main: 162934784 03:13:39 INFO - PID 7665 | 03:16:43 INFO - PID 7665 | Cycle 1(1): loaded http://localhost:52272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52272/tests/dromaeo/cssquery-yui.html) 03:16:43 INFO - PID 7665 | RSS: Main: 164007936 03:16:43 INFO - PID 7665 | 03:19:45 INFO - PID 7665 | Cycle 1(1): loaded http://localhost:52272/tests/dromaeo/cssquery-yui.html (next: http://localhost:52272/tests/dromaeo/cssquery-dojo.html) 03:19:45 INFO - PID 7665 | RSS: Main: 166617088 03:19:45 INFO - PID 7665 | 03:19:45 INFO - PID 7665 | __start_tp_report 03:19:45 INFO - PID 7665 | _x_x_mozilla_page_load 03:19:45 INFO - PID 7665 | _x_x_mozilla_page_load_details 03:19:45 INFO - PID 7665 | |i|pagename|runs| 03:19:45 INFO - PID 7665 | |0;dojo.html;3884.4950156479654;3916.334223182059;3919.216156768647;3919.3924941634054;3939.7833119178454;6530.836729081763;6604.40560349569;6632.568883022606;6651.135352404187;6663.333666633334;6734.090897728805;6782.8304292392595;6790.898136527956;6791.219009813873;6811.591304521732;6061.090836374557;6069.1199776032445;6079.4528492435675;6107.175531303284;6169.352218017111;6021.187139736124;6117.938820611781;6137.2635283765785;6204.968975155118;6231.096491008801;1100.2698775171275;6054.909176362358;6119.877602447948;6185.721642526097;6233.750649973996;6081.6351018939;6083.8174854754425;6084.4219799119455;6174.660393678346;6190.380961903818;5988.101784732282;6058.091286307045;6151.538634602378;6157.2919114301985;6160.260768707739;6798.490113241527;6841.726330946756;6904.792856214322;6919.550229235084;6934.029235906977;7317.158526769436;7324.414046876291;7336.082989626297;7427.922951172104;7453.962730186368;7262.419006479522;7310.553637540013;7375.59434231117;7422.294881986203;7492.02603661526;6819.454443644497;6825.55633883796;6874.8625027499675;6893.310668933066;6912.688928998157;7443.925560744323;7477.214892436325;7518.699252029857;7536.811579710551;7587.620618969029;7205.747798827016;7208.35124838767;7269.454790890704;7297.270272972766;7318.524295920748;7322.743703970335;7344.96327518359;7378.114626244885;7402.666879990414;7436.0333156689285;7161.713531458683;7251.56490610565;7265.564066156047;7270.563766174047;7300.160481544584;7237.240089790602;7239.082457033897;7268.818279542948;7282.089738782653;7309.597972111585;6072.939270607326;6074.3925607438905;6084.5132389408745;6119.96940015306;6169.598976066542;5254.185601231815;5326.840194794162;5329;5333.306670132858;5365.865853353619;6146.0473626587955;6183.072539119168;6216.067589861466;6246.625202487865;6263.3736626337;7036.859262814715;7271.163816161209;7306.707731690779;7319.890201646979;7362.300581444754;6192.566520343533;6210.378962103753;6243.43809057187;6326.588771729823;6332.33510481402;6545.083688283644;6548.738050478023;6590.373914478117;6606.471482281406;6644.634545100066;6892.414144797748;6911.274316196827;6951.582905025714;6996.720131194696;7004.089468369181;7061.540999834994;7087.681054352463;7099.148102227766;7198.172210195895;7199.964000179965;6883.862322753416;6973.267806880204;6995.125609298838;7059.788206353817;7064.576125432491;7222.169450513258;7241.384482318851;7278.381337586365;7279.272072792678;7302.342789149002;7224.819379515554;7262.818429539304;7345.522541034816;7382.4094072473;7383.483156179016;6945.0971373721095;6965.547239429624;6987.965060174666;7076.681549330396;7102.254263302279;6872.725090996304;6941.965290173719;6993.160820701549;7024;7039.120109986252;6994.510384273052;7032.296770322927;7061.046758687511;7080.822979425556;7081.114860642419;6974.721011159497;7006.649667516705;7052.823679408056;7112.679929403086;7113.964430177816;6753.054572359775;6848.897266541106;6858.108445902001;6904.27505111956;6954.513184077066 03:19:45 INFO - PID 7665 | |1;ext.html;890.3722875372863;935.4621092871596;963.4508330251757;996.063700121885;997.8852431970325;40490.79754601227;40780.59219407809;41360.58639413605;41613.58386416135;41614.99999999993;38777.41833872494;39173.21653566927;39234.80382598083;39354;39379.21241575167;7308.086489188851;7462.440316976222;7464.738734144306;7466.775996720107;7467.327940485356;7273.12722473304;7341.926580734178;7342.596157211351;7351.742689005886;7352.595607241599;7233.565986040854;7997.720079797208;8179.059408168047;8188.754337369859;8200.05699344577;8078.23256790604;8091.352691784673;8123.837523249561;8151.307138893201;8156.388270879678;7735.303822655988;7928.207179282083;7936.047674279066;7938.6427610757655;7945.960270198612;37854.24291514182;38233.235335293415;38262.80868595667;38356.424653630216;38374.23251534982;3491.633378495272;3507.053095664182;3525.0306165804354;3527;3527.5414196154334;31092.689073109206;31204;31328;31378.843105784326;31535.526967095513;5369.275147855029;5396.514313711785;5400.945990540084;5406.432324605899;5411.18832175173;192905;196363;202672;216161;220141;51356.74321628368;51629.741851291255;51644.74177629088;51657.45027649176;51718;53169.202461963105;53257.733711331195;53565.1965220522;53638.463615364126;53661.46338536565;15786.289616967264;15871.444499442465;15951.361945522049;15976.440824571317;16002.599935001717;13009;13017.544385946636;13017.609471715863;13019.479220831061;13063.934680326727;14748;14851.2574371281;14891.255437228096;14940.850591494163;15036.774448383283;5944.554158438048;5976.61152025117;6013.278406591237;6026.54800889935;6140.938590614126;1063.4682658670665;1071.400015991032;1104.8011357955645;1110.722319420145;1116.6761639124725;24980.515198145476;25322.493550128893;25582.616260756102;25827.48345033089;25918.611220831703;1188.3879801902028;1191.4995701805262;1192.469351138735;1212.2908098762146;1226.779179747654;13451.32743362828;13605.659858503617;13634.86365136336;17466.65066698659;17930.462086137435;306293;308807;308853;308914;309193;59253.40746592565;59587;59649.40350596525;59710.402895971354;59813.70093149506;1444.8049513315777;1456.5921541968055;1485.131198249015;1498.3107770425727;1515.5301856424544;30304.39391212119;30673.846630766704;30939.226519337197;31183;32233.67766322307;57984.42015579959;57990.42009580019;58149.4185058144;58196.709016454646;58434.4156558446;244547;246033;246556;247011;247563;39885.80057099696;40953.59046409498;41743.7912810434;41999.95000125021;42090;49837;51005.489945100075;51049.744751276005;51282.48717512777;51533.48466515437;37175.62824371722;37197;37212;37580.624193757714;37722.24555508819;36864.26271474609;37393.81303093576;37525.4371184427;37720;38284.808575956944;44000.33999490074;44009.339859902764;44146;44194.558054419045;44419;35814.227747906676;36057.45913811243;36178.63821361752;36437.63562364342;37216.76699397936;27045.188644339913;27476.313092172855;28327.858360708065;35476.64523354734;35499.645003550664 03:19:45 INFO - PID 7665 | |2;jquery.html;601.038338658147;606.8179546136158;611.7644706787888;619.6220305613572;620.2804746494066;22889.084436622514;23632.88183559082;24455.75544244557;24541.877290613545;24591.63112553313;27581.31046723833;27825.304367390825;27910.581341279896;27923;28033.85983070087;6680.498962577802;6803.693833777492;6806.931930680704;6817.420519255845;6821.897671534931;6487.286398496161;6725.32746725326;6730.226024007253;6738.22510411304;6795.558288711242;6643.069970204176;6680.866382672343;6686.598804071771;6689.464842812587;6691.130153080093;6688.1305430294;6708.329167083277;6709.798706038827;6712.496562757764;6714.563553369016;6218.751249950042;6224.9688751556405;6262.561620686553;6271.310155882895;6280.15217945576;27550.5867411989;30453.847730761427;30985;31006.0698179055;31222.06333809989;2309.4919117794057;2339.263132113379;2342.320726989171;2343.109618345018;2345.3433038749176;20961.475963100893;21134;21272.46818829526;21355.89322053395;21374.893125534272;2416.685830841997;2436.2204094689705;2492.0156538167503;2573.5496288149498;2592.675915510561;142977;150517;150806;151415;151556.2422187893;15742;15784.447544335893;15954.361825527081;16309.51071467858;16867.57831054209;24541.263762087165;25367.36581585475;25398.365040873756;25599.872000639876;26292.605610915853;10297.794044119075;10358.171346292278;10540.209484288709;10593.364398136137;10681.946590266998;9432.151106400457;9500.334976551714;9507.524623768784;9627.133557979676;9640.855387169198;9671.661491847752;9744.512774361254;9890.456024918554;9945.651902183534;10000.849987250198;2858.998052045344;2883.865939204973;2949.6460424749166;3005.669376368641;3009.80436271646;1049.9895001050045;1062.946852657364;1064.7976884392097;1065.5045403887075;1070.2936062198912;20551.280705175246;21756.89121554382;27145.592816107775;27553.586696199574;28367;1109.367660433545;1111.777644471109;1131.7057565033151;1138.7153211697075;1140.5779861451317;11680.766384672259;12322.876771232352;12501;12571.434285457177;12675;662286;715558;720722;742252;761709;148449;150194.24902875416;150708.99999999782;151742;153471.23264383606;1286.620446968128;1288.9677758055936;1346.7575836349356;1348.0967751606447;1350.6150747037047;22563.210287639853;24203.75796242015;25700.8714956424;26067.348316292246;26227.868860656337;66139.33860661332;66240.33759662535;66757;67558.66220668865;69229;86826.1317386818;87197;87286.12713873034;87465;89234.10765892518;21922.89038554797;22799.544009120058;22873.771262287166;22910;22914.885425572764;26065;26170.084047058263;26307.868460657573;26368.604470933336;26434.735652643227;20460.283890063776;21102.366928992156;21200.893995529925;21343.35969920905;21514;17940.641187175923;17963.820361796737;18368.265269389074;18605.813941860408;18695.25218991225;20941.952902355126;21276.148954041666;21533.24633637815;21830.345089647337;21932;20753.688694669894;21405.35783926485;21522.569548608626;21537.24619638305;21558.245461408776;20037;20328.085236164112;20397.286094986604;20399.592008159456;20472.59054818925 03:19:45 INFO - PID 7665 | |3;mootools.html;1424.9928750356248;1489.9644746900908;1494.618872187592;1504.5185540626996;1512.8108986376706;16282.592935176626;16545.25546350414;16920.830791692093;16934;16978.15109244539;16393.508194754177;17599.560010999732;17642.64714705705;17644.206010729515;17689.55776105595;2584.0826506590124;2610.3082683088996;2611.373270415096;2615.869206539675;2622.645942797717;2721.8502982336063;2797.692253852075;2797.9860100699466;2800.2579316481147;2800.495910736067;2755.2285360099204;2779.7915156363356;2790.8185967912023;2796.8881244750187;2799.6220510231165;2812.367217376084;2833.957490637642;2866.9999999999895;2867.584200290967;2869.6412948381453;2787.6515435570554;2809.395979864339;2811.0723461257735;2820.4641118187683;2820.703826098251;15990.280437380346;16385.42651007209;16403.67192656152;16419.261133249034;16425.09661968591;8022.318102961255;8243.546604936726;8340.29107525861;8340.499570025819;8341.249287564087;5305.416404195574;5624.3250809902665;5693.77224911003;5720.856978575527;5766.8374359615145;2091.968620470694;2115.3019503563787;2117.587070521249;2170.305502239284;2195.8133558647532;29229.85385073061;29429.852850735824;29452.96914608;29498.70501294981;29548.55677164823;6779.457643388517;6849.691763870638;6935.682220378112;7018.227994920555;7059.2351912021995;19116.904415477835;19165.233390664496;19166.137523811463;19169.233230670896;19226.230950762092;7942.960285198537;8096.959515202387;8204.097549269576;8469.576521173916;8490.066092729794;6311.779644461881;6890.655467226718;6892.966055091676;7395.112586489656;7848.215178482106;5584;5738.053221218535;5787.045137552341;5789.536837053026;5872.2366092408565;1662.2602941690832;1669.0653234188894;1690.8309169082993;1701.2259421963224;1704.4545745361365;968.0658164870933;981.0532835813332;992.7468495533593;1000.9049140331657;1018.7758693087509;8445.155484451505;8575.485470871768;8650.87023694645;8712.956435217784;8791.648334066565;731.4185222747928;733.2374330696016;734.963251837406;740.7777666699968;742.413493340266;5056.620753443506;5219;5329.706866122323;5393.487618676219;5403.756830942617;23720.288391348287;24083.759162408503;24628.507429851303;24639.630405543932;25158.622620660706;23914.40213994629;24005.759942400702;24070;24437.755622443903;24614.138505152234;12001.699957501132;12186.695332616755;12261.93869030667;12321.815172772416;12338.568150114703;9739.610415583298;9780.755481113029;9787.902120978699;9831.410115393099;9860.605575776888;20281.49296267605;20314.59370812605;20563.280285189947;21051.578968420854;21413.143474260854;21432.678509822053;21466.356009319745;21641.13435462627;21700;21790.673139902596;13579.185248885098;13720.794188087386;13774.793378099537;13835.515756948458;13836.446542138201;15346.23268836576;15556.68886622284;15632;15636.218189090727;15709.764353534478;11830.112741544246;11833.65838816048;11990.760184796081;12080.275183489019;12160.695982600506;11764.35296058725;12133.332666703063;12168.148229623841;12230.633081007583;12323;11000.724981875517;11251.268667536584;11256.662300131009;11268.211225214156;11270;8354.540500272544;8454.281386082252;8487.193716596914;8487.925795618014;8553.82892342162;8162.387820913336;8290.46112002718;8299.585020748818;8307.543085130133;8357.540335281617 03:19:45 INFO - PID 7665 | |4;prototype.html;545.1740612971348;557.4898967444789;567.5005994724642;568.579251353998;572.2674976030681;6555.7049932753025;6565.212174539056;6609;6655.835228834952;6679.265280819106;6426.421622054014;6444.838879028027;6451.451626611743;6540.640264785435;6581.83545411365;5290.153575427923;5336.4930331618625;5401.297831281928;5422.105352616813;5458.563314934816;5159.832870194508;5231.110711179109;5319.946800532003;5422.2951016367815;5426.8914621707545;5205.646531901714;5226.163813789794;5243.370795504545;5250.527452529272;5250.894982100355;5255.264263003182;5264.447233040514;5271.472852714736;5278.155495120801;5292.655977361459;4622.1911165546235;4720.59874910633;4727.172744769686;4731.840699028726;4732.627538013971;6441.194850643669;6447.291467761001;6478.319776423455;6487.545871788977;6529.020646902955;6089.695515224221;6149;6156.722947467375;6197.504199664061;6209.130721698965;6975.511714180009;6979.930200697979;7039.472039597;7048.612326322051;7050.189228238767;5247.842564723064;5288.8413347599635;5303.284056652341;5308.601854860901;5311.7344132793205;7084.43324534036;7094.503384763068;7107.324804143598;7161.785146445615;7298.452616053817;5408.864778380494;5414.83755487336;5436;5467.917981230285;5485.122380419114;5480.862978425492;5523.392426833045;5524.917126243109;5548.9722551386985;5559.666420014732;6019.819405417845;6023.698815059317;6132.424381759672;6134.4172303631085;6159.692015399212;7036.120484939383;7055.3650171484815;7073.257307982691;7092.368755186233;7125.608091555015;1885.8779026479224;1920.203115706994;1922.144645632708;1933.2073849721828;1935.8868650526003;1265.5001274496522;1314.9737005259842;1320.2870449956915;1335.732853429318;1347.7169794343101;6073.423024812636;6073.787417440369;6076.75692972285;6094.207752992172;6115.602485838495;6910.378065973986;6986.240435028136;7055.89416158758;7068.3991860691385;7088.964555177191;6051.8487037823525;6088.969555152285;6112.17485639433;6128.448439640454;6183.505319574423;3763.717721170923;3770.8680196193;3791.526059242595;3804.2391521695217;3833.5399752029934;6961.26906674802;6999.300069993062;7007.614581198083;7009.579425234502;7039.768040592876;7320.1569176181865;7327.157376901724;7350.963245183847;7356.742514012091;7371.299726525972;6682.632455214913;6971.5468494547695;7007.369336759716;7029.29707029293;7038.8944165838575;7009.264027277062;7113.181984071898;7119.786406407816;7137.500374973702;7148.392386647193;7097.25478824716;7128.750493732695;7155;7171.820704482429;7200.207977122408;6927.965360173167;6949.339812717783;7027.121609798775;7036.859262814817;7052.717891284497;5388.946110538845;5399.541039011571;5403.270558474713;5418.512333889811;5427.592930530305;6754.324567543403;7084.681189346595;7147.428205743424;7162.964185179041;7193.712251509881;6733.192016957996;6762.560433571802;6802.285759995327;6885.173779146535;6912.55068420551;6708.396244338033;6737.562058466184;6756.898646520208;6792.592444453348;6930.549514281555;6628.237752658313;6722.899156512558;6752.189737231563;6853.246142924175;6893.345132212432;6783.050372947692;6849.1438570178725;6889.517733758589;6905.654717264017;6987.161540615159;6672.733090676318;6675.83310417224;6707.496937729592;6798.864022719419;6799.388055075067 03:19:45 INFO - PID 7665 | |5;yui.html;968.8207681578908;983.9704808855734;1011.7875246198298;1011.9241056920732;1017.844746213048;18976.430707078776;19606.31377901774;20112.4971875703;20120.69818952715;20132.899335503338;20213.090410931505;20527.794722052775;20567.074481648324;20611.793882061174;20630;1050.4852622215099;1059.9947000264988;1064.925455218135;1064.9574017039308;1066.7919755647652;1089;1089.3246187363845;1089.5587287148717;1092;1093.0872721277742;1058.973525661857;1099.5381939585393;1106.7233191702076;1106.8118419868601;1106.9225154239207;1090.8909109089107;1091.983620245693;1092.1099304067159;1092.1208427216088;1092.30638544524;804.4368941740805;805.1224165659401;805.2108933245453;805.5005896344245;805.830775537132;20554.28060017907;21133.3659990199;21310.786892131036;21522.461938451506;21547.892260538596;23181.652275215718;23409.41476463085;23663;23773.881130594236;23963.640545391834;17330.913345433193;17575.2091155897;17580.736288955675;17716.20277087534;17895.37366192177;4057.6145266199665;4081.10215752534;4083.775392353419;4087.632113109805;4088.2845502037026;145811;147420;147487.26256368757;148545;149694;17501.649967000587;17765.200565974563;17878.999999999738;17982.37061702834;18076.186571604045;33741.493877591856;33935;34000;34029.31941361159;34260.14349641229;8477.109903460048;8852.601632926406;9036.728898133066;9065.68270110543;9263.907360926305;4450.354698568754;4556.635469162459;4569.611583015414;4569.81720731167;4581.656375771764;6109.266887973383;6188.350223226496;6224.1597384352535;6262.624242545462;6277.497800175975;1272.7963525836008;1301.0177316126453;1314.5596225264453;1316.2892038299403;1318.0575888239955;744.244591739385;763.2634507699985;770.8573913825848;779.3064172886136;786.7010535996285;12532.937335313265;13104.344782761014;13169.209847409185;13188.406521706545;13541.932290338485;757.3524636435878;772.97681069568;773;781.0276206123299;788.0661416221795;6304.653244071528;6391.360863913572;6423.678816059271;6437.581557198767;6465.094886715863;136115;136146;136210.31894840463;137226;143620.5637943628;115679.42160289313;117609;117682;119930;120929;101601.49199253957;102266;103613.48193258986;104548;104839.4758026205;11668.474918628512;11779;11825.349605771766;12096.697582560506;12164.695882603006;52733.47266527286;53380;54203.18695219496;54275.45724542704;55048.17427738507;66598.66700666466;68939.65530172316;69493;69495.65252173707;69795;16764.66470670604;17065.146742663066;17074.48776536706;19614.705779413034;20537.589248214652;22846.885765571067;23461;24566.754332456447;24598.754012460362;24668.753312466644;21795.12819487266;21909.78090219141;22176.88911555432;22249.666255006512;22452.663210051534;19752.604947900672;19854.90072549628;20309.49226269355;21120.260790872246;21154.365369038955;21611;21635.242766503095;21868.343949680897;22043;22223.888880555493;14883.851161488246;15351.462698805488;15621.297041633381;15789.921050394674;16072.196390180678;15270.618234543781;15584.220788960733;15608.453704120302;15684.92157539205;16560.585985350463 03:19:45 INFO - PID 7665 | __end_tp_report 03:19:45 INFO - PID 7665 | __start_cc_report 03:19:45 INFO - PID 7665 | _x_x_mozilla_cycle_collect,344 03:19:45 INFO - PID 7665 | __end_cc_report 03:19:45 INFO - PID 7665 | __startTimestamp1504088385450__endTimestamp 03:19:45 INFO - PID 7665 | 03:19:45 INFO - PID 7665 | ------- Summary: start ------- 03:19:45 INFO - PID 7665 | Number of tests: 6 03:19:45 INFO - PID 7665 | 03:19:45 INFO - PID 7665 | [#0] dojo.html Cycles:165 Average:6679.88 Median:6934.03 stddev:841.83 (12.1%) stddev-sans-first:815.35 03:19:45 INFO - PID 7665 | Values: 3884.5 3916.3 3919.2 3919.4 3939.8 6530.8 6604.4 6632.6 6651.1 6663.3 6734.1 6782.8 6790.9 6791.2 6811.6 6061.1 6069.1 6079.5 6107.2 6169.4 6021.2 6117.9 6137.3 6205.0 6231.1 1100.3 6054.9 6119.9 6185.7 6233.8 6081.6 6083.8 6084.4 6174.7 6190.4 5988.1 6058.1 6151.5 6157.3 6160.3 6798.5 6841.7 6904.8 6919.6 6934.0 7317.2 7324.4 7336.1 7427.9 7454.0 7262.4 7310.6 7375.6 7422.3 7492.0 6819.5 6825.6 6874.9 6893.3 6912.7 7443.9 7477.2 7518.7 7536.8 7587.6 7205.7 7208.4 7269.5 7297.3 7318.5 7322.7 7345.0 7378.1 7402.7 7436.0 7161.7 7251.6 7265.6 7270.6 7300.2 7237.2 7239.1 7268.8 7282.1 7309.6 6072.9 6074.4 6084.5 6120.0 6169.6 5254.2 5326.8 5329.0 5333.3 5365.9 6146.0 6183.1 6216.1 6246.6 6263.4 7036.9 7271.2 7306.7 7319.9 7362.3 6192.6 6210.4 6243.4 6326.6 6332.3 6545.1 6548.7 6590.4 6606.5 6644.6 6892.4 6911.3 6951.6 6996.7 7004.1 7061.5 7087.7 7099.1 7198.2 7200.0 6883.9 6973.3 6995.1 7059.8 7064.6 7222.2 7241.4 7278.4 7279.3 7302.3 7224.8 7262.8 7345.5 7382.4 7383.5 6945.1 6965.5 6988.0 7076.7 7102.3 6872.7 6942.0 6993.2 7024.0 7039.1 6994.5 7032.3 7061.0 7080.8 7081.1 6974.7 7006.6 7052.8 7112.7 7114.0 6753.1 6848.9 6858.1 6904.3 6954.5 03:19:45 INFO - PID 7665 | 03:19:45 INFO - PID 7665 | [#1] ext.html Cycles:180 Average:44045.10 Median:38248.02 stddev:67003.99 (175.2%) stddev-sans-first:67113.61 03:19:45 INFO - PID 7665 | Values: 890.4 935.5 963.5 996.1 997.9 40490.8 40780.6 41360.6 41613.6 41615.0 38777.4 39173.2 39234.8 39354.0 39379.2 7308.1 7462.4 7464.7 7466.8 7467.3 7273.1 7341.9 7342.6 7351.7 7352.6 7233.6 7997.7 8179.1 8188.8 8200.1 8078.2 8091.4 8123.8 8151.3 8156.4 7735.3 7928.2 7936.0 7938.6 7946.0 37854.2 38233.2 38262.8 38356.4 38374.2 3491.6 3507.1 3525.0 3527.0 3527.5 31092.7 31204.0 31328.0 31378.8 31535.5 5369.3 5396.5 5400.9 5406.4 5411.2 192905.0 196363.0 202672.0 216161.0 220141.0 51356.7 51629.7 51644.7 51657.5 51718.0 53169.2 53257.7 53565.2 53638.5 53661.5 15786.3 15871.4 15951.4 15976.4 16002.6 13009.0 13017.5 13017.6 13019.5 13063.9 14748.0 14851.3 14891.3 14940.9 15036.8 5944.6 5976.6 6013.3 6026.5 6140.9 1063.5 1071.4 1104.8 1110.7 1116.7 24980.5 25322.5 25582.6 25827.5 25918.6 1188.4 1191.5 1192.5 1212.3 1226.8 13451.3 13605.7 13634.9 17466.7 17930.5 306293.0 308807.0 308853.0 308914.0 309193.0 59253.4 59587.0 59649.4 59710.4 59813.7 1444.8 1456.6 1485.1 1498.3 1515.5 30304.4 30673.8 30939.2 31183.0 32233.7 57984.4 57990.4 58149.4 58196.7 58434.4 244547.0 246033.0 246556.0 247011.0 247563.0 39885.8 40953.6 41743.8 42000.0 42090.0 49837.0 51005.5 51049.7 51282.5 51533.5 37175.6 37197.0 37212.0 37580.6 37722.2 36864.3 37393.8 37525.4 37720.0 38284.8 44000.3 44009.3 44146.0 44194.6 44419.0 35814.2 36057.5 36178.6 36437.6 37216.8 27045.2 27476.3 28327.9 35476.6 35499.6 03:19:45 INFO - PID 7665 | 03:19:45 INFO - PID 7665 | [#2] jquery.html Cycles:180 Average:44706.54 Median:24541.57 stddev:119882.77 (488.5%) stddev-sans-first:120173.33 03:19:45 INFO - PID 7665 | Values: 601.0 606.8 611.8 619.6 620.3 22889.1 23632.9 24455.8 24541.9 24591.6 27581.3 27825.3 27910.6 27923.0 28033.9 6680.5 6803.7 6806.9 6817.4 6821.9 6487.3 6725.3 6730.2 6738.2 6795.6 6643.1 6680.9 6686.6 6689.5 6691.1 6688.1 6708.3 6709.8 6712.5 6714.6 6218.8 6225.0 6262.6 6271.3 6280.2 27550.6 30453.8 30985.0 31006.1 31222.1 2309.5 2339.3 2342.3 2343.1 2345.3 20961.5 21134.0 21272.5 21355.9 21374.9 2416.7 2436.2 2492.0 2573.5 2592.7 142977.0 150517.0 150806.0 151415.0 151556.2 15742.0 15784.4 15954.4 16309.5 16867.6 24541.3 25367.4 25398.4 25599.9 26292.6 10297.8 10358.2 10540.2 10593.4 10681.9 9432.2 9500.3 9507.5 9627.1 9640.9 9671.7 9744.5 9890.5 9945.7 10000.8 2859.0 2883.9 2949.6 3005.7 3009.8 1050.0 1062.9 1064.8 1065.5 1070.3 20551.3 21756.9 27145.6 27553.6 28367.0 1109.4 1111.8 1131.7 1138.7 1140.6 11680.8 12322.9 12501.0 12571.4 12675.0 662286.0 715558.0 720722.0 742252.0 761709.0 148449.0 150194.2 150709.0 151742.0 153471.2 1286.6 1289.0 1346.8 1348.1 1350.6 22563.2 24203.8 25700.9 26067.3 26227.9 66139.3 66240.3 66757.0 67558.7 69229.0 86826.1 87197.0 87286.1 87465.0 89234.1 21922.9 22799.5 22873.8 22910.0 22914.9 26065.0 26170.1 26307.9 26368.6 26434.7 20460.3 21102.4 21200.9 21343.4 21514.0 17940.6 17963.8 18368.3 18605.8 18695.3 20942.0 21276.1 21533.2 21830.3 21932.0 20753.7 21405.4 21522.6 21537.2 21558.2 20037.0 20328.1 20397.3 20399.6 20472.6 03:19:45 INFO - PID 7665 | 03:19:45 INFO - PID 7665 | [#3] mootools.html Cycles:180 Average:10280.83 Median:2619.26 stddev:7554.88 (288.4%) stddev-sans-first:7546.77 03:19:45 INFO - PID 7665 | Values: 1425.0 1490.0 1494.6 1504.5 1512.8 16282.6 16545.3 16920.8 16934.0 16978.2 16393.5 17599.6 17642.6 17644.2 17689.6 2584.1 2610.3 2611.4 2615.9 2622.6 2721.9 2797.7 2798.0 2800.3 2800.5 2755.2 2779.8 2790.8 2796.9 2799.6 2812.4 2834.0 2867.0 2867.6 2869.6 2787.7 2809.4 2811.1 2820.5 2820.7 15990.3 16385.4 16403.7 16419.3 16425.1 8022.3 8243.5 8340.3 8340.5 8341.2 5305.4 5624.3 5693.8 5720.9 5766.8 2092.0 2115.3 2117.6 2170.3 2195.8 29229.9 29429.9 29453.0 29498.7 29548.6 6779.5 6849.7 6935.7 7018.2 7059.2 19116.9 19165.2 19166.1 19169.2 19226.2 7943.0 8097.0 8204.1 8469.6 8490.1 6311.8 6890.7 6893.0 7395.1 7848.2 5584.0 5738.1 5787.0 5789.5 5872.2 1662.3 1669.1 1690.8 1701.2 1704.5 968.1 981.1 992.7 1000.9 1018.8 8445.2 8575.5 8650.9 8713.0 8791.6 731.4 733.2 735.0 740.8 742.4 5056.6 5219.0 5329.7 5393.5 5403.8 23720.3 24083.8 24628.5 24639.6 25158.6 23914.4 24005.8 24070.0 24437.8 24614.1 12001.7 12186.7 12261.9 12321.8 12338.6 9739.6 9780.8 9787.9 9831.4 9860.6 20281.5 20314.6 20563.3 21051.6 21413.1 21432.7 21466.4 21641.1 21700.0 21790.7 13579.2 13720.8 13774.8 13835.5 13836.4 15346.2 15556.7 15632.0 15636.2 15709.8 11830.1 11833.7 11990.8 12080.3 12160.7 11764.4 12133.3 12168.1 12230.6 12323.0 11000.7 11251.3 11256.7 11268.2 11270.0 8354.5 8454.3 8487.2 8487.9 8553.8 8162.4 8290.5 8299.6 8307.5 8357.5 03:19:45 INFO - PID 7665 | 03:19:45 INFO - PID 7665 | [#4] prototype.html Cycles:180 Average:5863.20 Median:6482.93 stddev:1625.19 (25.1%) stddev-sans-first:1579.97 03:19:45 INFO - PID 7665 | Values: 545.2 557.5 567.5 568.6 572.3 6555.7 6565.2 6609.0 6655.8 6679.3 6426.4 6444.8 6451.5 6540.6 6581.8 5290.2 5336.5 5401.3 5422.1 5458.6 5159.8 5231.1 5319.9 5422.3 5426.9 5205.6 5226.2 5243.4 5250.5 5250.9 5255.3 5264.4 5271.5 5278.2 5292.7 4622.2 4720.6 4727.2 4731.8 4732.6 6441.2 6447.3 6478.3 6487.5 6529.0 6089.7 6149.0 6156.7 6197.5 6209.1 6975.5 6979.9 7039.5 7048.6 7050.2 5247.8 5288.8 5303.3 5308.6 5311.7 7084.4 7094.5 7107.3 7161.8 7298.5 5408.9 5414.8 5436.0 5467.9 5485.1 5480.9 5523.4 5524.9 5549.0 5559.7 6019.8 6023.7 6132.4 6134.4 6159.7 7036.1 7055.4 7073.3 7092.4 7125.6 1885.9 1920.2 1922.1 1933.2 1935.9 1265.5 1315.0 1320.3 1335.7 1347.7 6073.4 6073.8 6076.8 6094.2 6115.6 6910.4 6986.2 7055.9 7068.4 7089.0 6051.8 6089.0 6112.2 6128.4 6183.5 3763.7 3770.9 3791.5 3804.2 3833.5 6961.3 6999.3 7007.6 7009.6 7039.8 7320.2 7327.2 7351.0 7356.7 7371.3 6682.6 6971.5 7007.4 7029.3 7038.9 7009.3 7113.2 7119.8 7137.5 7148.4 7097.3 7128.8 7155.0 7171.8 7200.2 6928.0 6949.3 7027.1 7036.9 7052.7 5388.9 5399.5 5403.3 5418.5 5427.6 6754.3 7084.7 7147.4 7163.0 7193.7 6733.2 6762.6 6802.3 6885.2 6912.6 6708.4 6737.6 6756.9 6792.6 6930.5 6628.2 6722.9 6752.2 6853.2 6893.3 6783.1 6849.1 6889.5 6905.7 6987.2 6672.7 6675.8 6707.5 6798.9 6799.4 03:19:45 INFO - PID 7665 | 03:19:45 INFO - PID 7665 | [#5] yui.html Cycles:180 Average:27413.79 Median:20418.64 stddev:38501.97 (188.6%) stddev-sans-first:38558.77 03:19:45 INFO - PID 7665 | Values: 968.8 984.0 1011.8 1011.9 1017.8 18976.4 19606.3 20112.5 20120.7 20132.9 20213.1 20527.8 20567.1 20611.8 20630.0 1050.5 1060.0 1064.9 1065.0 1066.8 1089.0 1089.3 1089.6 1092.0 1093.1 1059.0 1099.5 1106.7 1106.8 1106.9 1090.9 1092.0 1092.1 1092.1 1092.3 804.4 805.1 805.2 805.5 805.8 20554.3 21133.4 21310.8 21522.5 21547.9 23181.7 23409.4 23663.0 23773.9 23963.6 17330.9 17575.2 17580.7 17716.2 17895.4 4057.6 4081.1 4083.8 4087.6 4088.3 145811.0 147420.0 147487.3 148545.0 149694.0 17501.6 17765.2 17879.0 17982.4 18076.2 33741.5 33935.0 34000.0 34029.3 34260.1 8477.1 8852.6 9036.7 9065.7 9263.9 4450.4 4556.6 4569.6 4569.8 4581.7 6109.3 6188.4 6224.2 6262.6 6277.5 1272.8 1301.0 1314.6 1316.3 1318.1 744.2 763.3 770.9 779.3 786.7 12532.9 13104.3 13169.2 13188.4 13541.9 757.4 773.0 773.0 781.0 788.1 6304.7 6391.4 6423.7 6437.6 6465.1 136115.0 136146.0 136210.3 137226.0 143620.6 115679.4 117609.0 117682.0 119930.0 120929.0 101601.5 102266.0 103613.5 104548.0 104839.5 11668.5 11779.0 11825.3 12096.7 12164.7 52733.5 53380.0 54203.2 54275.5 55048.2 66598.7 68939.7 69493.0 69495.7 69795.0 16764.7 17065.1 17074.5 19614.7 20537.6 22846.9 23461.0 24566.8 24598.8 24668.8 21795.1 21909.8 22176.9 22249.7 22452.7 19752.6 19854.9 20309.5 21120.3 21154.4 21611.0 21635.2 21868.3 22043.0 22223.9 14883.9 15351.5 15621.3 15789.9 16072.2 15270.6 15584.2 15608.5 15684.9 16560.6 03:19:45 INFO - PID 7665 | -------- Summary: end -------- 03:19:45 INFO - PID 7665 | 03:19:46 INFO - TEST-INFO | 7665: exit 0 03:19:46 INFO - TEST-OK | dromaeo_css | took 1109629ms 03:19:46 INFO - TEST-START | kraken 03:19:46 INFO - Initialising browser for kraken test... 03:19:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52272/getInfo.html -profile /tmp/tmpoY8OTc/profile 03:19:46 INFO - TEST-INFO | started process 8042 (/builds/slave/test/build/application/firefox/firefox http://localhost:52272/getInfo.html) 03:19:51 INFO - TEST-INFO | 8042: exit 0 03:19:51 INFO - Browser initialized. 03:19:51 INFO - Running cycle 1/1 for kraken test... 03:19:51 INFO - Using env: {'DISPLAY': ':0', 03:19:51 INFO - 'HOME': '/home/cltbld', 03:19:51 INFO - 'JSGC_DISABLE_POISONING': '1', 03:19:51 INFO - 'LANG': 'en_US.UTF-8', 03:19:51 INFO - 'LANGUAGE': 'en_US:en', 03:19:51 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:19:51 INFO - 'LOGNAME': 'cltbld', 03:19:51 INFO - 'MAIL': '/var/mail/cltbld', 03:19:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:19:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:19:51 INFO - 'MOZ_CRASHREPORTER': '1', 03:19:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:51 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:19:51 INFO - 'MOZ_NO_REMOTE': '1', 03:19:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:19:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:51 INFO - 'NO_EM_RESTART': '1', 03:19:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:51 INFO - 'PWD': '/builds/slave/test', 03:19:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:19:51 INFO - 'RUST_BACKTRACE': 'full', 03:19:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:19:51 INFO - 'SHELL': '/bin/bash', 03:19:51 INFO - 'SHLVL': '1', 03:19:51 INFO - 'TERM': 'linux', 03:19:51 INFO - 'TMOUT': '86400', 03:19:51 INFO - 'USER': 'cltbld', 03:19:51 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1504084707.132134-1014938893', 03:19:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:51 INFO - '_': '/tools/buildbot/bin/python'} 03:19:51 INFO - TEST-INFO | started process 8229 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoY8OTc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:19:52 INFO - PID 8229 | 03:19:52 INFO - PID 8229 | (/builds/slave/test/build/application/firefox/firefox:8281): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:19:52 INFO - PID 8229 | 03:20:02 INFO - PID 8229 | RSS: Main: 163364864 03:20:02 INFO - PID 8229 | 03:20:05 INFO - PID 8229 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52272/tests/kraken/driver.html?testName=audio-beat-detection) 03:20:05 INFO - PID 8229 | RSS: Main: 168124416 03:20:05 INFO - PID 8229 | 03:20:08 INFO - PID 8229 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52272/tests/kraken/driver.html?testName=audio-dft) 03:20:09 INFO - PID 8229 | RSS: Main: 168640512 03:20:09 INFO - PID 8229 | 03:20:12 INFO - PID 8229 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52272/tests/kraken/driver.html?testName=audio-fft) 03:20:12 INFO - PID 8229 | RSS: Main: 163454976 03:20:12 INFO - PID 8229 | 03:20:14 INFO - PID 8229 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52272/tests/kraken/driver.html?testName=audio-oscillator) 03:20:15 INFO - PID 8229 | RSS: Main: 164163584 03:20:15 INFO - PID 8229 | 03:20:17 INFO - PID 8229 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:20:18 INFO - PID 8229 | RSS: Main: 163983360 03:20:18 INFO - PID 8229 | 03:20:21 INFO - PID 8229 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52272/tests/kraken/driver.html?testName=imaging-darkroom) 03:20:21 INFO - PID 8229 | RSS: Main: 164524032 03:20:21 INFO - PID 8229 | 03:20:25 INFO - PID 8229 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52272/tests/kraken/driver.html?testName=imaging-desaturate) 03:20:25 INFO - PID 8229 | RSS: Main: 167137280 03:20:25 INFO - PID 8229 | 03:20:28 INFO - PID 8229 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52272/tests/kraken/driver.html?testName=json-parse-financial) 03:20:29 INFO - PID 8229 | RSS: Main: 167235584 03:20:29 INFO - PID 8229 | 03:20:30 INFO - PID 8229 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:20:31 INFO - PID 8229 | RSS: Main: 167825408 03:20:31 INFO - PID 8229 | 03:20:32 INFO - PID 8229 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:20:33 INFO - PID 8229 | RSS: Main: 167264256 03:20:33 INFO - PID 8229 | 03:20:35 INFO - PID 8229 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:20:36 INFO - PID 8229 | RSS: Main: 164003840 03:20:36 INFO - PID 8229 | 03:20:38 INFO - PID 8229 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:20:39 INFO - PID 8229 | RSS: Main: 164118528 03:20:39 INFO - PID 8229 | 03:20:41 INFO - PID 8229 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:20:42 INFO - PID 8229 | RSS: Main: 164184064 03:20:42 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | Cycle 1(1): loaded http://localhost:52272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52272/tests/kraken/driver.html?testName=ai-astar) 03:20:44 INFO - PID 8229 | RSS: Main: 166027264 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | __start_tp_report 03:20:44 INFO - PID 8229 | _x_x_mozilla_page_load 03:20:44 INFO - PID 8229 | _x_x_mozilla_page_load_details 03:20:44 INFO - PID 8229 | |i|pagename|runs| 03:20:44 INFO - PID 8229 | |0;ai-astar;96;104;99;106;104;104;106;98;105;107 03:20:44 INFO - PID 8229 | |1;audio-beat-detection;137;111;148;172;113;153;137;112;163;114 03:20:44 INFO - PID 8229 | |2;audio-dft;162;163;160;161;161;163;162;162;161;168 03:20:44 INFO - PID 8229 | |3;audio-fft;76;74;73;171;73;73;73;73;154;75 03:20:44 INFO - PID 8229 | |4;audio-oscillator;78;78;139;78;79;79;156;78;79;122 03:20:44 INFO - PID 8229 | |5;imaging-gaussian-blur;113;113;114;115;114;113;114;114;114;113 03:20:44 INFO - PID 8229 | |6;imaging-darkroom;150;152;158;151;150;151;151;149;151;150 03:20:44 INFO - PID 8229 | |7;imaging-desaturate;94;98;95;94;94;93;94;95;95;95 03:20:44 INFO - PID 8229 | |8;json-parse-financial;44;44;43;43;44;49;52;43;46;43 03:20:44 INFO - PID 8229 | |9;json-stringify-tinderbox;38;39;38;39;39;38;39;39;39;38 03:20:44 INFO - PID 8229 | |10;stanford-crypto-aes;74;73;72;72;72;71;72;72;72;70 03:20:44 INFO - PID 8229 | |11;stanford-crypto-ccm;116;111;111;113;114;113;112;118;116;150 03:20:44 INFO - PID 8229 | |12;stanford-crypto-pbkdf2;157;156;158;155;152;151;151;152;161;153 03:20:44 INFO - PID 8229 | |13;stanford-crypto-sha256-iterative;61;60;62;60;59;59;58;58;60;57 03:20:44 INFO - PID 8229 | __end_tp_report 03:20:44 INFO - PID 8229 | __start_cc_report 03:20:44 INFO - PID 8229 | _x_x_mozilla_cycle_collect,898 03:20:44 INFO - PID 8229 | __end_cc_report 03:20:44 INFO - PID 8229 | __startTimestamp1504088444482__endTimestamp 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | ------- Summary: start ------- 03:20:44 INFO - PID 8229 | Number of tests: 14 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | [#0] ai-astar Cycles:10 Average:102.90 Median:106.50 stddev:3.81 (3.6%) stddev-sans-first:3.12 03:20:44 INFO - PID 8229 | Values: 96.0 104.0 99.0 106.0 104.0 104.0 106.0 98.0 105.0 107.0 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | [#1] audio-beat-detection Cycles:10 Average:136.00 Median:142.50 stddev:22.79 (16.0%) stddev-sans-first:24.17 03:20:44 INFO - PID 8229 | Values: 137.0 111.0 148.0 172.0 113.0 153.0 137.0 112.0 163.0 114.0 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.00 stddev:2.21 (1.4%) stddev-sans-first:2.35 03:20:44 INFO - PID 8229 | Values: 162.0 163.0 160.0 161.0 161.0 163.0 162.0 162.0 161.0 168.0 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | [#3] audio-fft Cycles:10 Average:91.50 Median:73.00 stddev:37.65 (51.6%) stddev-sans-first:39.51 03:20:44 INFO - PID 8229 | Values: 76.0 74.0 73.0 171.0 73.0 73.0 73.0 73.0 154.0 75.0 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:78.00 stddev:30.34 (38.9%) stddev-sans-first:31.42 03:20:44 INFO - PID 8229 | Values: 78.0 78.0 139.0 78.0 79.0 79.0 156.0 78.0 79.0 122.0 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 03:20:44 INFO - PID 8229 | Values: 113.0 113.0 114.0 115.0 114.0 113.0 114.0 114.0 114.0 113.0 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | [#6] imaging-darkroom Cycles:10 Average:151.30 Median:151.00 stddev:2.50 (1.7%) stddev-sans-first:2.60 03:20:44 INFO - PID 8229 | Values: 150.0 152.0 158.0 151.0 150.0 151.0 151.0 149.0 151.0 150.0 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:1.34 (1.4%) stddev-sans-first:1.39 03:20:44 INFO - PID 8229 | Values: 94.0 98.0 95.0 94.0 94.0 93.0 94.0 95.0 95.0 95.0 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | [#8] json-parse-financial Cycles:10 Average:45.10 Median:44.00 stddev:3.07 (7.0%) stddev-sans-first:3.23 03:20:44 INFO - PID 8229 | Values: 44.0 44.0 43.0 43.0 44.0 49.0 52.0 43.0 46.0 43.0 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | [#9] json-stringify-tinderbox Cycles:10 Average:38.60 Median:39.00 stddev:0.52 (1.3%) stddev-sans-first:0.50 03:20:44 INFO - PID 8229 | Values: 38.0 39.0 38.0 39.0 39.0 38.0 39.0 39.0 39.0 38.0 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | [#10] stanford-crypto-aes Cycles:10 Average:72.00 Median:72.00 stddev:1.05 (1.5%) stddev-sans-first:0.83 03:20:44 INFO - PID 8229 | Values: 74.0 73.0 72.0 72.0 72.0 71.0 72.0 72.0 72.0 70.0 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | [#11] stanford-crypto-ccm Cycles:10 Average:117.40 Median:115.00 stddev:11.68 (10.2%) stddev-sans-first:12.38 03:20:44 INFO - PID 8229 | Values: 116.0 111.0 111.0 113.0 114.0 113.0 112.0 118.0 116.0 150.0 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.60 Median:155.50 stddev:3.37 (2.2%) stddev-sans-first:3.46 03:20:44 INFO - PID 8229 | Values: 157.0 156.0 158.0 155.0 152.0 151.0 151.0 152.0 161.0 153.0 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.40 Median:60.00 stddev:1.51 (2.5%) stddev-sans-first:1.48 03:20:44 INFO - PID 8229 | Values: 61.0 60.0 62.0 60.0 59.0 59.0 58.0 58.0 60.0 57.0 03:20:44 INFO - PID 8229 | -------- Summary: end -------- 03:20:44 INFO - PID 8229 | 03:20:44 INFO - PID 8229 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:20:45 INFO - TEST-INFO | 8229: exit 0 03:20:45 INFO - TEST-OK | kraken | took 59055ms 03:20:45 INFO - SUITE-END | took 1168s 03:20:45 INFO - Completed test suite (00:19:29) 03:20:45 INFO - javascript benchmark 03:20:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9354.799197402492, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3884.4950156479654, 3916.334223182059, 3919.216156768647, 3919.3924941634054, 3939.7833119178454, 6530.836729081763, 6604.40560349569, 6632.568883022606, 6651.135352404187, 6663.333666633334, 6734.090897728805, 6782.8304292392595, 6790.898136527956, 6791.219009813873, 6811.591304521732, 6061.090836374557, 6069.1199776032445, 6079.4528492435675, 6107.175531303284, 6169.352218017111, 6021.187139736124, 6117.938820611781, 6137.2635283765785, 6204.968975155118, 6231.096491008801, 1100.2698775171275, 6054.909176362358, 6119.877602447948, 6185.721642526097, 6233.750649973996, 6081.6351018939, 6083.8174854754425, 6084.4219799119455, 6174.660393678346, 6190.380961903818, 5988.101784732282, 6058.091286307045, 6151.538634602378, 6157.2919114301985, 6160.260768707739, 6798.490113241527, 6841.726330946756, 6904.792856214322, 6919.550229235084, 6934.029235906977, 7317.158526769436, 7324.414046876291, 7336.082989626297, 7427.922951172104, 7453.962730186368, 7262.419006479522, 7310.553637540013, 7375.59434231117, 7422.294881986203, 7492.02603661526, 6819.454443644497, 6825.55633883796, 6874.8625027499675, 6893.310668933066, 6912.688928998157, 7443.925560744323, 7477.214892436325, 7518.699252029857, 7536.811579710551, 7587.620618969029, 7205.747798827016, 7208.35124838767, 7269.454790890704, 7297.270272972766, 7318.524295920748, 7322.743703970335, 7344.96327518359, 7378.114626244885, 7402.666879990414, 7436.0333156689285, 7161.713531458683, 7251.56490610565, 7265.564066156047, 7270.563766174047, 7300.160481544584, 7237.240089790602, 7239.082457033897, 7268.818279542948, 7282.089738782653, 7309.597972111585, 6072.939270607326, 6074.3925607438905, 6084.5132389408745, 6119.96940015306, 6169.598976066542, 5254.185601231815, 5326.840194794162, 5329.0, 5333.306670132858, 5365.865853353619, 6146.0473626587955, 6183.072539119168, 6216.067589861466, 6246.625202487865, 6263.3736626337, 7036.859262814715, 7271.163816161209, 7306.707731690779, 7319.890201646979, 7362.300581444754, 6192.566520343533, 6210.378962103753, 6243.43809057187, 6326.588771729823, 6332.33510481402, 6545.083688283644, 6548.738050478023, 6590.373914478117, 6606.471482281406, 6644.634545100066, 6892.414144797748, 6911.274316196827, 6951.582905025714, 6996.720131194696, 7004.089468369181, 7061.540999834994, 7087.681054352463, 7099.148102227766, 7198.172210195895, 7199.964000179965, 6883.862322753416, 6973.267806880204, 6995.125609298838, 7059.788206353817, 7064.576125432491, 7222.169450513258, 7241.384482318851, 7278.381337586365, 7279.272072792678, 7302.342789149002, 7224.819379515554, 7262.818429539304, 7345.522541034816, 7382.4094072473, 7383.483156179016, 6945.0971373721095, 6965.547239429624, 6987.965060174666, 7076.681549330396, 7102.254263302279, 6872.725090996304, 6941.965290173719, 6993.160820701549, 7024.0, 7039.120109986252, 6994.510384273052, 7032.296770322927, 7061.046758687511, 7080.822979425556, 7081.114860642419, 6974.721011159497, 7006.649667516705, 7052.823679408056, 7112.679929403086, 7113.964430177816, 6753.054572359775, 6848.897266541106, 6858.108445902001, 6904.27505111956, 6954.513184077066], "value": 6628.541793682154, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [890.3722875372863, 935.4621092871596, 963.4508330251757, 996.063700121885, 997.8852431970325, 40490.79754601227, 40780.59219407809, 41360.58639413605, 41613.58386416135, 41614.99999999993, 38777.41833872494, 39173.21653566927, 39234.80382598083, 39354.0, 39379.21241575167, 7308.086489188851, 7462.440316976222, 7464.738734144306, 7466.775996720107, 7467.327940485356, 7273.12722473304, 7341.926580734178, 7342.596157211351, 7351.742689005886, 7352.595607241599, 7233.565986040854, 7997.720079797208, 8179.059408168047, 8188.754337369859, 8200.05699344577, 8078.23256790604, 8091.352691784673, 8123.837523249561, 8151.307138893201, 8156.388270879678, 7735.303822655988, 7928.207179282083, 7936.047674279066, 7938.6427610757655, 7945.960270198612, 37854.24291514182, 38233.235335293415, 38262.80868595667, 38356.424653630216, 38374.23251534982, 3491.633378495272, 3507.053095664182, 3525.0306165804354, 3527.0, 3527.5414196154334, 31092.689073109206, 31204.0, 31328.0, 31378.843105784326, 31535.526967095513, 5369.275147855029, 5396.514313711785, 5400.945990540084, 5406.432324605899, 5411.18832175173, 192905.0, 196363.0, 202672.0, 216161.0, 220141.0, 51356.74321628368, 51629.741851291255, 51644.74177629088, 51657.45027649176, 51718.0, 53169.202461963105, 53257.733711331195, 53565.1965220522, 53638.463615364126, 53661.46338536565, 15786.289616967264, 15871.444499442465, 15951.361945522049, 15976.440824571317, 16002.599935001717, 13009.0, 13017.544385946636, 13017.609471715863, 13019.479220831061, 13063.934680326727, 14748.0, 14851.2574371281, 14891.255437228096, 14940.850591494163, 15036.774448383283, 5944.554158438048, 5976.61152025117, 6013.278406591237, 6026.54800889935, 6140.938590614126, 1063.4682658670665, 1071.400015991032, 1104.8011357955645, 1110.722319420145, 1116.6761639124725, 24980.515198145476, 25322.493550128893, 25582.616260756102, 25827.48345033089, 25918.611220831703, 1188.3879801902028, 1191.4995701805262, 1192.469351138735, 1212.2908098762146, 1226.779179747654, 13451.32743362828, 13605.659858503617, 13634.86365136336, 17466.65066698659, 17930.462086137435, 306293.0, 308807.0, 308853.0, 308914.0, 309193.0, 59253.40746592565, 59587.0, 59649.40350596525, 59710.402895971354, 59813.70093149506, 1444.8049513315777, 1456.5921541968055, 1485.131198249015, 1498.3107770425727, 1515.5301856424544, 30304.39391212119, 30673.846630766704, 30939.226519337197, 31183.0, 32233.67766322307, 57984.42015579959, 57990.42009580019, 58149.4185058144, 58196.709016454646, 58434.4156558446, 244547.0, 246033.0, 246556.0, 247011.0, 247563.0, 39885.80057099696, 40953.59046409498, 41743.7912810434, 41999.95000125021, 42090.0, 49837.0, 51005.489945100075, 51049.744751276005, 51282.48717512777, 51533.48466515437, 37175.62824371722, 37197.0, 37212.0, 37580.624193757714, 37722.24555508819, 36864.26271474609, 37393.81303093576, 37525.4371184427, 37720.0, 38284.808575956944, 44000.33999490074, 44009.339859902764, 44146.0, 44194.558054419045, 44419.0, 35814.227747906676, 36057.45913811243, 36178.63821361752, 36437.63562364342, 37216.76699397936, 27045.188644339913, 27476.313092172855, 28327.858360708065, 35476.64523354734, 35499.645003550664], "value": 18919.644876726845, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.038338658147, 606.8179546136158, 611.7644706787888, 619.6220305613572, 620.2804746494066, 22889.084436622514, 23632.88183559082, 24455.75544244557, 24541.877290613545, 24591.63112553313, 27581.31046723833, 27825.304367390825, 27910.581341279896, 27923.0, 28033.85983070087, 6680.498962577802, 6803.693833777492, 6806.931930680704, 6817.420519255845, 6821.897671534931, 6487.286398496161, 6725.32746725326, 6730.226024007253, 6738.22510411304, 6795.558288711242, 6643.069970204176, 6680.866382672343, 6686.598804071771, 6689.464842812587, 6691.130153080093, 6688.1305430294, 6708.329167083277, 6709.798706038827, 6712.496562757764, 6714.563553369016, 6218.751249950042, 6224.9688751556405, 6262.561620686553, 6271.310155882895, 6280.15217945576, 27550.5867411989, 30453.847730761427, 30985.0, 31006.0698179055, 31222.06333809989, 2309.4919117794057, 2339.263132113379, 2342.320726989171, 2343.109618345018, 2345.3433038749176, 20961.475963100893, 21134.0, 21272.46818829526, 21355.89322053395, 21374.893125534272, 2416.685830841997, 2436.2204094689705, 2492.0156538167503, 2573.5496288149498, 2592.675915510561, 142977.0, 150517.0, 150806.0, 151415.0, 151556.2422187893, 15742.0, 15784.447544335893, 15954.361825527081, 16309.51071467858, 16867.57831054209, 24541.263762087165, 25367.36581585475, 25398.365040873756, 25599.872000639876, 26292.605610915853, 10297.794044119075, 10358.171346292278, 10540.209484288709, 10593.364398136137, 10681.946590266998, 9432.151106400457, 9500.334976551714, 9507.524623768784, 9627.133557979676, 9640.855387169198, 9671.661491847752, 9744.512774361254, 9890.456024918554, 9945.651902183534, 10000.849987250198, 2858.998052045344, 2883.865939204973, 2949.6460424749166, 3005.669376368641, 3009.80436271646, 1049.9895001050045, 1062.946852657364, 1064.7976884392097, 1065.5045403887075, 1070.2936062198912, 20551.280705175246, 21756.89121554382, 27145.592816107775, 27553.586696199574, 28367.0, 1109.367660433545, 1111.777644471109, 1131.7057565033151, 1138.7153211697075, 1140.5779861451317, 11680.766384672259, 12322.876771232352, 12501.0, 12571.434285457177, 12675.0, 662286.0, 715558.0, 720722.0, 742252.0, 761709.0, 148449.0, 150194.24902875416, 150708.99999999782, 151742.0, 153471.23264383606, 1286.620446968128, 1288.9677758055936, 1346.7575836349356, 1348.0967751606447, 1350.6150747037047, 22563.210287639853, 24203.75796242015, 25700.8714956424, 26067.348316292246, 26227.868860656337, 66139.33860661332, 66240.33759662535, 66757.0, 67558.66220668865, 69229.0, 86826.1317386818, 87197.0, 87286.12713873034, 87465.0, 89234.10765892518, 21922.89038554797, 22799.544009120058, 22873.771262287166, 22910.0, 22914.885425572764, 26065.0, 26170.084047058263, 26307.868460657573, 26368.604470933336, 26434.735652643227, 20460.283890063776, 21102.366928992156, 21200.893995529925, 21343.35969920905, 21514.0, 17940.641187175923, 17963.820361796737, 18368.265269389074, 18605.813941860408, 18695.25218991225, 20941.952902355126, 21276.148954041666, 21533.24633637815, 21830.345089647337, 21932.0, 20753.688694669894, 21405.35783926485, 21522.569548608626, 21537.24619638305, 21558.245461408776, 20037.0, 20328.085236164112, 20397.286094986604, 20399.592008159456, 20472.59054818925], "value": 13567.752925461995, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.9928750356248, 1489.9644746900908, 1494.618872187592, 1504.5185540626996, 1512.8108986376706, 16282.592935176626, 16545.25546350414, 16920.830791692093, 16934.0, 16978.15109244539, 16393.508194754177, 17599.560010999732, 17642.64714705705, 17644.206010729515, 17689.55776105595, 2584.0826506590124, 2610.3082683088996, 2611.373270415096, 2615.869206539675, 2622.645942797717, 2721.8502982336063, 2797.692253852075, 2797.9860100699466, 2800.2579316481147, 2800.495910736067, 2755.2285360099204, 2779.7915156363356, 2790.8185967912023, 2796.8881244750187, 2799.6220510231165, 2812.367217376084, 2833.957490637642, 2866.9999999999895, 2867.584200290967, 2869.6412948381453, 2787.6515435570554, 2809.395979864339, 2811.0723461257735, 2820.4641118187683, 2820.703826098251, 15990.280437380346, 16385.42651007209, 16403.67192656152, 16419.261133249034, 16425.09661968591, 8022.318102961255, 8243.546604936726, 8340.29107525861, 8340.499570025819, 8341.249287564087, 5305.416404195574, 5624.3250809902665, 5693.77224911003, 5720.856978575527, 5766.8374359615145, 2091.968620470694, 2115.3019503563787, 2117.587070521249, 2170.305502239284, 2195.8133558647532, 29229.85385073061, 29429.852850735824, 29452.96914608, 29498.70501294981, 29548.55677164823, 6779.457643388517, 6849.691763870638, 6935.682220378112, 7018.227994920555, 7059.2351912021995, 19116.904415477835, 19165.233390664496, 19166.137523811463, 19169.233230670896, 19226.230950762092, 7942.960285198537, 8096.959515202387, 8204.097549269576, 8469.576521173916, 8490.066092729794, 6311.779644461881, 6890.655467226718, 6892.966055091676, 7395.112586489656, 7848.215178482106, 5584.0, 5738.053221218535, 5787.045137552341, 5789.536837053026, 5872.2366092408565, 1662.2602941690832, 1669.0653234188894, 1690.8309169082993, 1701.2259421963224, 1704.4545745361365, 968.0658164870933, 981.0532835813332, 992.7468495533593, 1000.9049140331657, 1018.7758693087509, 8445.155484451505, 8575.485470871768, 8650.87023694645, 8712.956435217784, 8791.648334066565, 731.4185222747928, 733.2374330696016, 734.963251837406, 740.7777666699968, 742.413493340266, 5056.620753443506, 5219.0, 5329.706866122323, 5393.487618676219, 5403.756830942617, 23720.288391348287, 24083.759162408503, 24628.507429851303, 24639.630405543932, 25158.622620660706, 23914.40213994629, 24005.759942400702, 24070.0, 24437.755622443903, 24614.138505152234, 12001.699957501132, 12186.695332616755, 12261.93869030667, 12321.815172772416, 12338.568150114703, 9739.610415583298, 9780.755481113029, 9787.902120978699, 9831.410115393099, 9860.605575776888, 20281.49296267605, 20314.59370812605, 20563.280285189947, 21051.578968420854, 21413.143474260854, 21432.678509822053, 21466.356009319745, 21641.13435462627, 21700.0, 21790.673139902596, 13579.185248885098, 13720.794188087386, 13774.793378099537, 13835.515756948458, 13836.446542138201, 15346.23268836576, 15556.68886622284, 15632.0, 15636.218189090727, 15709.764353534478, 11830.112741544246, 11833.65838816048, 11990.760184796081, 12080.275183489019, 12160.695982600506, 11764.35296058725, 12133.332666703063, 12168.148229623841, 12230.633081007583, 12323.0, 11000.724981875517, 11251.268667536584, 11256.662300131009, 11268.211225214156, 11270.0, 8354.540500272544, 8454.281386082252, 8487.193716596914, 8487.925795618014, 8553.82892342162, 8162.387820913336, 8290.46112002718, 8299.585020748818, 8307.543085130133, 8357.540335281617], "value": 7176.593333277751, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.1740612971348, 557.4898967444789, 567.5005994724642, 568.579251353998, 572.2674976030681, 6555.7049932753025, 6565.212174539056, 6609.0, 6655.835228834952, 6679.265280819106, 6426.421622054014, 6444.838879028027, 6451.451626611743, 6540.640264785435, 6581.83545411365, 5290.153575427923, 5336.4930331618625, 5401.297831281928, 5422.105352616813, 5458.563314934816, 5159.832870194508, 5231.110711179109, 5319.946800532003, 5422.2951016367815, 5426.8914621707545, 5205.646531901714, 5226.163813789794, 5243.370795504545, 5250.527452529272, 5250.894982100355, 5255.264263003182, 5264.447233040514, 5271.472852714736, 5278.155495120801, 5292.655977361459, 4622.1911165546235, 4720.59874910633, 4727.172744769686, 4731.840699028726, 4732.627538013971, 6441.194850643669, 6447.291467761001, 6478.319776423455, 6487.545871788977, 6529.020646902955, 6089.695515224221, 6149.0, 6156.722947467375, 6197.504199664061, 6209.130721698965, 6975.511714180009, 6979.930200697979, 7039.472039597, 7048.612326322051, 7050.189228238767, 5247.842564723064, 5288.8413347599635, 5303.284056652341, 5308.601854860901, 5311.7344132793205, 7084.43324534036, 7094.503384763068, 7107.324804143598, 7161.785146445615, 7298.452616053817, 5408.864778380494, 5414.83755487336, 5436.0, 5467.917981230285, 5485.122380419114, 5480.862978425492, 5523.392426833045, 5524.917126243109, 5548.9722551386985, 5559.666420014732, 6019.819405417845, 6023.698815059317, 6132.424381759672, 6134.4172303631085, 6159.692015399212, 7036.120484939383, 7055.3650171484815, 7073.257307982691, 7092.368755186233, 7125.608091555015, 1885.8779026479224, 1920.203115706994, 1922.144645632708, 1933.2073849721828, 1935.8868650526003, 1265.5001274496522, 1314.9737005259842, 1320.2870449956915, 1335.732853429318, 1347.7169794343101, 6073.423024812636, 6073.787417440369, 6076.75692972285, 6094.207752992172, 6115.602485838495, 6910.378065973986, 6986.240435028136, 7055.89416158758, 7068.3991860691385, 7088.964555177191, 6051.8487037823525, 6088.969555152285, 6112.17485639433, 6128.448439640454, 6183.505319574423, 3763.717721170923, 3770.8680196193, 3791.526059242595, 3804.2391521695217, 3833.5399752029934, 6961.26906674802, 6999.300069993062, 7007.614581198083, 7009.579425234502, 7039.768040592876, 7320.1569176181865, 7327.157376901724, 7350.963245183847, 7356.742514012091, 7371.299726525972, 6682.632455214913, 6971.5468494547695, 7007.369336759716, 7029.29707029293, 7038.8944165838575, 7009.264027277062, 7113.181984071898, 7119.786406407816, 7137.500374973702, 7148.392386647193, 7097.25478824716, 7128.750493732695, 7155.0, 7171.820704482429, 7200.207977122408, 6927.965360173167, 6949.339812717783, 7027.121609798775, 7036.859262814817, 7052.717891284497, 5388.946110538845, 5399.541039011571, 5403.270558474713, 5418.512333889811, 5427.592930530305, 6754.324567543403, 7084.681189346595, 7147.428205743424, 7162.964185179041, 7193.712251509881, 6733.192016957996, 6762.560433571802, 6802.285759995327, 6885.173779146535, 6912.55068420551, 6708.396244338033, 6737.562058466184, 6756.898646520208, 6792.592444453348, 6930.549514281555, 6628.237752658313, 6722.899156512558, 6752.189737231563, 6853.246142924175, 6893.345132212432, 6783.050372947692, 6849.1438570178725, 6889.517733758589, 6905.654717264017, 6987.161540615159, 6672.733090676318, 6675.83310417224, 6707.496937729592, 6798.864022719419, 6799.388055075067], "value": 5390.319242173837, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [968.8207681578908, 983.9704808855734, 1011.7875246198298, 1011.9241056920732, 1017.844746213048, 18976.430707078776, 19606.31377901774, 20112.4971875703, 20120.69818952715, 20132.899335503338, 20213.090410931505, 20527.794722052775, 20567.074481648324, 20611.793882061174, 20630.0, 1050.4852622215099, 1059.9947000264988, 1064.925455218135, 1064.9574017039308, 1066.7919755647652, 1089.0, 1089.3246187363845, 1089.5587287148717, 1092.0, 1093.0872721277742, 1058.973525661857, 1099.5381939585393, 1106.7233191702076, 1106.8118419868601, 1106.9225154239207, 1090.8909109089107, 1091.983620245693, 1092.1099304067159, 1092.1208427216088, 1092.30638544524, 804.4368941740805, 805.1224165659401, 805.2108933245453, 805.5005896344245, 805.830775537132, 20554.28060017907, 21133.3659990199, 21310.786892131036, 21522.461938451506, 21547.892260538596, 23181.652275215718, 23409.41476463085, 23663.0, 23773.881130594236, 23963.640545391834, 17330.913345433193, 17575.2091155897, 17580.736288955675, 17716.20277087534, 17895.37366192177, 4057.6145266199665, 4081.10215752534, 4083.775392353419, 4087.632113109805, 4088.2845502037026, 145811.0, 147420.0, 147487.26256368757, 148545.0, 149694.0, 17501.649967000587, 17765.200565974563, 17878.999999999738, 17982.37061702834, 18076.186571604045, 33741.493877591856, 33935.0, 34000.0, 34029.31941361159, 34260.14349641229, 8477.109903460048, 8852.601632926406, 9036.728898133066, 9065.68270110543, 9263.907360926305, 4450.354698568754, 4556.635469162459, 4569.611583015414, 4569.81720731167, 4581.656375771764, 6109.266887973383, 6188.350223226496, 6224.1597384352535, 6262.624242545462, 6277.497800175975, 1272.7963525836008, 1301.0177316126453, 1314.5596225264453, 1316.2892038299403, 1318.0575888239955, 744.244591739385, 763.2634507699985, 770.8573913825848, 779.3064172886136, 786.7010535996285, 12532.937335313265, 13104.344782761014, 13169.209847409185, 13188.406521706545, 13541.932290338485, 757.3524636435878, 772.97681069568, 773.0, 781.0276206123299, 788.0661416221795, 6304.653244071528, 6391.360863913572, 6423.678816059271, 6437.581557198767, 6465.094886715863, 136115.0, 136146.0, 136210.31894840463, 137226.0, 143620.5637943628, 115679.42160289313, 117609.0, 117682.0, 119930.0, 120929.0, 101601.49199253957, 102266.0, 103613.48193258986, 104548.0, 104839.4758026205, 11668.474918628512, 11779.0, 11825.349605771766, 12096.697582560506, 12164.695882603006, 52733.47266527286, 53380.0, 54203.18695219496, 54275.45724542704, 55048.17427738507, 66598.66700666466, 68939.65530172316, 69493.0, 69495.65252173707, 69795.0, 16764.66470670604, 17065.146742663066, 17074.48776536706, 19614.705779413034, 20537.589248214652, 22846.885765571067, 23461.0, 24566.754332456447, 24598.754012460362, 24668.753312466644, 21795.12819487266, 21909.78090219141, 22176.88911555432, 22249.666255006512, 22452.663210051534, 19752.604947900672, 19854.90072549628, 20309.49226269355, 21120.260790872246, 21154.365369038955, 21611.0, 21635.242766503095, 21868.343949680897, 22043.0, 22223.888880555493, 14883.851161488246, 15351.462698805488, 15621.297041633381, 15789.921050394674, 16072.196390180678, 15270.618234543781, 15584.220788960733, 15608.453704120302, 15684.92157539205, 16560.585985350463], "value": 10181.444999124222, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1436.1000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 104.0, 99.0, 106.0, 104.0, 104.0, 106.0, 98.0, 105.0, 107.0], "value": 102.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 111.0, 148.0, 172.0, 113.0, 153.0, 137.0, 112.0, 163.0, 114.0], "value": 136.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 160.0, 161.0, 161.0, 163.0, 162.0, 162.0, 161.0, 168.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 73.0, 171.0, 73.0, 73.0, 73.0, 73.0, 154.0, 75.0], "value": 91.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 139.0, 78.0, 79.0, 79.0, 156.0, 78.0, 79.0, 122.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 115.0, 114.0, 113.0, 114.0, 114.0, 114.0, 113.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 158.0, 151.0, 150.0, 151.0, 151.0, 149.0, 151.0, 150.0], "value": 151.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 98.0, 95.0, 94.0, 94.0, 93.0, 94.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 44.0, 43.0, 43.0, 44.0, 49.0, 52.0, 43.0, 46.0, 43.0], "value": 45.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 38.0, 39.0, 39.0, 38.0, 39.0, 39.0, 39.0, 38.0], "value": 38.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 72.0, 72.0, 72.0, 71.0, 72.0, 72.0, 72.0, 70.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 111.0, 111.0, 113.0, 114.0, 113.0, 112.0, 118.0, 116.0, 150.0], "value": 117.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 156.0, 158.0, 155.0, 152.0, 151.0, 151.0, 152.0, 161.0, 153.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 62.0, 60.0, 59.0, 59.0, 58.0, 58.0, 60.0, 57.0], "value": 59.4, "unit": "score"}]}]} 03:20:45 INFO - Return code: 0 03:20:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:20:45 INFO - # TBPL SUCCESS # 03:20:45 INFO - Running post-action listener: _package_coverage_data 03:20:45 INFO - Running post-action listener: _resource_record_post_action 03:20:45 INFO - [mozharness: 2017-08-30 10:20:45.789082Z] Finished run-tests step (success) 03:20:45 INFO - Running post-run listener: _resource_record_post_run 03:20:45 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 8192; Write bytes: 375566336; Read time: 56; Write time: 376716 03:20:45 INFO - TinderboxPrint: CPU usage
13.1% 03:20:45 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 56 03:20:45 INFO - TinderboxPrint: I/O write bytes / time
375,566,336 / 376,716 03:20:45 INFO - TinderboxPrint: CPU idle
8,220.3 (86.9%) 03:20:45 INFO - TinderboxPrint: CPU user
1,186.4 (12.5%) 03:20:45 INFO - TinderboxPrint: Swap in / out
0 / 0 03:20:45 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 107417600; Read time: 0; Write time: 149072 03:20:45 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:20:45 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 8192; Write bytes: 268148736; Read time: 56; Write time: 227644 03:20:46 INFO - Running post-run listener: _upload_blobber_files 03:20:46 INFO - Blob upload gear active. 03:20:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:20:46 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:20:46 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:20:46 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:20:46 INFO - (blobuploader) - INFO - Open directory for files ... 03:20:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:20:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:20:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:47 INFO - (blobuploader) - INFO - Done attempting. 03:20:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:20:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:20:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:51 INFO - (blobuploader) - INFO - Done attempting. 03:20:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:20:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:20:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:52 INFO - (blobuploader) - INFO - Done attempting. 03:20:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:20:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:20:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:53 INFO - (blobuploader) - INFO - Done attempting. 03:20:53 INFO - (blobuploader) - INFO - Iteration through files over. 03:20:53 INFO - Return code: 0 03:20:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:20:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:20:53 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2441c6975f90923238d6116ff3a77cbccf2cb6be9e3b0d5b72ee550f2f7a083240af13b6eba277fafdd172d395f9b35556e241658dbf897ead759a38ff7f7fac", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83ab39ac354f07bd8243e1caf23dbc32cf873ac327daf7aafbe7cbfbfae0a94bfd9632c80c3c1ec4593fc82ca07bfb03b194a19acf630608e55c13d21fd26784", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cbe037dec283f4a723a8ebb430bfb1040bea48af62f859636112c3b73432b9d3b101981c4ed453549771b082bb8f4dce469f38ba31e91d0284f5a562ee6dd94", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8e5624f993fc5fda1e4a2cd56ac625708ff141a83ced37268558ac1cdc17fbf9bb9ff2438a8b71e98ffdec1dfd2939d582b5c1acfb1ee2d5284788b88a352f6"} 03:20:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:20:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:20:53 INFO - Contents: 03:20:53 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2441c6975f90923238d6116ff3a77cbccf2cb6be9e3b0d5b72ee550f2f7a083240af13b6eba277fafdd172d395f9b35556e241658dbf897ead759a38ff7f7fac", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83ab39ac354f07bd8243e1caf23dbc32cf873ac327daf7aafbe7cbfbfae0a94bfd9632c80c3c1ec4593fc82ca07bfb03b194a19acf630608e55c13d21fd26784", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cbe037dec283f4a723a8ebb430bfb1040bea48af62f859636112c3b73432b9d3b101981c4ed453549771b082bb8f4dce469f38ba31e91d0284f5a562ee6dd94", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8e5624f993fc5fda1e4a2cd56ac625708ff141a83ced37268558ac1cdc17fbf9bb9ff2438a8b71e98ffdec1dfd2939d582b5c1acfb1ee2d5284788b88a352f6"} 03:20:53 INFO - Running post-run listener: copy_logs_to_upload_dir 03:20:53 INFO - Copying logs to upload dir... 03:20:53 INFO - mkdir: /builds/slave/test/build/upload/logs 03:20:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.466078 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-08-30 03:20:53.370211) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-30 03:20:53.375211) ========= 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=745a4373a2a16df560f216e000000264-1504084707.132134-1014938893 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2441c6975f90923238d6116ff3a77cbccf2cb6be9e3b0d5b72ee550f2f7a083240af13b6eba277fafdd172d395f9b35556e241658dbf897ead759a38ff7f7fac", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83ab39ac354f07bd8243e1caf23dbc32cf873ac327daf7aafbe7cbfbfae0a94bfd9632c80c3c1ec4593fc82ca07bfb03b194a19acf630608e55c13d21fd26784", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cbe037dec283f4a723a8ebb430bfb1040bea48af62f859636112c3b73432b9d3b101981c4ed453549771b082bb8f4dce469f38ba31e91d0284f5a562ee6dd94", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8e5624f993fc5fda1e4a2cd56ac625708ff141a83ced37268558ac1cdc17fbf9bb9ff2438a8b71e98ffdec1dfd2939d582b5c1acfb1ee2d5284788b88a352f6"} build_url:https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011149 build_url: 'https://queue.taskcluster.net/v1/task/agGxgv-nSMeSLvEC4QRiKA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2441c6975f90923238d6116ff3a77cbccf2cb6be9e3b0d5b72ee550f2f7a083240af13b6eba277fafdd172d395f9b35556e241658dbf897ead759a38ff7f7fac", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83ab39ac354f07bd8243e1caf23dbc32cf873ac327daf7aafbe7cbfbfae0a94bfd9632c80c3c1ec4593fc82ca07bfb03b194a19acf630608e55c13d21fd26784", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cbe037dec283f4a723a8ebb430bfb1040bea48af62f859636112c3b73432b9d3b101981c4ed453549771b082bb8f4dce469f38ba31e91d0284f5a562ee6dd94", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8e5624f993fc5fda1e4a2cd56ac625708ff141a83ced37268558ac1cdc17fbf9bb9ff2438a8b71e98ffdec1dfd2939d582b5c1acfb1ee2d5284788b88a352f6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-30 03:20:53.405217) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:20:53.405639) ========= 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=745a4373a2a16df560f216e000000264-1504084707.132134-1014938893 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004476 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:20:53.484646) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-30 03:20:53.487773) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-30 03:20:53.488211) ========= ========= Total master_lag: 0.25 =========