builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-045 starttime: 1504865239.77 results: success (0) revision: dd75dcec7da162d8ceaaf0883e0e7561bd772992 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:19.765970) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:19.766428) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:19.766702) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:19.784323) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:19.784648) ========= 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=4300f881736b422476174874000001bc-1504865238.917504-463219999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-08 03:07:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 36.9M=0s 2017-09-08 03:07:19 (36.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.073477 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:19.876211) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:19.876827) ========= 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=4300f881736b422476174874000001bc-1504865238.917504-463219999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.024523 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:19.928188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:19.928529) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dd75dcec7da162d8ceaaf0883e0e7561bd772992 --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 dd75dcec7da162d8ceaaf0883e0e7561bd772992 --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=4300f881736b422476174874000001bc-1504865238.917504-463219999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-08 03:07:19,978 truncating revision to first 12 chars 2017-09-08 03:07:19,978 Setting DEBUG logging. 2017-09-08 03:07:19,978 attempt 1/10 2017-09-08 03:07:19,978 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dd75dcec7da1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-08 03:07:20,247 unpacking tar archive at: mozilla-central-dd75dcec7da1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.579157 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:20.525282) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:20.525615) ========= script_repo_revision: dd75dcec7da162d8ceaaf0883e0e7561bd772992 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:20.525986) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:20.526435) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-08 03:07:20.540550) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-08 03:07:20.541100) ========= /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=4300f881736b422476174874000001bc-1504865238.917504-463219999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:07:20 INFO - MultiFileLogger online at 20170908 03:07:20 in /builds/slave/test 03:07:20 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:07:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:07:20 INFO - {'append_to_log': False, 03:07:20 INFO - 'base_work_dir': '/builds/slave/test', 03:07:20 INFO - 'blob_upload_branch': 'Firefox', 03:07:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:07:20 INFO - 'branch': 'Firefox', 03:07:20 INFO - 'buildbot_json_path': 'buildprops.json', 03:07:20 INFO - 'code_coverage': False, 03:07:20 INFO - 'config_files': ('talos/linux_config.py',), 03:07:20 INFO - 'default_actions': ('clobber', 03:07:20 INFO - 'read-buildbot-config', 03:07:20 INFO - 'download-and-extract', 03:07:20 INFO - 'populate-webroot', 03:07:20 INFO - 'create-virtualenv', 03:07:20 INFO - 'install', 03:07:20 INFO - 'setup-mitmproxy', 03:07:20 INFO - 'run-tests'), 03:07:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:07:20 INFO - 'disable_ccov_upload': False, 03:07:20 INFO - 'disable_stylo': False, 03:07:20 INFO - 'download_minidump_stackwalk': True, 03:07:20 INFO - 'download_symbols': 'ondemand', 03:07:20 INFO - 'enable_stylo': False, 03:07:20 INFO - 'enable_webrender': False, 03:07:20 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:07:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:07:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:07:20 INFO - 'gecko_profile': False, 03:07:20 INFO - 'gecko_profile_interval': 0, 03:07:20 INFO - 'installer_path': 'installer.exe', 03:07:20 INFO - 'log_level': 'info', 03:07:20 INFO - 'log_name': 'talos', 03:07:20 INFO - 'log_to_console': True, 03:07:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:07:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:07:20 INFO - 'opt_config_files': (), 03:07:20 INFO - 'pip_index': False, 03:07:20 INFO - 'suite': 'dromaeojs-e10s', 03:07:20 INFO - 'system_bits': '32', 03:07:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:07:20 INFO - 'title': 'talos-linux64-ix-045', 03:07:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:07:20 INFO - 'use_talos_json': True, 03:07:20 INFO - 'verify': 'False', 03:07:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:07:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:07:20 INFO - 'work_dir': 'build'} 03:07:20 INFO - [mozharness: 2017-09-08 10:07:20.734509Z] Running clobber step. 03:07:20 INFO - Running pre-action listener: _resource_record_pre_action 03:07:20 INFO - Running main action method: clobber 03:07:20 INFO - rmtree: /builds/slave/test/build 03:07:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:07:22 INFO - Running post-action listener: _resource_record_post_action 03:07:22 INFO - [mozharness: 2017-09-08 10:07:22.416446Z] Finished clobber step (success) 03:07:22 INFO - [mozharness: 2017-09-08 10:07:22.416542Z] Running read-buildbot-config step. 03:07:22 INFO - Running pre-action listener: _resource_record_pre_action 03:07:22 INFO - Running main action method: read_buildbot_config 03:07:22 INFO - Using buildbot properties: 03:07:22 INFO - { 03:07:22 INFO - "project": "", 03:07:22 INFO - "product": "firefox", 03:07:22 INFO - "who": "archaeopteryx@coole-files.de", 03:07:22 INFO - "installer_path": "public/build/target.tar.bz2", 03:07:22 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:07:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:07:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 03:07:22 INFO - "stage_platform": "linux64-pgo", 03:07:22 INFO - "basedir": "/builds/slave/test", 03:07:22 INFO - "buildnumber": 165, 03:07:22 INFO - "platform": "ubuntu64_hw", 03:07:22 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:07:22 INFO - "slavebuilddir": "test", 03:07:22 INFO - "taskId": "Ty9eWmIsTHaX7ZJXyiVDsA", 03:07:22 INFO - "branch": "mozilla-central", 03:07:22 INFO - "script_repo_revision": "production", 03:07:22 INFO - "revision": "dd75dcec7da162d8ceaaf0883e0e7561bd772992", 03:07:22 INFO - "slavename": "talos-linux64-ix-045", 03:07:22 INFO - "repo_path": "mozilla-central" 03:07:22 INFO - } 03:07:22 INFO - Finding installer, test and symbols from parent task. 03:07:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ty9eWmIsTHaX7ZJXyiVDsA'}, attempt #1 03:07:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ty9eWmIsTHaX7ZJXyiVDsA'}, attempt #1 03:07:23 INFO - Task dependencies: ahLuQrZERhaQob3zkw8yZQ 03:07:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ'}, attempt #1 03:07:23 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.tar.bz2 03:07:23 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.test_packages.json 03:07:23 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.crashreporter-symbols.zip 03:07:23 INFO - Running post-action listener: _resource_record_post_action 03:07:23 INFO - [mozharness: 2017-09-08 10:07:23.762370Z] Finished read-buildbot-config step (success) 03:07:23 INFO - [mozharness: 2017-09-08 10:07:23.762587Z] Running download-and-extract step. 03:07:23 INFO - Running pre-action listener: _resource_record_pre_action 03:07:23 INFO - Running main action method: download_and_extract 03:07:23 INFO - mkdir: /builds/slave/test/build/tests 03:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:23 INFO - trying https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.test_packages.json 03:07:23 INFO - Downloading https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:07:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:07:24 INFO - Downloaded 989 bytes. 03:07:24 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:07:24 INFO - Using the following test package requirements: 03:07:24 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:07:24 INFO - u'common': [u'target.common.tests.zip'], 03:07:24 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:07:24 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:07:24 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:07:24 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:07:24 INFO - u'mozbase': [u'target.common.tests.zip'], 03:07:24 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:07:24 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:07:24 INFO - u'web-platform': [u'target.common.tests.zip', 03:07:24 INFO - u'target.web-platform.tests.tar.gz'], 03:07:24 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:07:24 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:07:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.common.tests.zip 03:07:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:07:24 INFO - Fetch https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.common.tests.zip into memory 03:07:25 INFO - Content-Length response header: 55694157 03:07:25 INFO - Bytes received: 55694157 03:07:36 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:07:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.common.tests.zip 03:07:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:07:36 INFO - Fetch https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.common.tests.zip into memory 03:07:37 INFO - Content-Length response header: 55694157 03:07:37 INFO - Bytes received: 55694157 03:07:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.talos.tests.zip 03:07:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:07:49 INFO - Fetch https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.talos.tests.zip into memory 03:07:50 INFO - Content-Length response header: 14496665 03:07:50 INFO - Bytes received: 14496665 03:07: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:07:50 INFO - trying https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.tar.bz2 03:07:50 INFO - Downloading https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:07:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:07:51 INFO - Downloaded 63621891 bytes. 03:07:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.tar.bz2 03:07:51 INFO - mkdir: /builds/slave/test/properties 03:07:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:07:51 INFO - Writing to file /builds/slave/test/properties/build_url 03:07:51 INFO - Contents: 03:07:51 INFO - build_url:https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.tar.bz2 03:07:51 INFO - Running post-action listener: _resource_record_post_action 03:07:51 INFO - Running post-action listener: find_tests_for_verification 03:07:51 INFO - Running post-action listener: set_extra_try_arguments 03:07:51 INFO - [mozharness: 2017-09-08 10:07:51.592976Z] Finished download-and-extract step (success) 03:07:51 INFO - [mozharness: 2017-09-08 10:07:51.593072Z] Running populate-webroot step. 03:07:51 INFO - Running pre-action listener: _resource_record_pre_action 03:07:51 INFO - Running main action method: populate_webroot 03:07:51 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:07:51 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:07:51 INFO - 'tests': ['tresize', 03:07:51 INFO - 'tcanvasmark']}, 03:07:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:07:51 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:07:51 INFO - 'tests': ['dromaeo_css', 03:07:51 INFO - 'kraken']}, 03:07:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:07:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:07:51 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:07:51 INFO - 'talos_options': ['--disable-stylo'], 03:07:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:07:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:07:51 INFO - 'tests': ['damp', 'tps']}, 03:07:51 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:07:51 INFO - 'talos_options': ['--disable-stylo'], 03:07:51 INFO - 'tests': ['damp', 'tps']}, 03:07:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:07:51 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:07:51 INFO - 'tests': ['dromaeo_dom']}, 03:07:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:07:51 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:07:51 INFO - 'tests': ['basic_compositor_video', 03:07:51 INFO - 'glvideo']}, 03:07:51 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:07:51 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:07:51 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:07:51 INFO - 'talos_options': ['--disable-stylo'], 03:07:51 INFO - 'tests': ['ts_paint_webext', 03:07:51 INFO - 'tp5o_webext']}, 03:07:51 INFO - 'other-e10s': {'tests': ['a11yr', 03:07:51 INFO - 'ts_paint', 03:07:51 INFO - 'tpaint', 03:07:51 INFO - 'sessionrestore', 03:07:51 INFO - 'sessionrestore_many_windows', 03:07:51 INFO - 'sessionrestore_no_auto_restore', 03:07:51 INFO - 'tabpaint']}, 03:07:51 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:07:51 INFO - 'tests': ['a11yr', 03:07:51 INFO - 'ts_paint', 03:07:51 INFO - 'tpaint', 03:07:51 INFO - 'sessionrestore', 03:07:51 INFO - 'sessionrestore_many_windows', 03:07:51 INFO - 'sessionrestore_no_auto_restore', 03:07:51 INFO - 'tabpaint']}, 03:07:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:07:51 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:07:51 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:07:51 INFO - 'tests': ['bloom_basic_singleton']}, 03:07:51 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:07:51 INFO - 'tests': ['bloom_basic']}, 03:07:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:07:51 INFO - 'tsvgr_opacity', 03:07:51 INFO - 'tart', 03:07:51 INFO - 'tscrollx', 03:07:51 INFO - 'tsvg_static']}, 03:07:51 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 03:07:51 INFO - 'tests': ['tsvgx', 03:07:51 INFO - 'tsvgr_opacity', 03:07:51 INFO - 'tart', 03:07:51 INFO - 'tscrollx', 03:07:51 INFO - 'tsvg_static']}, 03:07:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:07:51 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:07:51 INFO - 'talos_options': ['--disable-stylo'], 03:07:51 INFO - 'tests': ['tp5o']}, 03:07:51 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:07:51 INFO - 'talos_options': ['--mitmproxy', 03:07:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:07:51 INFO - '--firstNonBlankPaint'], 03:07:51 INFO - 'tests': ['tp6_google', 03:07:51 INFO - 'tp6_youtube', 03:07:51 INFO - 'tp6_amazon', 03:07:51 INFO - 'tp6_facebook']}, 03:07:51 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:07:51 INFO - 'talos_options': ['--disable-stylo', 03:07:51 INFO - '--mitmproxy', 03:07:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:07:51 INFO - '--firstNonBlankPaint'], 03:07:51 INFO - 'tests': ['tp6_google', 03:07:51 INFO - 'tp6_youtube', 03:07:51 INFO - 'tp6_amazon', 03:07:51 INFO - 'tp6_facebook']}, 03:07:51 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:07:51 INFO - 'talos_options': ['--stylo-threads=1', 03:07:51 INFO - '--mitmproxy', 03:07:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:07:51 INFO - '--firstNonBlankPaint'], 03:07:51 INFO - 'tests': ['tp6_google', 03:07:51 INFO - 'tp6_youtube', 03:07:51 INFO - 'tp6_amazon', 03:07:51 INFO - 'tp6_facebook']}, 03:07:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:07:51 INFO - 'talos_options': ['--xperf_path', 03:07:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:07:51 INFO - 'tests': ['tp5n']}, 03:07:51 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 03:07:51 INFO - 'talos_options': ['--disable-stylo', 03:07:51 INFO - '--xperf_path', 03:07:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:07:51 INFO - 'tests': ['tp5n']}}} 03:07:51 INFO - Running post-action listener: _resource_record_post_action 03:07:51 INFO - [mozharness: 2017-09-08 10:07:51.607992Z] Finished populate-webroot step (success) 03:07:51 INFO - [mozharness: 2017-09-08 10:07:51.608081Z] Running create-virtualenv step. 03:07:51 INFO - Running pre-action listener: _resource_record_pre_action 03:07:51 INFO - Running main action method: create_virtualenv 03:07:51 INFO - Creating virtualenv /builds/slave/test/build/venv 03:07:51 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:07:51 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:07:51 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:07:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:07:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:07:52 INFO - Installing distribute.............................................................................................................................................................................................done. 03:07:52 INFO - Installing pip...............done. 03:07:52 INFO - Return code: 0 03:07:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:07:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:07:52 INFO - Reading from file tmpfile_stdout 03:07:52 INFO - Output received: 03:07:52 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:07:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:07:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1391a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1534a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1538090>, '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 0x1538d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1532f90>, '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': '4300f881736b422476174874000001bc-1504865238.917504-463219999', '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:07:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:07:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:07:52 INFO - Using env: {'DISPLAY': ':0', 03:07:52 INFO - 'HOME': '/home/cltbld', 03:07:52 INFO - 'LANG': 'en_US.UTF-8', 03:07:52 INFO - 'LANGUAGE': 'en_US:en', 03:07:52 INFO - 'LOGNAME': 'cltbld', 03:07:52 INFO - 'MAIL': '/var/mail/cltbld', 03:07:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:52 INFO - 'MOZ_NO_REMOTE': '1', 03:07:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:07:52 INFO - 'NO_EM_RESTART': '1', 03:07:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:07:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:52 INFO - 'PWD': '/builds/slave/test', 03:07:52 INFO - 'SHELL': '/bin/bash', 03:07:52 INFO - 'SHLVL': '1', 03:07:52 INFO - 'TERM': 'linux', 03:07:52 INFO - 'TMOUT': '86400', 03:07:52 INFO - 'USER': 'cltbld', 03:07:52 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504865238.917504-463219999', 03:07:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:52 INFO - '_': '/tools/buildbot/bin/python'} 03:07:53 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:07:53 INFO - Downloading/unpacking pip>=1.5 03:07:53 INFO - Running setup.py egg_info for package pip 03:07:53 INFO - warning: no files found matching 'pip/cacert.pem' 03:07:53 INFO - warning: no files found matching '*.html' under directory 'docs' 03:07:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:07:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:07:53 INFO - Installing collected packages: pip 03:07:53 INFO - Found existing installation: pip 1.1 03:07:53 INFO - Uninstalling pip: 03:07:53 INFO - Successfully uninstalled pip 03:07:53 INFO - Running setup.py install for pip 03:07:53 INFO - warning: no files found matching 'pip/cacert.pem' 03:07:53 INFO - warning: no files found matching '*.html' under directory 'docs' 03:07:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:07:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:07:53 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:07:53 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:07:53 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:07:53 INFO - Successfully installed pip 03:07:53 INFO - Cleaning up... 03:07:53 INFO - Return code: 0 03:07:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:07: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:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07: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:07:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1391a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1534a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1538090>, '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 0x1538d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1532f90>, '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': '4300f881736b422476174874000001bc-1504865238.917504-463219999', '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:07:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:07:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:07:53 INFO - Using env: {'DISPLAY': ':0', 03:07:53 INFO - 'HOME': '/home/cltbld', 03:07:53 INFO - 'LANG': 'en_US.UTF-8', 03:07:53 INFO - 'LANGUAGE': 'en_US:en', 03:07:53 INFO - 'LOGNAME': 'cltbld', 03:07:53 INFO - 'MAIL': '/var/mail/cltbld', 03:07:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:53 INFO - 'MOZ_NO_REMOTE': '1', 03:07:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:07:53 INFO - 'NO_EM_RESTART': '1', 03:07:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:07:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:53 INFO - 'PWD': '/builds/slave/test', 03:07:53 INFO - 'SHELL': '/bin/bash', 03:07:53 INFO - 'SHLVL': '1', 03:07:53 INFO - 'TERM': 'linux', 03:07:53 INFO - 'TMOUT': '86400', 03:07:53 INFO - 'USER': 'cltbld', 03:07:53 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504865238.917504-463219999', 03:07:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:53 INFO - '_': '/tools/buildbot/bin/python'} 03:07:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:54 INFO - Downloading/unpacking psutil>=3.1.1 03:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:07:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:07:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:07:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:07:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:07:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:07:55 INFO - Installing collected packages: psutil 03:07:55 INFO - Running setup.py install for psutil 03:07:55 INFO - building 'psutil._psutil_linux' extension 03:07:55 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:07:55 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:07:55 INFO - building 'psutil._psutil_posix' extension 03:07:55 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:07:55 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:07:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:07:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:07:55 INFO - Successfully installed psutil 03:07:55 INFO - Cleaning up... 03:07:55 INFO - Return code: 0 03:07:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:07:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:55 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 0x11c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1391a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1534a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1538090>, '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 0x1538d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1532f90>, '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': '4300f881736b422476174874000001bc-1504865238.917504-463219999', '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:07:55 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:07:55 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:07:55 INFO - Using env: {'DISPLAY': ':0', 03:07:55 INFO - 'HOME': '/home/cltbld', 03:07:55 INFO - 'LANG': 'en_US.UTF-8', 03:07:55 INFO - 'LANGUAGE': 'en_US:en', 03:07:55 INFO - 'LOGNAME': 'cltbld', 03:07:55 INFO - 'MAIL': '/var/mail/cltbld', 03:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:55 INFO - 'MOZ_NO_REMOTE': '1', 03:07:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:07:55 INFO - 'NO_EM_RESTART': '1', 03:07:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:07:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:55 INFO - 'PWD': '/builds/slave/test', 03:07:55 INFO - 'SHELL': '/bin/bash', 03:07:55 INFO - 'SHLVL': '1', 03:07:55 INFO - 'TERM': 'linux', 03:07:55 INFO - 'TMOUT': '86400', 03:07:55 INFO - 'USER': 'cltbld', 03:07:55 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504865238.917504-463219999', 03:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:55 INFO - '_': '/tools/buildbot/bin/python'} 03:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:07: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:07: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:07: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:07: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:07:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:07:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:07:57 INFO - Installing collected packages: mozsystemmonitor 03:07:57 INFO - Running setup.py install for mozsystemmonitor 03:07:57 INFO - Successfully installed mozsystemmonitor 03:07:57 INFO - Cleaning up... 03:07:57 INFO - Return code: 0 03:07:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:07:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:57 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 0x11c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1391a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1534a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1538090>, '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 0x1538d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1532f90>, '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': '4300f881736b422476174874000001bc-1504865238.917504-463219999', '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:07:57 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:07:57 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:07:57 INFO - Using env: {'DISPLAY': ':0', 03:07:57 INFO - 'HOME': '/home/cltbld', 03:07:57 INFO - 'LANG': 'en_US.UTF-8', 03:07:57 INFO - 'LANGUAGE': 'en_US:en', 03:07:57 INFO - 'LOGNAME': 'cltbld', 03:07:57 INFO - 'MAIL': '/var/mail/cltbld', 03:07:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:57 INFO - 'MOZ_NO_REMOTE': '1', 03:07:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:07:57 INFO - 'NO_EM_RESTART': '1', 03:07:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:07:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:57 INFO - 'PWD': '/builds/slave/test', 03:07:57 INFO - 'SHELL': '/bin/bash', 03:07:57 INFO - 'SHLVL': '1', 03:07:57 INFO - 'TERM': 'linux', 03:07:57 INFO - 'TMOUT': '86400', 03:07:57 INFO - 'USER': 'cltbld', 03:07:57 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504865238.917504-463219999', 03:07:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:57 INFO - '_': '/tools/buildbot/bin/python'} 03:07:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:57 INFO - Downloading/unpacking jsonschema==2.5.1 03:07:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:07:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:07:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:07:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:07:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:07:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:07:58 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:07:58 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:07:58 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:07:58 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:07:58 INFO - Downloading functools32-3.2.3-2.tar.gz 03:07:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:07:58 INFO - warning: no files found matching '*.txt' 03:07:58 INFO - no previously-included directories found matching 'build' 03:07:58 INFO - no previously-included directories found matching 'dist' 03:07:58 INFO - no previously-included directories found matching '.git*' 03:07:58 INFO - Installing collected packages: jsonschema, functools32 03:07:58 INFO - Running setup.py install for functools32 03:07:58 INFO - warning: no files found matching '*.txt' 03:07:58 INFO - no previously-included directories found matching 'build' 03:07:58 INFO - no previously-included directories found matching 'dist' 03:07:58 INFO - no previously-included directories found matching '.git*' 03:07:58 INFO - Successfully installed jsonschema functools32 03:07:58 INFO - Cleaning up... 03:07:58 INFO - Return code: 0 03:07:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:07:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07:58 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 0x11c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1391a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1534a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1538090>, '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 0x1538d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1532f90>, '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': '4300f881736b422476174874000001bc-1504865238.917504-463219999', '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:07:58 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:07:58 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:07:58 INFO - Using env: {'DISPLAY': ':0', 03:07:58 INFO - 'HOME': '/home/cltbld', 03:07:58 INFO - 'LANG': 'en_US.UTF-8', 03:07:58 INFO - 'LANGUAGE': 'en_US:en', 03:07:58 INFO - 'LOGNAME': 'cltbld', 03:07:58 INFO - 'MAIL': '/var/mail/cltbld', 03:07:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:58 INFO - 'MOZ_NO_REMOTE': '1', 03:07:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:07:58 INFO - 'NO_EM_RESTART': '1', 03:07:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:07:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:58 INFO - 'PWD': '/builds/slave/test', 03:07:58 INFO - 'SHELL': '/bin/bash', 03:07:58 INFO - 'SHLVL': '1', 03:07:58 INFO - 'TERM': 'linux', 03:07:58 INFO - 'TMOUT': '86400', 03:07:58 INFO - 'USER': 'cltbld', 03:07:58 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504865238.917504-463219999', 03:07:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:58 INFO - '_': '/tools/buildbot/bin/python'} 03:07:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:07:59 INFO - Cleaning up... 03:07:59 INFO - Return code: 0 03:07:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:07: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:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:07: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:07:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:07: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', '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 0x11c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1391a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1534a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1538090>, '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 0x1538d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1532f90>, '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': '4300f881736b422476174874000001bc-1504865238.917504-463219999', '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:07: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:07: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 blobuploader==1.2.4 03:07:59 INFO - Using env: {'DISPLAY': ':0', 03:07:59 INFO - 'HOME': '/home/cltbld', 03:07:59 INFO - 'LANG': 'en_US.UTF-8', 03:07:59 INFO - 'LANGUAGE': 'en_US:en', 03:07:59 INFO - 'LOGNAME': 'cltbld', 03:07:59 INFO - 'MAIL': '/var/mail/cltbld', 03:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:07:59 INFO - 'MOZ_NO_REMOTE': '1', 03:07:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:07:59 INFO - 'NO_EM_RESTART': '1', 03:07:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:07:59 INFO - 'PWD': '/builds/slave/test', 03:07:59 INFO - 'SHELL': '/bin/bash', 03:07:59 INFO - 'SHLVL': '1', 03:07:59 INFO - 'TERM': 'linux', 03:07:59 INFO - 'TMOUT': '86400', 03:07:59 INFO - 'USER': 'cltbld', 03:07:59 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504865238.917504-463219999', 03:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:07:59 INFO - '_': '/tools/buildbot/bin/python'} 03:07:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:07:59 INFO - Downloading/unpacking blobuploader==1.2.4 03:07:59 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:07:59 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:07:59 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:07:59 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:08:00 INFO - Downloading blobuploader-1.2.4.tar.gz 03:08:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:08:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:08:00 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:08:00 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:08:00 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:08:00 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:08:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:08:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:08:00 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:08:00 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:08:00 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:08:00 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:08:00 INFO - Downloading docopt-0.6.1.tar.gz 03:08:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:08:01 INFO - Installing collected packages: blobuploader, requests, docopt 03:08:01 INFO - Running setup.py install for blobuploader 03:08:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:08:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:08:01 INFO - Running setup.py install for requests 03:08:01 INFO - Running setup.py install for docopt 03:08:01 INFO - Successfully installed blobuploader requests docopt 03:08:01 INFO - Cleaning up... 03:08:01 INFO - Return code: 0 03:08:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:08:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:01 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 0x11c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1391a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1534a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1538090>, '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 0x1538d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1532f90>, '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': '4300f881736b422476174874000001bc-1504865238.917504-463219999', '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:08:01 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:08:01 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:08:01 INFO - Using env: {'DISPLAY': ':0', 03:08:01 INFO - 'HOME': '/home/cltbld', 03:08:01 INFO - 'LANG': 'en_US.UTF-8', 03:08:01 INFO - 'LANGUAGE': 'en_US:en', 03:08:01 INFO - 'LOGNAME': 'cltbld', 03:08:01 INFO - 'MAIL': '/var/mail/cltbld', 03:08:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:01 INFO - 'MOZ_NO_REMOTE': '1', 03:08:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:01 INFO - 'NO_EM_RESTART': '1', 03:08:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:01 INFO - 'PWD': '/builds/slave/test', 03:08:01 INFO - 'SHELL': '/bin/bash', 03:08:01 INFO - 'SHLVL': '1', 03:08:01 INFO - 'TERM': 'linux', 03:08:01 INFO - 'TMOUT': '86400', 03:08:01 INFO - 'USER': 'cltbld', 03:08:01 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504865238.917504-463219999', 03:08:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:01 INFO - '_': '/tools/buildbot/bin/python'} 03:08:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:08:01 INFO - Running setup.py (path:/tmp/pip-zExUk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:08:01 INFO - Running setup.py (path:/tmp/pip-2Jidl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:08:02 INFO - Running setup.py (path:/tmp/pip-qOLx84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:08:02 INFO - Running setup.py (path:/tmp/pip-_9AeNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:08:02 INFO - Running setup.py (path:/tmp/pip-8KrLso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:08:02 INFO - Running setup.py (path:/tmp/pip-vce4SW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:08:02 INFO - Running setup.py (path:/tmp/pip-o7QH4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:08:02 INFO - Running setup.py (path:/tmp/pip-x_f6Qk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:08:02 INFO - Running setup.py (path:/tmp/pip-Z5Bg1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:08:02 INFO - Running setup.py (path:/tmp/pip-Mxyuwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:08:02 INFO - Running setup.py (path:/tmp/pip-x5VZgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:08:02 INFO - Running setup.py (path:/tmp/pip-GzR8Qh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:08:02 INFO - Running setup.py (path:/tmp/pip-UtSQje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:08:02 INFO - Running setup.py (path:/tmp/pip-86DfPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:08:02 INFO - Running setup.py (path:/tmp/pip-lQl9pc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:08:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:08:02 INFO - Running setup.py (path:/tmp/pip-Z1MTmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:08:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:08:03 INFO - Running setup.py (path:/tmp/pip-hwTAsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:08:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:08:03 INFO - Running setup.py install for manifestparser 03:08:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:08:03 INFO - Running setup.py install for mozcrash 03:08:03 INFO - Running setup.py install for mozdebug 03:08:03 INFO - Running setup.py install for mozdevice 03:08:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:08:03 INFO - Running setup.py install for mozfile 03:08:03 INFO - Running setup.py install for mozhttpd 03:08:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:08:03 INFO - Running setup.py install for mozinfo 03:08:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:08:03 INFO - Running setup.py install for mozInstall 03:08:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:08:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:08:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:08:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:08:03 INFO - Running setup.py install for mozleak 03:08:03 INFO - Running setup.py install for mozlog 03:08:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:08:04 INFO - Running setup.py install for moznetwork 03:08:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:08:04 INFO - Running setup.py install for mozprocess 03:08:04 INFO - Running setup.py install for mozprofile 03:08:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:08:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:08:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:08:04 INFO - Running setup.py install for mozrunner 03:08:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:08:04 INFO - Running setup.py install for mozscreenshot 03:08:04 INFO - Running setup.py install for moztest 03:08:04 INFO - Running setup.py install for mozversion 03:08:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:08:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:08:04 INFO - Cleaning up... 03:08:04 INFO - Return code: 0 03:08:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:08:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:04 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 0x11c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1391a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1534a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1538090>, '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 0x1538d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1532f90>, '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': '4300f881736b422476174874000001bc-1504865238.917504-463219999', '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:08:04 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:08:04 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:08:04 INFO - Using env: {'DISPLAY': ':0', 03:08:04 INFO - 'HOME': '/home/cltbld', 03:08:04 INFO - 'LANG': 'en_US.UTF-8', 03:08:04 INFO - 'LANGUAGE': 'en_US:en', 03:08:04 INFO - 'LOGNAME': 'cltbld', 03:08:04 INFO - 'MAIL': '/var/mail/cltbld', 03:08:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:04 INFO - 'MOZ_NO_REMOTE': '1', 03:08:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:04 INFO - 'NO_EM_RESTART': '1', 03:08:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:04 INFO - 'PWD': '/builds/slave/test', 03:08:04 INFO - 'SHELL': '/bin/bash', 03:08:04 INFO - 'SHLVL': '1', 03:08:04 INFO - 'TERM': 'linux', 03:08:04 INFO - 'TMOUT': '86400', 03:08:04 INFO - 'USER': 'cltbld', 03:08:04 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504865238.917504-463219999', 03:08:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:04 INFO - '_': '/tools/buildbot/bin/python'} 03:08:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:08:05 INFO - Running setup.py (path:/tmp/pip-IfBhik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:08:05 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:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:08:05 INFO - Running setup.py (path:/tmp/pip-kqQDJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:08:05 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:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:08:05 INFO - Running setup.py (path:/tmp/pip-W9Zlc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:08:05 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:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:08:05 INFO - Running setup.py (path:/tmp/pip-tMQPSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:08:05 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:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:08:05 INFO - Running setup.py (path:/tmp/pip-fcc6FC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:08:05 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:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:08:05 INFO - Running setup.py (path:/tmp/pip-F1obzW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:08:05 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:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:08:05 INFO - Running setup.py (path:/tmp/pip-ZshyI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:08:05 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:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:08:05 INFO - Running setup.py (path:/tmp/pip-Q5ndq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:08:05 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:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:08:05 INFO - Running setup.py (path:/tmp/pip-IgoGp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:08:05 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:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:08:05 INFO - Running setup.py (path:/tmp/pip-C7AEPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:08:05 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:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:08:05 INFO - Running setup.py (path:/tmp/pip-YjfeGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:08:05 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:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:08:05 INFO - Running setup.py (path:/tmp/pip-Op0agY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:08:05 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:08:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:08:05 INFO - Running setup.py (path:/tmp/pip-t5y0FA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:08:06 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:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:08:06 INFO - Running setup.py (path:/tmp/pip-lFerGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:08:06 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:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:08:06 INFO - Running setup.py (path:/tmp/pip-n_DoSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:08:06 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:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:08:06 INFO - Running setup.py (path:/tmp/pip-F9VEYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:08:06 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:08:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:08:06 INFO - Running setup.py (path:/tmp/pip-pU_F2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:08:06 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:08:06 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:08:06 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:08:06 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:08:06 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:08:06 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:08:06 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:08:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:08:06 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:08:06 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:08:06 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:08:06 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:08:07 INFO - Downloading blessings-1.6.tar.gz 03:08:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:08:07 INFO - Installing collected packages: blessings 03:08:07 INFO - Running setup.py install for blessings 03:08:07 INFO - Successfully installed blessings 03:08:07 INFO - Cleaning up... 03:08:07 INFO - Return code: 0 03:08:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:08:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:08:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:08:07 INFO - Reading from file tmpfile_stdout 03:08:07 INFO - Current package versions: 03:08:07 INFO - argparse == 1.2.1 03:08:07 INFO - blessings == 1.6 03:08:07 INFO - blobuploader == 1.2.4 03:08:07 INFO - docopt == 0.6.1 03:08:07 INFO - functools32 == 3.2.3-2 03:08:07 INFO - jsonschema == 2.5.1 03:08:07 INFO - manifestparser == 1.1 03:08:07 INFO - mozInstall == 1.13 03:08:07 INFO - mozcrash == 1.0 03:08:07 INFO - mozdebug == 0.1 03:08:07 INFO - mozdevice == 0.51 03:08:07 INFO - mozfile == 1.2 03:08:07 INFO - mozhttpd == 0.7 03:08:07 INFO - mozinfo == 0.10 03:08:07 INFO - mozleak == 0.1 03:08:07 INFO - mozlog == 3.5 03:08:07 INFO - moznetwork == 0.27 03:08:07 INFO - mozprocess == 0.25 03:08:07 INFO - mozprofile == 0.28 03:08:07 INFO - mozrunner == 6.13 03:08:07 INFO - mozscreenshot == 0.1 03:08:07 INFO - mozsystemmonitor == 0.3 03:08:07 INFO - moztest == 0.8 03:08:07 INFO - mozversion == 1.4 03:08:07 INFO - psutil == 3.1.1 03:08:07 INFO - requests == 1.2.3 03:08:07 INFO - wsgiref == 0.1.2 03:08:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:08:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:08:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:07 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 0x11c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1391a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1534a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1538090>, '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 0x1538d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1532f90>, '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': '4300f881736b422476174874000001bc-1504865238.917504-463219999', '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:08:07 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:08:07 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:08:07 INFO - Using env: {'DISPLAY': ':0', 03:08:07 INFO - 'HOME': '/home/cltbld', 03:08:07 INFO - 'LANG': 'en_US.UTF-8', 03:08:07 INFO - 'LANGUAGE': 'en_US:en', 03:08:07 INFO - 'LOGNAME': 'cltbld', 03:08:07 INFO - 'MAIL': '/var/mail/cltbld', 03:08:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:07 INFO - 'MOZ_NO_REMOTE': '1', 03:08:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:07 INFO - 'NO_EM_RESTART': '1', 03:08:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:07 INFO - 'PWD': '/builds/slave/test', 03:08:07 INFO - 'SHELL': '/bin/bash', 03:08:07 INFO - 'SHLVL': '1', 03:08:07 INFO - 'TERM': 'linux', 03:08:07 INFO - 'TMOUT': '86400', 03:08:07 INFO - 'USER': 'cltbld', 03:08:07 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504865238.917504-463219999', 03:08:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:07 INFO - '_': '/tools/buildbot/bin/python'} 03:08:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:08 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:08:08 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:08:08 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:08:08 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:08:08 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:08:08 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:08:08 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:08:08 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:08:08 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:08:08 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:08:08 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:08:08 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:08:08 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:08:08 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:08:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:08:09 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:08:09 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:08:09 INFO - Installing collected packages: simplejson 03:08:09 INFO - Running setup.py install for simplejson 03:08:09 INFO - building 'simplejson._speedups' extension 03:08:09 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:08:10 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:08:10 INFO - Successfully installed simplejson 03:08:10 INFO - Cleaning up... 03:08:10 INFO - Return code: 0 03:08:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:08:10 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:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:08:10 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:08:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:08:10 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 0x11c81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1391a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1534a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1538090>, '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 0x1538d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1532f90>, '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': '4300f881736b422476174874000001bc-1504865238.917504-463219999', '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:08:10 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:08:10 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:08:10 INFO - Using env: {'DISPLAY': ':0', 03:08:10 INFO - 'HOME': '/home/cltbld', 03:08:10 INFO - 'LANG': 'en_US.UTF-8', 03:08:10 INFO - 'LANGUAGE': 'en_US:en', 03:08:10 INFO - 'LOGNAME': 'cltbld', 03:08:10 INFO - 'MAIL': '/var/mail/cltbld', 03:08:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:10 INFO - 'MOZ_NO_REMOTE': '1', 03:08:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:10 INFO - 'NO_EM_RESTART': '1', 03:08:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:10 INFO - 'PWD': '/builds/slave/test', 03:08:10 INFO - 'SHELL': '/bin/bash', 03:08:10 INFO - 'SHLVL': '1', 03:08:10 INFO - 'TERM': 'linux', 03:08:10 INFO - 'TMOUT': '86400', 03:08:10 INFO - 'USER': 'cltbld', 03:08:10 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504865238.917504-463219999', 03:08:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:10 INFO - '_': '/tools/buildbot/bin/python'} 03:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:08:10 INFO - Cleaning up... 03:08:10 INFO - Return code: 0 03:08:10 INFO - Running post-action listener: _resource_record_post_action 03:08:10 INFO - Running post-action listener: _start_resource_monitoring 03:08:10 INFO - Starting resource monitoring. 03:08:10 INFO - [mozharness: 2017-09-08 10:08:10.559257Z] Finished create-virtualenv step (success) 03:08:10 INFO - [mozharness: 2017-09-08 10:08:10.559796Z] Running install step. 03:08:10 INFO - Running pre-action listener: _resource_record_pre_action 03:08:10 INFO - Running main action method: install 03:08:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:08:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:08:10 INFO - Reading from file tmpfile_stdout 03:08:10 INFO - Detecting whether we're running mozinstall >=1.0... 03:08:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:08:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:08:10 INFO - Reading from file tmpfile_stdout 03:08:10 INFO - Output received: 03:08:10 INFO - Usage: mozinstall [options] installer 03:08:10 INFO - Options: 03:08:10 INFO - -h, --help show this help message and exit 03:08:10 INFO - -d DEST, --destination=DEST 03:08:10 INFO - Directory to install application into. [default: 03:08:10 INFO - "/builds/slave/test"] 03:08:10 INFO - --app=APP Application being installed. [default: firefox] 03:08:10 INFO - mkdir: /builds/slave/test/build/application 03:08:10 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:08:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:08:27 INFO - Reading from file tmpfile_stdout 03:08:27 INFO - Output received: 03:08:27 INFO - /builds/slave/test/build/application/firefox/firefox 03:08:27 INFO - Running post-action listener: _resource_record_post_action 03:08:27 INFO - [mozharness: 2017-09-08 10:08:27.313113Z] Finished install step (success) 03:08:27 INFO - [mozharness: 2017-09-08 10:08:27.313364Z] Running setup-mitmproxy step. 03:08:27 INFO - Running pre-action listener: _resource_record_pre_action 03:08:27 INFO - Running main action method: setup_mitmproxy 03:08:27 INFO - Skipping: mitmproxy is not required 03:08:27 INFO - Running post-action listener: _resource_record_post_action 03:08:27 INFO - [mozharness: 2017-09-08 10:08:27.314486Z] Finished setup-mitmproxy step (success) 03:08:27 INFO - [mozharness: 2017-09-08 10:08:27.314702Z] Running run-tests step. 03:08:27 INFO - Running pre-action listener: _resource_record_pre_action 03:08:27 INFO - Running pre-action listener: _set_gcov_prefix 03:08:27 INFO - Running main action method: run_tests 03:08:27 WARNING - Try message not found. 03:08:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:08:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:08:27 INFO - Python 2.7.3 03:08:27 INFO - Return code: 0 03:08:27 INFO - grabbing minidump binary from tooltool 03:08:27 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:08:27 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 0x1538090>, '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 0x1538d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1532f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:08:27 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:08:27 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:08:27 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:08:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:08:27 INFO - Return code: 0 03:08:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:08:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:08:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:08:27 INFO - ENV: RUST_BACKTRACE is now full 03:08:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:08:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:08:27 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/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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:08:27 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/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-045 --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:08:27 INFO - Using env: {'DISPLAY': ':0', 03:08:27 INFO - 'HOME': '/home/cltbld', 03:08:27 INFO - 'LANG': 'en_US.UTF-8', 03:08:27 INFO - 'LANGUAGE': 'en_US:en', 03:08:27 INFO - 'LOGNAME': 'cltbld', 03:08:27 INFO - 'MAIL': '/var/mail/cltbld', 03:08:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:08:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:27 INFO - 'MOZ_NO_REMOTE': '1', 03:08:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:08:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:27 INFO - 'NO_EM_RESTART': '1', 03:08:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:27 INFO - 'PWD': '/builds/slave/test', 03:08:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:08:27 INFO - 'RUST_BACKTRACE': 'full', 03:08:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:08:27 INFO - 'SHELL': '/bin/bash', 03:08:27 INFO - 'SHLVL': '1', 03:08:27 INFO - 'TERM': 'linux', 03:08:27 INFO - 'TMOUT': '86400', 03:08:27 INFO - 'USER': 'cltbld', 03:08:27 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504865238.917504-463219999', 03:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:27 INFO - '_': '/tools/buildbot/bin/python'} 03:08:27 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/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-045', '--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:08:27 INFO - mozversion application_buildid: 20170908085005 03:08:27 INFO - mozversion application_changeset: dd75dcec7da162d8ceaaf0883e0e7561bd772992 03:08:27 INFO - mozversion application_display_name: Nightly 03:08:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:08:27 INFO - mozversion application_name: Firefox 03:08:27 INFO - mozversion application_remotingname: firefox 03:08:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:08:27 INFO - mozversion application_vendor: Mozilla 03:08:27 INFO - mozversion application_version: 57.0a1 03:08:27 INFO - mozversion platform_buildid: 20170908085005 03:08:27 INFO - mozversion platform_changeset: dd75dcec7da162d8ceaaf0883e0e7561bd772992 03:08:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:08:27 INFO - mozversion platform_version: 57.0a1 03:08:27 INFO - using testdate: 1504865307 03:08:27 INFO - actual date: 1504865307 03:08:27 INFO - starting webserver on 'localhost:37248' 03:08:27 INFO - SUITE-START | Running 2 tests 03:08:27 INFO - TEST-START | dromaeo_css 03:08:27 INFO - Initialising browser for dromaeo_css test... 03:08:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37248/getInfo.html -profile /tmp/tmpwFbW82/profile 03:08:27 INFO - TEST-INFO | started process 26621 (/builds/slave/test/build/application/firefox/firefox http://localhost:37248/getInfo.html) 03:08:33 INFO - TEST-INFO | 26621: exit 0 03:08:33 INFO - Browser initialized. 03:08:33 INFO - Running cycle 1/1 for dromaeo_css test... 03:08:33 INFO - Using env: {'DISPLAY': ':0', 03:08:33 INFO - 'HOME': '/home/cltbld', 03:08:33 INFO - 'JSGC_DISABLE_POISONING': '1', 03:08:33 INFO - 'LANG': 'en_US.UTF-8', 03:08:33 INFO - 'LANGUAGE': 'en_US:en', 03:08:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:08:33 INFO - 'LOGNAME': 'cltbld', 03:08:33 INFO - 'MAIL': '/var/mail/cltbld', 03:08:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:08:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:08:33 INFO - 'MOZ_CRASHREPORTER': '1', 03:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:08:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:08:33 INFO - 'MOZ_NO_REMOTE': '1', 03:08:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:08:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:08:33 INFO - 'NO_EM_RESTART': '1', 03:08:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:08:33 INFO - 'PWD': '/builds/slave/test', 03:08:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:08:33 INFO - 'RUST_BACKTRACE': 'full', 03:08:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:08:33 INFO - 'SHELL': '/bin/bash', 03:08:33 INFO - 'SHLVL': '1', 03:08:33 INFO - 'TERM': 'linux', 03:08:33 INFO - 'TMOUT': '86400', 03:08:33 INFO - 'USER': 'cltbld', 03:08:33 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504865238.917504-463219999', 03:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:08:33 INFO - '_': '/tools/buildbot/bin/python'} 03:08:33 INFO - TEST-INFO | started process 26822 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwFbW82/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:08:34 INFO - PID 26822 | 03:08:34 INFO - PID 26822 | (/builds/slave/test/build/application/firefox/firefox:26873): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:08:34 INFO - PID 26822 | 03:08:44 INFO - PID 26822 | RSS: Main: 163979264 03:08:44 INFO - PID 26822 | 03:11:41 INFO - PID 26822 | Cycle 1(1): loaded http://localhost:37248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37248/tests/dromaeo/cssquery-ext.html) 03:11:43 INFO - PID 26822 | RSS: Main: 167489536 03:11:43 INFO - PID 26822 | 03:14:44 INFO - PID 26822 | Cycle 1(1): loaded http://localhost:37248/tests/dromaeo/cssquery-ext.html (next: http://localhost:37248/tests/dromaeo/cssquery-jquery.html) 03:14:45 INFO - PID 26822 | RSS: Main: 164290560 03:14:45 INFO - PID 26822 | 03:17:46 INFO - PID 26822 | Cycle 1(1): loaded http://localhost:37248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37248/tests/dromaeo/cssquery-mootools.html) 03:17:47 INFO - PID 26822 | RSS: Main: 166240256 03:17:47 INFO - PID 26822 | 03:20:48 INFO - PID 26822 | Cycle 1(1): loaded http://localhost:37248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37248/tests/dromaeo/cssquery-prototype.html) 03:20:49 INFO - PID 26822 | RSS: Main: 164995072 03:20:49 INFO - PID 26822 | 03:23:52 INFO - PID 26822 | Cycle 1(1): loaded http://localhost:37248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37248/tests/dromaeo/cssquery-yui.html) 03:23:53 INFO - PID 26822 | RSS: Main: 166768640 03:23:53 INFO - PID 26822 | 03:26:54 INFO - PID 26822 | Cycle 1(1): loaded http://localhost:37248/tests/dromaeo/cssquery-yui.html (next: http://localhost:37248/tests/dromaeo/cssquery-dojo.html) 03:26:54 INFO - PID 26822 | RSS: Main: 168976384 03:26:54 INFO - PID 26822 | 03:26:54 INFO - PID 26822 | __start_tp_report 03:26:54 INFO - PID 26822 | _x_x_mozilla_page_load 03:26:54 INFO - PID 26822 | _x_x_mozilla_page_load_details 03:26:54 INFO - PID 26822 | |i|pagename|runs| 03:26:54 INFO - PID 26822 | |0;dojo.html;3779.659830615244;3784.848606055758;3816;3847.7306588538795;3848.1149335652817;6157.815265542041;6173.629582225064;6192.071189321593;6216.16081828953;6282.937170628293;6254.374562543743;6261.561690681654;6262.561620686553;6282.497400207983;6310.716017779199;5555.777768889239;5623.606347555673;5641.379448260688;5662.6036177467595;5722.37053924068;5574.303212098488;5625.437456254383;5640.576956728261;5642.069058605346;5665.575081868856;302.93899878310555;5466.617336786387;5493.011257973583;5540.168974653834;5648.406917273689;5556.222128901957;5577.3028371453565;5628.155776633538;5645.915311270334;5656.773729050833;5543.085390910495;5558.666480011212;5612.102063669793;5630.85922851932;5635.718214089279;6089.908651370233;6131.601445906002;6215.378462153749;6238.2825975013;6259.749610015594;6664.666766661648;6680.164979377578;6758.831029224309;6762.188537375498;6770.5599136056;6724.596524208563;6799.490038247101;6808.795736127924;6827.69275382604;6831.6242606656615;6253.468455181315;6300.747970081237;6323.715432805535;6341.619502829845;6348.206474190726;7057.188423331382;7096.787096387116;7102.112235970504;7122.430205583541;7127.073480447509;6703.66481675924;6715.832104197434;6774.390304872487;6792.796216113525;6801.897971530431;6655.800325990228;6804.11546498952;6811.080504131879;6836.4530837532875;6893.20728116273;6703.329667033355;6740.359665831738;6761;6780.982852572055;6781.62701051437;6689.49828762835;6762.053312536249;6791.388775010273;6805.523613347018;6857.657117144123;4926.58124059459;4992.72540010303;5004.324416203839;5005.524475174852;5026.89946201074;4497.865064048084;4498.527654596286;4519.480259770103;4530.365748795171;4573.428321459817;6172.598781079305;6308.936910630927;6316.463100636497;6321.589096708699;6324.33594472583;6668.499862510331;6743.123393958754;6754.223264324665;6755.054292399068;6772.83067923296;6240.7503699852405;6319.05214217871;6329.082283069021;6357;6364.331745166691;5459.426760190202;5527.723613819293;5578.553715702734;5581;5598.804041858515;6891.793246202622;6992.1958974717545;7023.92976070243;7059.788206353817;7125.714971401189;6743.393094621508;6768.120144381199;6769.9661501694145;6771.085903402977;6771.559848609923;6960.095187625576;7028.262032486516;7085.397741192057;7087.184973728087;7104.964475177591;6693.564918280296;6740.528163028541;6779.2542820288745;6785.864282714417;6818.965905170442;6793.626350550767;6816.216135144522;6822.249552549117;6916.100906882073;6923.169219693669;6484.416402523795;6564.343565643398;6567.9014814778775;6576.309487503743;6643.534952553275;6563.3764792344655;6577.309382514767;6587.341265873374;6589.0775291460195;6589.27517973013;6489.7404103835315;6511.055896895018;6514.250861151028;6518.57629254097;6522.184726909137;6542.476601871934;6577.506686998398;6629.303923088007;6690.531662783558;6710.563813352116;6322.747090116344;6406.647634380154;6426.260979987174;6512.3162067982175;6516.804495865132 03:26:54 INFO - PID 26822 | |1;ext.html;927.7866090799116;941.3081385181895;974.3325821812059;1001.714511364261;1001.7145113642613;42777.57222427775;43250.351244731355;43524;43668.34497482536;43859.78070109645;39988.40017399741;40121.59878401215;40225.798871005674;40292.19415611694;40305.39541906874;7404.14852291988;7490.700371985114;7502.62486875654;7508.774736757906;7527.172011078805;7225.891611625803;7288.744893928713;7301.926980730178;7303.2331605181225;7308.853822923538;7993.520388776692;8221.424500284982;8224.38317126218;8226.465279756827;8227.13615070418;8072.273495385414;8102.7569172924905;8166.632501537445;8169.346452283803;8173.95913020437;7880.369570434352;8018.518888866628;8021.919780802175;8036.316913062396;8053.557054362008;39871.601283987366;40226.59773402258;40232.396514052314;40235.59764402348;40309.193816123516;3650.7809531428197;3693.907652308687;3694.07648087978;3696.8706095286807;3704.0369503929173;32833.835830820935;32900;32931.34137317264;32936.835315823264;32941;5426.593005524602;5463.7814487420455;5487.890242195133;5491.7254137292975;5507.586930980193;210911;211959;212355;213172;214364.99999999843;51270.487295127314;51397.48602514002;51446.485535144915;51474;51645.22532162021;54138.72930635322;54195.729021354644;54196.187057194176;54273.185902211495;54314.45685543094;16486.258118384703;16617.169141543116;16630.08534530588;16691.749623755655;16747.246373913204;13644;13667.17996920188;13697.178169309873;13700.3834827433;13702.177869327872;14562.927185364006;14813.333399997025;14853.405863765329;14877.553673389815;14934.626634334012;5878;5898.970505147447;5926.2295901532525;6062.666553339521;6136.693165341715;1074.7689246811974;1084.8915108489089;1085.2999815119204;1090.7273181704575;1110.2838669058574;24274.271771846874;24680;24695.38261543476;24801.379965500644;24840.25479235626;1188.9346085965356;1207.5532053140394;1208.546794951893;1218.524775337619;1218.61004478566;13002.479900803863;13173.802392964113;13222.140560863512;13311.800322995163;13345.132566383349;271029;274446;274834;274993;275289;54619.45380546144;54764.726176368866;60040.69979650161;60089;60622;1459.3360021190545;1502.1512845242403;1514.356398530642;1517.8102737157856;1519.3487364548712;29594.852025739732;29838.25404364908;30000.549991750577;30456.086317410514;30522.389552208388;39772.80113599414;39891.60108398879;39914.60085399109;39957.80021099876;40014.1997160061;247017;247201;247397;247427;247840;34721.479177811845;34725.82637086899;34738;34811.65188348084;34812.91122532656;44660.55339446694;45654.31518527158;46094.76952615215;46197;46342;33535.664643354234;33906.15234619154;34201;34281.314373712885;34699.65300346964;33256.501152483215;33975.66024339725;34116;34277;34889.82555087208;39652.60347396489;39914.60085399225;40174.59825401709;40254;40418.1916361677;33222.16944576405;33363.165920852174;33666;34045.319093618484;34112.82943585266;26101.477970440865;26277.73722262753;26317.736822632294;26323.868380657976;26724.19827405181 03:26:54 INFO - PID 26822 | |2;jquery.html;542.2642767239278;544.3467838593689;548.7942021741846;553.5129086403965;554.2157846646994;23202;23994.88002559987;24040.27879163628;24042.75957240427;24069.39826504336;22052.8897355513;22350.21774237902;22415;22497.55004899901;22556.21053263136;5996.610220335675;6479.092926990225;6507.577007494521;6513.348665133496;6519.8044058678315;6367.267764207129;6462.321456247097;6465.321141280168;6466.25638051623;6469.74121035158;6222.253329600454;6350.142730731338;6492.642904640243;6497.05792660064;6497.155369801919;6447.4197322241225;6452.387023232785;6463.418292353711;6484.578502397353;6488.708008139622;5782.479576838105;6002.789902353396;6018.15745795589;6021.909671354933;6025.668588227646;29690.851545742134;30129.246768830737;30223.848880755675;30238.395232095234;30243.697563024307;2273.863568185914;2300.344401845466;2300.574393737164;2300.907963681451;2302.1597117052324;19002.23991040357;19543.706844397344;19823.306184283625;20262.392128236177;20347.491312717153;2545.52907712072;2556.7187609362954;2594.4681340325187;2597.8181527293095;2607.38726399296;131163;137011;143363.2831835834;150357.24821375933;150468;14387.425325629141;14802.925985370004;14900.776488352683;14937.402503899722;14978.775318370233;22360.105595776313;22446.32661020172;22464.43838904008;22469.550608988055;22475.21336753206;10571.577136914591;10573.568079341641;10578.682639520826;10696.78606427867;10718.892811071946;9977.700668979942;10019.599216031424;10043.296969212084;10043.849342259726;10138.391696498235;9258.074192580689;9348;9379.718608441757;9420.811583768424;9642.517874106266;2450.6691596634582;2517.798576113943;2523.3691577105724;2533.1007492340323;2667.1731763153484;1021.9948900255451;1035.4460363705382;1036.9533370998324;1037.3205550364523;1059.1526778577108;20443;20556.486087847923;20590.382288531368;20715.96420178985;21018.78981210199;1084.1056128693858;1099.2964502718266;1102.5204036244259;1103.7351035751362;1114.3258328711174;11067.778644427228;11186.888131118747;11361.602343917923;11393.829092563617;11440.828387574193;614780;629841;635079;659833;668359;125720;126874;127750;128116;128508;1211.6789050901518;1225.436299302313;1243.4155946705034;1249.256692268099;1252.6492582077033;17913.37303194382;18066.548336291697;18086.18612162482;18239.452816415527;18323.175457104197;61318.69340653447;62276;62772.68613656903;63676.36323636704;64280;81031.59484202541;81567.59216203881;81587.59206203932;81660.59169704113;82435;14116.788248176492;14314.355853986805;14349.56951291463;14441.35013924397;14500;23313.184038558567;23351.299461016195;23466.76533234646;23512.529749405257;23638.527229454972;19050.714239286146;19081.42755717331;19335.903320483307;19423.514412139808;19443.4166974991;17251;17376.82623173752;17401.390951316644;17513.124343782503;17582.648347033242;19626.411207663794;20040.799592004478;20177.39467815968;20247.696284555448;20443.182272708924;14075.577732668036;14109;14174.50389236413;14199.148051116967;14202.50291239843;19287.903560482107;19303.51741206481;19468.707969380754;19555.902220488806;19604.117814698657 03:26:54 INFO - PID 26822 | |3;mootools.html;1406.7678832992553;1460.1677044084872;1468.698916722072;1471.352604853865;1475.0780762023735;16867.83132168678;17492.650146997054;17584.120793960283;17649.73525397117;17663.028533430657;17081.316747330122;17102.572435689082;17124.143792810373;17205.483835484923;17234.310627574912;2448.228807925502;2520.861352625605;2541.7077036140895;2555.8722063896826;2565.5125526150064;2632.6577544919132;2746.3546066674317;2752.3944732158893;2752.862356882158;2752.9724702753015;2728.4952283827456;2821.7883658725573;2823.9294017649518;2824.8163869348527;2831.0091467986244;2846.814957027797;2854.685984541699;2868.584055311968;2869.139258222525;2876.324063845005;2790.372166262595;2802.985985070082;2809.8173618714923;2815;2815.380616264439;16432.424865129662;16738.83261167385;16770.916145419196;16774.412895548598;16780.664386712197;7880.330171935392;8117.472364296302;8126;8144.75565733029;8148;4685.367475390813;4755.239161734106;4822.855314340575;5366.812161574365;5515.090010148361;2019.636465436221;2040.36748607932;2058.3207541511265;2071.7617473990535;2099.6325643012565;30405.39189216203;30589.847050764827;30607.8469607655;30699;30725.846370768228;7046.189688185822;7100.9644951775945;7127.536710113826;7240.565566066053;7290.45321600882;19551.413457596296;19622.509437264183;19638.803611963984;19649.705254421195;19681.31115410954;8299.419040667217;8433.704820331259;8451.873221901677;8455.069942306342;8457.915420845837;7415.1843297237265;7566.546007239584;7688.654010569538;7691.192424795316;7705.730299439605;5227.111391063529;5443.782248710087;5568.248286481355;5579.246801681802;5634.690092044977;1406.6413064668443;1437.4968760933589;1485.502356710514;1520.711064897666;1523.5810152208232;948.2224575847739;966.5312323523086;967.9225661947028;974.5030034682363;976.1605019683067;9542.952285238529;9550.61797528105;9557.713268601952;9591.42451452915;9741.464219467856;1064.377339256528;1073.818799320741;1084.1760262200785;1089.5260561655705;1098.807708650983;5535.916961245584;5821.679807610622;5824.9323088912915;6055.182550355646;6097.054956481752;24554.877225613756;24791.380215494395;24902.003919843366;25061.37346566314;25119.74880251174;24248.526963300872;24279.150229742227;24515;24627.630585541232;24802.379940501633;11838.052955763691;11869.643910682693;12027.879721202677;12047.939760301319;12055.698607534707;9070.727878163665;9092.909070909207;9174.816503670023;9178.26573874075;9300.255979521487;20856.270030548858;21109.366718998455;21145.577088458453;21396.251131210334;21736.673949890446;21717.26261899076;21830.89084554567;22007.11971521121;22046.669299960835;22092.668609971184;12171.14801963854;12374.62876113718;12377.938110309391;12382.81425778632;12387.75224495487;15187.240637968278;15409.84590154084;15480.303386347416;15482.612934676723;15524.767128492857;12046.939765301116;12077;12182.512699491921;12188.20776649515;12548.498060077494;12305;12388.194767340094;12460.750784984431;12477.313747743961;12764.106512544033;10075.84886226722;10357.896421035995;10367.27429079957;10465.529051192563;10519;8347.58262086881;8358.665653373797;8421.578921054046;8457.57712114404;8460.196281353261;7828.960855195687;7953.721619743281;8027.478213916077;8052.396070294634;8062.314703250289 03:26:54 INFO - PID 26822 | |4;prototype.html;488.62864223190365;519.3949049357498;519.6414474012931;520.877593765465;528.5639347538277;6197.535184861136;6314.621122732639;6318.715657795398;6376.617402955826;6397.536948274913;6109.725062372193;6240.968795156018;6290.811275661737;6327.968360158205;6359.459445947088;5025.0954828130925;5063.84808455745;5068.417132029808;5090.821821236248;5130.281760553526;4993.875153121165;5038.244263360489;5042;5049.293098966147;5078.162103252977;4731.6451266155;4834.9033019339595;4895.926561101586;4924.876878078041;4970.329005584236;4831.130396528623;4833.154198015333;4843.370361852954;4847.927281090751;4907.214845624683;4332.1985432695055;4342.739435633872;4366.104948485553;4376.387305777193;4378.759168245745;6140.969295153541;6158.445739883387;6161.19904412428;6173.351798061229;6230;5652.236948012007;5716.485516303511;5727.971360143172;5735.283089613798;5744.856378590527;6511.479081673455;6605.801825945181;6606.570572912745;6610.768623098216;6664.266930637626;4824.6863953842885;4857.344258525124;4869.659123861331;4877.414710234759;4925.236588328815;6793.898091528631;6860.656967151673;6929.5842249465195;6941.895871561931;6954.930450695479;5037.672551284155;5040.79836806531;5054.646174767805;5075.923861142086;5097.46476619957;5055.823046193439;5059.2916991621205;5070.036693028313;5097.770600322994;5122.974385128051;5682.857928551736;5691.800786972436;5702.657840529581;5735.655860648374;5736.6558006519745;6580.341965803382;6612.471002319803;6641.402273795382;6664.433523150489;6740.157480314961;1724.508300320214;1751.8248175182634;1761.806201317854;1769.566456218209;1778.8399044086095;1126.380490730095;1171.77736230116;1186.4542310537076;1187.6971372300009;1221.3893053473264;6182.227221597301;6184.6598437085495;6193.442590166816;6278.152449419361;6341.302456729756;6585.835354116185;6599;6606.570572912745;6718.8320291993095;6761.4590832733265;6101.938980610137;6131.754729810847;6157.013848283579;6243.594166379171;6318.273398559132;3489.860405583748;3514.61339252682;3514.876979305712;3588.7308451866215;3604.98197509016;6895.586264824126;6984.965075174591;7031.191412987469;7034.291797657938;7037.6481175941;6873.450123990069;6956.79129626112;6972.407345375598;7011.579305241702;7021.648917554204;6970.51206415546;6994.405475534637;7001.92998070023;7010.649467526603;7053.188883278283;5972.999045319866;6633.270340262664;6774.559653622498;6789.864202716017;6793.558418702769;6595.82337828525;6636.668166591748;6700.363465470772;6741.966290168518;6769.052332673528;7110.217876033529;7124.964375178091;7140.642967851691;7146.428285737233;7167.7132914682825;4921.9015619688125;5012.899742005066;5019.397672279204;5035.144025515744;5036.546710796046;6581.881080216471;6748.19021717397;6773.424258938046;6877.965610171917;6902.481454080094;6429.164208652845;6660.100886380276;6668.799936001928;6687.565308254948;6692.364225398579;6577.14497115372;6624.138861947916;6669.7665581704405;6687.598744075371;6692.628011257778;6596.472282217507;6605;6611.966940165268;6619.642973190581;6624.503162262753;6615.4376877965915;6664.866702666017;6685.298043705536;6693.497987651043;6693.866122677422;6414.166158399378;6492;6493.2857385688485;6514.446272066927;6517.4134327910715 03:26:54 INFO - PID 26822 | |5;yui.html;922.5156792683845;951.3530799056643;952.4666186935317;995.6316163019682;1024.8616436781033;20831.895840520796;21801.890990545064;21866.562668746614;21928.342149735494;21937.354698397645;18803.153858076384;19274.22903083875;19760.407187784353;19765.901170494126;19912.90043549784;995.144176008632;1023.5905637744905;1023.8976102389738;1024.3956065921104;1025.0262250861674;954.6944977607134;1097.5006372100675;1105.6351404036689;1105.9557617695282;1106.6292791914677;1126.7577470843767;1136.8578927634046;1139.2253267777908;1141.2125633313028;1142.3146112332618;1122.45560902962;1131.1459847814888;1134.4101067444965;1134.8467956825805;1135.7728454309088;793.2701914238856;806.2139414071293;811.3549727966222;828.4615000249826;832.1886160993043;21534.784652153434;21773.782262177494;21802.89098554513;21815.563688726295;21830.126794928183;23018.42453938648;23993.88003059991;24189.637155442684;24407.87796061026;24432.755672443403;18758.906205469022;18972.525686857938;19154;19205.80794192068;19226.615467690568;4074.490688663917;4074.7351422157467;4089.284375234322;4094.3858421236755;4097.508299004138;164776;164782;165617;166761;169529;15220.543383698507;15238.161901095546;15258.618534536725;15451.690966180613;15465.226738663021;27241;27506;27844.721552784617;28090.157295281173;28093.859530702626;9616.615335386507;9703.223742100616;9770.75573110678;9772.071653192936;9790.608375665037;4788.976055119702;4859.757012149378;4867.440244371872;4871.074495845778;4883.4628190898975;6082.361352058058;6171.753129874754;6198.783042593487;6209.5032397408095;6211.472024877846;1144.199060657543;1152.4065106470175;1186.0867132308074;1187.441902305915;1190.2917763930448;737.2406421385981;749.8237914090184;760.2929275773473;766.8849672549162;778.1284960843891;12789.872101278868;13112.934435327954;13121.409536570878;13333;13475.258860762557;744.8845428958521;744.9404047676173;750.3097150621442;758.7571976967317;762.5996351915289;5924.644521328734;5955.940440595626;5962.5826192167;6136.17161683176;6199.473044791243;151832;156243;157044;160556;160800;135168;135484.3225783865;136157.3192134033;136873;136992;113529;114787.42606287081;117125;117647;121291.39354303172;11331.660050198507;11421.486033128363;11445.25605835618;11445.828312575151;11550.249233799776;54803.451965479835;55411;56020;56355.71822141027;57051.714741426025;70045.64977175082;70185;71101.28898710947;71628.64185679039;72741;16809.327626895276;16966.660666786844;16984.235709393353;17019.23413446373;17099.572510687332;18110.094495275447;19096;19124.61750764949;19227.230910763414;19248.8075119247;16863.156842157598;17239.7414038792;17334.04662743561;17456.563585910455;17521.56196095108;16888.746668799733;17095.91452042732;17240.224189911234;17353.392631257844;17395.7390639138;16482.917585411997;16760.32958681639;16973.74539381935;16992.150392480078;17036.744448833524;11212.383318917538;11332.206745527734;11471.13966452536;11504.482298296429;11943.402829858645;9134.406263593111;9151.862722059308;9348.439093654402;9364.531773411167;9399.577019034023 03:26:54 INFO - PID 26822 | __end_tp_report 03:26:54 INFO - PID 26822 | __start_cc_report 03:26:54 INFO - PID 26822 | _x_x_mozilla_cycle_collect,366 03:26:54 INFO - PID 26822 | __end_cc_report 03:26:54 INFO - PID 26822 | __startTimestamp1504866414840__endTimestamp 03:26:54 INFO - PID 26822 | 03:26:54 INFO - PID 26822 | ------- Summary: start ------- 03:26:54 INFO - PID 26822 | Number of tests: 6 03:26:54 INFO - PID 26822 | 03:26:54 INFO - PID 26822 | [#0] dojo.html Cycles:165 Average:6214.52 Median:6514.25 stddev:877.85 (13.5%) stddev-sans-first:859.51 03:26:54 INFO - PID 26822 | Values: 3779.7 3784.8 3816.0 3847.7 3848.1 6157.8 6173.6 6192.1 6216.2 6282.9 6254.4 6261.6 6262.6 6282.5 6310.7 5555.8 5623.6 5641.4 5662.6 5722.4 5574.3 5625.4 5640.6 5642.1 5665.6 302.9 5466.6 5493.0 5540.2 5648.4 5556.2 5577.3 5628.2 5645.9 5656.8 5543.1 5558.7 5612.1 5630.9 5635.7 6089.9 6131.6 6215.4 6238.3 6259.7 6664.7 6680.2 6758.8 6762.2 6770.6 6724.6 6799.5 6808.8 6827.7 6831.6 6253.5 6300.7 6323.7 6341.6 6348.2 7057.2 7096.8 7102.1 7122.4 7127.1 6703.7 6715.8 6774.4 6792.8 6801.9 6655.8 6804.1 6811.1 6836.5 6893.2 6703.3 6740.4 6761.0 6781.0 6781.6 6689.5 6762.1 6791.4 6805.5 6857.7 4926.6 4992.7 5004.3 5005.5 5026.9 4497.9 4498.5 4519.5 4530.4 4573.4 6172.6 6308.9 6316.5 6321.6 6324.3 6668.5 6743.1 6754.2 6755.1 6772.8 6240.8 6319.1 6329.1 6357.0 6364.3 5459.4 5527.7 5578.6 5581.0 5598.8 6891.8 6992.2 7023.9 7059.8 7125.7 6743.4 6768.1 6770.0 6771.1 6771.6 6960.1 7028.3 7085.4 7087.2 7105.0 6693.6 6740.5 6779.3 6785.9 6819.0 6793.6 6816.2 6822.2 6916.1 6923.2 6484.4 6564.3 6567.9 6576.3 6643.5 6563.4 6577.3 6587.3 6589.1 6589.3 6489.7 6511.1 6514.3 6518.6 6522.2 6542.5 6577.5 6629.3 6690.5 6710.6 6322.7 6406.6 6426.3 6512.3 6516.8 03:26:54 INFO - PID 26822 | 03:26:54 INFO - PID 26822 | [#1] ext.html Cycles:180 Average:42092.08 Median:34851.37 stddev:63930.50 (183.4%) stddev-sans-first:64035.13 03:26:54 INFO - PID 26822 | Values: 927.8 941.3 974.3 1001.7 1001.7 42777.6 43250.4 43524.0 43668.3 43859.8 39988.4 40121.6 40225.8 40292.2 40305.4 7404.1 7490.7 7502.6 7508.8 7527.2 7225.9 7288.7 7301.9 7303.2 7308.9 7993.5 8221.4 8224.4 8226.5 8227.1 8072.3 8102.8 8166.6 8169.3 8174.0 7880.4 8018.5 8021.9 8036.3 8053.6 39871.6 40226.6 40232.4 40235.6 40309.2 3650.8 3693.9 3694.1 3696.9 3704.0 32833.8 32900.0 32931.3 32936.8 32941.0 5426.6 5463.8 5487.9 5491.7 5507.6 210911.0 211959.0 212355.0 213172.0 214365.0 51270.5 51397.5 51446.5 51474.0 51645.2 54138.7 54195.7 54196.2 54273.2 54314.5 16486.3 16617.2 16630.1 16691.7 16747.2 13644.0 13667.2 13697.2 13700.4 13702.2 14562.9 14813.3 14853.4 14877.6 14934.6 5878.0 5899.0 5926.2 6062.7 6136.7 1074.8 1084.9 1085.3 1090.7 1110.3 24274.3 24680.0 24695.4 24801.4 24840.3 1188.9 1207.6 1208.5 1218.5 1218.6 13002.5 13173.8 13222.1 13311.8 13345.1 271029.0 274446.0 274834.0 274993.0 275289.0 54619.5 54764.7 60040.7 60089.0 60622.0 1459.3 1502.2 1514.4 1517.8 1519.3 29594.9 29838.3 30000.5 30456.1 30522.4 39772.8 39891.6 39914.6 39957.8 40014.2 247017.0 247201.0 247397.0 247427.0 247840.0 34721.5 34725.8 34738.0 34811.7 34812.9 44660.6 45654.3 46094.8 46197.0 46342.0 33535.7 33906.2 34201.0 34281.3 34699.7 33256.5 33975.7 34116.0 34277.0 34889.8 39652.6 39914.6 40174.6 40254.0 40418.2 33222.2 33363.2 33666.0 34045.3 34112.8 26101.5 26277.7 26317.7 26323.9 26724.2 03:26:54 INFO - PID 26822 | 03:26:54 INFO - PID 26822 | [#2] jquery.html Cycles:180 Average:40020.89 Median:22355.16 stddev:106817.02 (477.8%) stddev-sans-first:107075.54 03:26:54 INFO - PID 26822 | Values: 542.3 544.3 548.8 553.5 554.2 23202.0 23994.9 24040.3 24042.8 24069.4 22052.9 22350.2 22415.0 22497.6 22556.2 5996.6 6479.1 6507.6 6513.3 6519.8 6367.3 6462.3 6465.3 6466.3 6469.7 6222.3 6350.1 6492.6 6497.1 6497.2 6447.4 6452.4 6463.4 6484.6 6488.7 5782.5 6002.8 6018.2 6021.9 6025.7 29690.9 30129.2 30223.8 30238.4 30243.7 2273.9 2300.3 2300.6 2300.9 2302.2 19002.2 19543.7 19823.3 20262.4 20347.5 2545.5 2556.7 2594.5 2597.8 2607.4 131163.0 137011.0 143363.3 150357.2 150468.0 14387.4 14802.9 14900.8 14937.4 14978.8 22360.1 22446.3 22464.4 22469.6 22475.2 10571.6 10573.6 10578.7 10696.8 10718.9 9977.7 10019.6 10043.3 10043.8 10138.4 9258.1 9348.0 9379.7 9420.8 9642.5 2450.7 2517.8 2523.4 2533.1 2667.2 1022.0 1035.4 1037.0 1037.3 1059.2 20443.0 20556.5 20590.4 20716.0 21018.8 1084.1 1099.3 1102.5 1103.7 1114.3 11067.8 11186.9 11361.6 11393.8 11440.8 614780.0 629841.0 635079.0 659833.0 668359.0 125720.0 126874.0 127750.0 128116.0 128508.0 1211.7 1225.4 1243.4 1249.3 1252.6 17913.4 18066.5 18086.2 18239.5 18323.2 61318.7 62276.0 62772.7 63676.4 64280.0 81031.6 81567.6 81587.6 81660.6 82435.0 14116.8 14314.4 14349.6 14441.4 14500.0 23313.2 23351.3 23466.8 23512.5 23638.5 19050.7 19081.4 19335.9 19423.5 19443.4 17251.0 17376.8 17401.4 17513.1 17582.6 19626.4 20040.8 20177.4 20247.7 20443.2 14075.6 14109.0 14174.5 14199.1 14202.5 19287.9 19303.5 19468.7 19555.9 19604.1 03:26:54 INFO - PID 26822 | 03:26:54 INFO - PID 26822 | [#3] mootools.html Cycles:180 Average:10346.04 Median:13820.31 stddev:7728.53 (55.9%) stddev-sans-first:7721.03 03:26:54 INFO - PID 26822 | Values: 1406.8 1460.2 1468.7 1471.4 1475.1 16867.8 17492.7 17584.1 17649.7 17663.0 17081.3 17102.6 17124.1 17205.5 17234.3 2448.2 2520.9 2541.7 2555.9 2565.5 2632.7 2746.4 2752.4 2752.9 2753.0 2728.5 2821.8 2823.9 2824.8 2831.0 2846.8 2854.7 2868.6 2869.1 2876.3 2790.4 2803.0 2809.8 2815.0 2815.4 16432.4 16738.8 16770.9 16774.4 16780.7 7880.3 8117.5 8126.0 8144.8 8148.0 4685.4 4755.2 4822.9 5366.8 5515.1 2019.6 2040.4 2058.3 2071.8 2099.6 30405.4 30589.8 30607.8 30699.0 30725.8 7046.2 7101.0 7127.5 7240.6 7290.5 19551.4 19622.5 19638.8 19649.7 19681.3 8299.4 8433.7 8451.9 8455.1 8457.9 7415.2 7566.5 7688.7 7691.2 7705.7 5227.1 5443.8 5568.2 5579.2 5634.7 1406.6 1437.5 1485.5 1520.7 1523.6 948.2 966.5 967.9 974.5 976.2 9543.0 9550.6 9557.7 9591.4 9741.5 1064.4 1073.8 1084.2 1089.5 1098.8 5535.9 5821.7 5824.9 6055.2 6097.1 24554.9 24791.4 24902.0 25061.4 25119.7 24248.5 24279.2 24515.0 24627.6 24802.4 11838.1 11869.6 12027.9 12047.9 12055.7 9070.7 9092.9 9174.8 9178.3 9300.3 20856.3 21109.4 21145.6 21396.3 21736.7 21717.3 21830.9 22007.1 22046.7 22092.7 12171.1 12374.6 12377.9 12382.8 12387.8 15187.2 15409.8 15480.3 15482.6 15524.8 12046.9 12077.0 12182.5 12188.2 12548.5 12305.0 12388.2 12460.8 12477.3 12764.1 10075.8 10357.9 10367.3 10465.5 10519.0 8347.6 8358.7 8421.6 8457.6 8460.2 7829.0 7953.7 8027.5 8052.4 8062.3 03:26:54 INFO - PID 26822 | 03:26:54 INFO - PID 26822 | [#4] prototype.html Cycles:180 Average:5606.50 Median:6260.87 stddev:1612.16 (25.7%) stddev-sans-first:1570.25 03:26:54 INFO - PID 26822 | Values: 488.6 519.4 519.6 520.9 528.6 6197.5 6314.6 6318.7 6376.6 6397.5 6109.7 6241.0 6290.8 6328.0 6359.5 5025.1 5063.8 5068.4 5090.8 5130.3 4993.9 5038.2 5042.0 5049.3 5078.2 4731.6 4834.9 4895.9 4924.9 4970.3 4831.1 4833.2 4843.4 4847.9 4907.2 4332.2 4342.7 4366.1 4376.4 4378.8 6141.0 6158.4 6161.2 6173.4 6230.0 5652.2 5716.5 5728.0 5735.3 5744.9 6511.5 6605.8 6606.6 6610.8 6664.3 4824.7 4857.3 4869.7 4877.4 4925.2 6793.9 6860.7 6929.6 6941.9 6954.9 5037.7 5040.8 5054.6 5075.9 5097.5 5055.8 5059.3 5070.0 5097.8 5123.0 5682.9 5691.8 5702.7 5735.7 5736.7 6580.3 6612.5 6641.4 6664.4 6740.2 1724.5 1751.8 1761.8 1769.6 1778.8 1126.4 1171.8 1186.5 1187.7 1221.4 6182.2 6184.7 6193.4 6278.2 6341.3 6585.8 6599.0 6606.6 6718.8 6761.5 6101.9 6131.8 6157.0 6243.6 6318.3 3489.9 3514.6 3514.9 3588.7 3605.0 6895.6 6985.0 7031.2 7034.3 7037.6 6873.5 6956.8 6972.4 7011.6 7021.6 6970.5 6994.4 7001.9 7010.6 7053.2 5973.0 6633.3 6774.6 6789.9 6793.6 6595.8 6636.7 6700.4 6742.0 6769.1 7110.2 7125.0 7140.6 7146.4 7167.7 4921.9 5012.9 5019.4 5035.1 5036.5 6581.9 6748.2 6773.4 6878.0 6902.5 6429.2 6660.1 6668.8 6687.6 6692.4 6577.1 6624.1 6669.8 6687.6 6692.6 6596.5 6605.0 6612.0 6619.6 6624.5 6615.4 6664.9 6685.3 6693.5 6693.9 6414.2 6492.0 6493.3 6514.4 6517.4 03:26:54 INFO - PID 26822 | 03:26:54 INFO - PID 26822 | [#5] yui.html Cycles:180 Average:28475.03 Median:19179.90 stddev:43948.44 (229.1%) stddev-sans-first:44023.04 03:26:54 INFO - PID 26822 | Values: 922.5 951.4 952.5 995.6 1024.9 20831.9 21801.9 21866.6 21928.3 21937.4 18803.2 19274.2 19760.4 19765.9 19912.9 995.1 1023.6 1023.9 1024.4 1025.0 954.7 1097.5 1105.6 1106.0 1106.6 1126.8 1136.9 1139.2 1141.2 1142.3 1122.5 1131.1 1134.4 1134.8 1135.8 793.3 806.2 811.4 828.5 832.2 21534.8 21773.8 21802.9 21815.6 21830.1 23018.4 23993.9 24189.6 24407.9 24432.8 18758.9 18972.5 19154.0 19205.8 19226.6 4074.5 4074.7 4089.3 4094.4 4097.5 164776.0 164782.0 165617.0 166761.0 169529.0 15220.5 15238.2 15258.6 15451.7 15465.2 27241.0 27506.0 27844.7 28090.2 28093.9 9616.6 9703.2 9770.8 9772.1 9790.6 4789.0 4859.8 4867.4 4871.1 4883.5 6082.4 6171.8 6198.8 6209.5 6211.5 1144.2 1152.4 1186.1 1187.4 1190.3 737.2 749.8 760.3 766.9 778.1 12789.9 13112.9 13121.4 13333.0 13475.3 744.9 744.9 750.3 758.8 762.6 5924.6 5955.9 5962.6 6136.2 6199.5 151832.0 156243.0 157044.0 160556.0 160800.0 135168.0 135484.3 136157.3 136873.0 136992.0 113529.0 114787.4 117125.0 117647.0 121291.4 11331.7 11421.5 11445.3 11445.8 11550.2 54803.5 55411.0 56020.0 56355.7 57051.7 70045.6 70185.0 71101.3 71628.6 72741.0 16809.3 16966.7 16984.2 17019.2 17099.6 18110.1 19096.0 19124.6 19227.2 19248.8 16863.2 17239.7 17334.0 17456.6 17521.6 16888.7 17095.9 17240.2 17353.4 17395.7 16482.9 16760.3 16973.7 16992.2 17036.7 11212.4 11332.2 11471.1 11504.5 11943.4 9134.4 9151.9 9348.4 9364.5 9399.6 03:26:54 INFO - PID 26822 | -------- Summary: end -------- 03:26:54 INFO - PID 26822 | 03:26:55 INFO - TEST-INFO | 26822: exit 0 03:26:55 INFO - TEST-OK | dromaeo_css | took 1107825ms 03:26:55 INFO - TEST-START | kraken 03:26:55 INFO - Initialising browser for kraken test... 03:26:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37248/getInfo.html -profile /tmp/tmp8jidqY/profile 03:26:55 INFO - TEST-INFO | started process 27205 (/builds/slave/test/build/application/firefox/firefox http://localhost:37248/getInfo.html) 03:27:01 INFO - TEST-INFO | 27205: exit 0 03:27:01 INFO - Browser initialized. 03:27:01 INFO - Running cycle 1/1 for kraken test... 03:27:01 INFO - Using env: {'DISPLAY': ':0', 03:27:01 INFO - 'HOME': '/home/cltbld', 03:27:01 INFO - 'JSGC_DISABLE_POISONING': '1', 03:27:01 INFO - 'LANG': 'en_US.UTF-8', 03:27:01 INFO - 'LANGUAGE': 'en_US:en', 03:27:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:27:01 INFO - 'LOGNAME': 'cltbld', 03:27:01 INFO - 'MAIL': '/var/mail/cltbld', 03:27:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:27:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:27:01 INFO - 'MOZ_CRASHREPORTER': '1', 03:27:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:27:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:27:01 INFO - 'MOZ_NO_REMOTE': '1', 03:27:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:27:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:27:01 INFO - 'NO_EM_RESTART': '1', 03:27:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:27:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:27:01 INFO - 'PWD': '/builds/slave/test', 03:27:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:27:01 INFO - 'RUST_BACKTRACE': 'full', 03:27:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:27:01 INFO - 'SHELL': '/bin/bash', 03:27:01 INFO - 'SHLVL': '1', 03:27:01 INFO - 'TERM': 'linux', 03:27:01 INFO - 'TMOUT': '86400', 03:27:01 INFO - 'USER': 'cltbld', 03:27:01 INFO - 'XDG_SESSION_COOKIE': '4300f881736b422476174874000001bc-1504865238.917504-463219999', 03:27:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:27:01 INFO - '_': '/tools/buildbot/bin/python'} 03:27:01 INFO - TEST-INFO | started process 27410 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8jidqY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:27:02 INFO - PID 27410 | 03:27:02 INFO - PID 27410 | (/builds/slave/test/build/application/firefox/firefox:27460): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:27:02 INFO - PID 27410 | 03:27:12 INFO - PID 27410 | RSS: Main: 160083968 03:27:12 INFO - PID 27410 | 03:27:15 INFO - PID 27410 | Cycle 1(1): loaded http://localhost:37248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37248/tests/kraken/driver.html?testName=audio-beat-detection) 03:27:16 INFO - PID 27410 | RSS: Main: 165740544 03:27:16 INFO - PID 27410 | 03:27:18 INFO - PID 27410 | Cycle 1(1): loaded http://localhost:37248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37248/tests/kraken/driver.html?testName=audio-dft) 03:27:19 INFO - PID 27410 | RSS: Main: 166641664 03:27:19 INFO - PID 27410 | 03:27:22 INFO - PID 27410 | Cycle 1(1): loaded http://localhost:37248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37248/tests/kraken/driver.html?testName=audio-fft) 03:27:22 INFO - PID 27410 | RSS: Main: 160628736 03:27:22 INFO - PID 27410 | 03:27:25 INFO - PID 27410 | Cycle 1(1): loaded http://localhost:37248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37248/tests/kraken/driver.html?testName=audio-oscillator) 03:27:25 INFO - PID 27410 | RSS: Main: 162222080 03:27:25 INFO - PID 27410 | 03:27:27 INFO - PID 27410 | Cycle 1(1): loaded http://localhost:37248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:27:28 INFO - PID 27410 | RSS: Main: 162254848 03:27:28 INFO - PID 27410 | 03:27:31 INFO - PID 27410 | Cycle 1(1): loaded http://localhost:37248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37248/tests/kraken/driver.html?testName=imaging-darkroom) 03:27:32 INFO - PID 27410 | RSS: Main: 163774464 03:27:32 INFO - PID 27410 | 03:27:35 INFO - PID 27410 | Cycle 1(1): loaded http://localhost:37248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37248/tests/kraken/driver.html?testName=imaging-desaturate) 03:27:36 INFO - PID 27410 | RSS: Main: 164700160 03:27:36 INFO - PID 27410 | 03:27:38 INFO - PID 27410 | Cycle 1(1): loaded http://localhost:37248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37248/tests/kraken/driver.html?testName=json-parse-financial) 03:27:39 INFO - PID 27410 | RSS: Main: 165347328 03:27:39 INFO - PID 27410 | 03:27:41 INFO - PID 27410 | Cycle 1(1): loaded http://localhost:37248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:27:41 INFO - PID 27410 | RSS: Main: 164536320 03:27:41 INFO - PID 27410 | 03:27:43 INFO - PID 27410 | Cycle 1(1): loaded http://localhost:37248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37248/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:27:43 INFO - PID 27410 | RSS: Main: 165404672 03:27:43 INFO - PID 27410 | 03:27:45 INFO - PID 27410 | Cycle 1(1): loaded http://localhost:37248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:27:46 INFO - PID 27410 | RSS: Main: 164937728 03:27:46 INFO - PID 27410 | 03:27:49 INFO - PID 27410 | Cycle 1(1): loaded http://localhost:37248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:27:49 INFO - PID 27410 | RSS: Main: 165269504 03:27:49 INFO - PID 27410 | 03:27:52 INFO - PID 27410 | Cycle 1(1): loaded http://localhost:37248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:27:53 INFO - PID 27410 | RSS: Main: 165433344 03:27:53 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | Cycle 1(1): loaded http://localhost:37248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37248/tests/kraken/driver.html?testName=ai-astar) 03:27:54 INFO - PID 27410 | RSS: Main: 167944192 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | __start_tp_report 03:27:54 INFO - PID 27410 | _x_x_mozilla_page_load 03:27:54 INFO - PID 27410 | _x_x_mozilla_page_load_details 03:27:54 INFO - PID 27410 | |i|pagename|runs| 03:27:54 INFO - PID 27410 | |0;ai-astar;109;106;107;107;108;111;109;108;107;107 03:27:54 INFO - PID 27410 | |1;audio-beat-detection;114;118;118;113;139;110;113;113;113;112 03:27:54 INFO - PID 27410 | |2;audio-dft;165;165;163;161;164;162;160;162;166;169 03:27:54 INFO - PID 27410 | |3;audio-fft;73;74;75;71;73;72;76;77;73;74 03:27:54 INFO - PID 27410 | |4;audio-oscillator;78;81;79;78;78;78;78;77;78;77 03:27:54 INFO - PID 27410 | |5;imaging-gaussian-blur;114;115;113;114;114;115;115;113;114;113 03:27:54 INFO - PID 27410 | |6;imaging-darkroom;151;150;149;150;150;149;149;149;148;149 03:27:54 INFO - PID 27410 | |7;imaging-desaturate;94;93;94;94;93;94;93;95;93;94 03:27:54 INFO - PID 27410 | |8;json-parse-financial;49;47;42;42;44;44;49;47;42;43 03:27:54 INFO - PID 27410 | |9;json-stringify-tinderbox;37;37;38;39;38;38;38;37;38;38 03:27:54 INFO - PID 27410 | |10;stanford-crypto-aes;75;72;73;73;72;72;72;76;72;71 03:27:54 INFO - PID 27410 | |11;stanford-crypto-ccm;127;120;113;117;111;113;109;122;119;116 03:27:54 INFO - PID 27410 | |12;stanford-crypto-pbkdf2;163;168;152;152;156;164;153;149;163;157 03:27:54 INFO - PID 27410 | |13;stanford-crypto-sha256-iterative;57;58;59;60;59;59;60;62;60;59 03:27:54 INFO - PID 27410 | __end_tp_report 03:27:54 INFO - PID 27410 | __start_cc_report 03:27:54 INFO - PID 27410 | _x_x_mozilla_cycle_collect,880 03:27:54 INFO - PID 27410 | __end_cc_report 03:27:54 INFO - PID 27410 | __startTimestamp1504866474973__endTimestamp 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | ------- Summary: start ------- 03:27:54 INFO - PID 27410 | Number of tests: 14 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | [#0] ai-astar Cycles:10 Average:107.90 Median:108.00 stddev:1.45 (1.3%) stddev-sans-first:1.48 03:27:54 INFO - PID 27410 | Values: 109.0 106.0 107.0 107.0 108.0 111.0 109.0 108.0 107.0 107.0 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | [#1] audio-beat-detection Cycles:10 Average:116.30 Median:113.50 stddev:8.35 (7.4%) stddev-sans-first:8.82 03:27:54 INFO - PID 27410 | Values: 114.0 118.0 118.0 113.0 139.0 110.0 113.0 113.0 113.0 112.0 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | [#2] audio-dft Cycles:10 Average:163.70 Median:164.50 stddev:2.67 (1.6%) stddev-sans-first:2.79 03:27:54 INFO - PID 27410 | Values: 165.0 165.0 163.0 161.0 164.0 162.0 160.0 162.0 166.0 169.0 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | [#3] audio-fft Cycles:10 Average:73.80 Median:74.00 stddev:1.81 (2.5%) stddev-sans-first:1.90 03:27:54 INFO - PID 27410 | Values: 73.0 74.0 75.0 71.0 73.0 72.0 76.0 77.0 73.0 74.0 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:78.00 stddev:1.14 (1.5%) stddev-sans-first:1.20 03:27:54 INFO - PID 27410 | Values: 78.0 81.0 79.0 78.0 78.0 78.0 78.0 77.0 78.0 77.0 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 03:27:54 INFO - PID 27410 | Values: 114.0 115.0 113.0 114.0 114.0 115.0 115.0 113.0 114.0 113.0 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | [#6] imaging-darkroom Cycles:10 Average:149.40 Median:149.50 stddev:0.84 (0.6%) stddev-sans-first:0.67 03:27:54 INFO - PID 27410 | Values: 151.0 150.0 149.0 150.0 150.0 149.0 149.0 149.0 148.0 149.0 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 03:27:54 INFO - PID 27410 | Values: 94.0 93.0 94.0 94.0 93.0 94.0 93.0 95.0 93.0 94.0 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | [#8] json-parse-financial Cycles:10 Average:44.90 Median:45.50 stddev:2.85 (6.3%) stddev-sans-first:2.60 03:27:54 INFO - PID 27410 | Values: 49.0 47.0 42.0 42.0 44.0 44.0 49.0 47.0 42.0 43.0 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | [#9] json-stringify-tinderbox Cycles:10 Average:37.80 Median:38.00 stddev:0.63 (1.7%) stddev-sans-first:0.60 03:27:54 INFO - PID 27410 | Values: 37.0 37.0 38.0 39.0 38.0 38.0 38.0 37.0 38.0 38.0 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | [#10] stanford-crypto-aes Cycles:10 Average:72.80 Median:72.50 stddev:1.55 (2.1%) stddev-sans-first:1.42 03:27:54 INFO - PID 27410 | Values: 75.0 72.0 73.0 73.0 72.0 72.0 72.0 76.0 72.0 71.0 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | [#11] stanford-crypto-ccm Cycles:10 Average:116.70 Median:118.00 stddev:5.48 (4.6%) stddev-sans-first:4.36 03:27:54 INFO - PID 27410 | Values: 127.0 120.0 113.0 117.0 111.0 113.0 109.0 122.0 119.0 116.0 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.70 Median:160.00 stddev:6.40 (4.0%) stddev-sans-first:6.49 03:27:54 INFO - PID 27410 | Values: 163.0 168.0 152.0 152.0 156.0 164.0 153.0 149.0 163.0 157.0 03:27:54 INFO - PID 27410 | 03:27:54 INFO - PID 27410 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.30 Median:59.50 stddev:1.34 (2.2%) stddev-sans-first:1.13 03:27:54 INFO - PID 27410 | Values: 57.0 58.0 59.0 60.0 59.0 59.0 60.0 62.0 60.0 59.0 03:27:54 INFO - PID 27410 | -------- Summary: end -------- 03:27:54 INFO - PID 27410 | 03:27:55 INFO - PID 27410 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:27:55 INFO - TEST-INFO | 27410: exit 0 03:27:55 INFO - TEST-OK | kraken | took 60203ms 03:27:55 INFO - SUITE-END | took 1168s 03:27:56 INFO - Completed test suite (00:19:28) 03:27:56 INFO - javascript benchmark 03:27:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8920.10080052817, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3779.659830615244, 3784.848606055758, 3816.0, 3847.7306588538795, 3848.1149335652817, 6157.815265542041, 6173.629582225064, 6192.071189321593, 6216.16081828953, 6282.937170628293, 6254.374562543743, 6261.561690681654, 6262.561620686553, 6282.497400207983, 6310.716017779199, 5555.777768889239, 5623.606347555673, 5641.379448260688, 5662.6036177467595, 5722.37053924068, 5574.303212098488, 5625.437456254383, 5640.576956728261, 5642.069058605346, 5665.575081868856, 302.93899878310555, 5466.617336786387, 5493.011257973583, 5540.168974653834, 5648.406917273689, 5556.222128901957, 5577.3028371453565, 5628.155776633538, 5645.915311270334, 5656.773729050833, 5543.085390910495, 5558.666480011212, 5612.102063669793, 5630.85922851932, 5635.718214089279, 6089.908651370233, 6131.601445906002, 6215.378462153749, 6238.2825975013, 6259.749610015594, 6664.666766661648, 6680.164979377578, 6758.831029224309, 6762.188537375498, 6770.5599136056, 6724.596524208563, 6799.490038247101, 6808.795736127924, 6827.69275382604, 6831.6242606656615, 6253.468455181315, 6300.747970081237, 6323.715432805535, 6341.619502829845, 6348.206474190726, 7057.188423331382, 7096.787096387116, 7102.112235970504, 7122.430205583541, 7127.073480447509, 6703.66481675924, 6715.832104197434, 6774.390304872487, 6792.796216113525, 6801.897971530431, 6655.800325990228, 6804.11546498952, 6811.080504131879, 6836.4530837532875, 6893.20728116273, 6703.329667033355, 6740.359665831738, 6761.0, 6780.982852572055, 6781.62701051437, 6689.49828762835, 6762.053312536249, 6791.388775010273, 6805.523613347018, 6857.657117144123, 4926.58124059459, 4992.72540010303, 5004.324416203839, 5005.524475174852, 5026.89946201074, 4497.865064048084, 4498.527654596286, 4519.480259770103, 4530.365748795171, 4573.428321459817, 6172.598781079305, 6308.936910630927, 6316.463100636497, 6321.589096708699, 6324.33594472583, 6668.499862510331, 6743.123393958754, 6754.223264324665, 6755.054292399068, 6772.83067923296, 6240.7503699852405, 6319.05214217871, 6329.082283069021, 6357.0, 6364.331745166691, 5459.426760190202, 5527.723613819293, 5578.553715702734, 5581.0, 5598.804041858515, 6891.793246202622, 6992.1958974717545, 7023.92976070243, 7059.788206353817, 7125.714971401189, 6743.393094621508, 6768.120144381199, 6769.9661501694145, 6771.085903402977, 6771.559848609923, 6960.095187625576, 7028.262032486516, 7085.397741192057, 7087.184973728087, 7104.964475177591, 6693.564918280296, 6740.528163028541, 6779.2542820288745, 6785.864282714417, 6818.965905170442, 6793.626350550767, 6816.216135144522, 6822.249552549117, 6916.100906882073, 6923.169219693669, 6484.416402523795, 6564.343565643398, 6567.9014814778775, 6576.309487503743, 6643.534952553275, 6563.3764792344655, 6577.309382514767, 6587.341265873374, 6589.0775291460195, 6589.27517973013, 6489.7404103835315, 6511.055896895018, 6514.250861151028, 6518.57629254097, 6522.184726909137, 6542.476601871934, 6577.506686998398, 6629.303923088007, 6690.531662783558, 6710.563813352116, 6322.747090116344, 6406.647634380154, 6426.260979987174, 6512.3162067982175, 6516.804495865132], "value": 6155.646993896062, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [927.7866090799116, 941.3081385181895, 974.3325821812059, 1001.714511364261, 1001.7145113642613, 42777.57222427775, 43250.351244731355, 43524.0, 43668.34497482536, 43859.78070109645, 39988.40017399741, 40121.59878401215, 40225.798871005674, 40292.19415611694, 40305.39541906874, 7404.14852291988, 7490.700371985114, 7502.62486875654, 7508.774736757906, 7527.172011078805, 7225.891611625803, 7288.744893928713, 7301.926980730178, 7303.2331605181225, 7308.853822923538, 7993.520388776692, 8221.424500284982, 8224.38317126218, 8226.465279756827, 8227.13615070418, 8072.273495385414, 8102.7569172924905, 8166.632501537445, 8169.346452283803, 8173.95913020437, 7880.369570434352, 8018.518888866628, 8021.919780802175, 8036.316913062396, 8053.557054362008, 39871.601283987366, 40226.59773402258, 40232.396514052314, 40235.59764402348, 40309.193816123516, 3650.7809531428197, 3693.907652308687, 3694.07648087978, 3696.8706095286807, 3704.0369503929173, 32833.835830820935, 32900.0, 32931.34137317264, 32936.835315823264, 32941.0, 5426.593005524602, 5463.7814487420455, 5487.890242195133, 5491.7254137292975, 5507.586930980193, 210911.0, 211959.0, 212355.0, 213172.0, 214364.99999999843, 51270.487295127314, 51397.48602514002, 51446.485535144915, 51474.0, 51645.22532162021, 54138.72930635322, 54195.729021354644, 54196.187057194176, 54273.185902211495, 54314.45685543094, 16486.258118384703, 16617.169141543116, 16630.08534530588, 16691.749623755655, 16747.246373913204, 13644.0, 13667.17996920188, 13697.178169309873, 13700.3834827433, 13702.177869327872, 14562.927185364006, 14813.333399997025, 14853.405863765329, 14877.553673389815, 14934.626634334012, 5878.0, 5898.970505147447, 5926.2295901532525, 6062.666553339521, 6136.693165341715, 1074.7689246811974, 1084.8915108489089, 1085.2999815119204, 1090.7273181704575, 1110.2838669058574, 24274.271771846874, 24680.0, 24695.38261543476, 24801.379965500644, 24840.25479235626, 1188.9346085965356, 1207.5532053140394, 1208.546794951893, 1218.524775337619, 1218.61004478566, 13002.479900803863, 13173.802392964113, 13222.140560863512, 13311.800322995163, 13345.132566383349, 271029.0, 274446.0, 274834.0, 274993.0, 275289.0, 54619.45380546144, 54764.726176368866, 60040.69979650161, 60089.0, 60622.0, 1459.3360021190545, 1502.1512845242403, 1514.356398530642, 1517.8102737157856, 1519.3487364548712, 29594.852025739732, 29838.25404364908, 30000.549991750577, 30456.086317410514, 30522.389552208388, 39772.80113599414, 39891.60108398879, 39914.60085399109, 39957.80021099876, 40014.1997160061, 247017.0, 247201.0, 247397.0, 247427.0, 247840.0, 34721.479177811845, 34725.82637086899, 34738.0, 34811.65188348084, 34812.91122532656, 44660.55339446694, 45654.31518527158, 46094.76952615215, 46197.0, 46342.0, 33535.664643354234, 33906.15234619154, 34201.0, 34281.314373712885, 34699.65300346964, 33256.501152483215, 33975.66024339725, 34116.0, 34277.0, 34889.82555087208, 39652.60347396489, 39914.60085399225, 40174.59825401709, 40254.0, 40418.1916361677, 33222.16944576405, 33363.165920852174, 33666.0, 34045.319093618484, 34112.82943585266, 26101.477970440865, 26277.73722262753, 26317.736822632294, 26323.868380657976, 26724.19827405181], "value": 18359.506842133338, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [542.2642767239278, 544.3467838593689, 548.7942021741846, 553.5129086403965, 554.2157846646994, 23202.0, 23994.88002559987, 24040.27879163628, 24042.75957240427, 24069.39826504336, 22052.8897355513, 22350.21774237902, 22415.0, 22497.55004899901, 22556.21053263136, 5996.610220335675, 6479.092926990225, 6507.577007494521, 6513.348665133496, 6519.8044058678315, 6367.267764207129, 6462.321456247097, 6465.321141280168, 6466.25638051623, 6469.74121035158, 6222.253329600454, 6350.142730731338, 6492.642904640243, 6497.05792660064, 6497.155369801919, 6447.4197322241225, 6452.387023232785, 6463.418292353711, 6484.578502397353, 6488.708008139622, 5782.479576838105, 6002.789902353396, 6018.15745795589, 6021.909671354933, 6025.668588227646, 29690.851545742134, 30129.246768830737, 30223.848880755675, 30238.395232095234, 30243.697563024307, 2273.863568185914, 2300.344401845466, 2300.574393737164, 2300.907963681451, 2302.1597117052324, 19002.23991040357, 19543.706844397344, 19823.306184283625, 20262.392128236177, 20347.491312717153, 2545.52907712072, 2556.7187609362954, 2594.4681340325187, 2597.8181527293095, 2607.38726399296, 131163.0, 137011.0, 143363.2831835834, 150357.24821375933, 150468.0, 14387.425325629141, 14802.925985370004, 14900.776488352683, 14937.402503899722, 14978.775318370233, 22360.105595776313, 22446.32661020172, 22464.43838904008, 22469.550608988055, 22475.21336753206, 10571.577136914591, 10573.568079341641, 10578.682639520826, 10696.78606427867, 10718.892811071946, 9977.700668979942, 10019.599216031424, 10043.296969212084, 10043.849342259726, 10138.391696498235, 9258.074192580689, 9348.0, 9379.718608441757, 9420.811583768424, 9642.517874106266, 2450.6691596634582, 2517.798576113943, 2523.3691577105724, 2533.1007492340323, 2667.1731763153484, 1021.9948900255451, 1035.4460363705382, 1036.9533370998324, 1037.3205550364523, 1059.1526778577108, 20443.0, 20556.486087847923, 20590.382288531368, 20715.96420178985, 21018.78981210199, 1084.1056128693858, 1099.2964502718266, 1102.5204036244259, 1103.7351035751362, 1114.3258328711174, 11067.778644427228, 11186.888131118747, 11361.602343917923, 11393.829092563617, 11440.828387574193, 614780.0, 629841.0, 635079.0, 659833.0, 668359.0, 125720.0, 126874.0, 127750.0, 128116.0, 128508.0, 1211.6789050901518, 1225.436299302313, 1243.4155946705034, 1249.256692268099, 1252.6492582077033, 17913.37303194382, 18066.548336291697, 18086.18612162482, 18239.452816415527, 18323.175457104197, 61318.69340653447, 62276.0, 62772.68613656903, 63676.36323636704, 64280.0, 81031.59484202541, 81567.59216203881, 81587.59206203932, 81660.59169704113, 82435.0, 14116.788248176492, 14314.355853986805, 14349.56951291463, 14441.35013924397, 14500.0, 23313.184038558567, 23351.299461016195, 23466.76533234646, 23512.529749405257, 23638.527229454972, 19050.714239286146, 19081.42755717331, 19335.903320483307, 19423.514412139808, 19443.4166974991, 17251.0, 17376.82623173752, 17401.390951316644, 17513.124343782503, 17582.648347033242, 19626.411207663794, 20040.799592004478, 20177.39467815968, 20247.696284555448, 20443.182272708924, 14075.577732668036, 14109.0, 14174.50389236413, 14199.148051116967, 14202.50291239843, 19287.903560482107, 19303.51741206481, 19468.707969380754, 19555.902220488806, 19604.117814698657], "value": 12352.268029732595, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1406.7678832992553, 1460.1677044084872, 1468.698916722072, 1471.352604853865, 1475.0780762023735, 16867.83132168678, 17492.650146997054, 17584.120793960283, 17649.73525397117, 17663.028533430657, 17081.316747330122, 17102.572435689082, 17124.143792810373, 17205.483835484923, 17234.310627574912, 2448.228807925502, 2520.861352625605, 2541.7077036140895, 2555.8722063896826, 2565.5125526150064, 2632.6577544919132, 2746.3546066674317, 2752.3944732158893, 2752.862356882158, 2752.9724702753015, 2728.4952283827456, 2821.7883658725573, 2823.9294017649518, 2824.8163869348527, 2831.0091467986244, 2846.814957027797, 2854.685984541699, 2868.584055311968, 2869.139258222525, 2876.324063845005, 2790.372166262595, 2802.985985070082, 2809.8173618714923, 2815.0, 2815.380616264439, 16432.424865129662, 16738.83261167385, 16770.916145419196, 16774.412895548598, 16780.664386712197, 7880.330171935392, 8117.472364296302, 8126.0, 8144.75565733029, 8148.0, 4685.367475390813, 4755.239161734106, 4822.855314340575, 5366.812161574365, 5515.090010148361, 2019.636465436221, 2040.36748607932, 2058.3207541511265, 2071.7617473990535, 2099.6325643012565, 30405.39189216203, 30589.847050764827, 30607.8469607655, 30699.0, 30725.846370768228, 7046.189688185822, 7100.9644951775945, 7127.536710113826, 7240.565566066053, 7290.45321600882, 19551.413457596296, 19622.509437264183, 19638.803611963984, 19649.705254421195, 19681.31115410954, 8299.419040667217, 8433.704820331259, 8451.873221901677, 8455.069942306342, 8457.915420845837, 7415.1843297237265, 7566.546007239584, 7688.654010569538, 7691.192424795316, 7705.730299439605, 5227.111391063529, 5443.782248710087, 5568.248286481355, 5579.246801681802, 5634.690092044977, 1406.6413064668443, 1437.4968760933589, 1485.502356710514, 1520.711064897666, 1523.5810152208232, 948.2224575847739, 966.5312323523086, 967.9225661947028, 974.5030034682363, 976.1605019683067, 9542.952285238529, 9550.61797528105, 9557.713268601952, 9591.42451452915, 9741.464219467856, 1064.377339256528, 1073.818799320741, 1084.1760262200785, 1089.5260561655705, 1098.807708650983, 5535.916961245584, 5821.679807610622, 5824.9323088912915, 6055.182550355646, 6097.054956481752, 24554.877225613756, 24791.380215494395, 24902.003919843366, 25061.37346566314, 25119.74880251174, 24248.526963300872, 24279.150229742227, 24515.0, 24627.630585541232, 24802.379940501633, 11838.052955763691, 11869.643910682693, 12027.879721202677, 12047.939760301319, 12055.698607534707, 9070.727878163665, 9092.909070909207, 9174.816503670023, 9178.26573874075, 9300.255979521487, 20856.270030548858, 21109.366718998455, 21145.577088458453, 21396.251131210334, 21736.673949890446, 21717.26261899076, 21830.89084554567, 22007.11971521121, 22046.669299960835, 22092.668609971184, 12171.14801963854, 12374.62876113718, 12377.938110309391, 12382.81425778632, 12387.75224495487, 15187.240637968278, 15409.84590154084, 15480.303386347416, 15482.612934676723, 15524.767128492857, 12046.939765301116, 12077.0, 12182.512699491921, 12188.20776649515, 12548.498060077494, 12305.0, 12388.194767340094, 12460.750784984431, 12477.313747743961, 12764.106512544033, 10075.84886226722, 10357.896421035995, 10367.27429079957, 10465.529051192563, 10519.0, 8347.58262086881, 8358.665653373797, 8421.578921054046, 8457.57712114404, 8460.196281353261, 7828.960855195687, 7953.721619743281, 8027.478213916077, 8052.396070294634, 8062.314703250289], "value": 7214.001052743226, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.62864223190365, 519.3949049357498, 519.6414474012931, 520.877593765465, 528.5639347538277, 6197.535184861136, 6314.621122732639, 6318.715657795398, 6376.617402955826, 6397.536948274913, 6109.725062372193, 6240.968795156018, 6290.811275661737, 6327.968360158205, 6359.459445947088, 5025.0954828130925, 5063.84808455745, 5068.417132029808, 5090.821821236248, 5130.281760553526, 4993.875153121165, 5038.244263360489, 5042.0, 5049.293098966147, 5078.162103252977, 4731.6451266155, 4834.9033019339595, 4895.926561101586, 4924.876878078041, 4970.329005584236, 4831.130396528623, 4833.154198015333, 4843.370361852954, 4847.927281090751, 4907.214845624683, 4332.1985432695055, 4342.739435633872, 4366.104948485553, 4376.387305777193, 4378.759168245745, 6140.969295153541, 6158.445739883387, 6161.19904412428, 6173.351798061229, 6230.0, 5652.236948012007, 5716.485516303511, 5727.971360143172, 5735.283089613798, 5744.856378590527, 6511.479081673455, 6605.801825945181, 6606.570572912745, 6610.768623098216, 6664.266930637626, 4824.6863953842885, 4857.344258525124, 4869.659123861331, 4877.414710234759, 4925.236588328815, 6793.898091528631, 6860.656967151673, 6929.5842249465195, 6941.895871561931, 6954.930450695479, 5037.672551284155, 5040.79836806531, 5054.646174767805, 5075.923861142086, 5097.46476619957, 5055.823046193439, 5059.2916991621205, 5070.036693028313, 5097.770600322994, 5122.974385128051, 5682.857928551736, 5691.800786972436, 5702.657840529581, 5735.655860648374, 5736.6558006519745, 6580.341965803382, 6612.471002319803, 6641.402273795382, 6664.433523150489, 6740.157480314961, 1724.508300320214, 1751.8248175182634, 1761.806201317854, 1769.566456218209, 1778.8399044086095, 1126.380490730095, 1171.77736230116, 1186.4542310537076, 1187.6971372300009, 1221.3893053473264, 6182.227221597301, 6184.6598437085495, 6193.442590166816, 6278.152449419361, 6341.302456729756, 6585.835354116185, 6599.0, 6606.570572912745, 6718.8320291993095, 6761.4590832733265, 6101.938980610137, 6131.754729810847, 6157.013848283579, 6243.594166379171, 6318.273398559132, 3489.860405583748, 3514.61339252682, 3514.876979305712, 3588.7308451866215, 3604.98197509016, 6895.586264824126, 6984.965075174591, 7031.191412987469, 7034.291797657938, 7037.6481175941, 6873.450123990069, 6956.79129626112, 6972.407345375598, 7011.579305241702, 7021.648917554204, 6970.51206415546, 6994.405475534637, 7001.92998070023, 7010.649467526603, 7053.188883278283, 5972.999045319866, 6633.270340262664, 6774.559653622498, 6789.864202716017, 6793.558418702769, 6595.82337828525, 6636.668166591748, 6700.363465470772, 6741.966290168518, 6769.052332673528, 7110.217876033529, 7124.964375178091, 7140.642967851691, 7146.428285737233, 7167.7132914682825, 4921.9015619688125, 5012.899742005066, 5019.397672279204, 5035.144025515744, 5036.546710796046, 6581.881080216471, 6748.19021717397, 6773.424258938046, 6877.965610171917, 6902.481454080094, 6429.164208652845, 6660.100886380276, 6668.799936001928, 6687.565308254948, 6692.364225398579, 6577.14497115372, 6624.138861947916, 6669.7665581704405, 6687.598744075371, 6692.628011257778, 6596.472282217507, 6605.0, 6611.966940165268, 6619.642973190581, 6624.503162262753, 6615.4376877965915, 6664.866702666017, 6685.298043705536, 6693.497987651043, 6693.866122677422, 6414.166158399378, 6492.0, 6493.2857385688485, 6514.446272066927, 6517.4134327910715], "value": 5126.132832363087, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [922.5156792683845, 951.3530799056643, 952.4666186935317, 995.6316163019682, 1024.8616436781033, 20831.895840520796, 21801.890990545064, 21866.562668746614, 21928.342149735494, 21937.354698397645, 18803.153858076384, 19274.22903083875, 19760.407187784353, 19765.901170494126, 19912.90043549784, 995.144176008632, 1023.5905637744905, 1023.8976102389738, 1024.3956065921104, 1025.0262250861674, 954.6944977607134, 1097.5006372100675, 1105.6351404036689, 1105.9557617695282, 1106.6292791914677, 1126.7577470843767, 1136.8578927634046, 1139.2253267777908, 1141.2125633313028, 1142.3146112332618, 1122.45560902962, 1131.1459847814888, 1134.4101067444965, 1134.8467956825805, 1135.7728454309088, 793.2701914238856, 806.2139414071293, 811.3549727966222, 828.4615000249826, 832.1886160993043, 21534.784652153434, 21773.782262177494, 21802.89098554513, 21815.563688726295, 21830.126794928183, 23018.42453938648, 23993.88003059991, 24189.637155442684, 24407.87796061026, 24432.755672443403, 18758.906205469022, 18972.525686857938, 19154.0, 19205.80794192068, 19226.615467690568, 4074.490688663917, 4074.7351422157467, 4089.284375234322, 4094.3858421236755, 4097.508299004138, 164776.0, 164782.0, 165617.0, 166761.0, 169529.0, 15220.543383698507, 15238.161901095546, 15258.618534536725, 15451.690966180613, 15465.226738663021, 27241.0, 27506.0, 27844.721552784617, 28090.157295281173, 28093.859530702626, 9616.615335386507, 9703.223742100616, 9770.75573110678, 9772.071653192936, 9790.608375665037, 4788.976055119702, 4859.757012149378, 4867.440244371872, 4871.074495845778, 4883.4628190898975, 6082.361352058058, 6171.753129874754, 6198.783042593487, 6209.5032397408095, 6211.472024877846, 1144.199060657543, 1152.4065106470175, 1186.0867132308074, 1187.441902305915, 1190.2917763930448, 737.2406421385981, 749.8237914090184, 760.2929275773473, 766.8849672549162, 778.1284960843891, 12789.872101278868, 13112.934435327954, 13121.409536570878, 13333.0, 13475.258860762557, 744.8845428958521, 744.9404047676173, 750.3097150621442, 758.7571976967317, 762.5996351915289, 5924.644521328734, 5955.940440595626, 5962.5826192167, 6136.17161683176, 6199.473044791243, 151832.0, 156243.0, 157044.0, 160556.0, 160800.0, 135168.0, 135484.3225783865, 136157.3192134033, 136873.0, 136992.0, 113529.0, 114787.42606287081, 117125.0, 117647.0, 121291.39354303172, 11331.660050198507, 11421.486033128363, 11445.25605835618, 11445.828312575151, 11550.249233799776, 54803.451965479835, 55411.0, 56020.0, 56355.71822141027, 57051.714741426025, 70045.64977175082, 70185.0, 71101.28898710947, 71628.64185679039, 72741.0, 16809.327626895276, 16966.660666786844, 16984.235709393353, 17019.23413446373, 17099.572510687332, 18110.094495275447, 19096.0, 19124.61750764949, 19227.230910763414, 19248.8075119247, 16863.156842157598, 17239.7414038792, 17334.04662743561, 17456.563585910455, 17521.56196095108, 16888.746668799733, 17095.91452042732, 17240.224189911234, 17353.392631257844, 17395.7390639138, 16482.917585411997, 16760.32958681639, 16973.74539381935, 16992.150392480078, 17036.744448833524, 11212.383318917538, 11332.206745527734, 11471.13966452536, 11504.482298296429, 11943.402829858645, 9134.406263593111, 9151.862722059308, 9348.439093654402, 9364.531773411167, 9399.577019034023], "value": 9757.631710695692, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1386.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 106.0, 107.0, 107.0, 108.0, 111.0, 109.0, 108.0, 107.0, 107.0], "value": 107.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 118.0, 113.0, 139.0, 110.0, 113.0, 113.0, 113.0, 112.0], "value": 116.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 163.0, 161.0, 164.0, 162.0, 160.0, 162.0, 166.0, 169.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 71.0, 73.0, 72.0, 76.0, 77.0, 73.0, 74.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 81.0, 79.0, 78.0, 78.0, 78.0, 78.0, 77.0, 78.0, 77.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 113.0, 114.0, 114.0, 115.0, 115.0, 113.0, 114.0, 113.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 149.0, 150.0, 150.0, 149.0, 149.0, 149.0, 148.0, 149.0], "value": 149.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 93.0, 95.0, 93.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 47.0, 42.0, 42.0, 44.0, 44.0, 49.0, 47.0, 42.0, 43.0], "value": 44.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 38.0, 39.0, 38.0, 38.0, 38.0, 37.0, 38.0, 38.0], "value": 37.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 72.0, 73.0, 73.0, 72.0, 72.0, 72.0, 76.0, 72.0, 71.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 120.0, 113.0, 117.0, 111.0, 113.0, 109.0, 122.0, 119.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 168.0, 152.0, 152.0, 156.0, 164.0, 153.0, 149.0, 163.0, 157.0], "value": 157.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 59.0, 60.0, 59.0, 59.0, 60.0, 62.0, 60.0, 59.0], "value": 59.3, "unit": "score"}]}]} 03:27:56 INFO - Return code: 0 03:27:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:27:56 INFO - # TBPL SUCCESS # 03:27:56 INFO - Running post-action listener: _package_coverage_data 03:27:56 INFO - Running post-action listener: _resource_record_post_action 03:27:56 INFO - [mozharness: 2017-09-08 10:27:56.244478Z] Finished run-tests step (success) 03:27:56 INFO - Running post-run listener: _resource_record_post_run 03:27:56 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 387747840; Read time: 12; Write time: 233988 03:27:56 INFO - TinderboxPrint: CPU usage
13.0% 03:27:56 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 03:27:56 INFO - TinderboxPrint: I/O write bytes / time
387,747,840 / 233,988 03:27:56 INFO - TinderboxPrint: CPU idle
8,213.8 (86.9%) 03:27:56 INFO - TinderboxPrint: CPU user
1,184.8 (12.5%) 03:27:56 INFO - TinderboxPrint: Swap in / out
0 / 0 03:27:56 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 105394176; Read time: 0; Write time: 79008 03:27:56 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:27:56 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 4096; Write bytes: 282353664; Read time: 12; Write time: 154980 03:27:56 INFO - Running post-run listener: _upload_blobber_files 03:27:56 INFO - Blob upload gear active. 03:27:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:27:56 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:27:56 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:27:56 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:27:57 INFO - (blobuploader) - INFO - Open directory for files ... 03:27:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:27:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:27:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:27:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:27:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:27:58 INFO - (blobuploader) - INFO - Done attempting. 03:27:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:27:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:27:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:28:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:28:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:28:05 INFO - (blobuploader) - INFO - Done attempting. 03:28:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:28:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:28:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:28:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:28:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:28:06 INFO - (blobuploader) - INFO - Done attempting. 03:28:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:28:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:28:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:28:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:28:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:28:07 INFO - (blobuploader) - INFO - Done attempting. 03:28:07 INFO - (blobuploader) - INFO - Iteration through files over. 03:28:07 INFO - Return code: 0 03:28:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:28:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:28:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3df4a9b8af937463996c271aac6f7f1d4c6cb91f7c38aca922930b58c9247b444ea48130323d00c9dbb7d9399132ec92587a7cec92722383eb4175094905f015", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9854f0915d8f3337fbed9b50f10a5f7529878f7dadcb86ca459beba4cbe089b819733418e1e0eff3fec251e3a2b8e993efe9ce096e34f8889cd21c25999e6235", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6ef22fc898aee4305a993c6a47c3491b5e6f786b0ed4b5cb8e7aeb8c6186ee6b53f8a7f91d06452ef19eb1468ec8ae5cdfc4bd054cff0f95ebc6bf15172e625", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5df5ea7b03933069ac604baf875db8e3c06aa5eca83a00fa9c16de5d587d4009066e64bece757066d0e745f5baee6494c0853cd44118ac452235e74dad7b1b8f"} 03:28:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:28:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:28:07 INFO - Contents: 03:28:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3df4a9b8af937463996c271aac6f7f1d4c6cb91f7c38aca922930b58c9247b444ea48130323d00c9dbb7d9399132ec92587a7cec92722383eb4175094905f015", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9854f0915d8f3337fbed9b50f10a5f7529878f7dadcb86ca459beba4cbe089b819733418e1e0eff3fec251e3a2b8e993efe9ce096e34f8889cd21c25999e6235", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6ef22fc898aee4305a993c6a47c3491b5e6f786b0ed4b5cb8e7aeb8c6186ee6b53f8a7f91d06452ef19eb1468ec8ae5cdfc4bd054cff0f95ebc6bf15172e625", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5df5ea7b03933069ac604baf875db8e3c06aa5eca83a00fa9c16de5d587d4009066e64bece757066d0e745f5baee6494c0853cd44118ac452235e74dad7b1b8f"} 03:28:07 INFO - Running post-run listener: copy_logs_to_upload_dir 03:28:07 INFO - Copying logs to upload dir... 03:28:07 INFO - mkdir: /builds/slave/test/build/upload/logs 03:28:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.225399 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-08 03:28:07.812218) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-08 03:28:07.816301) ========= 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=4300f881736b422476174874000001bc-1504865238.917504-463219999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3df4a9b8af937463996c271aac6f7f1d4c6cb91f7c38aca922930b58c9247b444ea48130323d00c9dbb7d9399132ec92587a7cec92722383eb4175094905f015", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9854f0915d8f3337fbed9b50f10a5f7529878f7dadcb86ca459beba4cbe089b819733418e1e0eff3fec251e3a2b8e993efe9ce096e34f8889cd21c25999e6235", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6ef22fc898aee4305a993c6a47c3491b5e6f786b0ed4b5cb8e7aeb8c6186ee6b53f8a7f91d06452ef19eb1468ec8ae5cdfc4bd054cff0f95ebc6bf15172e625", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5df5ea7b03933069ac604baf875db8e3c06aa5eca83a00fa9c16de5d587d4009066e64bece757066d0e745f5baee6494c0853cd44118ac452235e74dad7b1b8f"} build_url:https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011409 build_url: 'https://queue.taskcluster.net/v1/task/ahLuQrZERhaQob3zkw8yZQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3df4a9b8af937463996c271aac6f7f1d4c6cb91f7c38aca922930b58c9247b444ea48130323d00c9dbb7d9399132ec92587a7cec92722383eb4175094905f015", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9854f0915d8f3337fbed9b50f10a5f7529878f7dadcb86ca459beba4cbe089b819733418e1e0eff3fec251e3a2b8e993efe9ce096e34f8889cd21c25999e6235", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6ef22fc898aee4305a993c6a47c3491b5e6f786b0ed4b5cb8e7aeb8c6186ee6b53f8a7f91d06452ef19eb1468ec8ae5cdfc4bd054cff0f95ebc6bf15172e625", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5df5ea7b03933069ac604baf875db8e3c06aa5eca83a00fa9c16de5d587d4009066e64bece757066d0e745f5baee6494c0853cd44118ac452235e74dad7b1b8f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-08 03:28:07.851296) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 03:28:07.851687) ========= 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=4300f881736b422476174874000001bc-1504865238.917504-463219999 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004601 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-08 03:28:07.902738) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-08 03:28:07.903460) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-08 03:28:07.904002) ========= ========= Total master_lag: 0.18 =========