builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-057 starttime: 1504088302.62 results: success (0) revision: ab2d700fda2b4934d24227216972dce9fac19b74 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-30 03:18:22.616744) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-30 03:18:22.617209) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-30 03:18:22.617610) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-30 03:18:22.641473) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:18:22.641812) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-30 03:18:22-- 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% 42.5M=0s 2017-08-30 03:18:22 (42.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130791 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:18:22.788159) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:18:22.788740) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020146 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:18:22.838637) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:18:22.838995) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ab2d700fda2b4934d24227216972dce9fac19b74 --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 ab2d700fda2b4934d24227216972dce9fac19b74 --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=934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-30 03:18:22,960 truncating revision to first 12 chars 2017-08-30 03:18:22,960 Setting DEBUG logging. 2017-08-30 03:18:22,961 attempt 1/10 2017-08-30 03:18:22,961 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ab2d700fda2b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-30 03:18:23,405 unpacking tar archive at: mozilla-central-ab2d700fda2b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.798242 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:18:23.651078) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-30 03:18:23.651416) ========= script_repo_revision: ab2d700fda2b4934d24227216972dce9fac19b74 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-30 03:18:23.651846) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-30 03:18:23.652119) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-30 03:18:23.662025) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-08-30 03:18:23.662373) ========= /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=934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:18:23 INFO - MultiFileLogger online at 20170830 03:18:23 in /builds/slave/test 03:18:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:18:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:18:23 INFO - {'append_to_log': False, 03:18:23 INFO - 'base_work_dir': '/builds/slave/test', 03:18:23 INFO - 'blob_upload_branch': 'Firefox', 03:18:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:18:23 INFO - 'branch': 'Firefox', 03:18:23 INFO - 'buildbot_json_path': 'buildprops.json', 03:18:23 INFO - 'code_coverage': False, 03:18:23 INFO - 'config_files': ('talos/linux_config.py',), 03:18:23 INFO - 'default_actions': ('clobber', 03:18:23 INFO - 'read-buildbot-config', 03:18:23 INFO - 'download-and-extract', 03:18:23 INFO - 'populate-webroot', 03:18:23 INFO - 'create-virtualenv', 03:18:23 INFO - 'install', 03:18:23 INFO - 'setup-mitmproxy', 03:18:23 INFO - 'run-tests'), 03:18:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:18:23 INFO - 'disable_ccov_upload': False, 03:18:23 INFO - 'disable_stylo': False, 03:18:23 INFO - 'download_minidump_stackwalk': True, 03:18:23 INFO - 'download_symbols': 'ondemand', 03:18:23 INFO - 'enable_stylo': False, 03:18:23 INFO - 'enable_webrender': False, 03:18:23 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:18:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:18:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:18:23 INFO - 'gecko_profile': False, 03:18:23 INFO - 'gecko_profile_interval': 0, 03:18:23 INFO - 'installer_path': 'installer.exe', 03:18:23 INFO - 'log_level': 'info', 03:18:23 INFO - 'log_name': 'talos', 03:18:23 INFO - 'log_to_console': True, 03:18:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:18:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:18:23 INFO - 'opt_config_files': (), 03:18:23 INFO - 'pip_index': False, 03:18:23 INFO - 'suite': 'dromaeojs-e10s', 03:18:23 INFO - 'system_bits': '32', 03:18:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:18:23 INFO - 'title': 'talos-linux64-ix-057', 03:18:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:18:23 INFO - 'use_talos_json': True, 03:18:23 INFO - 'verify': 'False', 03:18:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:18:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:18:23 INFO - 'work_dir': 'build'} 03:18:23 INFO - [mozharness: 2017-08-30 10:18:23.926552Z] Running clobber step. 03:18:23 INFO - Running pre-action listener: _resource_record_pre_action 03:18:23 INFO - Running main action method: clobber 03:18:23 INFO - rmtree: /builds/slave/test/build 03:18:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:18:24 INFO - Running post-action listener: _resource_record_post_action 03:18:24 INFO - [mozharness: 2017-08-30 10:18:24.930630Z] Finished clobber step (success) 03:18:24 INFO - [mozharness: 2017-08-30 10:18:24.930727Z] Running read-buildbot-config step. 03:18:24 INFO - Running pre-action listener: _resource_record_pre_action 03:18:24 INFO - Running main action method: read_buildbot_config 03:18:24 INFO - Using buildbot properties: 03:18:24 INFO - { 03:18:24 INFO - "project": "", 03:18:24 INFO - "product": "firefox", 03:18:24 INFO - "who": "archaeopteryx@coole-files.de", 03:18:24 INFO - "installer_path": "public/build/target.tar.bz2", 03:18:24 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:18:24 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:18:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 03:18:24 INFO - "stage_platform": "linux64-pgo", 03:18:24 INFO - "basedir": "/builds/slave/test", 03:18:24 INFO - "buildnumber": 148, 03:18:24 INFO - "platform": "ubuntu64_hw", 03:18:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:18:24 INFO - "slavebuilddir": "test", 03:18:24 INFO - "taskId": "GABd_7HCSimJjrcUJfubKw", 03:18:24 INFO - "branch": "mozilla-central", 03:18:24 INFO - "script_repo_revision": "production", 03:18:24 INFO - "revision": "ab2d700fda2b4934d24227216972dce9fac19b74", 03:18:24 INFO - "slavename": "talos-linux64-ix-057", 03:18:24 INFO - "repo_path": "mozilla-central" 03:18:24 INFO - } 03:18:24 INFO - Finding installer, test and symbols from parent task. 03:18:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GABd_7HCSimJjrcUJfubKw'}, attempt #1 03:18:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GABd_7HCSimJjrcUJfubKw'}, attempt #1 03:18:25 INFO - Task dependencies: EglbPCUQT12-jMq7e8GjrQ 03:18:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ'}, attempt #1 03:18:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.tar.bz2 03:18:26 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.test_packages.json 03:18:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.crashreporter-symbols.zip 03:18:26 INFO - Running post-action listener: _resource_record_post_action 03:18:26 INFO - [mozharness: 2017-08-30 10:18:26.275271Z] Finished read-buildbot-config step (success) 03:18:26 INFO - [mozharness: 2017-08-30 10:18:26.275488Z] Running download-and-extract step. 03:18:26 INFO - Running pre-action listener: _resource_record_pre_action 03:18:26 INFO - Running main action method: download_and_extract 03:18:26 INFO - mkdir: /builds/slave/test/build/tests 03:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:18:26 INFO - trying https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.test_packages.json 03:18:26 INFO - Downloading https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:18:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:18:26 INFO - Downloaded 989 bytes. 03:18:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:18:26 INFO - Using the following test package requirements: 03:18:26 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:18:26 INFO - u'common': [u'target.common.tests.zip'], 03:18:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:18:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:18:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:18:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:18:26 INFO - u'mozbase': [u'target.common.tests.zip'], 03:18:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:18:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:18:26 INFO - u'web-platform': [u'target.common.tests.zip', 03:18:26 INFO - u'target.web-platform.tests.tar.gz'], 03:18:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:18:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:18:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.common.tests.zip 03:18:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:18:26 INFO - Fetch https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.common.tests.zip into memory 03:18:27 INFO - Content-Length response header: 55591016 03:18:27 INFO - Bytes received: 55591016 03:18:38 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:18:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.common.tests.zip 03:18:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:18:38 INFO - Fetch https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.common.tests.zip into memory 03:18:59 INFO - Content-Length response header: 55591016 03:18:59 INFO - Bytes received: 55591016 03:19:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.talos.tests.zip 03:19:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:19:13 INFO - Fetch https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.talos.tests.zip into memory 03:19:13 INFO - Content-Length response header: 14496650 03:19:13 INFO - Bytes received: 14496650 03:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:14 INFO - trying https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.tar.bz2 03:19:14 INFO - Downloading https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:19:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:19:20 INFO - Downloaded 63353494 bytes. 03:19:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.tar.bz2 03:19:20 INFO - mkdir: /builds/slave/test/properties 03:19:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:19:20 INFO - Writing to file /builds/slave/test/properties/build_url 03:19:20 INFO - Contents: 03:19:20 INFO - build_url:https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.tar.bz2 03:19:20 INFO - Running post-action listener: _resource_record_post_action 03:19:20 INFO - Running post-action listener: find_tests_for_verification 03:19:20 INFO - Running post-action listener: set_extra_try_arguments 03:19:20 INFO - [mozharness: 2017-08-30 10:19:20.109540Z] Finished download-and-extract step (success) 03:19:20 INFO - [mozharness: 2017-08-30 10:19:20.109636Z] Running populate-webroot step. 03:19:20 INFO - Running pre-action listener: _resource_record_pre_action 03:19:20 INFO - Running main action method: populate_webroot 03:19:20 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:19:20 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:19:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:19:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:19:20 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:19:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:19:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:19:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:19:20 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:19:20 INFO - 'talos_options': ['--enable-stylo'], 03:19:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:19:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:19:20 INFO - 'tests': ['damp', 'tps']}, 03:19:20 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:19:20 INFO - 'talos_options': ['--enable-stylo'], 03:19:20 INFO - 'tests': ['damp', 'tps']}, 03:19:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:19:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:19:20 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:19:20 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:19:20 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:19:20 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:19:20 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:19:20 INFO - 'talos_options': ['--enable-stylo'], 03:19:20 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:19:20 INFO - 'other-e10s': {'tests': ['a11yr', 03:19:20 INFO - 'ts_paint', 03:19:20 INFO - 'tpaint', 03:19:20 INFO - 'sessionrestore', 03:19:20 INFO - 'sessionrestore_many_windows', 03:19:20 INFO - 'sessionrestore_no_auto_restore', 03:19:20 INFO - 'tabpaint']}, 03:19:20 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:19:20 INFO - 'tests': ['a11yr', 03:19:20 INFO - 'ts_paint', 03:19:20 INFO - 'tpaint', 03:19:20 INFO - 'sessionrestore', 03:19:20 INFO - 'sessionrestore_many_windows', 03:19:20 INFO - 'sessionrestore_no_auto_restore', 03:19:20 INFO - 'tabpaint']}, 03:19:20 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:19:20 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:19:20 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:19:20 INFO - 'tests': ['bloom_basic_singleton']}, 03:19:20 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:19:20 INFO - 'tests': ['bloom_basic']}, 03:19:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:19:20 INFO - 'tsvgr_opacity', 03:19:20 INFO - 'tart', 03:19:20 INFO - 'tscrollx', 03:19:20 INFO - 'tsvg_static']}, 03:19:20 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:19:20 INFO - 'tests': ['tsvgx', 03:19:20 INFO - 'tsvgr_opacity', 03:19:20 INFO - 'tart', 03:19:20 INFO - 'tscrollx', 03:19:20 INFO - 'tsvg_static']}, 03:19:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:19:20 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:19:20 INFO - 'talos_options': ['--enable-stylo'], 03:19:20 INFO - 'tests': ['tp5o']}, 03:19:20 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:19:20 INFO - 'talos_options': ['--mitmproxy', 03:19:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:19:20 INFO - '--firstNonBlankPaint'], 03:19:20 INFO - 'tests': ['tp6_google', 03:19:20 INFO - 'tp6_youtube', 03:19:20 INFO - 'tp6_amazon', 03:19:20 INFO - 'tp6_facebook']}, 03:19:20 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:19:20 INFO - 'talos_options': ['--enable-stylo', 03:19:20 INFO - '--mitmproxy', 03:19:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:19:20 INFO - '--firstNonBlankPaint'], 03:19:20 INFO - 'tests': ['tp6_google', 03:19:20 INFO - 'tp6_youtube', 03:19:20 INFO - 'tp6_amazon', 03:19:20 INFO - 'tp6_facebook']}, 03:19:20 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:19:20 INFO - 'talos_options': ['--enable-stylo', 03:19:20 INFO - '--stylo-threads=1', 03:19:20 INFO - '--mitmproxy', 03:19:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:19:20 INFO - '--firstNonBlankPaint'], 03:19:20 INFO - 'tests': ['tp6_google', 03:19:20 INFO - 'tp6_youtube', 03:19:20 INFO - 'tp6_amazon', 03:19:20 INFO - 'tp6_facebook']}, 03:19:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:19:20 INFO - 'talos_options': ['--xperf_path', 03:19:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:19:20 INFO - 'tests': ['tp5n']}, 03:19:20 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:19:20 INFO - 'talos_options': ['--enable-stylo', 03:19:20 INFO - '--xperf_path', 03:19:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:19:20 INFO - 'tests': ['tp5n']}}} 03:19:20 INFO - Running post-action listener: _resource_record_post_action 03:19:20 INFO - [mozharness: 2017-08-30 10:19:20.123856Z] Finished populate-webroot step (success) 03:19:20 INFO - [mozharness: 2017-08-30 10:19:20.123945Z] Running create-virtualenv step. 03:19:20 INFO - Running pre-action listener: _resource_record_pre_action 03:19:20 INFO - Running main action method: create_virtualenv 03:19:20 INFO - Creating virtualenv /builds/slave/test/build/venv 03:19:20 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:19:20 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:19:20 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:19:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:19:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:19:20 INFO - Installing distribute.............................................................................................................................................................................................done. 03:19:21 INFO - Installing pip...............done. 03:19:21 INFO - Return code: 0 03:19:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:19:21 INFO - Reading from file tmpfile_stdout 03:19:21 INFO - Output received: 03:19:21 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:19:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:19:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:21 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 0x103a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1202a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6580>, '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 0x13a7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a7910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:19:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:19:21 INFO - Using env: {'DISPLAY': ':0', 03:19:21 INFO - 'HOME': '/home/cltbld', 03:19:21 INFO - 'LANG': 'en_US.UTF-8', 03:19:21 INFO - 'LANGUAGE': 'en_US:en', 03:19:21 INFO - 'LOGNAME': 'cltbld', 03:19:21 INFO - 'MAIL': '/var/mail/cltbld', 03:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:21 INFO - 'MOZ_NO_REMOTE': '1', 03:19:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:21 INFO - 'NO_EM_RESTART': '1', 03:19:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:21 INFO - 'PWD': '/builds/slave/test', 03:19:21 INFO - 'SHELL': '/bin/bash', 03:19:21 INFO - 'SHLVL': '1', 03:19:21 INFO - 'TERM': 'linux', 03:19:21 INFO - 'TMOUT': '86400', 03:19:21 INFO - 'USER': 'cltbld', 03:19:21 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 03:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:21 INFO - '_': '/tools/buildbot/bin/python'} 03:19:22 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:19:22 INFO - Downloading/unpacking pip>=1.5 03:19:22 INFO - Running setup.py egg_info for package pip 03:19:22 INFO - warning: no files found matching 'pip/cacert.pem' 03:19:22 INFO - warning: no files found matching '*.html' under directory 'docs' 03:19:22 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:19:22 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:19:22 INFO - Installing collected packages: pip 03:19:22 INFO - Found existing installation: pip 1.1 03:19:22 INFO - Uninstalling pip: 03:19:22 INFO - Successfully uninstalled pip 03:19:22 INFO - Running setup.py install for pip 03:19:22 INFO - warning: no files found matching 'pip/cacert.pem' 03:19:22 INFO - warning: no files found matching '*.html' under directory 'docs' 03:19:22 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:19:22 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:19:22 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:19:22 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:19:22 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:19:22 INFO - Successfully installed pip 03:19:22 INFO - Cleaning up... 03:19:22 INFO - Return code: 0 03:19:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:22 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 0x103a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1202a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6580>, '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 0x13a7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a7910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:19:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:19:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:19:22 INFO - Using env: {'DISPLAY': ':0', 03:19:22 INFO - 'HOME': '/home/cltbld', 03:19:22 INFO - 'LANG': 'en_US.UTF-8', 03:19:22 INFO - 'LANGUAGE': 'en_US:en', 03:19:22 INFO - 'LOGNAME': 'cltbld', 03:19:22 INFO - 'MAIL': '/var/mail/cltbld', 03:19:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:22 INFO - 'MOZ_NO_REMOTE': '1', 03:19:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:22 INFO - 'NO_EM_RESTART': '1', 03:19:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:22 INFO - 'PWD': '/builds/slave/test', 03:19:22 INFO - 'SHELL': '/bin/bash', 03:19:22 INFO - 'SHLVL': '1', 03:19:22 INFO - 'TERM': 'linux', 03:19:22 INFO - 'TMOUT': '86400', 03:19:22 INFO - 'USER': 'cltbld', 03:19:22 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 03:19:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:22 INFO - '_': '/tools/buildbot/bin/python'} 03:19:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:22 INFO - Downloading/unpacking psutil>=3.1.1 03:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:19:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:19:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:19:23 INFO - Installing collected packages: psutil 03:19:23 INFO - Running setup.py install for psutil 03:19:23 INFO - building 'psutil._psutil_linux' extension 03:19:23 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:19:24 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:19:24 INFO - building 'psutil._psutil_posix' extension 03:19:24 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:19:24 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:19:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:19:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:19:24 INFO - Successfully installed psutil 03:19:24 INFO - Cleaning up... 03:19:24 INFO - Return code: 0 03:19:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:24 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 0x103a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1202a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6580>, '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 0x13a7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a7910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:19:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:19:24 INFO - Using env: {'DISPLAY': ':0', 03:19:24 INFO - 'HOME': '/home/cltbld', 03:19:24 INFO - 'LANG': 'en_US.UTF-8', 03:19:24 INFO - 'LANGUAGE': 'en_US:en', 03:19:24 INFO - 'LOGNAME': 'cltbld', 03:19:24 INFO - 'MAIL': '/var/mail/cltbld', 03:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:24 INFO - 'MOZ_NO_REMOTE': '1', 03:19:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:24 INFO - 'NO_EM_RESTART': '1', 03:19:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:24 INFO - 'PWD': '/builds/slave/test', 03:19:24 INFO - 'SHELL': '/bin/bash', 03:19:24 INFO - 'SHLVL': '1', 03:19:24 INFO - 'TERM': 'linux', 03:19:24 INFO - 'TMOUT': '86400', 03:19:24 INFO - 'USER': 'cltbld', 03:19:24 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 03:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:24 INFO - '_': '/tools/buildbot/bin/python'} 03:19:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:19:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:19:25 INFO - Installing collected packages: mozsystemmonitor 03:19:25 INFO - Running setup.py install for mozsystemmonitor 03:19:25 INFO - Successfully installed mozsystemmonitor 03:19:25 INFO - Cleaning up... 03:19:25 INFO - Return code: 0 03:19:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:19:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:25 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 0x103a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1202a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6580>, '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 0x13a7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a7910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:19:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:19:25 INFO - Using env: {'DISPLAY': ':0', 03:19:25 INFO - 'HOME': '/home/cltbld', 03:19:25 INFO - 'LANG': 'en_US.UTF-8', 03:19:25 INFO - 'LANGUAGE': 'en_US:en', 03:19:25 INFO - 'LOGNAME': 'cltbld', 03:19:25 INFO - 'MAIL': '/var/mail/cltbld', 03:19:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:25 INFO - 'MOZ_NO_REMOTE': '1', 03:19:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:25 INFO - 'NO_EM_RESTART': '1', 03:19:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:25 INFO - 'PWD': '/builds/slave/test', 03:19:25 INFO - 'SHELL': '/bin/bash', 03:19:25 INFO - 'SHLVL': '1', 03:19:25 INFO - 'TERM': 'linux', 03:19:25 INFO - 'TMOUT': '86400', 03:19:25 INFO - 'USER': 'cltbld', 03:19:25 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 03:19:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:25 INFO - '_': '/tools/buildbot/bin/python'} 03:19:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:25 INFO - Downloading/unpacking jsonschema==2.5.1 03:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:19:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:27 INFO - Downloading functools32-3.2.3-2.tar.gz 03:19:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:19:27 INFO - warning: no files found matching '*.txt' 03:19:27 INFO - no previously-included directories found matching 'build' 03:19:27 INFO - no previously-included directories found matching 'dist' 03:19:27 INFO - no previously-included directories found matching '.git*' 03:19:27 INFO - Installing collected packages: jsonschema, functools32 03:19:27 INFO - Running setup.py install for functools32 03:19:27 INFO - warning: no files found matching '*.txt' 03:19:27 INFO - no previously-included directories found matching 'build' 03:19:27 INFO - no previously-included directories found matching 'dist' 03:19:27 INFO - no previously-included directories found matching '.git*' 03:19:27 INFO - Successfully installed jsonschema functools32 03:19:27 INFO - Cleaning up... 03:19:27 INFO - Return code: 0 03:19:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:27 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 0x103a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1202a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6580>, '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 0x13a7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a7910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:19:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:19:27 INFO - Using env: {'DISPLAY': ':0', 03:19:27 INFO - 'HOME': '/home/cltbld', 03:19:27 INFO - 'LANG': 'en_US.UTF-8', 03:19:27 INFO - 'LANGUAGE': 'en_US:en', 03:19:27 INFO - 'LOGNAME': 'cltbld', 03:19:27 INFO - 'MAIL': '/var/mail/cltbld', 03:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:27 INFO - 'MOZ_NO_REMOTE': '1', 03:19:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:27 INFO - 'NO_EM_RESTART': '1', 03:19:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:27 INFO - 'PWD': '/builds/slave/test', 03:19:27 INFO - 'SHELL': '/bin/bash', 03:19:27 INFO - 'SHLVL': '1', 03:19:27 INFO - 'TERM': 'linux', 03:19:27 INFO - 'TMOUT': '86400', 03:19:27 INFO - 'USER': 'cltbld', 03:19:27 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 03:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:27 INFO - '_': '/tools/buildbot/bin/python'} 03:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:19:27 INFO - Cleaning up... 03:19:27 INFO - Return code: 0 03:19:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:27 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 0x103a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1202a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6580>, '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 0x13a7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a7910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:19:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:19:27 INFO - Using env: {'DISPLAY': ':0', 03:19:27 INFO - 'HOME': '/home/cltbld', 03:19:27 INFO - 'LANG': 'en_US.UTF-8', 03:19:27 INFO - 'LANGUAGE': 'en_US:en', 03:19:27 INFO - 'LOGNAME': 'cltbld', 03:19:27 INFO - 'MAIL': '/var/mail/cltbld', 03:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:27 INFO - 'MOZ_NO_REMOTE': '1', 03:19:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:27 INFO - 'NO_EM_RESTART': '1', 03:19:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:27 INFO - 'PWD': '/builds/slave/test', 03:19:27 INFO - 'SHELL': '/bin/bash', 03:19:27 INFO - 'SHLVL': '1', 03:19:27 INFO - 'TERM': 'linux', 03:19:27 INFO - 'TMOUT': '86400', 03:19:27 INFO - 'USER': 'cltbld', 03:19:27 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 03:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:27 INFO - '_': '/tools/buildbot/bin/python'} 03:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:27 INFO - Downloading/unpacking blobuploader==1.2.4 03:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:28 INFO - Downloading blobuploader-1.2.4.tar.gz 03:19:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:19:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:19:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:29 INFO - Downloading docopt-0.6.1.tar.gz 03:19:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:19:29 INFO - Installing collected packages: blobuploader, requests, docopt 03:19:29 INFO - Running setup.py install for blobuploader 03:19:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:19:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:19:29 INFO - Running setup.py install for requests 03:19:29 INFO - Running setup.py install for docopt 03:19:30 INFO - Successfully installed blobuploader requests docopt 03:19:30 INFO - Cleaning up... 03:19:30 INFO - Return code: 0 03:19:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:30 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 0x103a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1202a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6580>, '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 0x13a7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a7910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:19:30 INFO - Using env: {'DISPLAY': ':0', 03:19:30 INFO - 'HOME': '/home/cltbld', 03:19:30 INFO - 'LANG': 'en_US.UTF-8', 03:19:30 INFO - 'LANGUAGE': 'en_US:en', 03:19:30 INFO - 'LOGNAME': 'cltbld', 03:19:30 INFO - 'MAIL': '/var/mail/cltbld', 03:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:30 INFO - 'MOZ_NO_REMOTE': '1', 03:19:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:30 INFO - 'NO_EM_RESTART': '1', 03:19:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:30 INFO - 'PWD': '/builds/slave/test', 03:19:30 INFO - 'SHELL': '/bin/bash', 03:19:30 INFO - 'SHLVL': '1', 03:19:30 INFO - 'TERM': 'linux', 03:19:30 INFO - 'TMOUT': '86400', 03:19:30 INFO - 'USER': 'cltbld', 03:19:30 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 03:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:30 INFO - '_': '/tools/buildbot/bin/python'} 03:19:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:19:30 INFO - Running setup.py (path:/tmp/pip-5Ay6wm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:19:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:19:30 INFO - Running setup.py (path:/tmp/pip-ZCI7eG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:19:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:19:30 INFO - Running setup.py (path:/tmp/pip-68v5tC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:19:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:19:30 INFO - Running setup.py (path:/tmp/pip-gjSxX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:19:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:19:30 INFO - Running setup.py (path:/tmp/pip-BVlny_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:19:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:19:30 INFO - Running setup.py (path:/tmp/pip-79LcDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:19:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:19:30 INFO - Running setup.py (path:/tmp/pip-Zu0C32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:19:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:19:30 INFO - Running setup.py (path:/tmp/pip-h1Ek71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:19:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:19:30 INFO - Running setup.py (path:/tmp/pip-fGJRDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:19:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:19:30 INFO - Running setup.py (path:/tmp/pip-tuBwP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:19:31 INFO - Running setup.py (path:/tmp/pip-mRelXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:19:31 INFO - Running setup.py (path:/tmp/pip-Hk_U35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:19:31 INFO - Running setup.py (path:/tmp/pip-B9la8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:19:31 INFO - Running setup.py (path:/tmp/pip-hiiG0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:19:31 INFO - Running setup.py (path:/tmp/pip-tBnggx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:19:31 INFO - Running setup.py (path:/tmp/pip-nsbabS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:19:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:19:31 INFO - Running setup.py (path:/tmp/pip-NTMf0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:19:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:19:31 INFO - Running setup.py install for manifestparser 03:19:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:19:31 INFO - Running setup.py install for mozcrash 03:19:31 INFO - Running setup.py install for mozdebug 03:19:31 INFO - Running setup.py install for mozdevice 03:19:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:19:31 INFO - Running setup.py install for mozfile 03:19:32 INFO - Running setup.py install for mozhttpd 03:19:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:19:32 INFO - Running setup.py install for mozinfo 03:19:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:19:32 INFO - Running setup.py install for mozInstall 03:19:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:19:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:19:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:19:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:19:32 INFO - Running setup.py install for mozleak 03:19:32 INFO - Running setup.py install for mozlog 03:19:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:19:32 INFO - Running setup.py install for moznetwork 03:19:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:19:32 INFO - Running setup.py install for mozprocess 03:19:32 INFO - Running setup.py install for mozprofile 03:19:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:19:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:19:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:19:32 INFO - Running setup.py install for mozrunner 03:19:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:19:32 INFO - Running setup.py install for mozscreenshot 03:19:33 INFO - Running setup.py install for moztest 03:19:33 INFO - Running setup.py install for mozversion 03:19:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:19:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:19:33 INFO - Cleaning up... 03:19:33 INFO - Return code: 0 03:19:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:19:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:33 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 0x103a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1202a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6580>, '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 0x13a7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a7910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:19:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:19:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:19:33 INFO - Using env: {'DISPLAY': ':0', 03:19:33 INFO - 'HOME': '/home/cltbld', 03:19:33 INFO - 'LANG': 'en_US.UTF-8', 03:19:33 INFO - 'LANGUAGE': 'en_US:en', 03:19:33 INFO - 'LOGNAME': 'cltbld', 03:19:33 INFO - 'MAIL': '/var/mail/cltbld', 03:19:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:33 INFO - 'MOZ_NO_REMOTE': '1', 03:19:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:33 INFO - 'NO_EM_RESTART': '1', 03:19:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:33 INFO - 'PWD': '/builds/slave/test', 03:19:33 INFO - 'SHELL': '/bin/bash', 03:19:33 INFO - 'SHLVL': '1', 03:19:33 INFO - 'TERM': 'linux', 03:19:33 INFO - 'TMOUT': '86400', 03:19:33 INFO - 'USER': 'cltbld', 03:19:33 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 03:19:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:33 INFO - '_': '/tools/buildbot/bin/python'} 03:19:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:19:33 INFO - Running setup.py (path:/tmp/pip-S0hi8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:19:33 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:19:33 INFO - Running setup.py (path:/tmp/pip-wMIgoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:19:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:19:33 INFO - Running setup.py (path:/tmp/pip-M95AvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:19:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:19:33 INFO - Running setup.py (path:/tmp/pip-QFFnEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:19:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:19:33 INFO - Running setup.py (path:/tmp/pip-uMchku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:19:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:19:33 INFO - Running setup.py (path:/tmp/pip-T5wrcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:19:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:19:33 INFO - Running setup.py (path:/tmp/pip-2jHO_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:19:34 INFO - Running setup.py (path:/tmp/pip-dRsEWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:19:34 INFO - Running setup.py (path:/tmp/pip-Mg7Zqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:19:34 INFO - Running setup.py (path:/tmp/pip-JKT77F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:19:34 INFO - Running setup.py (path:/tmp/pip-eycFDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:19:34 INFO - Running setup.py (path:/tmp/pip-qHMFE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:19:34 INFO - Running setup.py (path:/tmp/pip-HPqSrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:19:34 INFO - Running setup.py (path:/tmp/pip-6Fgpjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:19:34 INFO - Running setup.py (path:/tmp/pip-9FtXtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:19:34 INFO - Running setup.py (path:/tmp/pip-V2CGAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:19:34 INFO - Running setup.py (path:/tmp/pip-BlB3Sk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:19:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:35 INFO - Downloading blessings-1.6.tar.gz 03:19:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:19:36 INFO - Installing collected packages: blessings 03:19:36 INFO - Running setup.py install for blessings 03:19:36 INFO - Successfully installed blessings 03:19:36 INFO - Cleaning up... 03:19:36 INFO - Return code: 0 03:19:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:19:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:19:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:19:36 INFO - Reading from file tmpfile_stdout 03:19:36 INFO - Current package versions: 03:19:36 INFO - argparse == 1.2.1 03:19:36 INFO - blessings == 1.6 03:19:36 INFO - blobuploader == 1.2.4 03:19:36 INFO - docopt == 0.6.1 03:19:36 INFO - functools32 == 3.2.3-2 03:19:36 INFO - jsonschema == 2.5.1 03:19:36 INFO - manifestparser == 1.1 03:19:36 INFO - mozInstall == 1.13 03:19:36 INFO - mozcrash == 1.0 03:19:36 INFO - mozdebug == 0.1 03:19:36 INFO - mozdevice == 0.51 03:19:36 INFO - mozfile == 1.2 03:19:36 INFO - mozhttpd == 0.7 03:19:36 INFO - mozinfo == 0.10 03:19:36 INFO - mozleak == 0.1 03:19:36 INFO - mozlog == 3.5 03:19:36 INFO - moznetwork == 0.27 03:19:36 INFO - mozprocess == 0.25 03:19:36 INFO - mozprofile == 0.28 03:19:36 INFO - mozrunner == 6.13 03:19:36 INFO - mozscreenshot == 0.1 03:19:36 INFO - mozsystemmonitor == 0.3 03:19:36 INFO - moztest == 0.8 03:19:36 INFO - mozversion == 1.4 03:19:36 INFO - psutil == 3.1.1 03:19:36 INFO - requests == 1.2.3 03:19:36 INFO - wsgiref == 0.1.2 03:19:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:36 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 0x103a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1202a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6580>, '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 0x13a7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a7910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:19:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:19:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:19:36 INFO - Using env: {'DISPLAY': ':0', 03:19:36 INFO - 'HOME': '/home/cltbld', 03:19:36 INFO - 'LANG': 'en_US.UTF-8', 03:19:36 INFO - 'LANGUAGE': 'en_US:en', 03:19:36 INFO - 'LOGNAME': 'cltbld', 03:19:36 INFO - 'MAIL': '/var/mail/cltbld', 03:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:36 INFO - 'MOZ_NO_REMOTE': '1', 03:19:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:36 INFO - 'NO_EM_RESTART': '1', 03:19:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:36 INFO - 'PWD': '/builds/slave/test', 03:19:36 INFO - 'SHELL': '/bin/bash', 03:19:36 INFO - 'SHLVL': '1', 03:19:36 INFO - 'TERM': 'linux', 03:19:36 INFO - 'TMOUT': '86400', 03:19:36 INFO - 'USER': 'cltbld', 03:19:36 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 03:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:36 INFO - '_': '/tools/buildbot/bin/python'} 03:19:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:19:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:19:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:19:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:19:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:19:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:19:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:19:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:19:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:19:36 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:19:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:19:37 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:19:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:19:37 INFO - Installing collected packages: simplejson 03:19:37 INFO - Running setup.py install for simplejson 03:19:37 INFO - building 'simplejson._speedups' extension 03:19:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:19:38 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 03:19:38 INFO - Successfully installed simplejson 03:19:38 INFO - Cleaning up... 03:19:38 INFO - Return code: 0 03:19:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:19:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:19:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:19:38 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 0x103a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1202a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13a9b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13a6580>, '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 0x13a7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a7910>, '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': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:19:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:19:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:19:38 INFO - Using env: {'DISPLAY': ':0', 03:19:38 INFO - 'HOME': '/home/cltbld', 03:19:38 INFO - 'LANG': 'en_US.UTF-8', 03:19:38 INFO - 'LANGUAGE': 'en_US:en', 03:19:38 INFO - 'LOGNAME': 'cltbld', 03:19:38 INFO - 'MAIL': '/var/mail/cltbld', 03:19:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:38 INFO - 'MOZ_NO_REMOTE': '1', 03:19:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:38 INFO - 'NO_EM_RESTART': '1', 03:19:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:38 INFO - 'PWD': '/builds/slave/test', 03:19:38 INFO - 'SHELL': '/bin/bash', 03:19:38 INFO - 'SHLVL': '1', 03:19:38 INFO - 'TERM': 'linux', 03:19:38 INFO - 'TMOUT': '86400', 03:19:38 INFO - 'USER': 'cltbld', 03:19:38 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 03:19:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:38 INFO - '_': '/tools/buildbot/bin/python'} 03:19:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:19:39 INFO - Cleaning up... 03:19:39 INFO - Return code: 0 03:19:39 INFO - Running post-action listener: _resource_record_post_action 03:19:39 INFO - Running post-action listener: _start_resource_monitoring 03:19:39 INFO - Starting resource monitoring. 03:19:39 INFO - [mozharness: 2017-08-30 10:19:39.070107Z] Finished create-virtualenv step (success) 03:19:39 INFO - [mozharness: 2017-08-30 10:19:39.070627Z] Running install step. 03:19:39 INFO - Running pre-action listener: _resource_record_pre_action 03:19:39 INFO - Running main action method: install 03:19:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:19:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:19:39 INFO - Reading from file tmpfile_stdout 03:19:39 INFO - Detecting whether we're running mozinstall >=1.0... 03:19:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:19:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:19:39 INFO - Reading from file tmpfile_stdout 03:19:39 INFO - Output received: 03:19:39 INFO - Usage: mozinstall [options] installer 03:19:39 INFO - Options: 03:19:39 INFO - -h, --help show this help message and exit 03:19:39 INFO - -d DEST, --destination=DEST 03:19:39 INFO - Directory to install application into. [default: 03:19:39 INFO - "/builds/slave/test"] 03:19:39 INFO - --app=APP Application being installed. [default: firefox] 03:19:39 INFO - mkdir: /builds/slave/test/build/application 03:19:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:19:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:19:55 INFO - Reading from file tmpfile_stdout 03:19:55 INFO - Output received: 03:19:55 INFO - /builds/slave/test/build/application/firefox/firefox 03:19:55 INFO - Running post-action listener: _resource_record_post_action 03:19:55 INFO - [mozharness: 2017-08-30 10:19:55.754510Z] Finished install step (success) 03:19:55 INFO - [mozharness: 2017-08-30 10:19:55.754734Z] Running setup-mitmproxy step. 03:19:55 INFO - Running pre-action listener: _resource_record_pre_action 03:19:55 INFO - Running main action method: setup_mitmproxy 03:19:55 INFO - Skipping: mitmproxy is not required 03:19:55 INFO - Running post-action listener: _resource_record_post_action 03:19:55 INFO - [mozharness: 2017-08-30 10:19:55.755256Z] Finished setup-mitmproxy step (success) 03:19:55 INFO - [mozharness: 2017-08-30 10:19:55.755358Z] Running run-tests step. 03:19:55 INFO - Running pre-action listener: _resource_record_pre_action 03:19:55 INFO - Running pre-action listener: _set_gcov_prefix 03:19:55 INFO - Running main action method: run_tests 03:19:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:19:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:19:55 INFO - Python 2.7.3 03:19:55 INFO - Return code: 0 03:19:55 INFO - grabbing minidump binary from tooltool 03:19:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:19:55 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 0x13a6580>, '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 0x13a7480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13a7910>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:19:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:19:55 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:19:55 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:19:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:19:55 INFO - Return code: 0 03:19:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:19:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:19:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:19:55 INFO - ENV: RUST_BACKTRACE is now full 03:19:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:19:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:19:55 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/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:19:55 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/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-057 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:19:55 INFO - Using env: {'DISPLAY': ':0', 03:19:55 INFO - 'HOME': '/home/cltbld', 03:19:55 INFO - 'LANG': 'en_US.UTF-8', 03:19:55 INFO - 'LANGUAGE': 'en_US:en', 03:19:55 INFO - 'LOGNAME': 'cltbld', 03:19:55 INFO - 'MAIL': '/var/mail/cltbld', 03:19:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:19:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:19:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:19:55 INFO - 'MOZ_NO_REMOTE': '1', 03:19:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:19:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:19:55 INFO - 'NO_EM_RESTART': '1', 03:19:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:19:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:19:55 INFO - 'PWD': '/builds/slave/test', 03:19:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:19:55 INFO - 'RUST_BACKTRACE': 'full', 03:19:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:19:55 INFO - 'SHELL': '/bin/bash', 03:19:55 INFO - 'SHLVL': '1', 03:19:55 INFO - 'TERM': 'linux', 03:19:55 INFO - 'TMOUT': '86400', 03:19:55 INFO - 'USER': 'cltbld', 03:19:55 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 03:19:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:19:55 INFO - '_': '/tools/buildbot/bin/python'} 03:19:55 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/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:19:56 INFO - mozversion application_buildid: 20170830090533 03:19:56 INFO - mozversion application_changeset: ab2d700fda2b4934d24227216972dce9fac19b74 03:19:56 INFO - mozversion application_display_name: Nightly 03:19:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:19:56 INFO - mozversion application_name: Firefox 03:19:56 INFO - mozversion application_remotingname: firefox 03:19:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:19:56 INFO - mozversion application_vendor: Mozilla 03:19:56 INFO - mozversion application_version: 57.0a1 03:19:56 INFO - mozversion platform_buildid: 20170830090533 03:19:56 INFO - mozversion platform_changeset: ab2d700fda2b4934d24227216972dce9fac19b74 03:19:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:19:56 INFO - mozversion platform_version: 57.0a1 03:19:56 INFO - using testdate: 1504088396 03:19:56 INFO - actual date: 1504088396 03:19:56 INFO - starting webserver on 'localhost:41015' 03:19:56 INFO - SUITE-START | Running 2 tests 03:19:56 INFO - TEST-START | dromaeo_css 03:19:56 INFO - Initialising browser for dromaeo_css test... 03:19:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41015/getInfo.html -profile /tmp/tmpa7q49b/profile 03:19:56 INFO - TEST-INFO | started process 28848 (/builds/slave/test/build/application/firefox/firefox http://localhost:41015/getInfo.html) 03:20:01 INFO - TEST-INFO | 28848: exit 0 03:20:01 INFO - Browser initialized. 03:20:01 INFO - Running cycle 1/1 for dromaeo_css test... 03:20:01 INFO - Using env: {'DISPLAY': ':0', 03:20:01 INFO - 'HOME': '/home/cltbld', 03:20:01 INFO - 'JSGC_DISABLE_POISONING': '1', 03:20:01 INFO - 'LANG': 'en_US.UTF-8', 03:20:01 INFO - 'LANGUAGE': 'en_US:en', 03:20:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:20:01 INFO - 'LOGNAME': 'cltbld', 03:20:01 INFO - 'MAIL': '/var/mail/cltbld', 03:20:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:20:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:20:01 INFO - 'MOZ_CRASHREPORTER': '1', 03:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:20:01 INFO - 'MOZ_NO_REMOTE': '1', 03:20:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:20:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:20:01 INFO - 'NO_EM_RESTART': '1', 03:20:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:01 INFO - 'PWD': '/builds/slave/test', 03:20:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:20:01 INFO - 'RUST_BACKTRACE': 'full', 03:20:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:20:01 INFO - 'SHELL': '/bin/bash', 03:20:01 INFO - 'SHLVL': '1', 03:20:01 INFO - 'TERM': 'linux', 03:20:01 INFO - 'TMOUT': '86400', 03:20:01 INFO - 'USER': 'cltbld', 03:20:01 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 03:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:01 INFO - '_': '/tools/buildbot/bin/python'} 03:20:01 INFO - TEST-INFO | started process 29032 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa7q49b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:20:02 INFO - PID 29032 | 03:20:02 INFO - PID 29032 | (/builds/slave/test/build/application/firefox/firefox:29084): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:20:02 INFO - PID 29032 | 03:20:12 INFO - PID 29032 | RSS: Main: 163700736 03:20:12 INFO - PID 29032 | 03:23:11 INFO - PID 29032 | Cycle 1(1): loaded http://localhost:41015/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41015/tests/dromaeo/cssquery-ext.html) 03:23:13 INFO - PID 29032 | RSS: Main: 165396480 03:23:13 INFO - PID 29032 | 03:26:14 INFO - PID 29032 | Cycle 1(1): loaded http://localhost:41015/tests/dromaeo/cssquery-ext.html (next: http://localhost:41015/tests/dromaeo/cssquery-jquery.html) 03:26:15 INFO - PID 29032 | RSS: Main: 161734656 03:26:15 INFO - PID 29032 | 03:29:16 INFO - PID 29032 | Cycle 1(1): loaded http://localhost:41015/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41015/tests/dromaeo/cssquery-mootools.html) 03:29:17 INFO - PID 29032 | RSS: Main: 164323328 03:29:17 INFO - PID 29032 | 03:32:19 INFO - PID 29032 | Cycle 1(1): loaded http://localhost:41015/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41015/tests/dromaeo/cssquery-prototype.html) 03:32:19 INFO - PID 29032 | RSS: Main: 162816000 03:32:19 INFO - PID 29032 | 03:35:23 INFO - PID 29032 | Cycle 1(1): loaded http://localhost:41015/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41015/tests/dromaeo/cssquery-yui.html) 03:35:23 INFO - PID 29032 | RSS: Main: 163954688 03:35:23 INFO - PID 29032 | 03:38:25 INFO - PID 29032 | Cycle 1(1): loaded http://localhost:41015/tests/dromaeo/cssquery-yui.html (next: http://localhost:41015/tests/dromaeo/cssquery-dojo.html) 03:38:25 INFO - PID 29032 | RSS: Main: 165019648 03:38:25 INFO - PID 29032 | 03:38:25 INFO - PID 29032 | __start_tp_report 03:38:25 INFO - PID 29032 | _x_x_mozilla_page_load 03:38:25 INFO - PID 29032 | _x_x_mozilla_page_load_details 03:38:25 INFO - PID 29032 | |i|pagename|runs| 03:38:25 INFO - PID 29032 | |0;dojo.html;3843.7885916274613;3862.4978752762136;3883.145707944252;3888.2612303662318;3927;6470.126532918055;6557.606543607387;6571.769988050419;6576.539642225046;6680.231773346067;6708.429783468411;6772.72909083636;6782.830429239271;6791.456683465311;6797.422219111381;6006.129111278862;6031.909521357183;6032.245969253843;6072.635641861502;6109.969450152743;6141.232345956755;6147.508199344064;6169.691515424233;6190.84522886927;6211.440970312671;930.5090514602256;6020.337762846083;6046.425589568984;6112.113743507211;6168.722407491674;6017.037274036177;6017.969910150465;6029.487493563052;6104.755809767604;6138.539609529258;5951.137085122633;5963.314218864799;6050.818475445743;6064.484518815906;6065.150878876961;6730.730770769163;6805.740937926499;6870.484713646447;6895.034695142684;6906.343897329745;7333.230010848837;7379.298966598165;7388.926110738931;7485.1017877855;7485.251474852471;7385.150707668633;7467.925320746777;7488.663010164501;7550.73572424968;7560.584167870765;6855.245922948472;6858.005589189589;6859.828504287383;6863.828404289933;6864;7570.318671319607;7590.354819840265;7593.810154746065;7647.197044310378;7688.577128257888;7136.393406560397;7229.638518074075;7310.634468276565;7316.743913962986;7340.779776606711;7351.154617219088;7362.484626076123;7386.335229829341;7448.366888814403;7488.363489103487;7231.2768723127265;7237.5295605786505;7342.045534080642;7355.926440735524;7365.300296471826;7307.853842923112;7329.52358096722;7343.265673432721;7353.705851765976;7355.1541572719825;6169.321374648785;6208.875822483616;6208.906866397007;6306.432421082125;6321.494280457552;5263.789448422097;5335.653182543122;5356.035913535523;5357.491038351351;5358.651687640291;6201.162843016225;6255.0617407388345;6264.37356264379;6281.528885333618;6291.937080629135;7129.928700713031;7290.270972902667;7318.56088634673;7390.187079421259;7425.517341372793;6217.160683307786;6277.435030847154;6278.623282603058;6292.370762923671;6354.332795056545;6370.331115232926;6573.5069869759;6636.568623039487;6638.634875081917;6648.933510664928;7056.117985251741;7064.32888875556;7127.4298056155385;7141.214466408691;7145.606991615511;7190.42476601881;7213.639318034182;7297.781066568012;7304.233055529306;7315.890261646186;7032;7037.577745335297;7063.57618542889;7113.75101871432;7133.678984445816;7222.96388518054;7227.710891564278;7296.817579560554;7305.050343455317;7321.63391830417;7304.452166087671;7355.190928997913;7374.373178279906;7425.628718563942;7441.032665753418;6933.5146539743755;6954.3393377629045;7037.894431583428;7050.5769653820935;7061.8940715888275;6923.273056329214;6943.9652801735665;7014.894776578458;7025.332593403618;7044.260352662896;7050.717971281297;7098.36114749675;7101.928980710127;7107.395871350786;7115.146182457931;6983.580985140908;7112.0754301940415;7177.7487787927175;7180.712771489082;7193.244709305448;6859.279775623488;6861.4853885957755;6881.036654868422;6962.965185174042;6971.477139214478 03:38:25 INFO - PID 29032 | |1;ext.html;917.3945196170529;958.6548842416734;988.6786794291854;1015.431358439274;1020.9234307426944;43478.565214347844;43996;44837.77581112094;44839.77580112099;44884;44029.119417611626;44677.32984005242;45089.32366014504;45104;45105.774471127595;7954.085280192794;8202.58987050645;8211.425200235955;8215.424920255584;8217.835643287131;8069.798255043612;8130.471519351253;8198.795030124265;8200.01599808021;8206;8195.79510512236;8205.548694821813;8219.876701849476;8221.17788221179;8246.958765206165;8067.112617612058;8179.79550511239;8197.95901020497;8198.713045043425;8205.179482051746;8017.799555011113;8077.27304542594;8086.838263234761;8087.272145506874;8093.069297030799;39503.40744888829;39902.60097399018;39917.20165596672;39961.80019099886;39971.20057598861;3798.582155962842;3804.9619503804884;3808.9428658570146;3811.7712937223855;3823.6941044716355;32575.51136732951;32579;32657.836710816293;32821.17947051319;32831.179220519676;5423.159410291411;5508.14623733322;5524.779008839641;5546.944530554683;5571.4150014248735;205604;207751;210645;210730;211144.94427527918;51623.225651615256;51977.7401112992;52012;52076.739616301675;52107.47892521102;54421.72789136108;54508.72745636326;54518.182227266625;54609;54703.2648367588;15917.840821591868;15983.36066557348;16087.67824643524;16131.516054518383;16140.919295403448;13196.868031319756;13281.26953017574;13292.335383230993;13312.733745325038;13319.533816316383;14690.55928322152;14782.704345913022;14799.778003329959;14892.329845156994;14961.775573366409;5466.835994920159;5542.140968149944;5548.223248745179;5551.417101204396;5572.693501857356;1109.2290857853875;1109.3399427340719;1123.7808627317759;1127.8928501792316;1131.5926266544038;25713.87143064273;25788;25814.483710325687;25963.350916226867;25981.870090649427;1189.2685998111272;1196.7486827766268;1199.7060720123452;1211.0735287505065;1227.3372378915465;13546.86453135456;13623.659408514866;13629.43870848512;13802.861971380158;13817.516386926409;245492;245545;246066;246601;246851;55406.445935540934;55535.16697249545;61508.69245653743;62578.687106564175;62680.05979910305;1508.0517588115426;1548.442560678155;1552.2394026926725;1560.719070567286;1570.4424929150216;30636.540451893685;30903.845480772452;31178.688213118487;31187.064388068393;31341;41285.79357103195;41314.79342603388;41378.586214138675;41382.793086035585;41405.58594414017;162028;162234.18882905509;162456;162622;162846.1857690704;35663.10842228965;41846;42529.14941701211;42804.5719542813;42818.78590607027;51904.48095518997;51936;52014;52354.47645523496;52721;36424;37201.06997325089;37386.252274954895;37510.24979500449;37994.24011519699;37747.245055099294;37884.43173352457;37922.62077379191;37970.620293797816;38279.42580861345;44390.11219775651;45779.54220457753;46027.53972460233;46269.30596040995;46448.767756161;36251.81874090613;36670;36675;36792.81603591965;36835;25725.614115788652;25903.352416189748;26307.4738505225;27517.587236191874;28425.43149137047 03:38:25 INFO - PID 29032 | |2;jquery.html;603.4779915373203;606.357261303019;608.6561092982467;614.2383444528786;626.3141859663667;22710.205142820003;23313.300600981955;24083.75916240837;24136.275911722656;24176.274711758677;21546.89226553869;21873.562528749455;26776.330591735168;26830.06094786683;26930;6802.761903333372;6950.965245173792;6957.199922008957;6959.30406959305;6962.303769623048;6333.461655759266;6574.441172500343;6867.862642747142;6919.619420931846;6919.965400172992;6578.046183303418;6582.3746744059235;6583.9012414813815;6588.538802283842;6591.538592298541;6537.509686773487;6567.850626140406;6568.3103274156;6572.704228309737;6581.144451221335;6139.938600613981;6141.570090093695;6150.96924515379;6163.3220345762375;6171.691415429211;29851.552226716616;30308;30555.388892222254;30636.693633063605;30725.846370768228;2257.9887100564556;2296.632538793785;2307.273208939179;2308.995586919695;2320;20300.086496107564;20521.897390513102;21381.786182138134;21397.67903481449;21410.5717885643;2521.6595759572588;2528.6333481645243;2585.61215817627;2591.3521619595103;2593.4164812916943;120101.39949300197;124528;149741;154634.99999999886;155900;16272.83727162737;16613.58466038359;16706.749398759028;16808.243629036722;16840.494785156465;25901;25915;25927.611085833727;25955;26012.479750405266;10904.182186336056;10978.451077446096;11060.944695276472;11125.221234513507;11162.162837787198;9759.560819762986;9817.116459518535;9950.751231219278;9957.10386065243;10035.09684128432;10270.794584108276;10421.895781042243;10430.478476076165;10438.321509101885;10495.895041049493;2930.60436841028;2940.5736168255485;2965.4365670522534;2995.970040299569;3020.7130322619314;1017.1760873692333;1047.8218702820539;1068.1988508618535;1071.2180108520824;1078.5308390849848;21035.894820526104;21332.573348532944;21737.456563586038;21823.890880545496;21868;1111.5998240633367;1126.301692950376;1133.9489722962487;1134.0813940708053;1139.9943000285111;11659.766804664028;11947.880521194851;11990.580329688419;11991;12025.398730063462;592680;628407;630275;634813;642075;123917.38041309916;124287;124873.37563312125;125158;125687.3715631416;1235.7899157143306;1263.728298415845;1267.2459886367596;1268.3024336614824;1270.060155484953;18932.621347572695;19010.619787604446;19063.809361906202;19247.615047698688;19431.902840485705;60813;61935.690321549904;62529.68735156477;63065.684671576346;63483.36516634774;77918.22081779336;77926.6103669478;78885.60557197177;80373;84467.57766211375;15813.209339533207;16159.43441979525;16182.433614823425;16189.67620647557;16236.594085147966;24503.63244551369;24524.14165504199;24541.263762087165;24688.50622987566;24750.628740569267;19995.300164494172;20091.39725808228;20419.69370459474;20606.690899636218;20606.79393206049;17400.216990235218;17484.30062797474;17909.731354029438;18051.819481805014;18108.00405977684;18620.348287809862;18854.717179242598;18921.243150274386;20122.39632811018;20477;15938.203089845229;15964;15979.440719574759;16012.599685007968;16037.278322475284;19722.211111555378;19741.60516789685;19878.801211988273;19891;20009.599808004048 03:38:25 INFO - PID 29032 | |3;mootools.html;1428.0788891165198;1477.1284941884287;1480.3190532355115;1486.2865824404287;1505.1195050895233;16522.42171523997;16620.66758664829;17163.82836171638;17169.7424538632;17188.484345469642;17194.226259818308;17431.128443577803;17458.476245712616;17469.7379539307;17471.3011479541;2860.2706309891;2913.70862913708;2914.985425072882;2916.562515622663;2920.123962811159;2878.3667593129585;2969.9257518561994;2973.732364087232;2974.0780358088955;2975.747061499775;2906.1717410538;2910.723481269276;2915.7375836174742;2917.9603089838865;2934.779891508135;2876.5685147227873;2886.740193382595;2888.234617826278;2889.248795313223;2893.73956343929;2714;2729.931751706223;2744.1218809980815;2748.876300566459;2750.5049091163582;16413;16684.916575417166;16702;16710.248038838283;16731.581710457212;8530.573471326408;8891.644334226623;8908.331875109328;8911.821763564758;8915.821683566357;5446.8910621787345;5508.669479831223;5635.09838425854;5644.858878528029;5848.912266316009;2203.790639889208;2206.3380985704384;2213.247495851402;2229.4314949687887;2259.2544460328054;30488.08535743931;30698.386032279454;30723.2319192022;30729.846350768326;30821.22946926345;7362.77911662651;7641.732539361207;7747.690092396354;7974.122846486882;8055.677772889136;22449.551008979728;22471.213507527158;22529.88735056314;22566.661500077513;22662.546749064928;8699.782505437288;8942.955285223532;8954.373193876496;8955.41789783662;8957.14907083826;7900;7930.762077137695;8040.2361775631225;8065.919340806517;8069.354451643854;5977.491913187341;6182.938170618237;6222;6253.405926436982;6283.120363149072;1847.9353222637412;1897.9240830366907;1903.1150515010586;1946.7566554180728;1947.269773834812;960.8943016268205;977.3549457358109;985.7634167799677;1004.2719028704131;1007.8185926533295;8595.742127736177;8782.385233033627;8805.213758084084;8948.463092214488;9008.054154313833;786.6027656033666;796.223681910133;796.4544287163196;797.330242596222;810.1210186947228;5827.970860145672;5906.49794767441;5976.880462390728;6123.020316749299;6242.937570624326;22895.427614309443;23537.88231058834;23672.526549468916;24060.639090413657;24113;23241.651375229387;23499;23877.52244955091;23932.7606723934;23946.04215831382;12695.809562856564;12767.553135640208;12874.16317939331;12923.935380323226;12931.870681293067;9151.725448236564;9168.266538677024;9205.677801276923;9230.584623691815;9302;20598.897005515475;20606.690899636218;20667.793322066587;21177.894110529964;21200.68198976986;20992.265270715452;21495;21730.565388691823;21955;22368.440788980406;13272.203667779964;13373.66565835862;13608;13655.590332290047;13657.112287701268;15403.922980385474;15616.92191539035;15618.843811562194;15628.140452275235;15640.530784076496;12385.256884586954;12434.689132771753;12489.875101248872;12537.498500059895;12607.68480787951;11813.822792657946;11994.340311282964;12000.39998000079;12122.757544848877;12212.32832194238;10678.679639610824;10929.508172132115;11131.944340278247;11144.665660030212;11191.32852028881;8780.824383512421;8811.339149563937;8831.116888311117;8973.102689730975;9014.143656352637;8727.258183054511;8767.780805479913;8772.736817895473;8829.514376709085;8878.200961913459 03:38:25 INFO - PID 29032 | |4;prototype.html;536.6216817143911;553.2061491759325;566.2808233543398;578;580.1240127407615;6467.22393312802;6695.564788288758;6696.129503164594;6730.427913627342;6759.155105611799;6551.279359270488;6616.834579135524;6686.8662626747555;6687.297833727462;6769.35994961964;5521.696306703129;5562.916556251659;5567.610267281292;5578.721063946787;5594.13290939904;5391.595630327722;5534.335879694442;5559.58303127265;5590.133529302964;5592.3009623797025;5486.725663716818;5502.889942201174;5553.888922221553;5565.679309432404;5585.636933599303;5503.229547863302;5536.806211782568;5537.224788529589;5542.5565954723525;5552.167174923753;5207.427183009866;5234.9476505235225;5243.737813109329;5254.947450525484;5257.500537448975;6685.765998190089;6707.329267073303;6722.798316050526;6730.476071473197;6734.865302693918;6531.706073226716;6571.080048793173;6582.407583317525;6583.835404114935;6639.634820084893;7050.224475307763;7121.148501389676;7133.643317834088;7159.24827893069;7160.10498687664;5746.597738158331;5771.769129234867;5791.247137872092;5806.738696758656;5827.3298570664065;7221.27787221279;7285.4171666266575;7303.160136584254;7334.339909408125;7556.584387858666;5921.822345329647;5937.2578427696535;5945.483901605149;5974.671393073336;6004.219451471281;6007.669578173242;6009.699515024318;6075.362086980892;6084.756609735561;6127.509799216052;6535.607863528204;6537.836554086186;6564.442022428051;6587.80236592903;6631.092991186487;7006.404455621329;7033.4724895633035;7111.2533184015965;7111.857762844714;7122.893156602655;2087.9060442280133;2121.1621409543145;2131.8720876747134;2145.1419432227235;2147.76374598794;1540.9906511235158;1541.2248681847032;1591.7214487464648;1596.2816732470203;1603.1743652018986;6101.450869421773;6115.388461153849;6135.0797380392405;6145.139680444741;6253.874922501524;7153.533525627234;7255.419566434673;7260.2013778484325;7272.418206543463;7285.890711639329;6154;6193.544517038492;6202;6214.627122372672;6223.564350495512;3748.194138260287;3782.0333426618004;3831.63599458051;3849.8075096245075;3851.1912498375095;6954.40887524556;6999.965000174967;7017.614031228331;7024.683889224895;7108.964455177795;7318.890216646754;7353.374963128194;7377.520461170114;7417.962910185414;7440.218777028443;6878.8624227514165;6895.4138898194215;6899.6205208713;6995.440364770806;7014.614196219257;6964.233934267125;7007.334303241184;7089.837599531986;7132.179799323144;7180.281971802778;7056.7771152086925;7069.469789765685;7078.894241339907;7155.3202445767565;7199.660110464225;7012.894806577803;7056.6712984713895;7100.183478899992;7101.360877521048;7104.644767761694;5963.318794746104;5978.730957106854;5991.131285963424;6037.641530655743;6079.118527813531;6986.685599147949;7031.718731250693;7120.53716508446;7214.71141154348;7259.390287167792;6896.620685862325;6915.446764258948;6947.68735406918;6978.895316570357;6992.300769922967;6935.063766391472;6964.686589103401;6967.233604303421;6984.930150698631;7002.4398048157045;6942.201646810479;6964.303569642995;6975.232724400211;6987.510874238755;7019.227884932551;7042.577445353295;7072.611006394698;7072.929270707227;7075.752348667772;7082.8313328300255;6871.656417179221;6886.139232595926;6887.139107611549;6890.20762612306;6909.343612356818 03:38:25 INFO - PID 29032 | |5;yui.html;969.4377261188512;994.6618149829059;1007.637250589788;1018.0989824005762;1030.2170350533593;19382.321618743346;19988.90005549974;20080.397588072345;20119.59760804783;23295.883520582414;21992.8900355498;22275.777242227574;22574.43563910903;22577.887110564465;22591.774082259173;1064.7604289034964;1088.1892989722667;1088.820344643133;1089.1069323155016;1093.0326660905077;1041.9635312764055;1104.6244276945836;1105.5633024955139;1108.9500972456258;1110.5557776889227;1159.7100724818795;1168.5676299769098;1169.216624861345;1169.462047458166;1169.5964892112208;1160.5067846165389;1163.3892206591522;1165.2717051842599;1165.592042785027;1167.078008373384;851.1871163039283;853.7054716122927;853.8292341531718;854.4574195385912;854.8546747053016;17960.281588736434;18479.260829566934;18585.535361615934;18622.813771862242;20145.8992705037;21002.264920727855;21743.456413589785;22628;23119.76880231193;23125.537489250288;17553.473395798148;17718.468445946513;17807.19867605961;17871.195796189204;18061.458156255332;4117.464729585165;4137.586241375869;4141.5237247716295;4144.544100148981;4145.7719825409595;148297;150379.9999999989;150629;150673;151278;17049;17116.914415427844;17501.124943753017;17667.381641642736;17683.823161768218;32897.67102328946;32976.3404731904;33594.496082558784;33624.1593960153;34018.31963360769;8646.956765216133;9010.684626038057;9476.668316609024;9602.135807777333;9930.553125109387;4769.42766867978;4857.9028419431415;4863.7324947128245;4873.658843880965;4892.535209155196;5905.084711869667;6018.337982821886;6024.4879185268865;6088.391160883876;6116.052011938156;1237.1587320622064;1244.9190802597802;1263.943122559487;1268.0806415348798;1272.9745405091846;744.6797876912871;745.9328660420588;762.2758379539459;766.1916677904863;780.7618676303782;13089;13183.802242966363;13384.732305354033;13878.306084695725;13917.234552099731;746.9327760501473;763.2520130272364;765.6707615725292;767.7005967672612;770.4007077745848;6273.811785646438;6296.244450665858;6380.521460890452;6420.614763114135;6499.545031847725;126855.36572317079;128043;128269;131600.34199829132;135325;117727.41136294264;118216;118523;119354.4032279833;123689;102567.4871625637;105285;107219.46390268154;108125;109481.45259273812;13917.58247252584;13923.443062277396;13959.511417100353;14106.36521356562;14408.783868242195;49690;50866.23700644419;50975.49024509708;53526;53643.19535207053;84999.57500212459;85402;85938.7109193375;87788.56105719431;88304.11695882959;18429.354972575897;20290.59418811645;20860.582788343843;21052.368428947157;21218.57562848765;23866;23992.520149597258;24114.63828042616;24464.75535244696;24678.25965221046;20716.585668286247;20829.375118746462;22155.113795448;22196.001179947263;22262.77737222607;19905;20299.492512687302;21831.235906743186;22394.552108957403;22582.887085564467;20090.19639214415;21502.677459837803;21607.675884861426;21707.891460542596;21767.238146665422;14909.552713418614;15335.616609584404;15372.769408458658;15445;15706.136162511171;15471.845281547041;15823.525294241192;15975.840241597436;16143.838561614233;16897.91551042237 03:38:25 INFO - PID 29032 | __end_tp_report 03:38:25 INFO - PID 29032 | __start_cc_report 03:38:25 INFO - PID 29032 | _x_x_mozilla_cycle_collect,350 03:38:25 INFO - PID 29032 | __end_cc_report 03:38:25 INFO - PID 29032 | __startTimestamp1504089505721__endTimestamp 03:38:25 INFO - PID 29032 | 03:38:25 INFO - PID 29032 | ------- Summary: start ------- 03:38:25 INFO - PID 29032 | Number of tests: 6 03:38:25 INFO - PID 29032 | 03:38:25 INFO - PID 29032 | [#0] dojo.html Cycles:165 Average:6707.92 Median:6971.48 stddev:873.46 (12.5%) stddev-sans-first:846.74 03:38:25 INFO - PID 29032 | Values: 3843.8 3862.5 3883.1 3888.3 3927.0 6470.1 6557.6 6571.8 6576.5 6680.2 6708.4 6772.7 6782.8 6791.5 6797.4 6006.1 6031.9 6032.2 6072.6 6110.0 6141.2 6147.5 6169.7 6190.8 6211.4 930.5 6020.3 6046.4 6112.1 6168.7 6017.0 6018.0 6029.5 6104.8 6138.5 5951.1 5963.3 6050.8 6064.5 6065.2 6730.7 6805.7 6870.5 6895.0 6906.3 7333.2 7379.3 7388.9 7485.1 7485.3 7385.2 7467.9 7488.7 7550.7 7560.6 6855.2 6858.0 6859.8 6863.8 6864.0 7570.3 7590.4 7593.8 7647.2 7688.6 7136.4 7229.6 7310.6 7316.7 7340.8 7351.2 7362.5 7386.3 7448.4 7488.4 7231.3 7237.5 7342.0 7355.9 7365.3 7307.9 7329.5 7343.3 7353.7 7355.2 6169.3 6208.9 6208.9 6306.4 6321.5 5263.8 5335.7 5356.0 5357.5 5358.7 6201.2 6255.1 6264.4 6281.5 6291.9 7129.9 7290.3 7318.6 7390.2 7425.5 6217.2 6277.4 6278.6 6292.4 6354.3 6370.3 6573.5 6636.6 6638.6 6648.9 7056.1 7064.3 7127.4 7141.2 7145.6 7190.4 7213.6 7297.8 7304.2 7315.9 7032.0 7037.6 7063.6 7113.8 7133.7 7223.0 7227.7 7296.8 7305.1 7321.6 7304.5 7355.2 7374.4 7425.6 7441.0 6933.5 6954.3 7037.9 7050.6 7061.9 6923.3 6944.0 7014.9 7025.3 7044.3 7050.7 7098.4 7101.9 7107.4 7115.1 6983.6 7112.1 7177.7 7180.7 7193.2 6859.3 6861.5 6881.0 6963.0 6971.5 03:38:25 INFO - PID 29032 | 03:38:25 INFO - PID 29032 | [#1] ext.html Cycles:180 Average:39997.46 Median:3817.73 stddev:54157.65 (1418.6%) stddev-sans-first:54230.07 03:38:25 INFO - PID 29032 | Values: 917.4 958.7 988.7 1015.4 1020.9 43478.6 43996.0 44837.8 44839.8 44884.0 44029.1 44677.3 45089.3 45104.0 45105.8 7954.1 8202.6 8211.4 8215.4 8217.8 8069.8 8130.5 8198.8 8200.0 8206.0 8195.8 8205.5 8219.9 8221.2 8247.0 8067.1 8179.8 8198.0 8198.7 8205.2 8017.8 8077.3 8086.8 8087.3 8093.1 39503.4 39902.6 39917.2 39961.8 39971.2 3798.6 3805.0 3808.9 3811.8 3823.7 32575.5 32579.0 32657.8 32821.2 32831.2 5423.2 5508.1 5524.8 5546.9 5571.4 205604.0 207751.0 210645.0 210730.0 211144.9 51623.2 51977.7 52012.0 52076.7 52107.5 54421.7 54508.7 54518.2 54609.0 54703.3 15917.8 15983.4 16087.7 16131.5 16140.9 13196.9 13281.3 13292.3 13312.7 13319.5 14690.6 14782.7 14799.8 14892.3 14961.8 5466.8 5542.1 5548.2 5551.4 5572.7 1109.2 1109.3 1123.8 1127.9 1131.6 25713.9 25788.0 25814.5 25963.4 25981.9 1189.3 1196.7 1199.7 1211.1 1227.3 13546.9 13623.7 13629.4 13802.9 13817.5 245492.0 245545.0 246066.0 246601.0 246851.0 55406.4 55535.2 61508.7 62578.7 62680.1 1508.1 1548.4 1552.2 1560.7 1570.4 30636.5 30903.8 31178.7 31187.1 31341.0 41285.8 41314.8 41378.6 41382.8 41405.6 162028.0 162234.2 162456.0 162622.0 162846.2 35663.1 41846.0 42529.1 42804.6 42818.8 51904.5 51936.0 52014.0 52354.5 52721.0 36424.0 37201.1 37386.3 37510.2 37994.2 37747.2 37884.4 37922.6 37970.6 38279.4 44390.1 45779.5 46027.5 46269.3 46448.8 36251.8 36670.0 36675.0 36792.8 36835.0 25725.6 25903.4 26307.5 27517.6 28425.4 03:38:25 INFO - PID 29032 | 03:38:25 INFO - PID 29032 | [#2] jquery.html Cycles:180 Average:39974.79 Median:21845.95 stddev:104083.96 (476.4%) stddev-sans-first:104333.96 03:38:25 INFO - PID 29032 | Values: 603.5 606.4 608.7 614.2 626.3 22710.2 23313.3 24083.8 24136.3 24176.3 21546.9 21873.6 26776.3 26830.1 26930.0 6802.8 6951.0 6957.2 6959.3 6962.3 6333.5 6574.4 6867.9 6919.6 6920.0 6578.0 6582.4 6583.9 6588.5 6591.5 6537.5 6567.9 6568.3 6572.7 6581.1 6139.9 6141.6 6151.0 6163.3 6171.7 29851.6 30308.0 30555.4 30636.7 30725.8 2258.0 2296.6 2307.3 2309.0 2320.0 20300.1 20521.9 21381.8 21397.7 21410.6 2521.7 2528.6 2585.6 2591.4 2593.4 120101.4 124528.0 149741.0 154635.0 155900.0 16272.8 16613.6 16706.7 16808.2 16840.5 25901.0 25915.0 25927.6 25955.0 26012.5 10904.2 10978.5 11060.9 11125.2 11162.2 9759.6 9817.1 9950.8 9957.1 10035.1 10270.8 10421.9 10430.5 10438.3 10495.9 2930.6 2940.6 2965.4 2996.0 3020.7 1017.2 1047.8 1068.2 1071.2 1078.5 21035.9 21332.6 21737.5 21823.9 21868.0 1111.6 1126.3 1133.9 1134.1 1140.0 11659.8 11947.9 11990.6 11991.0 12025.4 592680.0 628407.0 630275.0 634813.0 642075.0 123917.4 124287.0 124873.4 125158.0 125687.4 1235.8 1263.7 1267.2 1268.3 1270.1 18932.6 19010.6 19063.8 19247.6 19431.9 60813.0 61935.7 62529.7 63065.7 63483.4 77918.2 77926.6 78885.6 80373.0 84467.6 15813.2 16159.4 16182.4 16189.7 16236.6 24503.6 24524.1 24541.3 24688.5 24750.6 19995.3 20091.4 20419.7 20606.7 20606.8 17400.2 17484.3 17909.7 18051.8 18108.0 18620.3 18854.7 18921.2 20122.4 20477.0 15938.2 15964.0 15979.4 16012.6 16037.3 19722.2 19741.6 19878.8 19891.0 20009.6 03:38:25 INFO - PID 29032 | 03:38:25 INFO - PID 29032 | [#3] mootools.html Cycles:180 Average:10567.03 Median:2749.69 stddev:7654.72 (278.4%) stddev-sans-first:7645.40 03:38:25 INFO - PID 29032 | Values: 1428.1 1477.1 1480.3 1486.3 1505.1 16522.4 16620.7 17163.8 17169.7 17188.5 17194.2 17431.1 17458.5 17469.7 17471.3 2860.3 2913.7 2915.0 2916.6 2920.1 2878.4 2969.9 2973.7 2974.1 2975.7 2906.2 2910.7 2915.7 2918.0 2934.8 2876.6 2886.7 2888.2 2889.2 2893.7 2714.0 2729.9 2744.1 2748.9 2750.5 16413.0 16684.9 16702.0 16710.2 16731.6 8530.6 8891.6 8908.3 8911.8 8915.8 5446.9 5508.7 5635.1 5644.9 5848.9 2203.8 2206.3 2213.2 2229.4 2259.3 30488.1 30698.4 30723.2 30729.8 30821.2 7362.8 7641.7 7747.7 7974.1 8055.7 22449.6 22471.2 22529.9 22566.7 22662.5 8699.8 8943.0 8954.4 8955.4 8957.1 7900.0 7930.8 8040.2 8065.9 8069.4 5977.5 6182.9 6222.0 6253.4 6283.1 1847.9 1897.9 1903.1 1946.8 1947.3 960.9 977.4 985.8 1004.3 1007.8 8595.7 8782.4 8805.2 8948.5 9008.1 786.6 796.2 796.5 797.3 810.1 5828.0 5906.5 5976.9 6123.0 6242.9 22895.4 23537.9 23672.5 24060.6 24113.0 23241.7 23499.0 23877.5 23932.8 23946.0 12695.8 12767.6 12874.2 12923.9 12931.9 9151.7 9168.3 9205.7 9230.6 9302.0 20598.9 20606.7 20667.8 21177.9 21200.7 20992.3 21495.0 21730.6 21955.0 22368.4 13272.2 13373.7 13608.0 13655.6 13657.1 15403.9 15616.9 15618.8 15628.1 15640.5 12385.3 12434.7 12489.9 12537.5 12607.7 11813.8 11994.3 12000.4 12122.8 12212.3 10678.7 10929.5 11131.9 11144.7 11191.3 8780.8 8811.3 8831.1 8973.1 9014.1 8727.3 8767.8 8772.7 8829.5 8878.2 03:38:25 INFO - PID 29032 | 03:38:25 INFO - PID 29032 | [#4] prototype.html Cycles:180 Average:6061.63 Median:6691.43 stddev:1599.71 (23.9%) stddev-sans-first:1549.52 03:38:25 INFO - PID 29032 | Values: 536.6 553.2 566.3 578.0 580.1 6467.2 6695.6 6696.1 6730.4 6759.2 6551.3 6616.8 6686.9 6687.3 6769.4 5521.7 5562.9 5567.6 5578.7 5594.1 5391.6 5534.3 5559.6 5590.1 5592.3 5486.7 5502.9 5553.9 5565.7 5585.6 5503.2 5536.8 5537.2 5542.6 5552.2 5207.4 5234.9 5243.7 5254.9 5257.5 6685.8 6707.3 6722.8 6730.5 6734.9 6531.7 6571.1 6582.4 6583.8 6639.6 7050.2 7121.1 7133.6 7159.2 7160.1 5746.6 5771.8 5791.2 5806.7 5827.3 7221.3 7285.4 7303.2 7334.3 7556.6 5921.8 5937.3 5945.5 5974.7 6004.2 6007.7 6009.7 6075.4 6084.8 6127.5 6535.6 6537.8 6564.4 6587.8 6631.1 7006.4 7033.5 7111.3 7111.9 7122.9 2087.9 2121.2 2131.9 2145.1 2147.8 1541.0 1541.2 1591.7 1596.3 1603.2 6101.5 6115.4 6135.1 6145.1 6253.9 7153.5 7255.4 7260.2 7272.4 7285.9 6154.0 6193.5 6202.0 6214.6 6223.6 3748.2 3782.0 3831.6 3849.8 3851.2 6954.4 7000.0 7017.6 7024.7 7109.0 7318.9 7353.4 7377.5 7418.0 7440.2 6878.9 6895.4 6899.6 6995.4 7014.6 6964.2 7007.3 7089.8 7132.2 7180.3 7056.8 7069.5 7078.9 7155.3 7199.7 7012.9 7056.7 7100.2 7101.4 7104.6 5963.3 5978.7 5991.1 6037.6 6079.1 6986.7 7031.7 7120.5 7214.7 7259.4 6896.6 6915.4 6947.7 6978.9 6992.3 6935.1 6964.7 6967.2 6984.9 7002.4 6942.2 6964.3 6975.2 6987.5 7019.2 7042.6 7072.6 7072.9 7075.8 7082.8 6871.7 6886.1 6887.1 6890.2 6909.3 03:38:25 INFO - PID 29032 | 03:38:25 INFO - PID 29032 | [#5] yui.html Cycles:180 Average:27917.50 Median:20295.04 stddev:38918.89 (191.8%) stddev-sans-first:38975.47 03:38:25 INFO - PID 29032 | Values: 969.4 994.7 1007.6 1018.1 1030.2 19382.3 19988.9 20080.4 20119.6 23295.9 21992.9 22275.8 22574.4 22577.9 22591.8 1064.8 1088.2 1088.8 1089.1 1093.0 1042.0 1104.6 1105.6 1109.0 1110.6 1159.7 1168.6 1169.2 1169.5 1169.6 1160.5 1163.4 1165.3 1165.6 1167.1 851.2 853.7 853.8 854.5 854.9 17960.3 18479.3 18585.5 18622.8 20145.9 21002.3 21743.5 22628.0 23119.8 23125.5 17553.5 17718.5 17807.2 17871.2 18061.5 4117.5 4137.6 4141.5 4144.5 4145.8 148297.0 150380.0 150629.0 150673.0 151278.0 17049.0 17116.9 17501.1 17667.4 17683.8 32897.7 32976.3 33594.5 33624.2 34018.3 8647.0 9010.7 9476.7 9602.1 9930.6 4769.4 4857.9 4863.7 4873.7 4892.5 5905.1 6018.3 6024.5 6088.4 6116.1 1237.2 1244.9 1263.9 1268.1 1273.0 744.7 745.9 762.3 766.2 780.8 13089.0 13183.8 13384.7 13878.3 13917.2 746.9 763.3 765.7 767.7 770.4 6273.8 6296.2 6380.5 6420.6 6499.5 126855.4 128043.0 128269.0 131600.3 135325.0 117727.4 118216.0 118523.0 119354.4 123689.0 102567.5 105285.0 107219.5 108125.0 109481.5 13917.6 13923.4 13959.5 14106.4 14408.8 49690.0 50866.2 50975.5 53526.0 53643.2 84999.6 85402.0 85938.7 87788.6 88304.1 18429.4 20290.6 20860.6 21052.4 21218.6 23866.0 23992.5 24114.6 24464.8 24678.3 20716.6 20829.4 22155.1 22196.0 22262.8 19905.0 20299.5 21831.2 22394.6 22582.9 20090.2 21502.7 21607.7 21707.9 21767.2 14909.6 15335.6 15372.8 15445.0 15706.1 15471.8 15823.5 15975.8 16143.8 16897.9 03:38:25 INFO - PID 29032 | -------- Summary: end -------- 03:38:25 INFO - PID 29032 | 03:38:26 INFO - TEST-INFO | 29032: exit 0 03:38:26 INFO - TEST-OK | dromaeo_css | took 1110333ms 03:38:26 INFO - TEST-START | kraken 03:38:26 INFO - Initialising browser for kraken test... 03:38:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41015/getInfo.html -profile /tmp/tmpLCjSWM/profile 03:38:26 INFO - TEST-INFO | started process 29401 (/builds/slave/test/build/application/firefox/firefox http://localhost:41015/getInfo.html) 03:38:32 INFO - TEST-INFO | 29401: exit 0 03:38:32 INFO - Browser initialized. 03:38:32 INFO - Running cycle 1/1 for kraken test... 03:38:32 INFO - Using env: {'DISPLAY': ':0', 03:38:32 INFO - 'HOME': '/home/cltbld', 03:38:32 INFO - 'JSGC_DISABLE_POISONING': '1', 03:38:32 INFO - 'LANG': 'en_US.UTF-8', 03:38:32 INFO - 'LANGUAGE': 'en_US:en', 03:38:32 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:38:32 INFO - 'LOGNAME': 'cltbld', 03:38:32 INFO - 'MAIL': '/var/mail/cltbld', 03:38:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:38:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:38:32 INFO - 'MOZ_CRASHREPORTER': '1', 03:38:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:38:32 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:38:32 INFO - 'MOZ_NO_REMOTE': '1', 03:38:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:38:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:38:32 INFO - 'NO_EM_RESTART': '1', 03:38:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:38:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:38:32 INFO - 'PWD': '/builds/slave/test', 03:38:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:38:32 INFO - 'RUST_BACKTRACE': 'full', 03:38:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:38:32 INFO - 'SHELL': '/bin/bash', 03:38:32 INFO - 'SHLVL': '1', 03:38:32 INFO - 'TERM': 'linux', 03:38:32 INFO - 'TMOUT': '86400', 03:38:32 INFO - 'USER': 'cltbld', 03:38:32 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611', 03:38:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:38:32 INFO - '_': '/tools/buildbot/bin/python'} 03:38:32 INFO - TEST-INFO | started process 29588 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLCjSWM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:38:33 INFO - PID 29588 | 03:38:33 INFO - PID 29588 | (/builds/slave/test/build/application/firefox/firefox:29639): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:38:33 INFO - PID 29588 | 03:38:43 INFO - PID 29588 | RSS: Main: 157732864 03:38:43 INFO - PID 29588 | 03:38:45 INFO - PID 29588 | Cycle 1(1): loaded http://localhost:41015/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41015/tests/kraken/driver.html?testName=audio-beat-detection) 03:38:46 INFO - PID 29588 | RSS: Main: 164093952 03:38:46 INFO - PID 29588 | 03:38:49 INFO - PID 29588 | Cycle 1(1): loaded http://localhost:41015/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41015/tests/kraken/driver.html?testName=audio-dft) 03:38:50 INFO - PID 29588 | RSS: Main: 167919616 03:38:50 INFO - PID 29588 | 03:38:52 INFO - PID 29588 | Cycle 1(1): loaded http://localhost:41015/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41015/tests/kraken/driver.html?testName=audio-fft) 03:38:53 INFO - PID 29588 | RSS: Main: 160108544 03:38:53 INFO - PID 29588 | 03:38:55 INFO - PID 29588 | Cycle 1(1): loaded http://localhost:41015/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41015/tests/kraken/driver.html?testName=audio-oscillator) 03:38:56 INFO - PID 29588 | RSS: Main: 161312768 03:38:56 INFO - PID 29588 | 03:38:58 INFO - PID 29588 | Cycle 1(1): loaded http://localhost:41015/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41015/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:38:58 INFO - PID 29588 | RSS: Main: 161263616 03:38:58 INFO - PID 29588 | 03:39:01 INFO - PID 29588 | Cycle 1(1): loaded http://localhost:41015/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41015/tests/kraken/driver.html?testName=imaging-darkroom) 03:39:02 INFO - PID 29588 | RSS: Main: 161460224 03:39:02 INFO - PID 29588 | 03:39:05 INFO - PID 29588 | Cycle 1(1): loaded http://localhost:41015/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41015/tests/kraken/driver.html?testName=imaging-desaturate) 03:39:06 INFO - PID 29588 | RSS: Main: 162844672 03:39:06 INFO - PID 29588 | 03:39:09 INFO - PID 29588 | Cycle 1(1): loaded http://localhost:41015/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41015/tests/kraken/driver.html?testName=json-parse-financial) 03:39:09 INFO - PID 29588 | RSS: Main: 162820096 03:39:09 INFO - PID 29588 | 03:39:11 INFO - PID 29588 | Cycle 1(1): loaded http://localhost:41015/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41015/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:39:12 INFO - PID 29588 | RSS: Main: 162332672 03:39:12 INFO - PID 29588 | 03:39:13 INFO - PID 29588 | Cycle 1(1): loaded http://localhost:41015/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41015/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:39:14 INFO - PID 29588 | RSS: Main: 162656256 03:39:14 INFO - PID 29588 | 03:39:16 INFO - PID 29588 | Cycle 1(1): loaded http://localhost:41015/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41015/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:39:16 INFO - PID 29588 | RSS: Main: 162766848 03:39:16 INFO - PID 29588 | 03:39:19 INFO - PID 29588 | Cycle 1(1): loaded http://localhost:41015/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41015/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:39:20 INFO - PID 29588 | RSS: Main: 162496512 03:39:20 INFO - PID 29588 | 03:39:22 INFO - PID 29588 | Cycle 1(1): loaded http://localhost:41015/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41015/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:39:23 INFO - PID 29588 | RSS: Main: 162697216 03:39:23 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | Cycle 1(1): loaded http://localhost:41015/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41015/tests/kraken/driver.html?testName=ai-astar) 03:39:25 INFO - PID 29588 | RSS: Main: 164806656 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | __start_tp_report 03:39:25 INFO - PID 29588 | _x_x_mozilla_page_load 03:39:25 INFO - PID 29588 | _x_x_mozilla_page_load_details 03:39:25 INFO - PID 29588 | |i|pagename|runs| 03:39:25 INFO - PID 29588 | |0;ai-astar;97;106;98;105;105;106;105;98;105;108 03:39:25 INFO - PID 29588 | |1;audio-beat-detection;142;110;139;167;114;135;137;112;169;113 03:39:25 INFO - PID 29588 | |2;audio-dft;164;161;161;161;163;161;162;161;161;162 03:39:25 INFO - PID 29588 | |3;audio-fft;74;74;73;168;73;82;76;73;176;74 03:39:25 INFO - PID 29588 | |4;audio-oscillator;79;78;136;78;77;78;122;78;79;122 03:39:25 INFO - PID 29588 | |5;imaging-gaussian-blur;120;113;114;114;113;114;114;114;114;114 03:39:25 INFO - PID 29588 | |6;imaging-darkroom;151;149;149;151;151;150;150;150;150;150 03:39:25 INFO - PID 29588 | |7;imaging-desaturate;94;93;94;94;94;94;94;94;95;94 03:39:25 INFO - PID 29588 | |8;json-parse-financial;44;50;43;43;44;43;45;45;43;44 03:39:25 INFO - PID 29588 | |9;json-stringify-tinderbox;36;36;37;37;40;37;37;37;36;37 03:39:25 INFO - PID 29588 | |10;stanford-crypto-aes;72;72;71;71;73;71;72;71;71;74 03:39:25 INFO - PID 29588 | |11;stanford-crypto-ccm;112;110;129;121;109;115;110;109;109;152 03:39:25 INFO - PID 29588 | |12;stanford-crypto-pbkdf2;153;154;162;153;152;156;157;153;153;152 03:39:25 INFO - PID 29588 | |13;stanford-crypto-sha256-iterative;62;60;59;59;58;62;63;59;58;58 03:39:25 INFO - PID 29588 | __end_tp_report 03:39:25 INFO - PID 29588 | __start_cc_report 03:39:25 INFO - PID 29588 | _x_x_mozilla_cycle_collect,903 03:39:25 INFO - PID 29588 | __end_cc_report 03:39:25 INFO - PID 29588 | __startTimestamp1504089565268__endTimestamp 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | ------- Summary: start ------- 03:39:25 INFO - PID 29588 | Number of tests: 14 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | [#0] ai-astar Cycles:10 Average:103.30 Median:107.00 stddev:4.00 (3.7%) stddev-sans-first:3.54 03:39:25 INFO - PID 29588 | Values: 97.0 106.0 98.0 105.0 105.0 106.0 105.0 98.0 105.0 108.0 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:138.00 stddev:21.89 (15.9%) stddev-sans-first:23.02 03:39:25 INFO - PID 29588 | Values: 142.0 110.0 139.0 167.0 114.0 135.0 137.0 112.0 169.0 113.0 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | [#2] audio-dft Cycles:10 Average:161.70 Median:161.50 stddev:1.06 (0.7%) stddev-sans-first:0.73 03:39:25 INFO - PID 29588 | Values: 164.0 161.0 161.0 161.0 163.0 161.0 162.0 161.0 161.0 162.0 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | [#3] audio-fft Cycles:10 Average:94.30 Median:74.00 stddev:41.08 (55.5%) stddev-sans-first:42.91 03:39:25 INFO - PID 29588 | Values: 74.0 74.0 73.0 168.0 73.0 82.0 76.0 73.0 176.0 74.0 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | [#4] audio-oscillator Cycles:10 Average:92.70 Median:78.00 stddev:23.75 (30.5%) stddev-sans-first:24.67 03:39:25 INFO - PID 29588 | Values: 79.0 78.0 136.0 78.0 77.0 78.0 122.0 78.0 79.0 122.0 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:2.01 (1.8%) stddev-sans-first:0.44 03:39:25 INFO - PID 29588 | Values: 120.0 113.0 114.0 114.0 113.0 114.0 114.0 114.0 114.0 114.0 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | [#6] imaging-darkroom Cycles:10 Average:150.10 Median:150.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 03:39:25 INFO - PID 29588 | Values: 151.0 149.0 149.0 151.0 151.0 150.0 150.0 150.0 150.0 150.0 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 03:39:25 INFO - PID 29588 | Values: 94.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | [#8] json-parse-financial Cycles:10 Average:44.40 Median:44.00 stddev:2.12 (4.8%) stddev-sans-first:2.24 03:39:25 INFO - PID 29588 | Values: 44.0 50.0 43.0 43.0 44.0 43.0 45.0 45.0 43.0 44.0 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | [#9] json-stringify-tinderbox Cycles:10 Average:37.00 Median:37.00 stddev:1.15 (3.1%) stddev-sans-first:1.17 03:39:25 INFO - PID 29588 | Values: 36.0 36.0 37.0 37.0 40.0 37.0 37.0 37.0 36.0 37.0 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | [#10] stanford-crypto-aes Cycles:10 Average:71.80 Median:72.00 stddev:1.03 (1.4%) stddev-sans-first:1.09 03:39:25 INFO - PID 29588 | Values: 72.0 72.0 71.0 71.0 73.0 71.0 72.0 71.0 71.0 74.0 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | [#11] stanford-crypto-ccm Cycles:10 Average:117.60 Median:113.50 stddev:13.75 (12.1%) stddev-sans-first:14.43 03:39:25 INFO - PID 29588 | Values: 112.0 110.0 129.0 121.0 109.0 115.0 110.0 109.0 109.0 152.0 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.50 Median:153.50 stddev:3.10 (2.0%) stddev-sans-first:3.24 03:39:25 INFO - PID 29588 | Values: 153.0 154.0 162.0 153.0 152.0 156.0 157.0 153.0 153.0 152.0 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.80 Median:59.50 stddev:1.87 (3.1%) stddev-sans-first:1.81 03:39:25 INFO - PID 29588 | Values: 62.0 60.0 59.0 59.0 58.0 62.0 63.0 59.0 58.0 58.0 03:39:25 INFO - PID 29588 | -------- Summary: end -------- 03:39:25 INFO - PID 29588 | 03:39:25 INFO - PID 29588 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:39:25 INFO - TEST-INFO | 29588: exit 0 03:39:25 INFO - TEST-OK | kraken | took 59454ms 03:39:25 INFO - SUITE-END | took 1169s 03:39:25 INFO - Completed test suite (00:19:29) 03:39:25 INFO - javascript benchmark 03:39:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9407.340755592013, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3843.7885916274613, 3862.4978752762136, 3883.145707944252, 3888.2612303662318, 3927.0, 6470.126532918055, 6557.606543607387, 6571.769988050419, 6576.539642225046, 6680.231773346067, 6708.429783468411, 6772.72909083636, 6782.830429239271, 6791.456683465311, 6797.422219111381, 6006.129111278862, 6031.909521357183, 6032.245969253843, 6072.635641861502, 6109.969450152743, 6141.232345956755, 6147.508199344064, 6169.691515424233, 6190.84522886927, 6211.440970312671, 930.5090514602256, 6020.337762846083, 6046.425589568984, 6112.113743507211, 6168.722407491674, 6017.037274036177, 6017.969910150465, 6029.487493563052, 6104.755809767604, 6138.539609529258, 5951.137085122633, 5963.314218864799, 6050.818475445743, 6064.484518815906, 6065.150878876961, 6730.730770769163, 6805.740937926499, 6870.484713646447, 6895.034695142684, 6906.343897329745, 7333.230010848837, 7379.298966598165, 7388.926110738931, 7485.1017877855, 7485.251474852471, 7385.150707668633, 7467.925320746777, 7488.663010164501, 7550.73572424968, 7560.584167870765, 6855.245922948472, 6858.005589189589, 6859.828504287383, 6863.828404289933, 6864.0, 7570.318671319607, 7590.354819840265, 7593.810154746065, 7647.197044310378, 7688.577128257888, 7136.393406560397, 7229.638518074075, 7310.634468276565, 7316.743913962986, 7340.779776606711, 7351.154617219088, 7362.484626076123, 7386.335229829341, 7448.366888814403, 7488.363489103487, 7231.2768723127265, 7237.5295605786505, 7342.045534080642, 7355.926440735524, 7365.300296471826, 7307.853842923112, 7329.52358096722, 7343.265673432721, 7353.705851765976, 7355.1541572719825, 6169.321374648785, 6208.875822483616, 6208.906866397007, 6306.432421082125, 6321.494280457552, 5263.789448422097, 5335.653182543122, 5356.035913535523, 5357.491038351351, 5358.651687640291, 6201.162843016225, 6255.0617407388345, 6264.37356264379, 6281.528885333618, 6291.937080629135, 7129.928700713031, 7290.270972902667, 7318.56088634673, 7390.187079421259, 7425.517341372793, 6217.160683307786, 6277.435030847154, 6278.623282603058, 6292.370762923671, 6354.332795056545, 6370.331115232926, 6573.5069869759, 6636.568623039487, 6638.634875081917, 6648.933510664928, 7056.117985251741, 7064.32888875556, 7127.4298056155385, 7141.214466408691, 7145.606991615511, 7190.42476601881, 7213.639318034182, 7297.781066568012, 7304.233055529306, 7315.890261646186, 7032.0, 7037.577745335297, 7063.57618542889, 7113.75101871432, 7133.678984445816, 7222.96388518054, 7227.710891564278, 7296.817579560554, 7305.050343455317, 7321.63391830417, 7304.452166087671, 7355.190928997913, 7374.373178279906, 7425.628718563942, 7441.032665753418, 6933.5146539743755, 6954.3393377629045, 7037.894431583428, 7050.5769653820935, 7061.8940715888275, 6923.273056329214, 6943.9652801735665, 7014.894776578458, 7025.332593403618, 7044.260352662896, 7050.717971281297, 7098.36114749675, 7101.928980710127, 7107.395871350786, 7115.146182457931, 6983.580985140908, 7112.0754301940415, 7177.7487787927175, 7180.712771489082, 7193.244709305448, 6859.279775623488, 6861.4853885957755, 6881.036654868422, 6962.965185174042, 6971.477139214478], "value": 6652.485345958085, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [917.3945196170529, 958.6548842416734, 988.6786794291854, 1015.431358439274, 1020.9234307426944, 43478.565214347844, 43996.0, 44837.77581112094, 44839.77580112099, 44884.0, 44029.119417611626, 44677.32984005242, 45089.32366014504, 45104.0, 45105.774471127595, 7954.085280192794, 8202.58987050645, 8211.425200235955, 8215.424920255584, 8217.835643287131, 8069.798255043612, 8130.471519351253, 8198.795030124265, 8200.01599808021, 8206.0, 8195.79510512236, 8205.548694821813, 8219.876701849476, 8221.17788221179, 8246.958765206165, 8067.112617612058, 8179.79550511239, 8197.95901020497, 8198.713045043425, 8205.179482051746, 8017.799555011113, 8077.27304542594, 8086.838263234761, 8087.272145506874, 8093.069297030799, 39503.40744888829, 39902.60097399018, 39917.20165596672, 39961.80019099886, 39971.20057598861, 3798.582155962842, 3804.9619503804884, 3808.9428658570146, 3811.7712937223855, 3823.6941044716355, 32575.51136732951, 32579.0, 32657.836710816293, 32821.17947051319, 32831.179220519676, 5423.159410291411, 5508.14623733322, 5524.779008839641, 5546.944530554683, 5571.4150014248735, 205604.0, 207751.0, 210645.0, 210730.0, 211144.94427527918, 51623.225651615256, 51977.7401112992, 52012.0, 52076.739616301675, 52107.47892521102, 54421.72789136108, 54508.72745636326, 54518.182227266625, 54609.0, 54703.2648367588, 15917.840821591868, 15983.36066557348, 16087.67824643524, 16131.516054518383, 16140.919295403448, 13196.868031319756, 13281.26953017574, 13292.335383230993, 13312.733745325038, 13319.533816316383, 14690.55928322152, 14782.704345913022, 14799.778003329959, 14892.329845156994, 14961.775573366409, 5466.835994920159, 5542.140968149944, 5548.223248745179, 5551.417101204396, 5572.693501857356, 1109.2290857853875, 1109.3399427340719, 1123.7808627317759, 1127.8928501792316, 1131.5926266544038, 25713.87143064273, 25788.0, 25814.483710325687, 25963.350916226867, 25981.870090649427, 1189.2685998111272, 1196.7486827766268, 1199.7060720123452, 1211.0735287505065, 1227.3372378915465, 13546.86453135456, 13623.659408514866, 13629.43870848512, 13802.861971380158, 13817.516386926409, 245492.0, 245545.0, 246066.0, 246601.0, 246851.0, 55406.445935540934, 55535.16697249545, 61508.69245653743, 62578.687106564175, 62680.05979910305, 1508.0517588115426, 1548.442560678155, 1552.2394026926725, 1560.719070567286, 1570.4424929150216, 30636.540451893685, 30903.845480772452, 31178.688213118487, 31187.064388068393, 31341.0, 41285.79357103195, 41314.79342603388, 41378.586214138675, 41382.793086035585, 41405.58594414017, 162028.0, 162234.18882905509, 162456.0, 162622.0, 162846.1857690704, 35663.10842228965, 41846.0, 42529.14941701211, 42804.5719542813, 42818.78590607027, 51904.48095518997, 51936.0, 52014.0, 52354.47645523496, 52721.0, 36424.0, 37201.06997325089, 37386.252274954895, 37510.24979500449, 37994.24011519699, 37747.245055099294, 37884.43173352457, 37922.62077379191, 37970.620293797816, 38279.42580861345, 44390.11219775651, 45779.54220457753, 46027.53972460233, 46269.30596040995, 46448.767756161, 36251.81874090613, 36670.0, 36675.0, 36792.81603591965, 36835.0, 25725.614115788652, 25903.352416189748, 26307.4738505225, 27517.587236191874, 28425.43149137047], "value": 18706.222420715447, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.4779915373203, 606.357261303019, 608.6561092982467, 614.2383444528786, 626.3141859663667, 22710.205142820003, 23313.300600981955, 24083.75916240837, 24136.275911722656, 24176.274711758677, 21546.89226553869, 21873.562528749455, 26776.330591735168, 26830.06094786683, 26930.0, 6802.761903333372, 6950.965245173792, 6957.199922008957, 6959.30406959305, 6962.303769623048, 6333.461655759266, 6574.441172500343, 6867.862642747142, 6919.619420931846, 6919.965400172992, 6578.046183303418, 6582.3746744059235, 6583.9012414813815, 6588.538802283842, 6591.538592298541, 6537.509686773487, 6567.850626140406, 6568.3103274156, 6572.704228309737, 6581.144451221335, 6139.938600613981, 6141.570090093695, 6150.96924515379, 6163.3220345762375, 6171.691415429211, 29851.552226716616, 30308.0, 30555.388892222254, 30636.693633063605, 30725.846370768228, 2257.9887100564556, 2296.632538793785, 2307.273208939179, 2308.995586919695, 2320.0, 20300.086496107564, 20521.897390513102, 21381.786182138134, 21397.67903481449, 21410.5717885643, 2521.6595759572588, 2528.6333481645243, 2585.61215817627, 2591.3521619595103, 2593.4164812916943, 120101.39949300197, 124528.0, 149741.0, 154634.99999999886, 155900.0, 16272.83727162737, 16613.58466038359, 16706.749398759028, 16808.243629036722, 16840.494785156465, 25901.0, 25915.0, 25927.611085833727, 25955.0, 26012.479750405266, 10904.182186336056, 10978.451077446096, 11060.944695276472, 11125.221234513507, 11162.162837787198, 9759.560819762986, 9817.116459518535, 9950.751231219278, 9957.10386065243, 10035.09684128432, 10270.794584108276, 10421.895781042243, 10430.478476076165, 10438.321509101885, 10495.895041049493, 2930.60436841028, 2940.5736168255485, 2965.4365670522534, 2995.970040299569, 3020.7130322619314, 1017.1760873692333, 1047.8218702820539, 1068.1988508618535, 1071.2180108520824, 1078.5308390849848, 21035.894820526104, 21332.573348532944, 21737.456563586038, 21823.890880545496, 21868.0, 1111.5998240633367, 1126.301692950376, 1133.9489722962487, 1134.0813940708053, 1139.9943000285111, 11659.766804664028, 11947.880521194851, 11990.580329688419, 11991.0, 12025.398730063462, 592680.0, 628407.0, 630275.0, 634813.0, 642075.0, 123917.38041309916, 124287.0, 124873.37563312125, 125158.0, 125687.3715631416, 1235.7899157143306, 1263.728298415845, 1267.2459886367596, 1268.3024336614824, 1270.060155484953, 18932.621347572695, 19010.619787604446, 19063.809361906202, 19247.615047698688, 19431.902840485705, 60813.0, 61935.690321549904, 62529.68735156477, 63065.684671576346, 63483.36516634774, 77918.22081779336, 77926.6103669478, 78885.60557197177, 80373.0, 84467.57766211375, 15813.209339533207, 16159.43441979525, 16182.433614823425, 16189.67620647557, 16236.594085147966, 24503.63244551369, 24524.14165504199, 24541.263762087165, 24688.50622987566, 24750.628740569267, 19995.300164494172, 20091.39725808228, 20419.69370459474, 20606.690899636218, 20606.79393206049, 17400.216990235218, 17484.30062797474, 17909.731354029438, 18051.819481805014, 18108.00405977684, 18620.348287809862, 18854.717179242598, 18921.243150274386, 20122.39632811018, 20477.0, 15938.203089845229, 15964.0, 15979.440719574759, 16012.599685007968, 16037.278322475284, 19722.211111555378, 19741.60516789685, 19878.801211988273, 19891.0, 20009.599808004048], "value": 12870.514814720458, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.0788891165198, 1477.1284941884287, 1480.3190532355115, 1486.2865824404287, 1505.1195050895233, 16522.42171523997, 16620.66758664829, 17163.82836171638, 17169.7424538632, 17188.484345469642, 17194.226259818308, 17431.128443577803, 17458.476245712616, 17469.7379539307, 17471.3011479541, 2860.2706309891, 2913.70862913708, 2914.985425072882, 2916.562515622663, 2920.123962811159, 2878.3667593129585, 2969.9257518561994, 2973.732364087232, 2974.0780358088955, 2975.747061499775, 2906.1717410538, 2910.723481269276, 2915.7375836174742, 2917.9603089838865, 2934.779891508135, 2876.5685147227873, 2886.740193382595, 2888.234617826278, 2889.248795313223, 2893.73956343929, 2714.0, 2729.931751706223, 2744.1218809980815, 2748.876300566459, 2750.5049091163582, 16413.0, 16684.916575417166, 16702.0, 16710.248038838283, 16731.581710457212, 8530.573471326408, 8891.644334226623, 8908.331875109328, 8911.821763564758, 8915.821683566357, 5446.8910621787345, 5508.669479831223, 5635.09838425854, 5644.858878528029, 5848.912266316009, 2203.790639889208, 2206.3380985704384, 2213.247495851402, 2229.4314949687887, 2259.2544460328054, 30488.08535743931, 30698.386032279454, 30723.2319192022, 30729.846350768326, 30821.22946926345, 7362.77911662651, 7641.732539361207, 7747.690092396354, 7974.122846486882, 8055.677772889136, 22449.551008979728, 22471.213507527158, 22529.88735056314, 22566.661500077513, 22662.546749064928, 8699.782505437288, 8942.955285223532, 8954.373193876496, 8955.41789783662, 8957.14907083826, 7900.0, 7930.762077137695, 8040.2361775631225, 8065.919340806517, 8069.354451643854, 5977.491913187341, 6182.938170618237, 6222.0, 6253.405926436982, 6283.120363149072, 1847.9353222637412, 1897.9240830366907, 1903.1150515010586, 1946.7566554180728, 1947.269773834812, 960.8943016268205, 977.3549457358109, 985.7634167799677, 1004.2719028704131, 1007.8185926533295, 8595.742127736177, 8782.385233033627, 8805.213758084084, 8948.463092214488, 9008.054154313833, 786.6027656033666, 796.223681910133, 796.4544287163196, 797.330242596222, 810.1210186947228, 5827.970860145672, 5906.49794767441, 5976.880462390728, 6123.020316749299, 6242.937570624326, 22895.427614309443, 23537.88231058834, 23672.526549468916, 24060.639090413657, 24113.0, 23241.651375229387, 23499.0, 23877.52244955091, 23932.7606723934, 23946.04215831382, 12695.809562856564, 12767.553135640208, 12874.16317939331, 12923.935380323226, 12931.870681293067, 9151.725448236564, 9168.266538677024, 9205.677801276923, 9230.584623691815, 9302.0, 20598.897005515475, 20606.690899636218, 20667.793322066587, 21177.894110529964, 21200.68198976986, 20992.265270715452, 21495.0, 21730.565388691823, 21955.0, 22368.440788980406, 13272.203667779964, 13373.66565835862, 13608.0, 13655.590332290047, 13657.112287701268, 15403.922980385474, 15616.92191539035, 15618.843811562194, 15628.140452275235, 15640.530784076496, 12385.256884586954, 12434.689132771753, 12489.875101248872, 12537.498500059895, 12607.68480787951, 11813.822792657946, 11994.340311282964, 12000.39998000079, 12122.757544848877, 12212.32832194238, 10678.679639610824, 10929.508172132115, 11131.944340278247, 11144.665660030212, 11191.32852028881, 8780.824383512421, 8811.339149563937, 8831.116888311117, 8973.102689730975, 9014.143656352637, 8727.258183054511, 8767.780805479913, 8772.736817895473, 8829.514376709085, 8878.200961913459], "value": 7445.1111811668425, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [536.6216817143911, 553.2061491759325, 566.2808233543398, 578.0, 580.1240127407615, 6467.22393312802, 6695.564788288758, 6696.129503164594, 6730.427913627342, 6759.155105611799, 6551.279359270488, 6616.834579135524, 6686.8662626747555, 6687.297833727462, 6769.35994961964, 5521.696306703129, 5562.916556251659, 5567.610267281292, 5578.721063946787, 5594.13290939904, 5391.595630327722, 5534.335879694442, 5559.58303127265, 5590.133529302964, 5592.3009623797025, 5486.725663716818, 5502.889942201174, 5553.888922221553, 5565.679309432404, 5585.636933599303, 5503.229547863302, 5536.806211782568, 5537.224788529589, 5542.5565954723525, 5552.167174923753, 5207.427183009866, 5234.9476505235225, 5243.737813109329, 5254.947450525484, 5257.500537448975, 6685.765998190089, 6707.329267073303, 6722.798316050526, 6730.476071473197, 6734.865302693918, 6531.706073226716, 6571.080048793173, 6582.407583317525, 6583.835404114935, 6639.634820084893, 7050.224475307763, 7121.148501389676, 7133.643317834088, 7159.24827893069, 7160.10498687664, 5746.597738158331, 5771.769129234867, 5791.247137872092, 5806.738696758656, 5827.3298570664065, 7221.27787221279, 7285.4171666266575, 7303.160136584254, 7334.339909408125, 7556.584387858666, 5921.822345329647, 5937.2578427696535, 5945.483901605149, 5974.671393073336, 6004.219451471281, 6007.669578173242, 6009.699515024318, 6075.362086980892, 6084.756609735561, 6127.509799216052, 6535.607863528204, 6537.836554086186, 6564.442022428051, 6587.80236592903, 6631.092991186487, 7006.404455621329, 7033.4724895633035, 7111.2533184015965, 7111.857762844714, 7122.893156602655, 2087.9060442280133, 2121.1621409543145, 2131.8720876747134, 2145.1419432227235, 2147.76374598794, 1540.9906511235158, 1541.2248681847032, 1591.7214487464648, 1596.2816732470203, 1603.1743652018986, 6101.450869421773, 6115.388461153849, 6135.0797380392405, 6145.139680444741, 6253.874922501524, 7153.533525627234, 7255.419566434673, 7260.2013778484325, 7272.418206543463, 7285.890711639329, 6154.0, 6193.544517038492, 6202.0, 6214.627122372672, 6223.564350495512, 3748.194138260287, 3782.0333426618004, 3831.63599458051, 3849.8075096245075, 3851.1912498375095, 6954.40887524556, 6999.965000174967, 7017.614031228331, 7024.683889224895, 7108.964455177795, 7318.890216646754, 7353.374963128194, 7377.520461170114, 7417.962910185414, 7440.218777028443, 6878.8624227514165, 6895.4138898194215, 6899.6205208713, 6995.440364770806, 7014.614196219257, 6964.233934267125, 7007.334303241184, 7089.837599531986, 7132.179799323144, 7180.281971802778, 7056.7771152086925, 7069.469789765685, 7078.894241339907, 7155.3202445767565, 7199.660110464225, 7012.894806577803, 7056.6712984713895, 7100.183478899992, 7101.360877521048, 7104.644767761694, 5963.318794746104, 5978.730957106854, 5991.131285963424, 6037.641530655743, 6079.118527813531, 6986.685599147949, 7031.718731250693, 7120.53716508446, 7214.71141154348, 7259.390287167792, 6896.620685862325, 6915.446764258948, 6947.68735406918, 6978.895316570357, 6992.300769922967, 6935.063766391472, 6964.686589103401, 6967.233604303421, 6984.930150698631, 7002.4398048157045, 6942.201646810479, 6964.303569642995, 6975.232724400211, 6987.510874238755, 7019.227884932551, 7042.577445353295, 7072.611006394698, 7072.929270707227, 7075.752348667772, 7082.8313328300255, 6871.656417179221, 6886.139232595926, 6887.139107611549, 6890.20762612306, 6909.343612356818], "value": 5604.3689582841225, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [969.4377261188512, 994.6618149829059, 1007.637250589788, 1018.0989824005762, 1030.2170350533593, 19382.321618743346, 19988.90005549974, 20080.397588072345, 20119.59760804783, 23295.883520582414, 21992.8900355498, 22275.777242227574, 22574.43563910903, 22577.887110564465, 22591.774082259173, 1064.7604289034964, 1088.1892989722667, 1088.820344643133, 1089.1069323155016, 1093.0326660905077, 1041.9635312764055, 1104.6244276945836, 1105.5633024955139, 1108.9500972456258, 1110.5557776889227, 1159.7100724818795, 1168.5676299769098, 1169.216624861345, 1169.462047458166, 1169.5964892112208, 1160.5067846165389, 1163.3892206591522, 1165.2717051842599, 1165.592042785027, 1167.078008373384, 851.1871163039283, 853.7054716122927, 853.8292341531718, 854.4574195385912, 854.8546747053016, 17960.281588736434, 18479.260829566934, 18585.535361615934, 18622.813771862242, 20145.8992705037, 21002.264920727855, 21743.456413589785, 22628.0, 23119.76880231193, 23125.537489250288, 17553.473395798148, 17718.468445946513, 17807.19867605961, 17871.195796189204, 18061.458156255332, 4117.464729585165, 4137.586241375869, 4141.5237247716295, 4144.544100148981, 4145.7719825409595, 148297.0, 150379.9999999989, 150629.0, 150673.0, 151278.0, 17049.0, 17116.914415427844, 17501.124943753017, 17667.381641642736, 17683.823161768218, 32897.67102328946, 32976.3404731904, 33594.496082558784, 33624.1593960153, 34018.31963360769, 8646.956765216133, 9010.684626038057, 9476.668316609024, 9602.135807777333, 9930.553125109387, 4769.42766867978, 4857.9028419431415, 4863.7324947128245, 4873.658843880965, 4892.535209155196, 5905.084711869667, 6018.337982821886, 6024.4879185268865, 6088.391160883876, 6116.052011938156, 1237.1587320622064, 1244.9190802597802, 1263.943122559487, 1268.0806415348798, 1272.9745405091846, 744.6797876912871, 745.9328660420588, 762.2758379539459, 766.1916677904863, 780.7618676303782, 13089.0, 13183.802242966363, 13384.732305354033, 13878.306084695725, 13917.234552099731, 746.9327760501473, 763.2520130272364, 765.6707615725292, 767.7005967672612, 770.4007077745848, 6273.811785646438, 6296.244450665858, 6380.521460890452, 6420.614763114135, 6499.545031847725, 126855.36572317079, 128043.0, 128269.0, 131600.34199829132, 135325.0, 117727.41136294264, 118216.0, 118523.0, 119354.4032279833, 123689.0, 102567.4871625637, 105285.0, 107219.46390268154, 108125.0, 109481.45259273812, 13917.58247252584, 13923.443062277396, 13959.511417100353, 14106.36521356562, 14408.783868242195, 49690.0, 50866.23700644419, 50975.49024509708, 53526.0, 53643.19535207053, 84999.57500212459, 85402.0, 85938.7109193375, 87788.56105719431, 88304.11695882959, 18429.354972575897, 20290.59418811645, 20860.582788343843, 21052.368428947157, 21218.57562848765, 23866.0, 23992.520149597258, 24114.63828042616, 24464.75535244696, 24678.25965221046, 20716.585668286247, 20829.375118746462, 22155.113795448, 22196.001179947263, 22262.77737222607, 19905.0, 20299.492512687302, 21831.235906743186, 22394.552108957403, 22582.887085564467, 20090.19639214415, 21502.677459837803, 21607.675884861426, 21707.891460542596, 21767.238146665422, 14909.552713418614, 15335.616609584404, 15372.769408458658, 15445.0, 15706.136162511171, 15471.845281547041, 15823.525294241192, 15975.840241597436, 16143.838561614233, 16897.91551042237], "value": 10370.85256826443, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1429.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 106.0, 98.0, 105.0, 105.0, 106.0, 105.0, 98.0, 105.0, 108.0], "value": 103.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 110.0, 139.0, 167.0, 114.0, 135.0, 137.0, 112.0, 169.0, 113.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 161.0, 161.0, 163.0, 161.0, 162.0, 161.0, 161.0, 162.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 168.0, 73.0, 82.0, 76.0, 73.0, 176.0, 74.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 136.0, 78.0, 77.0, 78.0, 122.0, 78.0, 79.0, 122.0], "value": 92.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 113.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 149.0, 149.0, 151.0, 151.0, 150.0, 150.0, 150.0, 150.0, 150.0], "value": 150.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 50.0, 43.0, 43.0, 44.0, 43.0, 45.0, 45.0, 43.0, 44.0], "value": 44.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 36.0, 37.0, 37.0, 40.0, 37.0, 37.0, 37.0, 36.0, 37.0], "value": 37.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 71.0, 71.0, 73.0, 71.0, 72.0, 71.0, 71.0, 74.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 129.0, 121.0, 109.0, 115.0, 110.0, 109.0, 109.0, 152.0], "value": 117.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 154.0, 162.0, 153.0, 152.0, 156.0, 157.0, 153.0, 153.0, 152.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 59.0, 59.0, 58.0, 62.0, 63.0, 59.0, 58.0, 58.0], "value": 59.8, "unit": "score"}]}]} 03:39:25 INFO - Return code: 0 03:39:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:39:26 INFO - # TBPL SUCCESS # 03:39:26 INFO - Running post-action listener: _package_coverage_data 03:39:26 INFO - Running post-action listener: _resource_record_post_action 03:39:26 INFO - [mozharness: 2017-08-30 10:39:26.037396Z] Finished run-tests step (success) 03:39:26 INFO - Running post-run listener: _resource_record_post_run 03:39:26 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 8192; Write bytes: 373329920; Read time: 236; Write time: 244508 03:39:26 INFO - TinderboxPrint: CPU usage
13.1% 03:39:26 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 236 03:39:26 INFO - TinderboxPrint: I/O write bytes / time
373,329,920 / 244,508 03:39:26 INFO - TinderboxPrint: CPU idle
8,219.8 (86.9%) 03:39:26 INFO - TinderboxPrint: CPU user
1,187.8 (12.6%) 03:39:26 INFO - TinderboxPrint: Swap in / out
0 / 0 03:39:26 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 107282432; Read time: 28; Write time: 102408 03:39:26 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:39:26 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 4096; Write bytes: 266047488; Read time: 208; Write time: 142100 03:39:26 INFO - Running post-run listener: _upload_blobber_files 03:39:26 INFO - Blob upload gear active. 03:39:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:39:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:39:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:39:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:39:27 INFO - (blobuploader) - INFO - Open directory for files ... 03:39:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:39:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:39:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:39:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:39:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:39:28 INFO - (blobuploader) - INFO - Done attempting. 03:39:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:39:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:39:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:39:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:39:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:39:31 INFO - (blobuploader) - INFO - Done attempting. 03:39:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:39:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:39:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:39:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:39:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:39:32 INFO - (blobuploader) - INFO - Done attempting. 03:39:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:39:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:39:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:39:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:39:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:39:33 INFO - (blobuploader) - INFO - Done attempting. 03:39:33 INFO - (blobuploader) - INFO - Iteration through files over. 03:39:33 INFO - Return code: 0 03:39:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:39:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:39:33 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d19b01326c0e908aa3b5f9ed7ff0613e4337f0c7df8ee849b7e1665a795b543c6b084a8f8c27eeab9d793a89b06d8833c3e10a40e51c5845b0553ace631a8a9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/190f9b7f04a0f6fe4e9846ad12c230cba8e711631bebf39aeaa1434e299c43a5c71bde5f2dd1f0e68bb57a891df9c3c550f1a242e75df0b32e63455fce96b933", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/286b7a4ee4b751bcbe252058b88eb246110c162fd1244ed6441544ed35017f1b3e86f4199c64f0a7eb92c59bf77f24ebc020ea812c627a659ceffb74b4d94134", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d74c003fd57ed47002e9909c032cb073256e725601762e282a542757eaef6108e0cef606d4c79485d46c3e6d9507d1dd9493e07965e1b2ed8fd07e556dba5a2f"} 03:39:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:39:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:39:33 INFO - Contents: 03:39:33 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d19b01326c0e908aa3b5f9ed7ff0613e4337f0c7df8ee849b7e1665a795b543c6b084a8f8c27eeab9d793a89b06d8833c3e10a40e51c5845b0553ace631a8a9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/190f9b7f04a0f6fe4e9846ad12c230cba8e711631bebf39aeaa1434e299c43a5c71bde5f2dd1f0e68bb57a891df9c3c550f1a242e75df0b32e63455fce96b933", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/286b7a4ee4b751bcbe252058b88eb246110c162fd1244ed6441544ed35017f1b3e86f4199c64f0a7eb92c59bf77f24ebc020ea812c627a659ceffb74b4d94134", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d74c003fd57ed47002e9909c032cb073256e725601762e282a542757eaef6108e0cef606d4c79485d46c3e6d9507d1dd9493e07965e1b2ed8fd07e556dba5a2f"} 03:39:33 INFO - Running post-run listener: copy_logs_to_upload_dir 03:39:33 INFO - Copying logs to upload dir... 03:39:33 INFO - mkdir: /builds/slave/test/build/upload/logs 03:39:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.786032 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-08-30 03:39:33.492532) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-30 03:39:33.496388) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d19b01326c0e908aa3b5f9ed7ff0613e4337f0c7df8ee849b7e1665a795b543c6b084a8f8c27eeab9d793a89b06d8833c3e10a40e51c5845b0553ace631a8a9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/190f9b7f04a0f6fe4e9846ad12c230cba8e711631bebf39aeaa1434e299c43a5c71bde5f2dd1f0e68bb57a891df9c3c550f1a242e75df0b32e63455fce96b933", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/286b7a4ee4b751bcbe252058b88eb246110c162fd1244ed6441544ed35017f1b3e86f4199c64f0a7eb92c59bf77f24ebc020ea812c627a659ceffb74b4d94134", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d74c003fd57ed47002e9909c032cb073256e725601762e282a542757eaef6108e0cef606d4c79485d46c3e6d9507d1dd9493e07965e1b2ed8fd07e556dba5a2f"} build_url:https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011422 build_url: 'https://queue.taskcluster.net/v1/task/EglbPCUQT12-jMq7e8GjrQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d19b01326c0e908aa3b5f9ed7ff0613e4337f0c7df8ee849b7e1665a795b543c6b084a8f8c27eeab9d793a89b06d8833c3e10a40e51c5845b0553ace631a8a9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/190f9b7f04a0f6fe4e9846ad12c230cba8e711631bebf39aeaa1434e299c43a5c71bde5f2dd1f0e68bb57a891df9c3c550f1a242e75df0b32e63455fce96b933", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/286b7a4ee4b751bcbe252058b88eb246110c162fd1244ed6441544ed35017f1b3e86f4199c64f0a7eb92c59bf77f24ebc020ea812c627a659ceffb74b4d94134", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d74c003fd57ed47002e9909c032cb073256e725601762e282a542757eaef6108e0cef606d4c79485d46c3e6d9507d1dd9493e07965e1b2ed8fd07e556dba5a2f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-30 03:39:33.530342) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:39:33.531092) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1504088109.944407-1832299611 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004469 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 03:39:33.612735) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-30 03:39:33.613052) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-30 03:39:33.613368) ========= ========= Total master_lag: 0.20 =========