builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-041 starttime: 1505431225.04 results: success (0) revision: 8e818b5e9b6bef0fc1a5c527ecf30b0d56a02f14 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-14 16:20:25.039505) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-14 16:20:25.040058) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-14 16:20:25.040365) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-14 16:20:25.157717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 16:20:25.158036) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-14 16:20:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.9M=0s 2017-09-14 16:20:25 (45.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126465 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 16:20:25.393091) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 16:20:25.393410) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.024187 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 16:20:25.506636) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-14 16:20:25.506942) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8e818b5e9b6bef0fc1a5c527ecf30b0d56a02f14 --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 8e818b5e9b6bef0fc1a5c527ecf30b0d56a02f14 --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=8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-14 16:20:25,787 truncating revision to first 12 chars 2017-09-14 16:20:25,787 Setting DEBUG logging. 2017-09-14 16:20:25,787 attempt 1/10 2017-09-14 16:20:25,787 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8e818b5e9b6b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-14 16:20:26,699 unpacking tar archive at: mozilla-central-8e818b5e9b6b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.250160 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-14 16:20:26.828905) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-14 16:20:26.829213) ========= script_repo_revision: 8e818b5e9b6bef0fc1a5c527ecf30b0d56a02f14 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-14 16:20:26.829644) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-14 16:20:26.829932) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-14 16:20:26.923976) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-14 16:20:26.924364) ========= /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=8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:20:27 INFO - MultiFileLogger online at 20170914 16:20:27 in /builds/slave/test 16:20:27 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 16:20:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:20:27 INFO - {'append_to_log': False, 16:20:27 INFO - 'base_work_dir': '/builds/slave/test', 16:20:27 INFO - 'blob_upload_branch': 'Firefox', 16:20:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:20:27 INFO - 'branch': 'Firefox', 16:20:27 INFO - 'buildbot_json_path': 'buildprops.json', 16:20:27 INFO - 'code_coverage': False, 16:20:27 INFO - 'config_files': ('talos/linux_config.py',), 16:20:27 INFO - 'default_actions': ('clobber', 16:20:27 INFO - 'read-buildbot-config', 16:20:27 INFO - 'download-and-extract', 16:20:27 INFO - 'populate-webroot', 16:20:27 INFO - 'create-virtualenv', 16:20:27 INFO - 'install', 16:20:27 INFO - 'setup-mitmproxy', 16:20:27 INFO - 'run-tests'), 16:20:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:20:27 INFO - 'disable_ccov_upload': False, 16:20:27 INFO - 'disable_stylo': False, 16:20:27 INFO - 'download_minidump_stackwalk': True, 16:20:27 INFO - 'download_symbols': 'ondemand', 16:20:27 INFO - 'enable_stylo': False, 16:20:27 INFO - 'enable_webrender': False, 16:20:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:20:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:20:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:20:27 INFO - 'gecko_profile': False, 16:20:27 INFO - 'gecko_profile_interval': 0, 16:20:27 INFO - 'installer_path': 'installer.exe', 16:20:27 INFO - 'log_level': 'info', 16:20:27 INFO - 'log_name': 'talos', 16:20:27 INFO - 'log_to_console': True, 16:20:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:20:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:20:27 INFO - 'opt_config_files': (), 16:20:27 INFO - 'pip_index': False, 16:20:27 INFO - 'suite': 'dromaeojs-e10s', 16:20:27 INFO - 'system_bits': '32', 16:20:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:20:27 INFO - 'title': 'talos-linux64-ix-041', 16:20:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:20:27 INFO - 'use_talos_json': True, 16:20:27 INFO - 'verify': 'False', 16:20:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:20:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:20:27 INFO - 'work_dir': 'build'} 16:20:27 INFO - [mozharness: 2017-09-14 23:20:27.306384Z] Running clobber step. 16:20:27 INFO - Running pre-action listener: _resource_record_pre_action 16:20:27 INFO - Running main action method: clobber 16:20:27 INFO - rmtree: /builds/slave/test/build 16:20:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:20:28 INFO - Running post-action listener: _resource_record_post_action 16:20:28 INFO - [mozharness: 2017-09-14 23:20:28.292831Z] Finished clobber step (success) 16:20:28 INFO - [mozharness: 2017-09-14 23:20:28.292928Z] Running read-buildbot-config step. 16:20:28 INFO - Running pre-action listener: _resource_record_pre_action 16:20:28 INFO - Running main action method: read_buildbot_config 16:20:28 INFO - Using buildbot properties: 16:20:28 INFO - { 16:20:28 INFO - "project": "", 16:20:28 INFO - "product": "firefox", 16:20:28 INFO - "who": "archaeopteryx@coole-files.de", 16:20:28 INFO - "installer_path": "public/build/target.tar.bz2", 16:20:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:20:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:20:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 16:20:28 INFO - "stage_platform": "linux64-pgo", 16:20:28 INFO - "basedir": "/builds/slave/test", 16:20:28 INFO - "buildnumber": 160, 16:20:28 INFO - "platform": "ubuntu64_hw", 16:20:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:20:28 INFO - "slavebuilddir": "test", 16:20:28 INFO - "taskId": "CNp4LQntRlaf8JQLFgwA3g", 16:20:28 INFO - "branch": "mozilla-central", 16:20:28 INFO - "script_repo_revision": "production", 16:20:28 INFO - "revision": "8e818b5e9b6bef0fc1a5c527ecf30b0d56a02f14", 16:20:28 INFO - "slavename": "talos-linux64-ix-041", 16:20:28 INFO - "repo_path": "mozilla-central" 16:20:28 INFO - } 16:20:28 INFO - Finding installer, test and symbols from parent task. 16:20:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CNp4LQntRlaf8JQLFgwA3g'}, attempt #1 16:20:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CNp4LQntRlaf8JQLFgwA3g'}, attempt #1 16:20:29 INFO - Task dependencies: YGh8JjFsQ5G0BruESi9ePQ 16:20:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ'}, attempt #1 16:20:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.tar.bz2 16:20:32 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.test_packages.json 16:20:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.crashreporter-symbols.zip 16:20:32 INFO - Running post-action listener: _resource_record_post_action 16:20:32 INFO - [mozharness: 2017-09-14 23:20:32.642391Z] Finished read-buildbot-config step (success) 16:20:32 INFO - [mozharness: 2017-09-14 23:20:32.642654Z] Running download-and-extract step. 16:20:32 INFO - Running pre-action listener: _resource_record_pre_action 16:20:32 INFO - Running main action method: download_and_extract 16:20:32 INFO - mkdir: /builds/slave/test/build/tests 16:20:32 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')]} 16:20:32 INFO - trying https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.test_packages.json 16:20:32 INFO - Downloading https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:20:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:20:33 INFO - Downloaded 989 bytes. 16:20:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:20:33 INFO - Using the following test package requirements: 16:20:33 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:20:33 INFO - u'common': [u'target.common.tests.zip'], 16:20:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:20:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:20:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:20:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:20:33 INFO - u'mozbase': [u'target.common.tests.zip'], 16:20:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:20:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:20:33 INFO - u'web-platform': [u'target.common.tests.zip', 16:20:33 INFO - u'target.web-platform.tests.tar.gz'], 16:20:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:20:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 16:20:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.common.tests.zip 16:20:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:20:33 INFO - Fetch https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.common.tests.zip into memory 16:20:34 INFO - Content-Length response header: 55333641 16:20:34 INFO - Bytes received: 55333641 16:20:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.talos.tests.zip 16:20:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:20:45 INFO - Fetch https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.talos.tests.zip into memory 16:20:45 INFO - Content-Length response header: 14500356 16:20:45 INFO - Bytes received: 14500356 16:20:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:20:46 INFO - trying https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.tar.bz2 16:20:46 INFO - Downloading https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:20:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:20:49 INFO - Downloaded 62235654 bytes. 16:20:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.tar.bz2 16:20:49 INFO - mkdir: /builds/slave/test/properties 16:20:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:20:49 INFO - Writing to file /builds/slave/test/properties/build_url 16:20:49 INFO - Contents: 16:20:49 INFO - build_url:https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.tar.bz2 16:20:49 INFO - Running post-action listener: _resource_record_post_action 16:20:49 INFO - Running post-action listener: find_tests_for_verification 16:20:49 INFO - Running post-action listener: set_extra_try_arguments 16:20:49 INFO - [mozharness: 2017-09-14 23:20:49.291779Z] Finished download-and-extract step (success) 16:20:49 INFO - [mozharness: 2017-09-14 23:20:49.291969Z] Running populate-webroot step. 16:20:49 INFO - Running pre-action listener: _resource_record_pre_action 16:20:49 INFO - Running main action method: populate_webroot 16:20:49 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:20:49 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:20:49 INFO - 'tests': ['tresize', 16:20:49 INFO - 'tcanvasmark']}, 16:20:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:20:49 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:20:49 INFO - 'tests': ['dromaeo_css', 16:20:49 INFO - 'kraken']}, 16:20:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:20:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:20:49 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:20:49 INFO - 'talos_options': ['--disable-stylo'], 16:20:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:20:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:20:49 INFO - 'tests': ['damp', 'tps']}, 16:20:49 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:20:49 INFO - 'talos_options': ['--disable-stylo'], 16:20:49 INFO - 'tests': ['damp', 'tps']}, 16:20:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:20:49 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:20:49 INFO - 'tests': ['dromaeo_dom']}, 16:20:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:20:49 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:20:49 INFO - 'tests': ['basic_compositor_video', 16:20:49 INFO - 'glvideo']}, 16:20:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:20:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:20:49 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:20:49 INFO - 'talos_options': ['--disable-stylo'], 16:20:49 INFO - 'tests': ['ts_paint_webext', 16:20:49 INFO - 'tp5o_webext']}, 16:20:49 INFO - 'other-e10s': {'tests': ['a11yr', 16:20:49 INFO - 'ts_paint', 16:20:49 INFO - 'tpaint', 16:20:49 INFO - 'sessionrestore', 16:20:49 INFO - 'sessionrestore_many_windows', 16:20:49 INFO - 'sessionrestore_no_auto_restore', 16:20:49 INFO - 'tabpaint']}, 16:20:49 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:20:49 INFO - 'tests': ['a11yr', 16:20:49 INFO - 'ts_paint', 16:20:49 INFO - 'tpaint', 16:20:49 INFO - 'sessionrestore', 16:20:49 INFO - 'sessionrestore_many_windows', 16:20:49 INFO - 'sessionrestore_no_auto_restore', 16:20:49 INFO - 'tabpaint']}, 16:20:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:20:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:20:49 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:20:49 INFO - 'tests': ['bloom_basic_singleton']}, 16:20:49 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:20:49 INFO - 'tests': ['bloom_basic']}, 16:20:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:20:49 INFO - 'tsvgr_opacity', 16:20:49 INFO - 'tart', 16:20:49 INFO - 'tscrollx', 16:20:49 INFO - 'tsvg_static']}, 16:20:49 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:20:49 INFO - 'tests': ['tsvgx', 16:20:49 INFO - 'tsvgr_opacity', 16:20:49 INFO - 'tart', 16:20:49 INFO - 'tscrollx', 16:20:49 INFO - 'tsvg_static']}, 16:20:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:20:49 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:20:49 INFO - 'talos_options': ['--disable-stylo'], 16:20:49 INFO - 'tests': ['tp5o']}, 16:20:49 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:20:49 INFO - 'talos_options': ['--mitmproxy', 16:20:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:20:49 INFO - '--firstNonBlankPaint'], 16:20:49 INFO - 'tests': ['tp6_google', 16:20:49 INFO - 'tp6_youtube', 16:20:49 INFO - 'tp6_amazon', 16:20:49 INFO - 'tp6_facebook']}, 16:20:49 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:20:49 INFO - 'talos_options': ['--disable-stylo', 16:20:49 INFO - '--mitmproxy', 16:20:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:20:49 INFO - '--firstNonBlankPaint'], 16:20:49 INFO - 'tests': ['tp6_google', 16:20:49 INFO - 'tp6_youtube', 16:20:49 INFO - 'tp6_amazon', 16:20:49 INFO - 'tp6_facebook']}, 16:20:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:20:49 INFO - 'talos_options': ['--stylo-threads=1', 16:20:49 INFO - '--mitmproxy', 16:20:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:20:49 INFO - '--firstNonBlankPaint'], 16:20:49 INFO - 'tests': ['tp6_google', 16:20:49 INFO - 'tp6_youtube', 16:20:49 INFO - 'tp6_amazon', 16:20:49 INFO - 'tp6_facebook']}, 16:20:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:20:49 INFO - 'talos_options': ['--xperf_path', 16:20:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:20:49 INFO - 'tests': ['tp5n']}, 16:20:49 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:20:49 INFO - 'talos_options': ['--disable-stylo', 16:20:49 INFO - '--xperf_path', 16:20:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:20:49 INFO - 'tests': ['tp5n']}}} 16:20:49 INFO - Running post-action listener: _resource_record_post_action 16:20:49 INFO - [mozharness: 2017-09-14 23:20:49.310559Z] Finished populate-webroot step (success) 16:20:49 INFO - [mozharness: 2017-09-14 23:20:49.310648Z] Running create-virtualenv step. 16:20:49 INFO - Running pre-action listener: _resource_record_pre_action 16:20:49 INFO - Running main action method: create_virtualenv 16:20:49 INFO - Creating virtualenv /builds/slave/test/build/venv 16:20:49 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:20:49 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:20:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:20:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:20:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:20:49 INFO - Installing distribute.............................................................................................................................................................................................done. 16:20:50 INFO - Installing pip...............done. 16:20:50 INFO - Return code: 0 16:20:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:20:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:20:50 INFO - Reading from file tmpfile_stdout 16:20:50 INFO - Output received: 16:20:50 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:20:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:20: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')]} 16:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:20: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')]} 16:20:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:20:50 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 0xd781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf41a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8090>, '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 0x10e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', '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 16:20:50 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 16:20:50 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 16:20:50 INFO - Using env: {'DISPLAY': ':0', 16:20:50 INFO - 'HOME': '/home/cltbld', 16:20:50 INFO - 'LANG': 'en_US.UTF-8', 16:20:50 INFO - 'LANGUAGE': 'en_US:en', 16:20:50 INFO - 'LOGNAME': 'cltbld', 16:20:50 INFO - 'MAIL': '/var/mail/cltbld', 16:20:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:50 INFO - 'MOZ_NO_REMOTE': '1', 16:20:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:20:50 INFO - 'NO_EM_RESTART': '1', 16:20:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:20:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:50 INFO - 'PWD': '/builds/slave/test', 16:20:50 INFO - 'SHELL': '/bin/bash', 16:20:50 INFO - 'SHLVL': '1', 16:20:50 INFO - 'TERM': 'linux', 16:20:50 INFO - 'TMOUT': '86400', 16:20:50 INFO - 'USER': 'cltbld', 16:20:50 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', 16:20:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:50 INFO - '_': '/tools/buildbot/bin/python'} 16:20:52 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:20:52 INFO - Downloading/unpacking pip>=1.5 16:20:52 INFO - Running setup.py egg_info for package pip 16:20:52 INFO - warning: no files found matching 'pip/cacert.pem' 16:20:52 INFO - warning: no files found matching '*.html' under directory 'docs' 16:20:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:20:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:20:52 INFO - Installing collected packages: pip 16:20:52 INFO - Found existing installation: pip 1.1 16:20:52 INFO - Uninstalling pip: 16:20:52 INFO - Successfully uninstalled pip 16:20:52 INFO - Running setup.py install for pip 16:20:52 INFO - warning: no files found matching 'pip/cacert.pem' 16:20:52 INFO - warning: no files found matching '*.html' under directory 'docs' 16:20:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:20:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:20:52 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:20:52 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:20:52 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:20:52 INFO - Successfully installed pip 16:20:52 INFO - Cleaning up... 16:20:52 INFO - Return code: 0 16:20:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:20: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')]} 16:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:20: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')]} 16:20:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:20: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', '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 0xd781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf41a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8090>, '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 0x10e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', '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 16:20: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:20: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 psutil>=3.1.1 16:20:52 INFO - Using env: {'DISPLAY': ':0', 16:20:52 INFO - 'HOME': '/home/cltbld', 16:20:52 INFO - 'LANG': 'en_US.UTF-8', 16:20:52 INFO - 'LANGUAGE': 'en_US:en', 16:20:52 INFO - 'LOGNAME': 'cltbld', 16:20:52 INFO - 'MAIL': '/var/mail/cltbld', 16:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:52 INFO - 'MOZ_NO_REMOTE': '1', 16:20:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:20:52 INFO - 'NO_EM_RESTART': '1', 16:20:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:52 INFO - 'PWD': '/builds/slave/test', 16:20:52 INFO - 'SHELL': '/bin/bash', 16:20:52 INFO - 'SHLVL': '1', 16:20:52 INFO - 'TERM': 'linux', 16:20:52 INFO - 'TMOUT': '86400', 16:20:52 INFO - 'USER': 'cltbld', 16:20:52 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', 16:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:52 INFO - '_': '/tools/buildbot/bin/python'} 16:20:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:20:52 INFO - Downloading/unpacking psutil>=3.1.1 16:20:52 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 16:20:52 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 16:20:52 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 16:20:52 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 16:20:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:20:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:20:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:20:53 INFO - Installing collected packages: psutil 16:20:53 INFO - Running setup.py install for psutil 16:20:53 INFO - building 'psutil._psutil_linux' extension 16:20:53 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 16:20:54 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 16:20:54 INFO - building 'psutil._psutil_posix' extension 16:20:54 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 16:20:54 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 16:20:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:20:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:20:54 INFO - Successfully installed psutil 16:20:54 INFO - Cleaning up... 16:20:54 INFO - Return code: 0 16:20:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:20:54 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')]} 16:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:20:54 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')]} 16:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:20:54 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 0xd781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf41a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8090>, '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 0x10e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', '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 16:20:54 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 16:20:54 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 16:20:54 INFO - Using env: {'DISPLAY': ':0', 16:20:54 INFO - 'HOME': '/home/cltbld', 16:20:54 INFO - 'LANG': 'en_US.UTF-8', 16:20:54 INFO - 'LANGUAGE': 'en_US:en', 16:20:54 INFO - 'LOGNAME': 'cltbld', 16:20:54 INFO - 'MAIL': '/var/mail/cltbld', 16:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:54 INFO - 'MOZ_NO_REMOTE': '1', 16:20:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:20:54 INFO - 'NO_EM_RESTART': '1', 16:20:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:54 INFO - 'PWD': '/builds/slave/test', 16:20:54 INFO - 'SHELL': '/bin/bash', 16:20:54 INFO - 'SHLVL': '1', 16:20:54 INFO - 'TERM': 'linux', 16:20:54 INFO - 'TMOUT': '86400', 16:20:54 INFO - 'USER': 'cltbld', 16:20:54 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', 16:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:54 INFO - '_': '/tools/buildbot/bin/python'} 16:20:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:20:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:20: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 16:20: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 16:20: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 16:20: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 16:20:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:20:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:20:55 INFO - Installing collected packages: mozsystemmonitor 16:20:55 INFO - Running setup.py install for mozsystemmonitor 16:20:55 INFO - Successfully installed mozsystemmonitor 16:20:55 INFO - Cleaning up... 16:20:55 INFO - Return code: 0 16:20:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:20: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')]} 16:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:20: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')]} 16:20:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:20: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', '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 0xd781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf41a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8090>, '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 0x10e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', '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 16:20: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:20: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 jsonschema==2.5.1 16:20:55 INFO - Using env: {'DISPLAY': ':0', 16:20:55 INFO - 'HOME': '/home/cltbld', 16:20:55 INFO - 'LANG': 'en_US.UTF-8', 16:20:55 INFO - 'LANGUAGE': 'en_US:en', 16:20:55 INFO - 'LOGNAME': 'cltbld', 16:20:55 INFO - 'MAIL': '/var/mail/cltbld', 16:20:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:55 INFO - 'MOZ_NO_REMOTE': '1', 16:20:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:20:55 INFO - 'NO_EM_RESTART': '1', 16:20:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:20:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:55 INFO - 'PWD': '/builds/slave/test', 16:20:55 INFO - 'SHELL': '/bin/bash', 16:20:55 INFO - 'SHLVL': '1', 16:20:55 INFO - 'TERM': 'linux', 16:20:55 INFO - 'TMOUT': '86400', 16:20:55 INFO - 'USER': 'cltbld', 16:20:55 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', 16:20:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:55 INFO - '_': '/tools/buildbot/bin/python'} 16:20:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:20:55 INFO - Downloading/unpacking jsonschema==2.5.1 16:20: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 16:20: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 16:20: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 16:20: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 16:20:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:20:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:20: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 16:20: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 16:20: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 16:20: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 16:20:57 INFO - Downloading functools32-3.2.3-2.tar.gz 16:20:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:20:57 INFO - warning: no files found matching '*.txt' 16:20:57 INFO - no previously-included directories found matching 'build' 16:20:57 INFO - no previously-included directories found matching 'dist' 16:20:57 INFO - no previously-included directories found matching '.git*' 16:20:57 INFO - Installing collected packages: jsonschema, functools32 16:20:57 INFO - Running setup.py install for functools32 16:20:57 INFO - warning: no files found matching '*.txt' 16:20:57 INFO - no previously-included directories found matching 'build' 16:20:57 INFO - no previously-included directories found matching 'dist' 16:20:57 INFO - no previously-included directories found matching '.git*' 16:20:57 INFO - Successfully installed jsonschema functools32 16:20:57 INFO - Cleaning up... 16:20:57 INFO - Return code: 0 16:20:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:20: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')]} 16:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:20: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')]} 16:20:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:20: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', '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 0xd781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf41a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8090>, '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 0x10e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', '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 16:20: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:20: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 functools32==3.2.3-2 16:20:57 INFO - Using env: {'DISPLAY': ':0', 16:20:57 INFO - 'HOME': '/home/cltbld', 16:20:57 INFO - 'LANG': 'en_US.UTF-8', 16:20:57 INFO - 'LANGUAGE': 'en_US:en', 16:20:57 INFO - 'LOGNAME': 'cltbld', 16:20:57 INFO - 'MAIL': '/var/mail/cltbld', 16:20:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:57 INFO - 'MOZ_NO_REMOTE': '1', 16:20:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:20:57 INFO - 'NO_EM_RESTART': '1', 16:20:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:20:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:57 INFO - 'PWD': '/builds/slave/test', 16:20:57 INFO - 'SHELL': '/bin/bash', 16:20:57 INFO - 'SHLVL': '1', 16:20:57 INFO - 'TERM': 'linux', 16:20:57 INFO - 'TMOUT': '86400', 16:20:57 INFO - 'USER': 'cltbld', 16:20:57 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', 16:20:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:57 INFO - '_': '/tools/buildbot/bin/python'} 16:20:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:20:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:20:57 INFO - Cleaning up... 16:20:57 INFO - Return code: 0 16:20:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:20: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')]} 16:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:20: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')]} 16:20:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:20: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', '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 0xd781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf41a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8090>, '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 0x10e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', '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 16:20: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:20: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 blobuploader==1.2.4 16:20:57 INFO - Using env: {'DISPLAY': ':0', 16:20:57 INFO - 'HOME': '/home/cltbld', 16:20:57 INFO - 'LANG': 'en_US.UTF-8', 16:20:57 INFO - 'LANGUAGE': 'en_US:en', 16:20:57 INFO - 'LOGNAME': 'cltbld', 16:20:57 INFO - 'MAIL': '/var/mail/cltbld', 16:20:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:57 INFO - 'MOZ_NO_REMOTE': '1', 16:20:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:20:57 INFO - 'NO_EM_RESTART': '1', 16:20:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:20:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:57 INFO - 'PWD': '/builds/slave/test', 16:20:57 INFO - 'SHELL': '/bin/bash', 16:20:57 INFO - 'SHLVL': '1', 16:20:57 INFO - 'TERM': 'linux', 16:20:57 INFO - 'TMOUT': '86400', 16:20:57 INFO - 'USER': 'cltbld', 16:20:57 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', 16:20:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:57 INFO - '_': '/tools/buildbot/bin/python'} 16:20:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:20:57 INFO - Downloading/unpacking blobuploader==1.2.4 16:20: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 16:20: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 16:20: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 16:20: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 16:20:58 INFO - Downloading blobuploader-1.2.4.tar.gz 16:20:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:20:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:20: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 16:20: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 16:20: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 16:20: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 16:20:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:20:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:20: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 16:20: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 16:20: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 16:20: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 16:20:59 INFO - Downloading docopt-0.6.1.tar.gz 16:20:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:20:59 INFO - Installing collected packages: blobuploader, requests, docopt 16:20:59 INFO - Running setup.py install for blobuploader 16:20:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:20:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:20:59 INFO - Running setup.py install for requests 16:21:00 INFO - Running setup.py install for docopt 16:21:00 INFO - Successfully installed blobuploader requests docopt 16:21:00 INFO - Cleaning up... 16:21:00 INFO - Return code: 0 16:21:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:21:00 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')]} 16:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:00 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')]} 16:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:00 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 0xd781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf41a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8090>, '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 0x10e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', '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 16:21:00 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 16:21:00 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 16:21:00 INFO - Using env: {'DISPLAY': ':0', 16:21:00 INFO - 'HOME': '/home/cltbld', 16:21:00 INFO - 'LANG': 'en_US.UTF-8', 16:21:00 INFO - 'LANGUAGE': 'en_US:en', 16:21:00 INFO - 'LOGNAME': 'cltbld', 16:21:00 INFO - 'MAIL': '/var/mail/cltbld', 16:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:00 INFO - 'MOZ_NO_REMOTE': '1', 16:21:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:00 INFO - 'NO_EM_RESTART': '1', 16:21:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:00 INFO - 'PWD': '/builds/slave/test', 16:21:00 INFO - 'SHELL': '/bin/bash', 16:21:00 INFO - 'SHLVL': '1', 16:21:00 INFO - 'TERM': 'linux', 16:21:00 INFO - 'TMOUT': '86400', 16:21:00 INFO - 'USER': 'cltbld', 16:21:00 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', 16:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:00 INFO - '_': '/tools/buildbot/bin/python'} 16:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:21:00 INFO - Running setup.py (path:/tmp/pip-w2VPBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:21:00 INFO - Running setup.py (path:/tmp/pip-3jT9u5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:21:00 INFO - Running setup.py (path:/tmp/pip-hOSe8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:21:00 INFO - Running setup.py (path:/tmp/pip-i0yxtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:21:00 INFO - Running setup.py (path:/tmp/pip-An1_2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:21:00 INFO - Running setup.py (path:/tmp/pip-WktbJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:21:00 INFO - Running setup.py (path:/tmp/pip-MLtQJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:21:00 INFO - Running setup.py (path:/tmp/pip-9tjiK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:21:00 INFO - Running setup.py (path:/tmp/pip-vt50vO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:21:01 INFO - Running setup.py (path:/tmp/pip-5gs67m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:21:01 INFO - Running setup.py (path:/tmp/pip-mpIAXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:21:01 INFO - Running setup.py (path:/tmp/pip-f5ZSyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:21:01 INFO - Running setup.py (path:/tmp/pip-QIKEn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:21:01 INFO - Running setup.py (path:/tmp/pip-jQAci8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:01 INFO - Running setup.py (path:/tmp/pip-uSZHdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:21:01 INFO - Running setup.py (path:/tmp/pip-3FE8D8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:21:01 INFO - Running setup.py (path:/tmp/pip-RbywCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:21:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:21:01 INFO - Running setup.py install for manifestparser 16:21:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:21:01 INFO - Running setup.py install for mozcrash 16:21:01 INFO - Running setup.py install for mozdebug 16:21:01 INFO - Running setup.py install for mozdevice 16:21:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:21:01 INFO - Running setup.py install for mozfile 16:21:02 INFO - Running setup.py install for mozhttpd 16:21:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Running setup.py install for mozinfo 16:21:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Running setup.py install for mozInstall 16:21:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Running setup.py install for mozleak 16:21:02 INFO - Running setup.py install for mozlog 16:21:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Running setup.py install for moznetwork 16:21:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Running setup.py install for mozprocess 16:21:02 INFO - Running setup.py install for mozprofile 16:21:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Running setup.py install for mozrunner 16:21:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:21:03 INFO - Running setup.py install for mozscreenshot 16:21:03 INFO - Running setup.py install for moztest 16:21:03 INFO - Running setup.py install for mozversion 16:21:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:21:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:21:03 INFO - Cleaning up... 16:21:03 INFO - Return code: 0 16:21:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:21:03 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')]} 16:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:03 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')]} 16:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:03 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 0xd781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf41a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8090>, '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 0x10e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', '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 16:21:03 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 16:21:03 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 16:21:03 INFO - Using env: {'DISPLAY': ':0', 16:21:03 INFO - 'HOME': '/home/cltbld', 16:21:03 INFO - 'LANG': 'en_US.UTF-8', 16:21:03 INFO - 'LANGUAGE': 'en_US:en', 16:21:03 INFO - 'LOGNAME': 'cltbld', 16:21:03 INFO - 'MAIL': '/var/mail/cltbld', 16:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:03 INFO - 'MOZ_NO_REMOTE': '1', 16:21:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:03 INFO - 'NO_EM_RESTART': '1', 16:21:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:03 INFO - 'PWD': '/builds/slave/test', 16:21:03 INFO - 'SHELL': '/bin/bash', 16:21:03 INFO - 'SHLVL': '1', 16:21:03 INFO - 'TERM': 'linux', 16:21:03 INFO - 'TMOUT': '86400', 16:21:03 INFO - 'USER': 'cltbld', 16:21:03 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', 16:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:03 INFO - '_': '/tools/buildbot/bin/python'} 16:21:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:21:03 INFO - Running setup.py (path:/tmp/pip-3QOop0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:21:03 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)) 16:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:21:03 INFO - Running setup.py (path:/tmp/pip-SiC2Dn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:21:03 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)) 16:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:21:03 INFO - Running setup.py (path:/tmp/pip-S3Hjux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:21:03 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)) 16:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:21:03 INFO - Running setup.py (path:/tmp/pip-tvFnzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:21:03 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)) 16:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:21:03 INFO - Running setup.py (path:/tmp/pip-lH2rDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:21:03 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)) 16:21:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:21:03 INFO - Running setup.py (path:/tmp/pip-tCsQEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:21:04 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)) 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:21:04 INFO - Running setup.py (path:/tmp/pip-vrJRxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:21:04 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)) 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:21:04 INFO - Running setup.py (path:/tmp/pip-z9R5fy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:21:04 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)) 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:21:04 INFO - Running setup.py (path:/tmp/pip-bdTLGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:21:04 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)) 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:21:04 INFO - Running setup.py (path:/tmp/pip-UooVuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:21:04 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)) 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:21:04 INFO - Running setup.py (path:/tmp/pip-6WfEX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:21:04 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)) 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:21:04 INFO - Running setup.py (path:/tmp/pip-IWCVZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:21:04 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)) 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:21:04 INFO - Running setup.py (path:/tmp/pip-_3kU_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:21:04 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)) 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:21:04 INFO - Running setup.py (path:/tmp/pip-0ltDR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:21:04 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)) 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:04 INFO - Running setup.py (path:/tmp/pip-wnJp5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:04 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)) 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:21:04 INFO - Running setup.py (path:/tmp/pip-Lrbsvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:21:04 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)) 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:21:04 INFO - Running setup.py (path:/tmp/pip-haBLUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:21:04 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)) 16:21:04 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)) 16:21:04 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)) 16:21:04 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)) 16:21:04 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)) 16:21:04 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)) 16:21:04 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)) 16:21:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:21:04 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 16:21:04 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 16:21:04 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 16:21:04 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 16:21:05 INFO - Downloading blessings-1.6.tar.gz 16:21:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:21:05 INFO - Installing collected packages: blessings 16:21:05 INFO - Running setup.py install for blessings 16:21:06 INFO - Successfully installed blessings 16:21:06 INFO - Cleaning up... 16:21:06 INFO - Return code: 0 16:21:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:21:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:21:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:21:06 INFO - Reading from file tmpfile_stdout 16:21:06 INFO - Current package versions: 16:21:06 INFO - argparse == 1.2.1 16:21:06 INFO - blessings == 1.6 16:21:06 INFO - blobuploader == 1.2.4 16:21:06 INFO - docopt == 0.6.1 16:21:06 INFO - functools32 == 3.2.3-2 16:21:06 INFO - jsonschema == 2.5.1 16:21:06 INFO - manifestparser == 1.1 16:21:06 INFO - mozInstall == 1.13 16:21:06 INFO - mozcrash == 1.0 16:21:06 INFO - mozdebug == 0.1 16:21:06 INFO - mozdevice == 0.51 16:21:06 INFO - mozfile == 1.2 16:21:06 INFO - mozhttpd == 0.7 16:21:06 INFO - mozinfo == 0.10 16:21:06 INFO - mozleak == 0.1 16:21:06 INFO - mozlog == 3.5 16:21:06 INFO - moznetwork == 0.27 16:21:06 INFO - mozprocess == 0.25 16:21:06 INFO - mozprofile == 0.28 16:21:06 INFO - mozrunner == 6.13 16:21:06 INFO - mozscreenshot == 0.1 16:21:06 INFO - mozsystemmonitor == 0.3 16:21:06 INFO - moztest == 0.8 16:21:06 INFO - mozversion == 1.4 16:21:06 INFO - psutil == 3.1.1 16:21:06 INFO - requests == 1.2.3 16:21:06 INFO - wsgiref == 0.1.2 16:21:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:21:06 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')]} 16:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:06 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')]} 16:21:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:06 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 0xd781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf41a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8090>, '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 0x10e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', '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 16:21:06 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 16:21:06 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 16:21:06 INFO - Using env: {'DISPLAY': ':0', 16:21:06 INFO - 'HOME': '/home/cltbld', 16:21:06 INFO - 'LANG': 'en_US.UTF-8', 16:21:06 INFO - 'LANGUAGE': 'en_US:en', 16:21:06 INFO - 'LOGNAME': 'cltbld', 16:21:06 INFO - 'MAIL': '/var/mail/cltbld', 16:21:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:06 INFO - 'MOZ_NO_REMOTE': '1', 16:21:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:06 INFO - 'NO_EM_RESTART': '1', 16:21:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:06 INFO - 'PWD': '/builds/slave/test', 16:21:06 INFO - 'SHELL': '/bin/bash', 16:21:06 INFO - 'SHLVL': '1', 16:21:06 INFO - 'TERM': 'linux', 16:21:06 INFO - 'TMOUT': '86400', 16:21:06 INFO - 'USER': 'cltbld', 16:21:06 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', 16:21:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:06 INFO - '_': '/tools/buildbot/bin/python'} 16:21:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:06 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)) 16:21:06 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)) 16:21:06 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)) 16:21:06 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)) 16:21:06 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)) 16:21:06 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)) 16:21:06 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)) 16:21:06 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)) 16:21:06 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)) 16:21:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:21: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 16:21: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 16:21: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 16:21: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 16:21:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:21:07 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)) 16:21:07 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)) 16:21:07 INFO - Installing collected packages: simplejson 16:21:07 INFO - Running setup.py install for simplejson 16:21:07 INFO - building 'simplejson._speedups' extension 16:21:07 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 16:21:08 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 16:21:08 INFO - Successfully installed simplejson 16:21:08 INFO - Cleaning up... 16:21:08 INFO - Return code: 0 16:21:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:21:08 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')]} 16:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:08 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')]} 16:21:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:08 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 0xd781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf41a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8090>, '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 0x10e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f90>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', '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 16:21:08 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 16:21:08 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 16:21:08 INFO - Using env: {'DISPLAY': ':0', 16:21:08 INFO - 'HOME': '/home/cltbld', 16:21:08 INFO - 'LANG': 'en_US.UTF-8', 16:21:08 INFO - 'LANGUAGE': 'en_US:en', 16:21:08 INFO - 'LOGNAME': 'cltbld', 16:21:08 INFO - 'MAIL': '/var/mail/cltbld', 16:21:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:08 INFO - 'MOZ_NO_REMOTE': '1', 16:21:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:08 INFO - 'NO_EM_RESTART': '1', 16:21:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:08 INFO - 'PWD': '/builds/slave/test', 16:21:08 INFO - 'SHELL': '/bin/bash', 16:21:08 INFO - 'SHLVL': '1', 16:21:08 INFO - 'TERM': 'linux', 16:21:08 INFO - 'TMOUT': '86400', 16:21:08 INFO - 'USER': 'cltbld', 16:21:08 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', 16:21:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:08 INFO - '_': '/tools/buildbot/bin/python'} 16:21:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:21:09 INFO - Cleaning up... 16:21:09 INFO - Return code: 0 16:21:09 INFO - Running post-action listener: _resource_record_post_action 16:21:09 INFO - Running post-action listener: _start_resource_monitoring 16:21:09 INFO - Starting resource monitoring. 16:21:09 INFO - [mozharness: 2017-09-14 23:21:09.067497Z] Finished create-virtualenv step (success) 16:21:09 INFO - [mozharness: 2017-09-14 23:21:09.068017Z] Running install step. 16:21:09 INFO - Running pre-action listener: _resource_record_pre_action 16:21:09 INFO - Running main action method: install 16:21:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:21:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:21:09 INFO - Reading from file tmpfile_stdout 16:21:09 INFO - Detecting whether we're running mozinstall >=1.0... 16:21:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:21:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:21:09 INFO - Reading from file tmpfile_stdout 16:21:09 INFO - Output received: 16:21:09 INFO - Usage: mozinstall [options] installer 16:21:09 INFO - Options: 16:21:09 INFO - -h, --help show this help message and exit 16:21:09 INFO - -d DEST, --destination=DEST 16:21:09 INFO - Directory to install application into. [default: 16:21:09 INFO - "/builds/slave/test"] 16:21:09 INFO - --app=APP Application being installed. [default: firefox] 16:21:09 INFO - mkdir: /builds/slave/test/build/application 16:21:09 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'] 16:21:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:21:27 INFO - Reading from file tmpfile_stdout 16:21:27 INFO - Output received: 16:21:27 INFO - /builds/slave/test/build/application/firefox/firefox 16:21:27 INFO - Running post-action listener: _resource_record_post_action 16:21:27 INFO - [mozharness: 2017-09-14 23:21:27.009496Z] Finished install step (success) 16:21:27 INFO - [mozharness: 2017-09-14 23:21:27.009749Z] Running setup-mitmproxy step. 16:21:27 INFO - Running pre-action listener: _resource_record_pre_action 16:21:27 INFO - Running main action method: setup_mitmproxy 16:21:27 INFO - Skipping: mitmproxy is not required 16:21:27 INFO - Running post-action listener: _resource_record_post_action 16:21:27 INFO - [mozharness: 2017-09-14 23:21:27.010932Z] Finished setup-mitmproxy step (success) 16:21:27 INFO - [mozharness: 2017-09-14 23:21:27.011154Z] Running run-tests step. 16:21:27 INFO - Running pre-action listener: _resource_record_pre_action 16:21:27 INFO - Running pre-action listener: _set_gcov_prefix 16:21:27 INFO - Running main action method: run_tests 16:21:27 WARNING - Try message not found. 16:21:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:21:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:21:27 INFO - Python 2.7.3 16:21:27 INFO - Return code: 0 16:21:27 INFO - grabbing minidump binary from tooltool 16:21: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')]} 16:21: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 0x10e8090>, '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 0x10e8d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e2f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:21: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 16:21: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 16:21: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 16:21:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:21:27 INFO - Return code: 0 16:21:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:21:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:21:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:21:27 INFO - ENV: RUST_BACKTRACE is now full 16:21:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:21:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:21: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/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 16:21: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/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-041 --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 16:21:27 INFO - Using env: {'DISPLAY': ':0', 16:21:27 INFO - 'HOME': '/home/cltbld', 16:21:27 INFO - 'LANG': 'en_US.UTF-8', 16:21:27 INFO - 'LANGUAGE': 'en_US:en', 16:21:27 INFO - 'LOGNAME': 'cltbld', 16:21:27 INFO - 'MAIL': '/var/mail/cltbld', 16:21:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:21:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:27 INFO - 'MOZ_NO_REMOTE': '1', 16:21:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:21:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:27 INFO - 'NO_EM_RESTART': '1', 16:21:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:27 INFO - 'PWD': '/builds/slave/test', 16:21:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:21:27 INFO - 'RUST_BACKTRACE': 'full', 16:21:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:21:27 INFO - 'SHELL': '/bin/bash', 16:21:27 INFO - 'SHLVL': '1', 16:21:27 INFO - 'TERM': 'linux', 16:21:27 INFO - 'TMOUT': '86400', 16:21:27 INFO - 'USER': 'cltbld', 16:21:27 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', 16:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:27 INFO - '_': '/tools/buildbot/bin/python'} 16:21: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/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--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 16:21:27 INFO - mozversion application_buildid: 20170914215922 16:21:27 INFO - mozversion application_changeset: 8e818b5e9b6bef0fc1a5c527ecf30b0d56a02f14 16:21:27 INFO - mozversion application_display_name: Nightly 16:21:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:21:27 INFO - mozversion application_name: Firefox 16:21:27 INFO - mozversion application_remotingname: firefox 16:21:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:21:27 INFO - mozversion application_vendor: Mozilla 16:21:27 INFO - mozversion application_version: 57.0a1 16:21:27 INFO - mozversion platform_buildid: 20170914215922 16:21:27 INFO - mozversion platform_changeset: 8e818b5e9b6bef0fc1a5c527ecf30b0d56a02f14 16:21:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:21:27 INFO - mozversion platform_version: 57.0a1 16:21:27 INFO - using testdate: 1505431287 16:21:27 INFO - actual date: 1505431287 16:21:27 INFO - starting webserver on 'localhost:50326' 16:21:27 INFO - SUITE-START | Running 2 tests 16:21:27 INFO - TEST-START | dromaeo_css 16:21:27 INFO - Initialising browser for dromaeo_css test... 16:21:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50326/getInfo.html -profile /tmp/tmplBGJT_/profile 16:21:27 INFO - TEST-INFO | started process 4406 (/builds/slave/test/build/application/firefox/firefox http://localhost:50326/getInfo.html) 16:21:33 INFO - TEST-INFO | 4406: exit 0 16:21:33 INFO - Browser initialized. 16:21:33 INFO - Running cycle 1/1 for dromaeo_css test... 16:21:33 INFO - Using env: {'DISPLAY': ':0', 16:21:33 INFO - 'HOME': '/home/cltbld', 16:21:33 INFO - 'JSGC_DISABLE_POISONING': '1', 16:21:33 INFO - 'LANG': 'en_US.UTF-8', 16:21:33 INFO - 'LANGUAGE': 'en_US:en', 16:21:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:21:33 INFO - 'LOGNAME': 'cltbld', 16:21:33 INFO - 'MAIL': '/var/mail/cltbld', 16:21:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:21:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:21:33 INFO - 'MOZ_CRASHREPORTER': '1', 16:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:21:33 INFO - 'MOZ_NO_REMOTE': '1', 16:21:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:21:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:21:33 INFO - 'NO_EM_RESTART': '1', 16:21:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:33 INFO - 'PWD': '/builds/slave/test', 16:21:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:21:33 INFO - 'RUST_BACKTRACE': 'full', 16:21:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:21:33 INFO - 'SHELL': '/bin/bash', 16:21:33 INFO - 'SHLVL': '1', 16:21:33 INFO - 'TERM': 'linux', 16:21:33 INFO - 'TMOUT': '86400', 16:21:33 INFO - 'USER': 'cltbld', 16:21:33 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', 16:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:33 INFO - '_': '/tools/buildbot/bin/python'} 16:21:33 INFO - TEST-INFO | started process 4647 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplBGJT_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:21:34 INFO - PID 4647 | 16:21:34 INFO - PID 4647 | (/builds/slave/test/build/application/firefox/firefox:4697): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:21:34 INFO - PID 4647 | 16:21:44 INFO - PID 4647 | RSS: Main: 164761600 16:21:44 INFO - PID 4647 | 16:24:42 INFO - PID 4647 | Cycle 1(1): loaded http://localhost:50326/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50326/tests/dromaeo/cssquery-ext.html) 16:24:43 INFO - PID 4647 | RSS: Main: 166735872 16:24:43 INFO - PID 4647 | 16:27:45 INFO - PID 4647 | Cycle 1(1): loaded http://localhost:50326/tests/dromaeo/cssquery-ext.html (next: http://localhost:50326/tests/dromaeo/cssquery-jquery.html) 16:27:46 INFO - PID 4647 | RSS: Main: 165273600 16:27:46 INFO - PID 4647 | 16:30:47 INFO - PID 4647 | Cycle 1(1): loaded http://localhost:50326/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50326/tests/dromaeo/cssquery-mootools.html) 16:30:48 INFO - PID 4647 | RSS: Main: 167559168 16:30:48 INFO - PID 4647 | 16:33:50 INFO - PID 4647 | Cycle 1(1): loaded http://localhost:50326/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50326/tests/dromaeo/cssquery-prototype.html) 16:33:50 INFO - PID 4647 | RSS: Main: 164323328 16:33:50 INFO - PID 4647 | 16:36:53 INFO - PID 4647 | Cycle 1(1): loaded http://localhost:50326/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50326/tests/dromaeo/cssquery-yui.html) 16:36:54 INFO - PID 4647 | RSS: Main: 167514112 16:36:54 INFO - PID 4647 | 16:39:55 INFO - PID 4647 | Cycle 1(1): loaded http://localhost:50326/tests/dromaeo/cssquery-yui.html (next: http://localhost:50326/tests/dromaeo/cssquery-dojo.html) 16:39:56 INFO - PID 4647 | RSS: Main: 170258432 16:39:56 INFO - PID 4647 | 16:39:56 INFO - PID 4647 | __start_tp_report 16:39:56 INFO - PID 4647 | _x_x_mozilla_page_load 16:39:56 INFO - PID 4647 | _x_x_mozilla_page_load_details 16:39:56 INFO - PID 4647 | |i|pagename|runs| 16:39:56 INFO - PID 4647 | |0;dojo.html;3796.22177453622;3873.903152421189;3882.514685664292;3887.708421868361;3895.435161901524;6168.136460895473;6247.7813276535335;6267.43593076623;6286.779962701306;6308.905366419502;6352.428281454668;6375.1712277403985;6401.871962560746;6405.1353067335895;6413.198350206224;5662.292213473316;5667.433256674341;5688.914666280009;5697.230873832042;5728.341240757304;5609.551235901138;5693.3452652945025;5694.971525142369;5710.086386178213;5783.190353350535;5688.345840228385;5705.144228365737;5761.711914404284;5798.159266906296;5809.651420914759;5260.420021105091;5516.062269414169;5646.802361917313;5706.201131841545;5724.685142317192;5666.320041594994;5697.943020569783;5735.053716136874;5747.396523365028;5753.971230143864;6239.469645080174;6240.438360547572;6295.622262664255;6412.967935160341;6415.422611964946;6794.898076528856;6827.044213810071;6827.897581536281;6828.760993365208;6831.248562658104;6884;6897.689603967833;6943.062686537303;6972.023916651673;6981.650917454158;6395.360463953567;6467.83830404239;6476.6114033158165;6486.578372405826;6487.870242595169;7062.71749130029;7066.470014748915;7070.46971477131;7161.427085833225;7164.426845852319;6607.141071660653;6610.140681711347;6684.86630267392;6708.2956289589865;6902.447804175654;6871.278515755874;6896.482763792736;6902.551334163263;6933.064036355128;6933.583984960918;6866.793996180123;6903.1371078615175;6927.7228910843005;6938.479614028968;6942.479314051467;6850.691718872663;6857.2114206866845;6942.271061538567;6947.5831450113155;6950.687219075154;4915.87710307238;4995.600351971833;5003.324551185616;5078.162103252922;5090.287359769635;4556.2937744649635;4620.029793743284;4620.907581848344;4635.142498637762;4647.442306923123;6160.507159427235;6256.186695729526;6330.145430366933;6331.050342448578;6336.52476064297;6671.499637527205;6732.629705366252;6815.931840681629;6817.113775209191;6818.624975626388;6244.687765611701;6298.622082675054;6326.240851097898;6379.138816259839;6379.425851673372;5540.030494663418;5605.159226116115;5616.241807356037;5617.045102332613;5621.325440947113;7110.608916509642;7113.253108423644;7174.246704096099;7215.458840586977;7223.241559636268;6666.600003999775;6756.054152418764;6816.727330906907;6893.862122757417;6894.551854129466;7101.893471598033;7180.7845764627145;7193.424526037799;7204.279572042754;7205.531640443354;6721.495887808336;6756.020377045203;6848.794536163923;6851;6857.108575885103;6838.658067096526;6861.897071543831;6930.792076237721;6956.130483689539;6957.443404527727;6513.576617519846;6522.934770652422;6532.216134063753;6535.640539770168;6539.6403197824575;6465.062565928009;6474.2554606219;6481.935180648133;6527.086207930988;6542.869142617026;6408.711607977559;6446.742130314735;6512.120863683342;6524.412802847766;6536.117624120683;6554.8361290968105;6599.637019963948;6608.372204640552;6632.403083722488;6644.867102658017;6423.710933007932;6431.196100487439;6444.742210311535;6446.387593178641;6446.645434501147 16:39:56 INFO - PID 4647 | |1;ext.html;944.768531709731;968.7384406210322;972.4165500699581;973.1630797514132;975.2295686407738;40571.79714101429;44899;45005.77497112514;45016.774916125454;45165.548344516545;41567.58432415675;41872;41978.58021419785;42088.78955605218;42215.78892105543;7368.263173682629;7503.399728021745;7540;7557.05536807899;7560.319571238588;7398.371138453238;7452.813679658025;7493;7494.138174109966;7496.550206987571;7398.926010739878;7465.06686664167;7526.172121066678;7942.086660034083;8142.145074767153;8026.518408895456;8080.111187769341;8090.676372945134;8091.919080809175;8092.190780921919;7868.606569671493;7936.0873499547715;8028.4781489203015;8065.838683226302;8085.636146373427;40586.59413405858;40615.7969210155;40792.38811417831;40858;40860;3594.622564630702;3628.3468975584383;3632.763870348419;3634.1641422472717;3642.1805093853936;33084.66915330864;33204.6679533204;33225;33248.83375583131;33267.833660831784;5366.2755528004;5425.810096646638;5487.368952570466;5498.642588231752;5529.253550770634;219451;219898;220998.9999999984;221220;222374;44706.870519064;45379;51326;52092.21861672078;52338.21492677613;56775;56857.715711422;57011.429885700614;57037.42962570404;57119.42880571141;14718.264086795618;14804.777928331083;14839.554813355617;14859.405623775145;14862.70274594502;11660.358680272666;11677.591284305008;11690.70773230676;11694.122940779476;11704.531818727326;15452.768208476882;15578.688426231412;15631.374745010073;15652.68694626101;15702.822288328422;6101;6203.2245969253845;6284.905726414107;6308.810735677937;6350.998870711423;1094.950727217277;1099.5381939585393;1104.3705088099866;1107.9279846809932;1117.3016864459712;24833.87583062073;24880.751192488206;24938.625920611204;25072.24783256505;25167.24498265055;1163.650904728578;1171.3616079236836;1175.2478413814995;1178.9469473873696;1182.9526818927318;12981.610551683465;13201.669958251121;13338.799918001238;13359.398827052806;13373.331333433289;285621;287093;290856;290958;291338;55753;56198.43801562014;56433.71783141058;56550.151747723816;56944.430555694744;1476.6308422894276;1496.4687535924809;1504.3832028868112;1505.1044628446057;1528.8457214802975;24803.95439846865;25054.498910022063;25155.87422062878;25232.74767252304;25259;40601.390979135926;40634.187316252915;40703.79648101859;40764.38853417142;40894.79552602218;233812;234251;234806;235066;235165;32438;33436.66563334336;35158.64841351554;35840.566377344614;36115;46424.767876160404;46571.30143047789;46919.530804692884;47000.05999880052;47031;34186.48720269148;34456.48315275323;34520.48219276763;34563.48154777731;35018.649813501535;33656;34091;34385.312293753486;34554.65445345514;34627.4805877907;38846.6115338843;39451.40822887602;39507.80246098866;39617;39764.20471590494;33113.33773324568;33149.66850331466;33151;33448.66551334455;33509.497357540145;25631.871840640677;25765.484690305715;25887.870560647076;25968.10480418877;26315.21054368372 16:39:56 INFO - PID 4647 | |2;jquery.html;487.52222821634797;576.7772322675925;587.791334076403;595.4700316718119;599.5593238969354;22398;23310;23369.18207862725;23400.765992340093;23553.175638852645;21499;21966.89016554919;22121.33635990919;22306.553868922612;23628.76371236287;6632.63520506372;6733.091032710595;6773.491988100887;6776.390124888759;6776.932230677704;6693.263740988487;6714.429273511757;6776.949572816221;6779.966100169492;6784.559003664771;6375.521835862306;6746.62893540855;6747.898781518281;6759.222689390709;6771.1874575050815;6724.83187920301;6770.72917083321;6771.8984215236815;6774.390304872585;6774.627395493246;5865.85335366615;6071.726772295213;6085.148079268862;6088.543359248074;6090.33006369299;31014.224644383845;31627.683723162932;31645.52531712026;31675.84162079198;31679;2257.7516473187934;2264.275431861805;2275.9544809103727;2284.942876428086;2291.026313816621;20286.08712607936;20485.4878628034;20873.68689469659;20885.895570522203;20903.68644470334;2445.0708730682404;2448.2288079255154;2508.0720133550703;2509.774120329179;2517.8237523373373;130479;136156;149291.25354373158;155611;157061;15270.618234544003;15741;15832.841671583366;16213.189340533163;16689.24898379576;25288.241352759447;25394;25407.237782866545;25483.853226604846;25527.234182974542;9762.219022478184;9812.509374531388;9849.113579777855;9949.950250248847;9996;8655.870161947576;8728.25809806161;8735.082816304324;8799.868001979976;8801.691940782042;9334.126337569833;9871.506424678737;9900.356476829127;9994.850077248848;10063.547140378554;2798.3423895384567;2810.8875644973973;2893.8119022263486;2903.083964583365;2973.747231485348;1033.3128469567653;1052.1109662335314;1054.093479607537;1063.324788759128;1065.616378103882;21343.25298614541;21438.892805536183;21446.57106857854;21670;22344.77655223427;1083.4636854756945;1112.0991996482874;1118.765059337532;1120.3389999899935;1134.7787181499527;11749.823752643717;11950.820737688942;11956.163068585289;12038.518459261531;12216.144869859025;624540;631422.850894494;649227;650790;655812;125845.37077314555;126503;129379.35310323388;129783.35108324398;130096;1095.956161753521;1151.7984352738238;1161.703765539782;1201.279232460534;1206.3726862031695;21965.011574478867;22718.204862829723;22790;23009;23047.769522304563;57497.42502574921;57973.71013144907;58428.4157158423;58740.70629646824;59335.70332148312;70050;72459.27540724525;73610;73915.63042184754;74797;15569.532914012598;15827.841721582638;15995.200239988188;16049.277782500048;16057;24866.378340541632;25112.497750045262;25314.114006010437;25486.10798622039;25753.871230643726;19784.703229451283;20011.899940500203;20320.28878989228;20546.897265513577;20549.794502055385;17552;17870.285188592312;18154.90922545379;18217.635647286716;18472.630547388708;20788.68816967777;20842.687359689313;21024.369268921957;21164.682529761758;21339.46651333729;15519.146446945526;15536.689266214385;15583.454579089677;15643.139627320606;15792.289346979183;20208;20274.89862550678;20322.59354812925;20392.796072039684;20481.69277460869 16:39:56 INFO - PID 4647 | |3;mootools.html;1415.235772682751;1492.7686208637665;1496.3864815425677;1498.4605542004879;1506.9623259418513;16394.34422623095;17079;17088.145592720375;17100.14499275036;17102.230399632015;16793.748093778573;16838.242279097438;16890.662186756257;16923.830761692378;16925.22285160059;2733.8906443742226;2747.4505098980235;2780.0408858943733;2780.7358300961478;2784.192584150604;2761.8066735328534;2789.0517224143787;2799.1322689966178;2800.075974928269;2801.8739156737893;2650.9734902651016;2838.7019362966903;2857.614222080024;2858.414025124855;2859.1994241612383;2746.8626568671684;2753.8347699138017;2765.1151631477937;2765.3224959884756;2776.569631707089;2654.8938042478276;2696.042904768818;2700.7974401919737;2701.7838572914316;2718.4834881372476;16446.095464749433;16519.174041297887;16545.50363489097;16555.50333489997;16605.750913736305;8199.918000819975;8499.66001359951;8504.362172837;8525.658973641046;8538.103499132563;5631.493165615114;5694.117411801178;5738.655680659173;5752.338481074647;5925.703714814285;2062.2988184017354;2092.801183887528;2114.291712276374;2147.4094623978376;2175.8368122390884;29448.55827162594;29651.40697186044;29721.851390743122;29861.25346866324;29968.700312997025;7123.43012558994;7296.7446139384865;7300.671469783766;7339.669714862844;7466.887996680054;18923;18974;19007.144678489225;19017.71473427872;19018.42944711661;8115.3913456491;8320.625571849281;8322.250997410261;8327.20891515305;8330.583470826434;7471.290227428385;7511.248875112445;7567.545947243183;7646.3118319350415;7672.194419585975;5506.229127922094;5684.204211410406;5692.48767610917;5720.170575266563;5756.30924289088;1509.9698006039819;1534.4782773856718;1578.629022179787;1600.7118718630525;1651.38898607516;992.533509250651;1008.4856723071287;1013.4983183324155;1013.9746506337401;1031.3244824639723;9322.347435679438;9398.624055037722;9493.335466517416;9566.043395660377;9579.233661307078;1025.9897401026044;1027.6557353286582;1030.6032177611523;1033.426448321189;1060.5068643081004;6065.181200537871;6147.877042459215;6190.288116866528;6224.502039836802;6229.408206220402;24037.51924961526;24606.384840379134;24891.377715557257;24917.875410623194;24990.875045624656;23957.401064973168;24532;24536.509269814862;24812;24887.751122488906;12092.818607720892;12308.753824923451;12358.50565977351;12378.44297006637;12392.380380981096;9489.288303377136;9570.425774453555;9694.85457718149;9736.269779766404;9776.9022309776;20520.692189616868;20804.47988800292;20953.89523052375;21144.471388214803;21305.89347053255;21609.35171944844;21959.34121976343;22064.77935220627;22119.115235391047;22344.21795237159;11984.340861252715;11999.460024299102;12035.398230088636;12121.636350909486;12154.149209555246;14239.216843073316;14422.206778627276;14454.566363009126;14454.783178252545;14459.855401446272;12544.686382840502;12640.936795316333;12681.365931703194;12756.362181891054;12871.970242380774;12025.4588543514;12193.085518586318;12439.191452555555;12462;12584.433700483318;11200.271982321123;11259.042981346678;11290.887091128983;11318.717032074264;11334.433278336215;8497;8525.275351595184;8655.52394618302;8710.085441028601;8736.78158046054;8445.239928406472;8558.957205213934;8592.914070859211;8598.871016934876;8628 16:39:56 INFO - PID 4647 | |4;prototype.html;525.424659997303;566.3402136510965;568.4060157135792;578.4591407034424;582.1616871704745;6263.749450021999;6326.968365158174;6376.29860715321;6427.582207156532;6430.131932189153;6258.311585725566;6288.465480434157;6293.465055470279;6342.460890824285;6367.267764207118;5191.506806853362;5198.142306519419;5200.947990520103;5203.9219411708855;5206.453322401131;5187.870303242411;5195.298634684307;5237.423883372845;5249.1601343785005;5253.133233016548;4809.1343558159515;4962.255661650764;4984.352034235562;4991.40103187618;5008.874778130557;4981.501849815026;4994.200927851526;5001.874953126183;5024.17101178307;5030.974845125787;4441.422615060022;4512.503624601291;4529.456465224162;4536.138133754576;4545.568171023748;6181.258249010102;6183.691724927187;6206.6586337751405;6230.96884515579;6239.594426362272;5812.505936995361;5882.705864706791;5900.262467191601;5909.8818023639715;5910.261217347832;6742.2920593337485;6742.730290788411;6752.966235168842;6754.838451695459;6759.864802703967;5007.69953802773;5023.522765337324;5045.621578381636;5065.924011139836;5093.872653183663;6788.287229840845;6859.314068593101;6867.656617169122;6979.243198936442;7067.505274630778;5152.149895267239;5168.2764412982215;5172.224166375074;5182;5190.792368305301;5263;5275.525202731773;5281.788728450896;5292.867678308073;5300.893982120336;5780.364159942403;5806.796762113306;5825.825225243249;5840.678762668009;5859.707014649336;6687.531872768956;6724.755920154689;6739.157605299338;6814.659267036727;6820.931790682129;1799.604087100862;1821.9544511387057;1823.717323814811;1840.0983518076046;1840.696285112968;1248.2260998181005;1252.2361359570655;1281.8077288406814;1295.7214198947272;1303.9217646941213;6285.560010799291;6295.276043255084;6325.3674632537295;6331.461825744771;6332.968335158294;6722.428593569503;6734.360235777691;6756.662166891636;6798.218204906499;6892.276310987274;6076.848078797977;6105.694715264219;6180.969095154495;6256.937430625727;6270.780522681775;3536.310419468179;3557.3418917500344;3589.5333606631493;3608.314420260142;3608.6391360864227;7068.338940349014;7082.929170708227;7083.150021997393;7119.6084215368655;7161.462890283249;6874.518783685194;6956.79129626112;6999.8600027999155;7013.298670133048;7019.543729657556;7127.893081603676;7160;7160.713571457083;7171.641417929082;7201.135863696285;6786.558873673195;6821.441959265276;6827.487938404739;6830.248672645906;6874.278200788845;6730.87977287153;6732.182310775965;6752.122224110834;6771.729130834711;6824.283450237654;6936.167659880644;7061.399781018671;7115.786526404217;7170.354668079898;7177.34438257848;5061.519155680205;5142.5885929126325;5183.144781111075;5226.163813789775;5237.685738855681;6581.80254592363;6607.174103237096;6849.212340580897;6861.691223895036;6940.9652951734915;6599.868002640016;6602.801915942529;6608.504362172953;6636.9004464932095;6637.834054148491;6559.147310849559;6559.376859198369;6563.835904102436;6618.63597502142;6626.105475760711;6398.360163983564;6524.641144737085;6534.215894092739;6552.148220731275;6588.8352791180605;6619.205695316593;6659.533832631863;6662.200535935719;6683.532152749261;6701.798946031627;6342.302346741762;6378.266499352449;6431.549791514549;6493.740250389931;6508 16:39:56 INFO - PID 4647 | |5;yui.html;973.250597040279;981.1905178227961;1001.50425539358;1001.8146642871064;1003.428046013772;16031.919840400797;16090.356385744572;16112.83887161128;16286.511404657864;16448.506544803644;15616.453424130157;15634.687306253869;15675.60810979726;15690.84309156908;15715.214239288047;1039.6932904793084;1061.4639606998455;1061.8194906865813;1062.9734256643608;1064.7125276175466;859.4800145911727;859.6733241368271;860.5525126933991;861.8965724113115;863.115306810518;1070.6413351527249;1110.7611863449397;1134.302404021525;1134.517829922284;1135.42660956217;1099.312929419113;1102.85662863828;1103.4041617526525;1104.8508451359044;1106.2035334559105;803.4335793265792;809.70041084799;811.5455345006815;813.1543195077113;820.327331588098;17626.206820692973;17629.942203467705;17633.294668213257;17666.558336041573;17722.91138544312;21207.469813254636;21542.461438464008;21708.240211592678;22272.88863555688;22301.442463938532;18169.63660726778;18386.90806545972;18741.81258187428;18848.62302753951;18961.620767584707;3515.6308587598446;3549.8402571884067;3571.7678350907368;3580.3913334733156;3580.785152890835;119245;153101;153929;155863.22068389587;156554.99999999886;18303;18321;18471.16879740415;18527.444176674722;18922.148503317385;32677.83661081727;33381.66618333834;33603.83198083994;33620.83189584036;33628;8255;8291.461055031403;8301.003879534373;8302.8339433211;8311.33509319253;3946.2699400610404;3993.84024639017;3995.360742281221;3997.260506806192;4008.6392224699334;5793.217915581343;5860.794872179454;5927.911081333784;5937.732802023919;5954.702264886739;1130.2370899642708;1155.4511606986782;1156.5547264303136;1158.7682463507333;1161.1523587780966;713.3437237741291;739.7115125101215;757.814335487809;769.692123150733;780.0678189563417;13391.330433478288;13631.522896698569;13868.306584670727;14343.56969290923;14370.856291436952;724.9782506524813;725.6625669063806;735.0921611809412;744.3858816476428;753.3596443023387;6322.209723784527;6468.676566171673;6471;6499.415052645285;6573.737050517926;148001.2599937015;148350.25824870807;149486;150614;152266.23866880595;125701;126670;126938;127660.36169819091;130849;107398;110071;110791;111813;113989.4300528492;11908.166428349932;12129.45417456199;12206.328651923874;12248.142630015813;12435.06736994711;53346.73326633342;53654.19518707145;55320;55844.72077639586;56158.43841561532;67467;68725.65637171782;68862.65568672125;69155;70128.6493567529;17408;17834.19746111454;17934.730979035063;18051.548711281797;18095.185716643045;17621.383251586132;18195.72629915893;18544.07279635986;18701.625967480846;19027.048647567844;18397.448076557725;18785.248590056246;18942.147603358157;18952.147153378406;19000.714989274897;18115.09424528742;18178.363757268962;18313.908430457763;18314.45056648303;18380.356687515872;16623.3350665972;16667.749983750495;16680.49958501247;16743.162841858102;17033.063181524638;12762.68093297675;13085.542006029744;13410.731785364433;13434.798478022642;13493.122947008413;12575.43410546546;12626.116171867881;12782.552610658955;12861.935690321863;13162.60512184636 16:39:56 INFO - PID 4647 | __end_tp_report 16:39:56 INFO - PID 4647 | __start_cc_report 16:39:56 INFO - PID 4647 | _x_x_mozilla_cycle_collect,360 16:39:56 INFO - PID 4647 | __end_cc_report 16:39:56 INFO - PID 4647 | __startTimestamp1505432396076__endTimestamp 16:39:56 INFO - PID 4647 | 16:39:56 INFO - PID 4647 | ------- Summary: start ------- 16:39:56 INFO - PID 4647 | Number of tests: 6 16:39:56 INFO - PID 4647 | 16:39:56 INFO - PID 4647 | [#0] dojo.html Cycles:165 Average:6304.92 Median:6481.94 stddev:749.21 (11.6%) stddev-sans-first:725.20 16:39:56 INFO - PID 4647 | Values: 3796.2 3873.9 3882.5 3887.7 3895.4 6168.1 6247.8 6267.4 6286.8 6308.9 6352.4 6375.2 6401.9 6405.1 6413.2 5662.3 5667.4 5688.9 5697.2 5728.3 5609.6 5693.3 5695.0 5710.1 5783.2 5688.3 5705.1 5761.7 5798.2 5809.7 5260.4 5516.1 5646.8 5706.2 5724.7 5666.3 5697.9 5735.1 5747.4 5754.0 6239.5 6240.4 6295.6 6413.0 6415.4 6794.9 6827.0 6827.9 6828.8 6831.2 6884.0 6897.7 6943.1 6972.0 6981.7 6395.4 6467.8 6476.6 6486.6 6487.9 7062.7 7066.5 7070.5 7161.4 7164.4 6607.1 6610.1 6684.9 6708.3 6902.4 6871.3 6896.5 6902.6 6933.1 6933.6 6866.8 6903.1 6927.7 6938.5 6942.5 6850.7 6857.2 6942.3 6947.6 6950.7 4915.9 4995.6 5003.3 5078.2 5090.3 4556.3 4620.0 4620.9 4635.1 4647.4 6160.5 6256.2 6330.1 6331.1 6336.5 6671.5 6732.6 6815.9 6817.1 6818.6 6244.7 6298.6 6326.2 6379.1 6379.4 5540.0 5605.2 5616.2 5617.0 5621.3 7110.6 7113.3 7174.2 7215.5 7223.2 6666.6 6756.1 6816.7 6893.9 6894.6 7101.9 7180.8 7193.4 7204.3 7205.5 6721.5 6756.0 6848.8 6851.0 6857.1 6838.7 6861.9 6930.8 6956.1 6957.4 6513.6 6522.9 6532.2 6535.6 6539.6 6465.1 6474.3 6481.9 6527.1 6542.9 6408.7 6446.7 6512.1 6524.4 6536.1 6554.8 6599.6 6608.4 6632.4 6644.9 6423.7 6431.2 6444.7 6446.4 6446.6 16:39:56 INFO - PID 4647 | 16:39:56 INFO - PID 4647 | [#1] ext.html Cycles:180 Average:42247.76 Median:19871.67 stddev:65086.41 (327.5%) stddev-sans-first:65195.11 16:39:56 INFO - PID 4647 | Values: 944.8 968.7 972.4 973.2 975.2 40571.8 44899.0 45005.8 45016.8 45165.5 41567.6 41872.0 41978.6 42088.8 42215.8 7368.3 7503.4 7540.0 7557.1 7560.3 7398.4 7452.8 7493.0 7494.1 7496.6 7398.9 7465.1 7526.2 7942.1 8142.1 8026.5 8080.1 8090.7 8091.9 8092.2 7868.6 7936.1 8028.5 8065.8 8085.6 40586.6 40615.8 40792.4 40858.0 40860.0 3594.6 3628.3 3632.8 3634.2 3642.2 33084.7 33204.7 33225.0 33248.8 33267.8 5366.3 5425.8 5487.4 5498.6 5529.3 219451.0 219898.0 220999.0 221220.0 222374.0 44706.9 45379.0 51326.0 52092.2 52338.2 56775.0 56857.7 57011.4 57037.4 57119.4 14718.3 14804.8 14839.6 14859.4 14862.7 11660.4 11677.6 11690.7 11694.1 11704.5 15452.8 15578.7 15631.4 15652.7 15702.8 6101.0 6203.2 6284.9 6308.8 6351.0 1095.0 1099.5 1104.4 1107.9 1117.3 24833.9 24880.8 24938.6 25072.2 25167.2 1163.7 1171.4 1175.2 1178.9 1183.0 12981.6 13201.7 13338.8 13359.4 13373.3 285621.0 287093.0 290856.0 290958.0 291338.0 55753.0 56198.4 56433.7 56550.2 56944.4 1476.6 1496.5 1504.4 1505.1 1528.8 24804.0 25054.5 25155.9 25232.7 25259.0 40601.4 40634.2 40703.8 40764.4 40894.8 233812.0 234251.0 234806.0 235066.0 235165.0 32438.0 33436.7 35158.6 35840.6 36115.0 46424.8 46571.3 46919.5 47000.1 47031.0 34186.5 34456.5 34520.5 34563.5 35018.6 33656.0 34091.0 34385.3 34554.7 34627.5 38846.6 39451.4 39507.8 39617.0 39764.2 33113.3 33149.7 33151.0 33448.7 33509.5 25631.9 25765.5 25887.9 25968.1 26315.2 16:39:56 INFO - PID 4647 | 16:39:56 INFO - PID 4647 | [#2] jquery.html Cycles:180 Average:40351.09 Median:23178.88 stddev:106812.44 (460.8%) stddev-sans-first:107070.14 16:39:56 INFO - PID 4647 | Values: 487.5 576.8 587.8 595.5 599.6 22398.0 23310.0 23369.2 23400.8 23553.2 21499.0 21966.9 22121.3 22306.6 23628.8 6632.6 6733.1 6773.5 6776.4 6776.9 6693.3 6714.4 6776.9 6780.0 6784.6 6375.5 6746.6 6747.9 6759.2 6771.2 6724.8 6770.7 6771.9 6774.4 6774.6 5865.9 6071.7 6085.1 6088.5 6090.3 31014.2 31627.7 31645.5 31675.8 31679.0 2257.8 2264.3 2276.0 2284.9 2291.0 20286.1 20485.5 20873.7 20885.9 20903.7 2445.1 2448.2 2508.1 2509.8 2517.8 130479.0 136156.0 149291.3 155611.0 157061.0 15270.6 15741.0 15832.8 16213.2 16689.2 25288.2 25394.0 25407.2 25483.9 25527.2 9762.2 9812.5 9849.1 9950.0 9996.0 8655.9 8728.3 8735.1 8799.9 8801.7 9334.1 9871.5 9900.4 9994.9 10063.5 2798.3 2810.9 2893.8 2903.1 2973.7 1033.3 1052.1 1054.1 1063.3 1065.6 21343.3 21438.9 21446.6 21670.0 22344.8 1083.5 1112.1 1118.8 1120.3 1134.8 11749.8 11950.8 11956.2 12038.5 12216.1 624540.0 631422.9 649227.0 650790.0 655812.0 125845.4 126503.0 129379.4 129783.4 130096.0 1096.0 1151.8 1161.7 1201.3 1206.4 21965.0 22718.2 22790.0 23009.0 23047.8 57497.4 57973.7 58428.4 58740.7 59335.7 70050.0 72459.3 73610.0 73915.6 74797.0 15569.5 15827.8 15995.2 16049.3 16057.0 24866.4 25112.5 25314.1 25486.1 25753.9 19784.7 20011.9 20320.3 20546.9 20549.8 17552.0 17870.3 18154.9 18217.6 18472.6 20788.7 20842.7 21024.4 21164.7 21339.5 15519.1 15536.7 15583.5 15643.1 15792.3 20208.0 20274.9 20322.6 20392.8 20481.7 16:39:56 INFO - PID 4647 | 16:39:56 INFO - PID 4647 | [#3] mootools.html Cycles:180 Average:10359.49 Median:24889.56 stddev:7552.79 (30.3%) stddev-sans-first:7544.09 16:39:56 INFO - PID 4647 | Values: 1415.2 1492.8 1496.4 1498.5 1507.0 16394.3 17079.0 17088.1 17100.1 17102.2 16793.7 16838.2 16890.7 16923.8 16925.2 2733.9 2747.5 2780.0 2780.7 2784.2 2761.8 2789.1 2799.1 2800.1 2801.9 2651.0 2838.7 2857.6 2858.4 2859.2 2746.9 2753.8 2765.1 2765.3 2776.6 2654.9 2696.0 2700.8 2701.8 2718.5 16446.1 16519.2 16545.5 16555.5 16605.8 8199.9 8499.7 8504.4 8525.7 8538.1 5631.5 5694.1 5738.7 5752.3 5925.7 2062.3 2092.8 2114.3 2147.4 2175.8 29448.6 29651.4 29721.9 29861.3 29968.7 7123.4 7296.7 7300.7 7339.7 7466.9 18923.0 18974.0 19007.1 19017.7 19018.4 8115.4 8320.6 8322.3 8327.2 8330.6 7471.3 7511.2 7567.5 7646.3 7672.2 5506.2 5684.2 5692.5 5720.2 5756.3 1510.0 1534.5 1578.6 1600.7 1651.4 992.5 1008.5 1013.5 1014.0 1031.3 9322.3 9398.6 9493.3 9566.0 9579.2 1026.0 1027.7 1030.6 1033.4 1060.5 6065.2 6147.9 6190.3 6224.5 6229.4 24037.5 24606.4 24891.4 24917.9 24990.9 23957.4 24532.0 24536.5 24812.0 24887.8 12092.8 12308.8 12358.5 12378.4 12392.4 9489.3 9570.4 9694.9 9736.3 9776.9 20520.7 20804.5 20953.9 21144.5 21305.9 21609.4 21959.3 22064.8 22119.1 22344.2 11984.3 11999.5 12035.4 12121.6 12154.1 14239.2 14422.2 14454.6 14454.8 14459.9 12544.7 12640.9 12681.4 12756.4 12872.0 12025.5 12193.1 12439.2 12462.0 12584.4 11200.3 11259.0 11290.9 11318.7 11334.4 8497.0 8525.3 8655.5 8710.1 8736.8 8445.2 8559.0 8592.9 8598.9 8628.0 16:39:56 INFO - PID 4647 | 16:39:56 INFO - PID 4647 | [#4] prototype.html Cycles:180 Average:5683.51 Median:6310.32 stddev:1595.93 (25.3%) stddev-sans-first:1552.74 16:39:56 INFO - PID 4647 | Values: 525.4 566.3 568.4 578.5 582.2 6263.7 6327.0 6376.3 6427.6 6430.1 6258.3 6288.5 6293.5 6342.5 6367.3 5191.5 5198.1 5200.9 5203.9 5206.5 5187.9 5195.3 5237.4 5249.2 5253.1 4809.1 4962.3 4984.4 4991.4 5008.9 4981.5 4994.2 5001.9 5024.2 5031.0 4441.4 4512.5 4529.5 4536.1 4545.6 6181.3 6183.7 6206.7 6231.0 6239.6 5812.5 5882.7 5900.3 5909.9 5910.3 6742.3 6742.7 6753.0 6754.8 6759.9 5007.7 5023.5 5045.6 5065.9 5093.9 6788.3 6859.3 6867.7 6979.2 7067.5 5152.1 5168.3 5172.2 5182.0 5190.8 5263.0 5275.5 5281.8 5292.9 5300.9 5780.4 5806.8 5825.8 5840.7 5859.7 6687.5 6724.8 6739.2 6814.7 6820.9 1799.6 1822.0 1823.7 1840.1 1840.7 1248.2 1252.2 1281.8 1295.7 1303.9 6285.6 6295.3 6325.4 6331.5 6333.0 6722.4 6734.4 6756.7 6798.2 6892.3 6076.8 6105.7 6181.0 6256.9 6270.8 3536.3 3557.3 3589.5 3608.3 3608.6 7068.3 7082.9 7083.2 7119.6 7161.5 6874.5 6956.8 6999.9 7013.3 7019.5 7127.9 7160.0 7160.7 7171.6 7201.1 6786.6 6821.4 6827.5 6830.2 6874.3 6730.9 6732.2 6752.1 6771.7 6824.3 6936.2 7061.4 7115.8 7170.4 7177.3 5061.5 5142.6 5183.1 5226.2 5237.7 6581.8 6607.2 6849.2 6861.7 6941.0 6599.9 6602.8 6608.5 6636.9 6637.8 6559.1 6559.4 6563.8 6618.6 6626.1 6398.4 6524.6 6534.2 6552.1 6588.8 6619.2 6659.5 6662.2 6683.5 6701.8 6342.3 6378.3 6431.5 6493.7 6508.0 16:39:56 INFO - PID 4647 | 16:39:56 INFO - PID 4647 | [#5] yui.html Cycles:180 Average:27235.21 Median:18174.00 stddev:40821.91 (224.6%) stddev-sans-first:40888.80 16:39:56 INFO - PID 4647 | Values: 973.3 981.2 1001.5 1001.8 1003.4 16031.9 16090.4 16112.8 16286.5 16448.5 15616.5 15634.7 15675.6 15690.8 15715.2 1039.7 1061.5 1061.8 1063.0 1064.7 859.5 859.7 860.6 861.9 863.1 1070.6 1110.8 1134.3 1134.5 1135.4 1099.3 1102.9 1103.4 1104.9 1106.2 803.4 809.7 811.5 813.2 820.3 17626.2 17629.9 17633.3 17666.6 17722.9 21207.5 21542.5 21708.2 22272.9 22301.4 18169.6 18386.9 18741.8 18848.6 18961.6 3515.6 3549.8 3571.8 3580.4 3580.8 119245.0 153101.0 153929.0 155863.2 156555.0 18303.0 18321.0 18471.2 18527.4 18922.1 32677.8 33381.7 33603.8 33620.8 33628.0 8255.0 8291.5 8301.0 8302.8 8311.3 3946.3 3993.8 3995.4 3997.3 4008.6 5793.2 5860.8 5927.9 5937.7 5954.7 1130.2 1155.5 1156.6 1158.8 1161.2 713.3 739.7 757.8 769.7 780.1 13391.3 13631.5 13868.3 14343.6 14370.9 725.0 725.7 735.1 744.4 753.4 6322.2 6468.7 6471.0 6499.4 6573.7 148001.3 148350.3 149486.0 150614.0 152266.2 125701.0 126670.0 126938.0 127660.4 130849.0 107398.0 110071.0 110791.0 111813.0 113989.4 11908.2 12129.5 12206.3 12248.1 12435.1 53346.7 53654.2 55320.0 55844.7 56158.4 67467.0 68725.7 68862.7 69155.0 70128.6 17408.0 17834.2 17934.7 18051.5 18095.2 17621.4 18195.7 18544.1 18701.6 19027.0 18397.4 18785.2 18942.1 18952.1 19000.7 18115.1 18178.4 18313.9 18314.5 18380.4 16623.3 16667.7 16680.5 16743.2 17033.1 12762.7 13085.5 13410.7 13434.8 13493.1 12575.4 12626.1 12782.6 12861.9 13162.6 16:39:56 INFO - PID 4647 | -------- Summary: end -------- 16:39:56 INFO - PID 4647 | 16:39:56 INFO - TEST-INFO | 4647: exit 0 16:39:56 INFO - TEST-OK | dromaeo_css | took 1109449ms 16:39:56 INFO - TEST-START | kraken 16:39:56 INFO - Initialising browser for kraken test... 16:39:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50326/getInfo.html -profile /tmp/tmpqo3q0C/profile 16:39:56 INFO - TEST-INFO | started process 5014 (/builds/slave/test/build/application/firefox/firefox http://localhost:50326/getInfo.html) 16:40:02 INFO - TEST-INFO | 5014: exit 0 16:40:02 INFO - Browser initialized. 16:40:02 INFO - Running cycle 1/1 for kraken test... 16:40:02 INFO - Using env: {'DISPLAY': ':0', 16:40:02 INFO - 'HOME': '/home/cltbld', 16:40:02 INFO - 'JSGC_DISABLE_POISONING': '1', 16:40:02 INFO - 'LANG': 'en_US.UTF-8', 16:40:02 INFO - 'LANGUAGE': 'en_US:en', 16:40:02 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:40:02 INFO - 'LOGNAME': 'cltbld', 16:40:02 INFO - 'MAIL': '/var/mail/cltbld', 16:40:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:40:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:40:02 INFO - 'MOZ_CRASHREPORTER': '1', 16:40:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:02 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:40:02 INFO - 'MOZ_NO_REMOTE': '1', 16:40:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:40:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:40:02 INFO - 'NO_EM_RESTART': '1', 16:40:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:40:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:02 INFO - 'PWD': '/builds/slave/test', 16:40:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:40:02 INFO - 'RUST_BACKTRACE': 'full', 16:40:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:40:02 INFO - 'SHELL': '/bin/bash', 16:40:02 INFO - 'SHLVL': '1', 16:40:02 INFO - 'TERM': 'linux', 16:40:02 INFO - 'TMOUT': '86400', 16:40:02 INFO - 'USER': 'cltbld', 16:40:02 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059', 16:40:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:02 INFO - '_': '/tools/buildbot/bin/python'} 16:40:02 INFO - TEST-INFO | started process 5252 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqo3q0C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:40:03 INFO - PID 5252 | 16:40:03 INFO - PID 5252 | (/builds/slave/test/build/application/firefox/firefox:5300): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:40:03 INFO - PID 5252 | 16:40:14 INFO - PID 5252 | RSS: Main: 165113856 16:40:14 INFO - PID 5252 | 16:40:16 INFO - PID 5252 | Cycle 1(1): loaded http://localhost:50326/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50326/tests/kraken/driver.html?testName=audio-beat-detection) 16:40:17 INFO - PID 5252 | RSS: Main: 170172416 16:40:17 INFO - PID 5252 | 16:40:20 INFO - PID 5252 | Cycle 1(1): loaded http://localhost:50326/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50326/tests/kraken/driver.html?testName=audio-dft) 16:40:20 INFO - PID 5252 | RSS: Main: 170508288 16:40:20 INFO - PID 5252 | 16:40:23 INFO - PID 5252 | Cycle 1(1): loaded http://localhost:50326/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50326/tests/kraken/driver.html?testName=audio-fft) 16:40:24 INFO - PID 5252 | RSS: Main: 165584896 16:40:24 INFO - PID 5252 | 16:40:26 INFO - PID 5252 | Cycle 1(1): loaded http://localhost:50326/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50326/tests/kraken/driver.html?testName=audio-oscillator) 16:40:27 INFO - PID 5252 | RSS: Main: 167108608 16:40:27 INFO - PID 5252 | 16:40:29 INFO - PID 5252 | Cycle 1(1): loaded http://localhost:50326/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50326/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:40:29 INFO - PID 5252 | RSS: Main: 167071744 16:40:29 INFO - PID 5252 | 16:40:32 INFO - PID 5252 | Cycle 1(1): loaded http://localhost:50326/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50326/tests/kraken/driver.html?testName=imaging-darkroom) 16:40:33 INFO - PID 5252 | RSS: Main: 170364928 16:40:33 INFO - PID 5252 | 16:40:37 INFO - PID 5252 | Cycle 1(1): loaded http://localhost:50326/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50326/tests/kraken/driver.html?testName=imaging-desaturate) 16:40:37 INFO - PID 5252 | RSS: Main: 169365504 16:40:37 INFO - PID 5252 | 16:40:40 INFO - PID 5252 | Cycle 1(1): loaded http://localhost:50326/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50326/tests/kraken/driver.html?testName=json-parse-financial) 16:40:41 INFO - PID 5252 | RSS: Main: 169562112 16:40:41 INFO - PID 5252 | 16:40:42 INFO - PID 5252 | Cycle 1(1): loaded http://localhost:50326/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50326/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:40:43 INFO - PID 5252 | RSS: Main: 169578496 16:40:43 INFO - PID 5252 | 16:40:44 INFO - PID 5252 | Cycle 1(1): loaded http://localhost:50326/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50326/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:40:45 INFO - PID 5252 | RSS: Main: 169201664 16:40:45 INFO - PID 5252 | 16:40:47 INFO - PID 5252 | Cycle 1(1): loaded http://localhost:50326/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50326/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:40:48 INFO - PID 5252 | RSS: Main: 166420480 16:40:48 INFO - PID 5252 | 16:40:50 INFO - PID 5252 | Cycle 1(1): loaded http://localhost:50326/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50326/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:40:51 INFO - PID 5252 | RSS: Main: 166584320 16:40:51 INFO - PID 5252 | 16:40:54 INFO - PID 5252 | Cycle 1(1): loaded http://localhost:50326/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50326/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:40:54 INFO - PID 5252 | RSS: Main: 167186432 16:40:54 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | Cycle 1(1): loaded http://localhost:50326/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50326/tests/kraken/driver.html?testName=ai-astar) 16:40:56 INFO - PID 5252 | RSS: Main: 169226240 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | __start_tp_report 16:40:56 INFO - PID 5252 | _x_x_mozilla_page_load 16:40:56 INFO - PID 5252 | _x_x_mozilla_page_load_details 16:40:56 INFO - PID 5252 | |i|pagename|runs| 16:40:56 INFO - PID 5252 | |0;ai-astar;105;106;105;106;108;107;107;107;105;105 16:40:56 INFO - PID 5252 | |1;audio-beat-detection;116;112;111;118;114;138;115;116;122;112 16:40:56 INFO - PID 5252 | |2;audio-dft;162;162;163;162;165;165;163;162;165;164 16:40:56 INFO - PID 5252 | |3;audio-fft;73;76;73;72;74;74;73;74;74;75 16:40:56 INFO - PID 5252 | |4;audio-oscillator;79;79;78;78;78;79;79;78;78;82 16:40:56 INFO - PID 5252 | |5;imaging-gaussian-blur;110;113;111;116;117;114;114;114;115;113 16:40:56 INFO - PID 5252 | |6;imaging-darkroom;150;149;150;149;149;149;149;151;151;157 16:40:56 INFO - PID 5252 | |7;imaging-desaturate;94;94;94;94;93;94;99;94;94;95 16:40:56 INFO - PID 5252 | |8;json-parse-financial;44;46;43;44;44;48;44;44;46;44 16:40:56 INFO - PID 5252 | |9;json-stringify-tinderbox;39;39;39;39;39;39;43;39;40;40 16:40:56 INFO - PID 5252 | |10;stanford-crypto-aes;72;72;73;72;74;73;73;73;72;72 16:40:56 INFO - PID 5252 | |11;stanford-crypto-ccm;114;104;106;106;113;110;114;108;113;110 16:40:56 INFO - PID 5252 | |12;stanford-crypto-pbkdf2;169;166;154;158;158;157;162;165;167;153 16:40:56 INFO - PID 5252 | |13;stanford-crypto-sha256-iterative;60;62;61;60;59;63;60;62;62;60 16:40:56 INFO - PID 5252 | __end_tp_report 16:40:56 INFO - PID 5252 | __start_cc_report 16:40:56 INFO - PID 5252 | _x_x_mozilla_cycle_collect,899 16:40:56 INFO - PID 5252 | __end_cc_report 16:40:56 INFO - PID 5252 | __startTimestamp1505432456808__endTimestamp 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | ------- Summary: start ------- 16:40:56 INFO - PID 5252 | Number of tests: 14 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | [#0] ai-astar Cycles:10 Average:106.10 Median:106.50 stddev:1.10 (1.0%) stddev-sans-first:1.09 16:40:56 INFO - PID 5252 | Values: 105.0 106.0 105.0 106.0 108.0 107.0 107.0 107.0 105.0 105.0 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | [#1] audio-beat-detection Cycles:10 Average:117.40 Median:116.00 stddev:7.93 (6.8%) stddev-sans-first:8.40 16:40:56 INFO - PID 5252 | Values: 116.0 112.0 111.0 118.0 114.0 138.0 115.0 116.0 122.0 112.0 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | [#2] audio-dft Cycles:10 Average:163.30 Median:163.50 stddev:1.34 (0.8%) stddev-sans-first:1.33 16:40:56 INFO - PID 5252 | Values: 162.0 162.0 163.0 162.0 165.0 165.0 163.0 162.0 165.0 164.0 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | [#3] audio-fft Cycles:10 Average:73.80 Median:74.00 stddev:1.14 (1.5%) stddev-sans-first:1.17 16:40:56 INFO - PID 5252 | Values: 73.0 76.0 73.0 72.0 74.0 74.0 73.0 74.0 74.0 75.0 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:79.00 stddev:1.23 (1.6%) stddev-sans-first:1.30 16:40:56 INFO - PID 5252 | Values: 79.0 79.0 78.0 78.0 78.0 79.0 79.0 78.0 78.0 82.0 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:2.11 (1.9%) stddev-sans-first:1.76 16:40:56 INFO - PID 5252 | Values: 110.0 113.0 111.0 116.0 117.0 114.0 114.0 114.0 115.0 113.0 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | [#6] imaging-darkroom Cycles:10 Average:150.40 Median:150.00 stddev:2.46 (1.6%) stddev-sans-first:2.60 16:40:56 INFO - PID 5252 | Values: 150.0 149.0 150.0 149.0 149.0 149.0 149.0 151.0 151.0 157.0 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.00 stddev:1.65 (1.8%) stddev-sans-first:1.74 16:40:56 INFO - PID 5252 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 99.0 94.0 94.0 95.0 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | [#8] json-parse-financial Cycles:10 Average:44.70 Median:44.00 stddev:1.49 (3.4%) stddev-sans-first:1.56 16:40:56 INFO - PID 5252 | Values: 44.0 46.0 43.0 44.0 44.0 48.0 44.0 44.0 46.0 44.0 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | [#9] json-stringify-tinderbox Cycles:10 Average:39.60 Median:39.00 stddev:1.26 (3.2%) stddev-sans-first:1.32 16:40:56 INFO - PID 5252 | Values: 39.0 39.0 39.0 39.0 39.0 39.0 43.0 39.0 40.0 40.0 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | [#10] stanford-crypto-aes Cycles:10 Average:72.60 Median:73.00 stddev:0.70 (1.0%) stddev-sans-first:0.71 16:40:56 INFO - PID 5252 | Values: 72.0 72.0 73.0 72.0 74.0 73.0 73.0 73.0 72.0 72.0 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | [#11] stanford-crypto-ccm Cycles:10 Average:109.80 Median:111.50 stddev:3.68 (3.3%) stddev-sans-first:3.57 16:40:56 INFO - PID 5252 | Values: 114.0 104.0 106.0 106.0 113.0 110.0 114.0 108.0 113.0 110.0 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.90 Median:163.50 stddev:5.67 (3.5%) stddev-sans-first:5.20 16:40:56 INFO - PID 5252 | Values: 169.0 166.0 154.0 158.0 158.0 157.0 162.0 165.0 167.0 153.0 16:40:56 INFO - PID 5252 | 16:40:56 INFO - PID 5252 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.90 Median:61.50 stddev:1.29 (2.1%) stddev-sans-first:1.32 16:40:56 INFO - PID 5252 | Values: 60.0 62.0 61.0 60.0 59.0 63.0 60.0 62.0 62.0 60.0 16:40:56 INFO - PID 5252 | -------- Summary: end -------- 16:40:56 INFO - PID 5252 | 16:40:57 INFO - PID 5252 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:40:57 INFO - TEST-INFO | 5252: exit 0 16:40:57 INFO - TEST-OK | kraken | took 60694ms 16:40:57 INFO - SUITE-END | took 1170s 16:40:57 INFO - Completed test suite (00:19:30) 16:40:57 INFO - javascript benchmark 16:40:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8986.37299750781, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3796.22177453622, 3873.903152421189, 3882.514685664292, 3887.708421868361, 3895.435161901524, 6168.136460895473, 6247.7813276535335, 6267.43593076623, 6286.779962701306, 6308.905366419502, 6352.428281454668, 6375.1712277403985, 6401.871962560746, 6405.1353067335895, 6413.198350206224, 5662.292213473316, 5667.433256674341, 5688.914666280009, 5697.230873832042, 5728.341240757304, 5609.551235901138, 5693.3452652945025, 5694.971525142369, 5710.086386178213, 5783.190353350535, 5688.345840228385, 5705.144228365737, 5761.711914404284, 5798.159266906296, 5809.651420914759, 5260.420021105091, 5516.062269414169, 5646.802361917313, 5706.201131841545, 5724.685142317192, 5666.320041594994, 5697.943020569783, 5735.053716136874, 5747.396523365028, 5753.971230143864, 6239.469645080174, 6240.438360547572, 6295.622262664255, 6412.967935160341, 6415.422611964946, 6794.898076528856, 6827.044213810071, 6827.897581536281, 6828.760993365208, 6831.248562658104, 6884.0, 6897.689603967833, 6943.062686537303, 6972.023916651673, 6981.650917454158, 6395.360463953567, 6467.83830404239, 6476.6114033158165, 6486.578372405826, 6487.870242595169, 7062.71749130029, 7066.470014748915, 7070.46971477131, 7161.427085833225, 7164.426845852319, 6607.141071660653, 6610.140681711347, 6684.86630267392, 6708.2956289589865, 6902.447804175654, 6871.278515755874, 6896.482763792736, 6902.551334163263, 6933.064036355128, 6933.583984960918, 6866.793996180123, 6903.1371078615175, 6927.7228910843005, 6938.479614028968, 6942.479314051467, 6850.691718872663, 6857.2114206866845, 6942.271061538567, 6947.5831450113155, 6950.687219075154, 4915.87710307238, 4995.600351971833, 5003.324551185616, 5078.162103252922, 5090.287359769635, 4556.2937744649635, 4620.029793743284, 4620.907581848344, 4635.142498637762, 4647.442306923123, 6160.507159427235, 6256.186695729526, 6330.145430366933, 6331.050342448578, 6336.52476064297, 6671.499637527205, 6732.629705366252, 6815.931840681629, 6817.113775209191, 6818.624975626388, 6244.687765611701, 6298.622082675054, 6326.240851097898, 6379.138816259839, 6379.425851673372, 5540.030494663418, 5605.159226116115, 5616.241807356037, 5617.045102332613, 5621.325440947113, 7110.608916509642, 7113.253108423644, 7174.246704096099, 7215.458840586977, 7223.241559636268, 6666.600003999775, 6756.054152418764, 6816.727330906907, 6893.862122757417, 6894.551854129466, 7101.893471598033, 7180.7845764627145, 7193.424526037799, 7204.279572042754, 7205.531640443354, 6721.495887808336, 6756.020377045203, 6848.794536163923, 6851.0, 6857.108575885103, 6838.658067096526, 6861.897071543831, 6930.792076237721, 6956.130483689539, 6957.443404527727, 6513.576617519846, 6522.934770652422, 6532.216134063753, 6535.640539770168, 6539.6403197824575, 6465.062565928009, 6474.2554606219, 6481.935180648133, 6527.086207930988, 6542.869142617026, 6408.711607977559, 6446.742130314735, 6512.120863683342, 6524.412802847766, 6536.117624120683, 6554.8361290968105, 6599.637019963948, 6608.372204640552, 6632.403083722488, 6644.867102658017, 6423.710933007932, 6431.196100487439, 6444.742210311535, 6446.387593178641, 6446.645434501147], "value": 6254.128937497741, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [944.768531709731, 968.7384406210322, 972.4165500699581, 973.1630797514132, 975.2295686407738, 40571.79714101429, 44899.0, 45005.77497112514, 45016.774916125454, 45165.548344516545, 41567.58432415675, 41872.0, 41978.58021419785, 42088.78955605218, 42215.78892105543, 7368.263173682629, 7503.399728021745, 7540.0, 7557.05536807899, 7560.319571238588, 7398.371138453238, 7452.813679658025, 7493.0, 7494.138174109966, 7496.550206987571, 7398.926010739878, 7465.06686664167, 7526.172121066678, 7942.086660034083, 8142.145074767153, 8026.518408895456, 8080.111187769341, 8090.676372945134, 8091.919080809175, 8092.190780921919, 7868.606569671493, 7936.0873499547715, 8028.4781489203015, 8065.838683226302, 8085.636146373427, 40586.59413405858, 40615.7969210155, 40792.38811417831, 40858.0, 40860.0, 3594.622564630702, 3628.3468975584383, 3632.763870348419, 3634.1641422472717, 3642.1805093853936, 33084.66915330864, 33204.6679533204, 33225.0, 33248.83375583131, 33267.833660831784, 5366.2755528004, 5425.810096646638, 5487.368952570466, 5498.642588231752, 5529.253550770634, 219451.0, 219898.0, 220998.9999999984, 221220.0, 222374.0, 44706.870519064, 45379.0, 51326.0, 52092.21861672078, 52338.21492677613, 56775.0, 56857.715711422, 57011.429885700614, 57037.42962570404, 57119.42880571141, 14718.264086795618, 14804.777928331083, 14839.554813355617, 14859.405623775145, 14862.70274594502, 11660.358680272666, 11677.591284305008, 11690.70773230676, 11694.122940779476, 11704.531818727326, 15452.768208476882, 15578.688426231412, 15631.374745010073, 15652.68694626101, 15702.822288328422, 6101.0, 6203.2245969253845, 6284.905726414107, 6308.810735677937, 6350.998870711423, 1094.950727217277, 1099.5381939585393, 1104.3705088099866, 1107.9279846809932, 1117.3016864459712, 24833.87583062073, 24880.751192488206, 24938.625920611204, 25072.24783256505, 25167.24498265055, 1163.650904728578, 1171.3616079236836, 1175.2478413814995, 1178.9469473873696, 1182.9526818927318, 12981.610551683465, 13201.669958251121, 13338.799918001238, 13359.398827052806, 13373.331333433289, 285621.0, 287093.0, 290856.0, 290958.0, 291338.0, 55753.0, 56198.43801562014, 56433.71783141058, 56550.151747723816, 56944.430555694744, 1476.6308422894276, 1496.4687535924809, 1504.3832028868112, 1505.1044628446057, 1528.8457214802975, 24803.95439846865, 25054.498910022063, 25155.87422062878, 25232.74767252304, 25259.0, 40601.390979135926, 40634.187316252915, 40703.79648101859, 40764.38853417142, 40894.79552602218, 233812.0, 234251.0, 234806.0, 235066.0, 235165.0, 32438.0, 33436.66563334336, 35158.64841351554, 35840.566377344614, 36115.0, 46424.767876160404, 46571.30143047789, 46919.530804692884, 47000.05999880052, 47031.0, 34186.48720269148, 34456.48315275323, 34520.48219276763, 34563.48154777731, 35018.649813501535, 33656.0, 34091.0, 34385.312293753486, 34554.65445345514, 34627.4805877907, 38846.6115338843, 39451.40822887602, 39507.80246098866, 39617.0, 39764.20471590494, 33113.33773324568, 33149.66850331466, 33151.0, 33448.66551334455, 33509.497357540145, 25631.871840640677, 25765.484690305715, 25887.870560647076, 25968.10480418877, 26315.21054368372], "value": 18199.08910372206, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.52222821634797, 576.7772322675925, 587.791334076403, 595.4700316718119, 599.5593238969354, 22398.0, 23310.0, 23369.18207862725, 23400.765992340093, 23553.175638852645, 21499.0, 21966.89016554919, 22121.33635990919, 22306.553868922612, 23628.76371236287, 6632.63520506372, 6733.091032710595, 6773.491988100887, 6776.390124888759, 6776.932230677704, 6693.263740988487, 6714.429273511757, 6776.949572816221, 6779.966100169492, 6784.559003664771, 6375.521835862306, 6746.62893540855, 6747.898781518281, 6759.222689390709, 6771.1874575050815, 6724.83187920301, 6770.72917083321, 6771.8984215236815, 6774.390304872585, 6774.627395493246, 5865.85335366615, 6071.726772295213, 6085.148079268862, 6088.543359248074, 6090.33006369299, 31014.224644383845, 31627.683723162932, 31645.52531712026, 31675.84162079198, 31679.0, 2257.7516473187934, 2264.275431861805, 2275.9544809103727, 2284.942876428086, 2291.026313816621, 20286.08712607936, 20485.4878628034, 20873.68689469659, 20885.895570522203, 20903.68644470334, 2445.0708730682404, 2448.2288079255154, 2508.0720133550703, 2509.774120329179, 2517.8237523373373, 130479.0, 136156.0, 149291.25354373158, 155611.0, 157061.0, 15270.618234544003, 15741.0, 15832.841671583366, 16213.189340533163, 16689.24898379576, 25288.241352759447, 25394.0, 25407.237782866545, 25483.853226604846, 25527.234182974542, 9762.219022478184, 9812.509374531388, 9849.113579777855, 9949.950250248847, 9996.0, 8655.870161947576, 8728.25809806161, 8735.082816304324, 8799.868001979976, 8801.691940782042, 9334.126337569833, 9871.506424678737, 9900.356476829127, 9994.850077248848, 10063.547140378554, 2798.3423895384567, 2810.8875644973973, 2893.8119022263486, 2903.083964583365, 2973.747231485348, 1033.3128469567653, 1052.1109662335314, 1054.093479607537, 1063.324788759128, 1065.616378103882, 21343.25298614541, 21438.892805536183, 21446.57106857854, 21670.0, 22344.77655223427, 1083.4636854756945, 1112.0991996482874, 1118.765059337532, 1120.3389999899935, 1134.7787181499527, 11749.823752643717, 11950.820737688942, 11956.163068585289, 12038.518459261531, 12216.144869859025, 624540.0, 631422.850894494, 649227.0, 650790.0, 655812.0, 125845.37077314555, 126503.0, 129379.35310323388, 129783.35108324398, 130096.0, 1095.956161753521, 1151.7984352738238, 1161.703765539782, 1201.279232460534, 1206.3726862031695, 21965.011574478867, 22718.204862829723, 22790.0, 23009.0, 23047.769522304563, 57497.42502574921, 57973.71013144907, 58428.4157158423, 58740.70629646824, 59335.70332148312, 70050.0, 72459.27540724525, 73610.0, 73915.63042184754, 74797.0, 15569.532914012598, 15827.841721582638, 15995.200239988188, 16049.277782500048, 16057.0, 24866.378340541632, 25112.497750045262, 25314.114006010437, 25486.10798622039, 25753.871230643726, 19784.703229451283, 20011.899940500203, 20320.28878989228, 20546.897265513577, 20549.794502055385, 17552.0, 17870.285188592312, 18154.90922545379, 18217.635647286716, 18472.630547388708, 20788.68816967777, 20842.687359689313, 21024.369268921957, 21164.682529761758, 21339.46651333729, 15519.146446945526, 15536.689266214385, 15583.454579089677, 15643.139627320606, 15792.289346979183, 20208.0, 20274.89862550678, 20322.59354812925, 20392.796072039684, 20481.69277460869], "value": 12697.938314750638, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.235772682751, 1492.7686208637665, 1496.3864815425677, 1498.4605542004879, 1506.9623259418513, 16394.34422623095, 17079.0, 17088.145592720375, 17100.14499275036, 17102.230399632015, 16793.748093778573, 16838.242279097438, 16890.662186756257, 16923.830761692378, 16925.22285160059, 2733.8906443742226, 2747.4505098980235, 2780.0408858943733, 2780.7358300961478, 2784.192584150604, 2761.8066735328534, 2789.0517224143787, 2799.1322689966178, 2800.075974928269, 2801.8739156737893, 2650.9734902651016, 2838.7019362966903, 2857.614222080024, 2858.414025124855, 2859.1994241612383, 2746.8626568671684, 2753.8347699138017, 2765.1151631477937, 2765.3224959884756, 2776.569631707089, 2654.8938042478276, 2696.042904768818, 2700.7974401919737, 2701.7838572914316, 2718.4834881372476, 16446.095464749433, 16519.174041297887, 16545.50363489097, 16555.50333489997, 16605.750913736305, 8199.918000819975, 8499.66001359951, 8504.362172837, 8525.658973641046, 8538.103499132563, 5631.493165615114, 5694.117411801178, 5738.655680659173, 5752.338481074647, 5925.703714814285, 2062.2988184017354, 2092.801183887528, 2114.291712276374, 2147.4094623978376, 2175.8368122390884, 29448.55827162594, 29651.40697186044, 29721.851390743122, 29861.25346866324, 29968.700312997025, 7123.43012558994, 7296.7446139384865, 7300.671469783766, 7339.669714862844, 7466.887996680054, 18923.0, 18974.0, 19007.144678489225, 19017.71473427872, 19018.42944711661, 8115.3913456491, 8320.625571849281, 8322.250997410261, 8327.20891515305, 8330.583470826434, 7471.290227428385, 7511.248875112445, 7567.545947243183, 7646.3118319350415, 7672.194419585975, 5506.229127922094, 5684.204211410406, 5692.48767610917, 5720.170575266563, 5756.30924289088, 1509.9698006039819, 1534.4782773856718, 1578.629022179787, 1600.7118718630525, 1651.38898607516, 992.533509250651, 1008.4856723071287, 1013.4983183324155, 1013.9746506337401, 1031.3244824639723, 9322.347435679438, 9398.624055037722, 9493.335466517416, 9566.043395660377, 9579.233661307078, 1025.9897401026044, 1027.6557353286582, 1030.6032177611523, 1033.426448321189, 1060.5068643081004, 6065.181200537871, 6147.877042459215, 6190.288116866528, 6224.502039836802, 6229.408206220402, 24037.51924961526, 24606.384840379134, 24891.377715557257, 24917.875410623194, 24990.875045624656, 23957.401064973168, 24532.0, 24536.509269814862, 24812.0, 24887.751122488906, 12092.818607720892, 12308.753824923451, 12358.50565977351, 12378.44297006637, 12392.380380981096, 9489.288303377136, 9570.425774453555, 9694.85457718149, 9736.269779766404, 9776.9022309776, 20520.692189616868, 20804.47988800292, 20953.89523052375, 21144.471388214803, 21305.89347053255, 21609.35171944844, 21959.34121976343, 22064.77935220627, 22119.115235391047, 22344.21795237159, 11984.340861252715, 11999.460024299102, 12035.398230088636, 12121.636350909486, 12154.149209555246, 14239.216843073316, 14422.206778627276, 14454.566363009126, 14454.783178252545, 14459.855401446272, 12544.686382840502, 12640.936795316333, 12681.365931703194, 12756.362181891054, 12871.970242380774, 12025.4588543514, 12193.085518586318, 12439.191452555555, 12462.0, 12584.433700483318, 11200.271982321123, 11259.042981346678, 11290.887091128983, 11318.717032074264, 11334.433278336215, 8497.0, 8525.275351595184, 8655.52394618302, 8710.085441028601, 8736.78158046054, 8445.239928406472, 8558.957205213934, 8592.914070859211, 8598.871016934876, 8628.0], "value": 7307.564387064615, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [525.424659997303, 566.3402136510965, 568.4060157135792, 578.4591407034424, 582.1616871704745, 6263.749450021999, 6326.968365158174, 6376.29860715321, 6427.582207156532, 6430.131932189153, 6258.311585725566, 6288.465480434157, 6293.465055470279, 6342.460890824285, 6367.267764207118, 5191.506806853362, 5198.142306519419, 5200.947990520103, 5203.9219411708855, 5206.453322401131, 5187.870303242411, 5195.298634684307, 5237.423883372845, 5249.1601343785005, 5253.133233016548, 4809.1343558159515, 4962.255661650764, 4984.352034235562, 4991.40103187618, 5008.874778130557, 4981.501849815026, 4994.200927851526, 5001.874953126183, 5024.17101178307, 5030.974845125787, 4441.422615060022, 4512.503624601291, 4529.456465224162, 4536.138133754576, 4545.568171023748, 6181.258249010102, 6183.691724927187, 6206.6586337751405, 6230.96884515579, 6239.594426362272, 5812.505936995361, 5882.705864706791, 5900.262467191601, 5909.8818023639715, 5910.261217347832, 6742.2920593337485, 6742.730290788411, 6752.966235168842, 6754.838451695459, 6759.864802703967, 5007.69953802773, 5023.522765337324, 5045.621578381636, 5065.924011139836, 5093.872653183663, 6788.287229840845, 6859.314068593101, 6867.656617169122, 6979.243198936442, 7067.505274630778, 5152.149895267239, 5168.2764412982215, 5172.224166375074, 5182.0, 5190.792368305301, 5263.0, 5275.525202731773, 5281.788728450896, 5292.867678308073, 5300.893982120336, 5780.364159942403, 5806.796762113306, 5825.825225243249, 5840.678762668009, 5859.707014649336, 6687.531872768956, 6724.755920154689, 6739.157605299338, 6814.659267036727, 6820.931790682129, 1799.604087100862, 1821.9544511387057, 1823.717323814811, 1840.0983518076046, 1840.696285112968, 1248.2260998181005, 1252.2361359570655, 1281.8077288406814, 1295.7214198947272, 1303.9217646941213, 6285.560010799291, 6295.276043255084, 6325.3674632537295, 6331.461825744771, 6332.968335158294, 6722.428593569503, 6734.360235777691, 6756.662166891636, 6798.218204906499, 6892.276310987274, 6076.848078797977, 6105.694715264219, 6180.969095154495, 6256.937430625727, 6270.780522681775, 3536.310419468179, 3557.3418917500344, 3589.5333606631493, 3608.314420260142, 3608.6391360864227, 7068.338940349014, 7082.929170708227, 7083.150021997393, 7119.6084215368655, 7161.462890283249, 6874.518783685194, 6956.79129626112, 6999.8600027999155, 7013.298670133048, 7019.543729657556, 7127.893081603676, 7160.0, 7160.713571457083, 7171.641417929082, 7201.135863696285, 6786.558873673195, 6821.441959265276, 6827.487938404739, 6830.248672645906, 6874.278200788845, 6730.87977287153, 6732.182310775965, 6752.122224110834, 6771.729130834711, 6824.283450237654, 6936.167659880644, 7061.399781018671, 7115.786526404217, 7170.354668079898, 7177.34438257848, 5061.519155680205, 5142.5885929126325, 5183.144781111075, 5226.163813789775, 5237.685738855681, 6581.80254592363, 6607.174103237096, 6849.212340580897, 6861.691223895036, 6940.9652951734915, 6599.868002640016, 6602.801915942529, 6608.504362172953, 6636.9004464932095, 6637.834054148491, 6559.147310849559, 6559.376859198369, 6563.835904102436, 6618.63597502142, 6626.105475760711, 6398.360163983564, 6524.641144737085, 6534.215894092739, 6552.148220731275, 6588.8352791180605, 6619.205695316593, 6659.533832631863, 6662.200535935719, 6683.532152749261, 6701.798946031627, 6342.302346741762, 6378.266499352449, 6431.549791514549, 6493.740250389931, 6508.0], "value": 5222.38776659845, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [973.250597040279, 981.1905178227961, 1001.50425539358, 1001.8146642871064, 1003.428046013772, 16031.919840400797, 16090.356385744572, 16112.83887161128, 16286.511404657864, 16448.506544803644, 15616.453424130157, 15634.687306253869, 15675.60810979726, 15690.84309156908, 15715.214239288047, 1039.6932904793084, 1061.4639606998455, 1061.8194906865813, 1062.9734256643608, 1064.7125276175466, 859.4800145911727, 859.6733241368271, 860.5525126933991, 861.8965724113115, 863.115306810518, 1070.6413351527249, 1110.7611863449397, 1134.302404021525, 1134.517829922284, 1135.42660956217, 1099.312929419113, 1102.85662863828, 1103.4041617526525, 1104.8508451359044, 1106.2035334559105, 803.4335793265792, 809.70041084799, 811.5455345006815, 813.1543195077113, 820.327331588098, 17626.206820692973, 17629.942203467705, 17633.294668213257, 17666.558336041573, 17722.91138544312, 21207.469813254636, 21542.461438464008, 21708.240211592678, 22272.88863555688, 22301.442463938532, 18169.63660726778, 18386.90806545972, 18741.81258187428, 18848.62302753951, 18961.620767584707, 3515.6308587598446, 3549.8402571884067, 3571.7678350907368, 3580.3913334733156, 3580.785152890835, 119245.0, 153101.0, 153929.0, 155863.22068389587, 156554.99999999886, 18303.0, 18321.0, 18471.16879740415, 18527.444176674722, 18922.148503317385, 32677.83661081727, 33381.66618333834, 33603.83198083994, 33620.83189584036, 33628.0, 8255.0, 8291.461055031403, 8301.003879534373, 8302.8339433211, 8311.33509319253, 3946.2699400610404, 3993.84024639017, 3995.360742281221, 3997.260506806192, 4008.6392224699334, 5793.217915581343, 5860.794872179454, 5927.911081333784, 5937.732802023919, 5954.702264886739, 1130.2370899642708, 1155.4511606986782, 1156.5547264303136, 1158.7682463507333, 1161.1523587780966, 713.3437237741291, 739.7115125101215, 757.814335487809, 769.692123150733, 780.0678189563417, 13391.330433478288, 13631.522896698569, 13868.306584670727, 14343.56969290923, 14370.856291436952, 724.9782506524813, 725.6625669063806, 735.0921611809412, 744.3858816476428, 753.3596443023387, 6322.209723784527, 6468.676566171673, 6471.0, 6499.415052645285, 6573.737050517926, 148001.2599937015, 148350.25824870807, 149486.0, 150614.0, 152266.23866880595, 125701.0, 126670.0, 126938.0, 127660.36169819091, 130849.0, 107398.0, 110071.0, 110791.0, 111813.0, 113989.4300528492, 11908.166428349932, 12129.45417456199, 12206.328651923874, 12248.142630015813, 12435.06736994711, 53346.73326633342, 53654.19518707145, 55320.0, 55844.72077639586, 56158.43841561532, 67467.0, 68725.65637171782, 68862.65568672125, 69155.0, 70128.6493567529, 17408.0, 17834.19746111454, 17934.730979035063, 18051.548711281797, 18095.185716643045, 17621.383251586132, 18195.72629915893, 18544.07279635986, 18701.625967480846, 19027.048647567844, 18397.448076557725, 18785.248590056246, 18942.147603358157, 18952.147153378406, 19000.714989274897, 18115.09424528742, 18178.363757268962, 18313.908430457763, 18314.45056648303, 18380.356687515872, 16623.3350665972, 16667.749983750495, 16680.49958501247, 16743.162841858102, 17033.063181524638, 12762.68093297675, 13085.542006029744, 13410.731785364433, 13434.798478022642, 13493.122947008413, 12575.43410546546, 12626.116171867881, 12782.552610658955, 12861.935690321863, 13162.60512184636], "value": 9547.497944085295, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1386.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 106.0, 105.0, 106.0, 108.0, 107.0, 107.0, 107.0, 105.0, 105.0], "value": 106.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 112.0, 111.0, 118.0, 114.0, 138.0, 115.0, 116.0, 122.0, 112.0], "value": 117.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 162.0, 165.0, 165.0, 163.0, 162.0, 165.0, 164.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 73.0, 72.0, 74.0, 74.0, 73.0, 74.0, 74.0, 75.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 78.0, 78.0, 79.0, 79.0, 78.0, 78.0, 82.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 111.0, 116.0, 117.0, 114.0, 114.0, 114.0, 115.0, 113.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 150.0, 149.0, 149.0, 149.0, 149.0, 151.0, 151.0, 157.0], "value": 150.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 99.0, 94.0, 94.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 46.0, 43.0, 44.0, 44.0, 48.0, 44.0, 44.0, 46.0, 44.0], "value": 44.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 39.0, 39.0, 39.0, 39.0, 43.0, 39.0, 40.0, 40.0], "value": 39.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 73.0, 72.0, 74.0, 73.0, 73.0, 73.0, 72.0, 72.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 104.0, 106.0, 106.0, 113.0, 110.0, 114.0, 108.0, 113.0, 110.0], "value": 109.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 154.0, 158.0, 158.0, 157.0, 162.0, 165.0, 167.0, 153.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 61.0, 60.0, 59.0, 63.0, 60.0, 62.0, 62.0, 60.0], "value": 60.9, "unit": "score"}]}]} 16:40:58 INFO - Return code: 0 16:40:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:40:58 INFO - # TBPL SUCCESS # 16:40:58 INFO - Running post-action listener: _package_coverage_data 16:40:58 INFO - Running post-action listener: _resource_record_post_action 16:40:58 INFO - [mozharness: 2017-09-14 23:40:58.106034Z] Finished run-tests step (success) 16:40:58 INFO - Running post-run listener: _resource_record_post_run 16:40:58 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 421888; Write bytes: 425877504; Read time: 48; Write time: 428568 16:40:58 INFO - TinderboxPrint: CPU usage
13.1% 16:40:58 INFO - TinderboxPrint: I/O read bytes / time
421,888 / 48 16:40:58 INFO - TinderboxPrint: I/O write bytes / time
425,877,504 / 428,568 16:40:58 INFO - TinderboxPrint: CPU idle
8,238.4 (86.9%) 16:40:58 INFO - TinderboxPrint: CPU user
1,189.2 (12.5%) 16:40:58 INFO - TinderboxPrint: Swap in / out
0 / 0 16:40:58 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 106164224; Read time: 0; Write time: 92364 16:40:58 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:40:58 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 421888; Write bytes: 319713280; Read time: 48; Write time: 336160 16:40:58 INFO - Running post-run listener: _upload_blobber_files 16:40:58 INFO - Blob upload gear active. 16:40:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:40:58 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 16:40:58 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'] 16:40:58 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 16:40:59 INFO - (blobuploader) - INFO - Open directory for files ... 16:40:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:40:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:40:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:41:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:02 INFO - (blobuploader) - INFO - Done attempting. 16:41:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:41:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:41:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:03 INFO - (blobuploader) - INFO - Done attempting. 16:41:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:41:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:41:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:04 INFO - (blobuploader) - INFO - Done attempting. 16:41:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:41:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:41:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:05 INFO - (blobuploader) - INFO - Done attempting. 16:41:05 INFO - (blobuploader) - INFO - Iteration through files over. 16:41:05 INFO - Return code: 0 16:41:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:41:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:41:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/789b05d69434f36e44b239ade8b8b24c99b57b0fe4fe17741bc618e9e5b3b913fd0405a6ac775e2192a8cb331238d29443f28abb8d258f58458c5a2630ed18b1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7ec46a21a8ebe30e37c8384a295931db94e80fd72b61e9f2559a048e49348863859c9de774d23b991a47c4ab92793806e9580194683a091ab658d55db9774c7", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc001640f2352abc5fdf10317d7068d151db713e8311a26909d73a0f7a52d24e9c9fb74f48009cb17c1b743a218bc84972412540ece17329e0f928003a6ec72c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b9bff5dbf5d2e15e12ddbb6a1b09870c77e1b049ce2716f31d73c7b798e16d747b1591f84b11635c63f4fbd6012d9326f19107a3f3a9b9a214362055f8b9a9b"} 16:41:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:41:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:41:05 INFO - Contents: 16:41:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/789b05d69434f36e44b239ade8b8b24c99b57b0fe4fe17741bc618e9e5b3b913fd0405a6ac775e2192a8cb331238d29443f28abb8d258f58458c5a2630ed18b1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7ec46a21a8ebe30e37c8384a295931db94e80fd72b61e9f2559a048e49348863859c9de774d23b991a47c4ab92793806e9580194683a091ab658d55db9774c7", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc001640f2352abc5fdf10317d7068d151db713e8311a26909d73a0f7a52d24e9c9fb74f48009cb17c1b743a218bc84972412540ece17329e0f928003a6ec72c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b9bff5dbf5d2e15e12ddbb6a1b09870c77e1b049ce2716f31d73c7b798e16d747b1591f84b11635c63f4fbd6012d9326f19107a3f3a9b9a214362055f8b9a9b"} 16:41:05 INFO - Running post-run listener: copy_logs_to_upload_dir 16:41:05 INFO - Copying logs to upload dir... 16:41:05 INFO - mkdir: /builds/slave/test/build/upload/logs 16:41:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.407249 ========= master_lag: 0.41 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-14 16:41:05.737147) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-14 16:41:05.746256) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059 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/789b05d69434f36e44b239ade8b8b24c99b57b0fe4fe17741bc618e9e5b3b913fd0405a6ac775e2192a8cb331238d29443f28abb8d258f58458c5a2630ed18b1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7ec46a21a8ebe30e37c8384a295931db94e80fd72b61e9f2559a048e49348863859c9de774d23b991a47c4ab92793806e9580194683a091ab658d55db9774c7", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc001640f2352abc5fdf10317d7068d151db713e8311a26909d73a0f7a52d24e9c9fb74f48009cb17c1b743a218bc84972412540ece17329e0f928003a6ec72c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b9bff5dbf5d2e15e12ddbb6a1b09870c77e1b049ce2716f31d73c7b798e16d747b1591f84b11635c63f4fbd6012d9326f19107a3f3a9b9a214362055f8b9a9b"} build_url:https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011341 build_url: 'https://queue.taskcluster.net/v1/task/YGh8JjFsQ5G0BruESi9ePQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/789b05d69434f36e44b239ade8b8b24c99b57b0fe4fe17741bc618e9e5b3b913fd0405a6ac775e2192a8cb331238d29443f28abb8d258f58458c5a2630ed18b1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7ec46a21a8ebe30e37c8384a295931db94e80fd72b61e9f2559a048e49348863859c9de774d23b991a47c4ab92793806e9580194683a091ab658d55db9774c7", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc001640f2352abc5fdf10317d7068d151db713e8311a26909d73a0f7a52d24e9c9fb74f48009cb17c1b743a218bc84972412540ece17329e0f928003a6ec72c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b9bff5dbf5d2e15e12ddbb6a1b09870c77e1b049ce2716f31d73c7b798e16d747b1591f84b11635c63f4fbd6012d9326f19107a3f3a9b9a214362055f8b9a9b"}' ========= master_lag: 0.21 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-14 16:41:05.970983) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 16:41:05.971311) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1505429955.736745-511912059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004518 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 16:41:06.052642) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-14 16:41:06.052958) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-14 16:41:06.053275) ========= ========= Total master_lag: 0.97 =========