builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-038 starttime: 1504688050.15 results: success (0) revision: b235fb79d6e017b9f47309cb06eb701c06b7e8d2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-06 01:54:10.145940) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-06 01:54:10.146360) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-06 01:54:10.146632) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-06 01:54:10.163823) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:54:10.164086) ========= 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=8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-06 01:54:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 54.7M=0s 2017-09-06 01:54:10 (54.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130346 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:54:10.310797) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:54:10.311112) ========= 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=8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019533 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 01:54:10.360208) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-06 01:54:10.360862) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b235fb79d6e017b9f47309cb06eb701c06b7e8d2 --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 b235fb79d6e017b9f47309cb06eb701c06b7e8d2 --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=8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-06 01:54:10,701 truncating revision to first 12 chars 2017-09-06 01:54:10,701 Setting DEBUG logging. 2017-09-06 01:54:10,701 attempt 1/10 2017-09-06 01:54:10,701 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b235fb79d6e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-06 01:54:11,714 unpacking tar archive at: mozilla-central-b235fb79d6e0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.314695 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-06 01:54:11.694544) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-06 01:54:11.694862) ========= script_repo_revision: b235fb79d6e017b9f47309cb06eb701c06b7e8d2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-06 01:54:11.695230) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-06 01:54:11.695504) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-06 01:54:11.709000) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-09-06 01:54:11.709283) ========= /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=8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:54:12 INFO - MultiFileLogger online at 20170906 01:54:12 in /builds/slave/test 01:54:12 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 01:54:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:54:12 INFO - {'append_to_log': False, 01:54:12 INFO - 'base_work_dir': '/builds/slave/test', 01:54:12 INFO - 'blob_upload_branch': 'Firefox', 01:54:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:54:12 INFO - 'branch': 'Firefox', 01:54:12 INFO - 'buildbot_json_path': 'buildprops.json', 01:54:12 INFO - 'code_coverage': False, 01:54:12 INFO - 'config_files': ('talos/linux_config.py',), 01:54:12 INFO - 'default_actions': ('clobber', 01:54:12 INFO - 'read-buildbot-config', 01:54:12 INFO - 'download-and-extract', 01:54:12 INFO - 'populate-webroot', 01:54:12 INFO - 'create-virtualenv', 01:54:12 INFO - 'install', 01:54:12 INFO - 'setup-mitmproxy', 01:54:12 INFO - 'run-tests'), 01:54:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:54:12 INFO - 'disable_ccov_upload': False, 01:54:12 INFO - 'disable_stylo': False, 01:54:12 INFO - 'download_minidump_stackwalk': True, 01:54:12 INFO - 'download_symbols': 'ondemand', 01:54:12 INFO - 'enable_stylo': False, 01:54:12 INFO - 'enable_webrender': False, 01:54:12 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 01:54:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:54:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:54:12 INFO - 'gecko_profile': False, 01:54:12 INFO - 'gecko_profile_interval': 0, 01:54:12 INFO - 'installer_path': 'installer.exe', 01:54:12 INFO - 'log_level': 'info', 01:54:12 INFO - 'log_name': 'talos', 01:54:12 INFO - 'log_to_console': True, 01:54:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:54:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:54:12 INFO - 'opt_config_files': (), 01:54:12 INFO - 'pip_index': False, 01:54:12 INFO - 'suite': 'dromaeojs-e10s', 01:54:12 INFO - 'system_bits': '32', 01:54:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:54:12 INFO - 'title': 'talos-linux64-ix-038', 01:54:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:54:12 INFO - 'use_talos_json': True, 01:54:12 INFO - 'verify': 'False', 01:54:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:54:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:54:12 INFO - 'work_dir': 'build'} 01:54:12 INFO - [mozharness: 2017-09-06 08:54:12.191701Z] Running clobber step. 01:54:12 INFO - Running pre-action listener: _resource_record_pre_action 01:54:12 INFO - Running main action method: clobber 01:54:12 INFO - rmtree: /builds/slave/test/build 01:54:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:54:13 INFO - Running post-action listener: _resource_record_post_action 01:54:13 INFO - [mozharness: 2017-09-06 08:54:13.716706Z] Finished clobber step (success) 01:54:13 INFO - [mozharness: 2017-09-06 08:54:13.716804Z] Running read-buildbot-config step. 01:54:13 INFO - Running pre-action listener: _resource_record_pre_action 01:54:13 INFO - Running main action method: read_buildbot_config 01:54:13 INFO - Using buildbot properties: 01:54:13 INFO - { 01:54:13 INFO - "project": "", 01:54:13 INFO - "product": "firefox", 01:54:13 INFO - "who": "archaeopteryx@coole-files.de", 01:54:13 INFO - "installer_path": "public/build/target.tar.bz2", 01:54:13 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 01:54:13 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 01:54:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 01:54:13 INFO - "stage_platform": "linux64-pgo", 01:54:13 INFO - "basedir": "/builds/slave/test", 01:54:13 INFO - "buildnumber": 141, 01:54:13 INFO - "platform": "ubuntu64_hw", 01:54:13 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 01:54:13 INFO - "slavebuilddir": "test", 01:54:13 INFO - "taskId": "dYTWBaMvQciP8LA7eFH4Qw", 01:54:13 INFO - "branch": "mozilla-central", 01:54:13 INFO - "script_repo_revision": "production", 01:54:13 INFO - "revision": "b235fb79d6e017b9f47309cb06eb701c06b7e8d2", 01:54:13 INFO - "slavename": "talos-linux64-ix-038", 01:54:13 INFO - "repo_path": "mozilla-central" 01:54:13 INFO - } 01:54:13 INFO - Finding installer, test and symbols from parent task. 01:54:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dYTWBaMvQciP8LA7eFH4Qw'}, attempt #1 01:54:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dYTWBaMvQciP8LA7eFH4Qw'}, attempt #1 01:54:14 INFO - Task dependencies: T_xb_5lOTcid8TTYpSDIPw 01:54:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw'}, attempt #1 01:54:15 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.tar.bz2 01:54:15 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.test_packages.json 01:54:15 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.crashreporter-symbols.zip 01:54:15 INFO - Running post-action listener: _resource_record_post_action 01:54:15 INFO - [mozharness: 2017-09-06 08:54:15.051041Z] Finished read-buildbot-config step (success) 01:54:15 INFO - [mozharness: 2017-09-06 08:54:15.051257Z] Running download-and-extract step. 01:54:15 INFO - Running pre-action listener: _resource_record_pre_action 01:54:15 INFO - Running main action method: download_and_extract 01:54:15 INFO - mkdir: /builds/slave/test/build/tests 01:54:15 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')]} 01:54:15 INFO - trying https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.test_packages.json 01:54:15 INFO - Downloading https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 01:54:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 01:54:15 INFO - Downloaded 989 bytes. 01:54:15 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 01:54:15 INFO - Using the following test package requirements: 01:54:15 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 01:54:15 INFO - u'common': [u'target.common.tests.zip'], 01:54:15 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 01:54:15 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 01:54:15 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 01:54:15 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 01:54:15 INFO - u'mozbase': [u'target.common.tests.zip'], 01:54:15 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 01:54:15 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 01:54:15 INFO - u'web-platform': [u'target.common.tests.zip', 01:54:15 INFO - u'target.web-platform.tests.tar.gz'], 01:54:15 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 01:54:15 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 01:54:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.common.tests.zip 01:54:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:54:15 INFO - Fetch https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.common.tests.zip into memory 01:54:16 INFO - Content-Length response header: 55675621 01:54:16 INFO - Bytes received: 55675621 01:54:28 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 01:54:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.common.tests.zip 01:54:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:54:28 INFO - Fetch https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.common.tests.zip into memory 01:54:29 INFO - Content-Length response header: 55675621 01:54:29 INFO - Bytes received: 55675621 01:54:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.talos.tests.zip 01:54:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 01:54:41 INFO - Fetch https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.talos.tests.zip into memory 01:54:41 INFO - Content-Length response header: 14496654 01:54:41 INFO - Bytes received: 14496654 01:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:41 INFO - trying https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.tar.bz2 01:54:41 INFO - Downloading https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 01:54:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 01:54:43 INFO - Downloaded 63534018 bytes. 01:54:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.tar.bz2 01:54:43 INFO - mkdir: /builds/slave/test/properties 01:54:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:54:43 INFO - Writing to file /builds/slave/test/properties/build_url 01:54:43 INFO - Contents: 01:54:43 INFO - build_url:https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.tar.bz2 01:54:43 INFO - Running post-action listener: _resource_record_post_action 01:54:43 INFO - Running post-action listener: find_tests_for_verification 01:54:43 INFO - Running post-action listener: set_extra_try_arguments 01:54:43 INFO - [mozharness: 2017-09-06 08:54:43.116987Z] Finished download-and-extract step (success) 01:54:43 INFO - [mozharness: 2017-09-06 08:54:43.117094Z] Running populate-webroot step. 01:54:43 INFO - Running pre-action listener: _resource_record_pre_action 01:54:43 INFO - Running main action method: populate_webroot 01:54:43 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:54:43 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:54:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:54:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:54:43 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:54:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:54:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:54:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:54:43 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 01:54:43 INFO - 'talos_options': ['--enable-stylo'], 01:54:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:54:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:54:43 INFO - 'tests': ['damp', 'tps']}, 01:54:43 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 01:54:43 INFO - 'talos_options': ['--enable-stylo'], 01:54:43 INFO - 'tests': ['damp', 'tps']}, 01:54:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:54:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 01:54:43 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:54:43 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 01:54:43 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 01:54:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 01:54:43 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 01:54:43 INFO - 'talos_options': ['--enable-stylo'], 01:54:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 01:54:43 INFO - 'other-e10s': {'tests': ['a11yr', 01:54:43 INFO - 'ts_paint', 01:54:43 INFO - 'tpaint', 01:54:43 INFO - 'sessionrestore', 01:54:43 INFO - 'sessionrestore_many_windows', 01:54:43 INFO - 'sessionrestore_no_auto_restore', 01:54:43 INFO - 'tabpaint']}, 01:54:43 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:54:43 INFO - 'tests': ['a11yr', 01:54:43 INFO - 'ts_paint', 01:54:43 INFO - 'tpaint', 01:54:43 INFO - 'sessionrestore', 01:54:43 INFO - 'sessionrestore_many_windows', 01:54:43 INFO - 'sessionrestore_no_auto_restore', 01:54:43 INFO - 'tabpaint']}, 01:54:43 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 01:54:43 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 01:54:43 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:54:43 INFO - 'tests': ['bloom_basic_singleton']}, 01:54:43 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:54:43 INFO - 'tests': ['bloom_basic']}, 01:54:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:54:43 INFO - 'tsvgr_opacity', 01:54:43 INFO - 'tart', 01:54:43 INFO - 'tscrollx', 01:54:43 INFO - 'tsvg_static']}, 01:54:43 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 01:54:43 INFO - 'tests': ['tsvgx', 01:54:43 INFO - 'tsvgr_opacity', 01:54:43 INFO - 'tart', 01:54:43 INFO - 'tscrollx', 01:54:43 INFO - 'tsvg_static']}, 01:54:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:54:43 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 01:54:43 INFO - 'talos_options': ['--enable-stylo'], 01:54:43 INFO - 'tests': ['tp5o']}, 01:54:43 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 01:54:43 INFO - 'talos_options': ['--mitmproxy', 01:54:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 01:54:43 INFO - '--firstNonBlankPaint'], 01:54:43 INFO - 'tests': ['tp6_google', 01:54:43 INFO - 'tp6_youtube', 01:54:43 INFO - 'tp6_amazon', 01:54:43 INFO - 'tp6_facebook']}, 01:54:43 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 01:54:43 INFO - 'talos_options': ['--enable-stylo', 01:54:43 INFO - '--mitmproxy', 01:54:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 01:54:43 INFO - '--firstNonBlankPaint'], 01:54:43 INFO - 'tests': ['tp6_google', 01:54:43 INFO - 'tp6_youtube', 01:54:43 INFO - 'tp6_amazon', 01:54:43 INFO - 'tp6_facebook']}, 01:54:43 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 01:54:43 INFO - 'talos_options': ['--enable-stylo', 01:54:43 INFO - '--stylo-threads=1', 01:54:43 INFO - '--mitmproxy', 01:54:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 01:54:43 INFO - '--firstNonBlankPaint'], 01:54:43 INFO - 'tests': ['tp6_google', 01:54:43 INFO - 'tp6_youtube', 01:54:43 INFO - 'tp6_amazon', 01:54:43 INFO - 'tp6_facebook']}, 01:54:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:54:43 INFO - 'talos_options': ['--xperf_path', 01:54:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:54:43 INFO - 'tests': ['tp5n']}, 01:54:43 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 01:54:43 INFO - 'talos_options': ['--enable-stylo', 01:54:43 INFO - '--xperf_path', 01:54:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:54:43 INFO - 'tests': ['tp5n']}}} 01:54:43 INFO - Running post-action listener: _resource_record_post_action 01:54:43 INFO - [mozharness: 2017-09-06 08:54:43.131318Z] Finished populate-webroot step (success) 01:54:43 INFO - [mozharness: 2017-09-06 08:54:43.131407Z] Running create-virtualenv step. 01:54:43 INFO - Running pre-action listener: _resource_record_pre_action 01:54:43 INFO - Running main action method: create_virtualenv 01:54:43 INFO - Creating virtualenv /builds/slave/test/build/venv 01:54:43 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:54:43 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 01:54:43 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 01:54:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:54:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:54:43 INFO - Installing distribute.............................................................................................................................................................................................done. 01:54:43 INFO - Installing pip...............done. 01:54:43 INFO - Return code: 0 01:54:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:54:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:54:44 INFO - Reading from file tmpfile_stdout 01:54:44 INFO - Output received: 01:54:44 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 01:54:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:54:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:54:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:54:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d53a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efa090>, '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 0x1efad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef4f90>, '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': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', '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 01:54:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:54:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:54:44 INFO - Using env: {'DISPLAY': ':0', 01:54:44 INFO - 'HOME': '/home/cltbld', 01:54:44 INFO - 'LANG': 'en_US.UTF-8', 01:54:44 INFO - 'LANGUAGE': 'en_US:en', 01:54:44 INFO - 'LOGNAME': 'cltbld', 01:54:44 INFO - 'MAIL': '/var/mail/cltbld', 01:54:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:54:44 INFO - 'MOZ_NO_REMOTE': '1', 01:54:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:54:44 INFO - 'NO_EM_RESTART': '1', 01:54:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:54:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:54:44 INFO - 'PWD': '/builds/slave/test', 01:54:44 INFO - 'SHELL': '/bin/bash', 01:54:44 INFO - 'SHLVL': '1', 01:54:44 INFO - 'TERM': 'linux', 01:54:44 INFO - 'TMOUT': '86400', 01:54:44 INFO - 'USER': 'cltbld', 01:54:44 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', 01:54:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:54:44 INFO - '_': '/tools/buildbot/bin/python'} 01:54:45 INFO - Ignoring indexes: http://pypi.python.org/simple/ 01:54:45 INFO - Downloading/unpacking pip>=1.5 01:54:45 INFO - Running setup.py egg_info for package pip 01:54:45 INFO - warning: no files found matching 'pip/cacert.pem' 01:54:45 INFO - warning: no files found matching '*.html' under directory 'docs' 01:54:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 01:54:45 INFO - no previously-included directories found matching 'docs/_build/_sources' 01:54:45 INFO - Installing collected packages: pip 01:54:45 INFO - Found existing installation: pip 1.1 01:54:45 INFO - Uninstalling pip: 01:54:45 INFO - Successfully uninstalled pip 01:54:45 INFO - Running setup.py install for pip 01:54:45 INFO - warning: no files found matching 'pip/cacert.pem' 01:54:45 INFO - warning: no files found matching '*.html' under directory 'docs' 01:54:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 01:54:45 INFO - no previously-included directories found matching 'docs/_build/_sources' 01:54:45 INFO - Installing pip script to /builds/slave/test/build/venv/bin 01:54:45 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 01:54:45 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 01:54:45 INFO - Successfully installed pip 01:54:45 INFO - Cleaning up... 01:54:45 INFO - Return code: 0 01:54:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:54:45 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')]} 01:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:54:45 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')]} 01:54:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:54:45 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 0x1b8a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d53a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efa090>, '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 0x1efad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef4f90>, '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': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', '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 01:54:45 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 01:54:45 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 01:54:45 INFO - Using env: {'DISPLAY': ':0', 01:54:45 INFO - 'HOME': '/home/cltbld', 01:54:45 INFO - 'LANG': 'en_US.UTF-8', 01:54:45 INFO - 'LANGUAGE': 'en_US:en', 01:54:45 INFO - 'LOGNAME': 'cltbld', 01:54:45 INFO - 'MAIL': '/var/mail/cltbld', 01:54:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:54:45 INFO - 'MOZ_NO_REMOTE': '1', 01:54:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:54:45 INFO - 'NO_EM_RESTART': '1', 01:54:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:54:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:54:45 INFO - 'PWD': '/builds/slave/test', 01:54:45 INFO - 'SHELL': '/bin/bash', 01:54:45 INFO - 'SHLVL': '1', 01:54:45 INFO - 'TERM': 'linux', 01:54:45 INFO - 'TMOUT': '86400', 01:54:45 INFO - 'USER': 'cltbld', 01:54:45 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', 01:54:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:54:45 INFO - '_': '/tools/buildbot/bin/python'} 01:54:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:54:45 INFO - Downloading/unpacking psutil>=3.1.1 01:54:45 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 01:54:45 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 01:54:45 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 01:54:45 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 01:54:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:54:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:54:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:54:46 INFO - Installing collected packages: psutil 01:54:46 INFO - Running setup.py install for psutil 01:54:46 INFO - building 'psutil._psutil_linux' extension 01:54:46 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 01:54:46 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 01:54:46 INFO - building 'psutil._psutil_posix' extension 01:54:46 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 01:54:46 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 01:54:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:54:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:54:46 INFO - Successfully installed psutil 01:54:46 INFO - Cleaning up... 01:54:46 INFO - Return code: 0 01:54:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:54:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d53a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efa090>, '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 0x1efad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef4f90>, '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': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', '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 01:54:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:54:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:54:46 INFO - Using env: {'DISPLAY': ':0', 01:54:46 INFO - 'HOME': '/home/cltbld', 01:54:46 INFO - 'LANG': 'en_US.UTF-8', 01:54:46 INFO - 'LANGUAGE': 'en_US:en', 01:54:46 INFO - 'LOGNAME': 'cltbld', 01:54:46 INFO - 'MAIL': '/var/mail/cltbld', 01:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:54:46 INFO - 'MOZ_NO_REMOTE': '1', 01:54:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:54:46 INFO - 'NO_EM_RESTART': '1', 01:54:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:54:46 INFO - 'PWD': '/builds/slave/test', 01:54:46 INFO - 'SHELL': '/bin/bash', 01:54:46 INFO - 'SHLVL': '1', 01:54:46 INFO - 'TERM': 'linux', 01:54:46 INFO - 'TMOUT': '86400', 01:54:46 INFO - 'USER': 'cltbld', 01:54:46 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', 01:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:54:46 INFO - '_': '/tools/buildbot/bin/python'} 01:54:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:54:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:54:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:54:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:54:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:54:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:54:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:54:47 INFO - Installing collected packages: mozsystemmonitor 01:54:47 INFO - Running setup.py install for mozsystemmonitor 01:54:47 INFO - Successfully installed mozsystemmonitor 01:54:47 INFO - Cleaning up... 01:54:47 INFO - Return code: 0 01:54:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:54:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1b8a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d53a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efa090>, '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 0x1efad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef4f90>, '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': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', '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 01:54:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:54:47 INFO - Using env: {'DISPLAY': ':0', 01:54:47 INFO - 'HOME': '/home/cltbld', 01:54:47 INFO - 'LANG': 'en_US.UTF-8', 01:54:47 INFO - 'LANGUAGE': 'en_US:en', 01:54:47 INFO - 'LOGNAME': 'cltbld', 01:54:47 INFO - 'MAIL': '/var/mail/cltbld', 01:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:54:47 INFO - 'MOZ_NO_REMOTE': '1', 01:54:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:54:47 INFO - 'NO_EM_RESTART': '1', 01:54:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:54:47 INFO - 'PWD': '/builds/slave/test', 01:54:47 INFO - 'SHELL': '/bin/bash', 01:54:47 INFO - 'SHLVL': '1', 01:54:47 INFO - 'TERM': 'linux', 01:54:47 INFO - 'TMOUT': '86400', 01:54:47 INFO - 'USER': 'cltbld', 01:54:47 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', 01:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:54:47 INFO - '_': '/tools/buildbot/bin/python'} 01:54:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:54:47 INFO - Downloading/unpacking jsonschema==2.5.1 01:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:54:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:54:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:54:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:54:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:54:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:54:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:54:48 INFO - Downloading functools32-3.2.3-2.tar.gz 01:54:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:54:48 INFO - warning: no files found matching '*.txt' 01:54:48 INFO - no previously-included directories found matching 'build' 01:54:48 INFO - no previously-included directories found matching 'dist' 01:54:48 INFO - no previously-included directories found matching '.git*' 01:54:48 INFO - Installing collected packages: jsonschema, functools32 01:54:48 INFO - Running setup.py install for functools32 01:54:48 INFO - warning: no files found matching '*.txt' 01:54:48 INFO - no previously-included directories found matching 'build' 01:54:48 INFO - no previously-included directories found matching 'dist' 01:54:48 INFO - no previously-included directories found matching '.git*' 01:54:48 INFO - Successfully installed jsonschema functools32 01:54:48 INFO - Cleaning up... 01:54:48 INFO - Return code: 0 01:54:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:54:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:54:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:54:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d53a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efa090>, '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 0x1efad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef4f90>, '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': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', '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 01:54:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:54:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:54:48 INFO - Using env: {'DISPLAY': ':0', 01:54:48 INFO - 'HOME': '/home/cltbld', 01:54:48 INFO - 'LANG': 'en_US.UTF-8', 01:54:48 INFO - 'LANGUAGE': 'en_US:en', 01:54:48 INFO - 'LOGNAME': 'cltbld', 01:54:48 INFO - 'MAIL': '/var/mail/cltbld', 01:54:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:54:48 INFO - 'MOZ_NO_REMOTE': '1', 01:54:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:54:48 INFO - 'NO_EM_RESTART': '1', 01:54:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:54:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:54:48 INFO - 'PWD': '/builds/slave/test', 01:54:48 INFO - 'SHELL': '/bin/bash', 01:54:48 INFO - 'SHLVL': '1', 01:54:48 INFO - 'TERM': 'linux', 01:54:48 INFO - 'TMOUT': '86400', 01:54:48 INFO - 'USER': 'cltbld', 01:54:48 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', 01:54:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:54:48 INFO - '_': '/tools/buildbot/bin/python'} 01:54:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:54:49 INFO - Cleaning up... 01:54:49 INFO - Return code: 0 01:54:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:54:49 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')]} 01:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:54:49 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')]} 01:54:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:54:49 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 0x1b8a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d53a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efa090>, '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 0x1efad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef4f90>, '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': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', '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 01:54:49 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 01:54:49 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 01:54:49 INFO - Using env: {'DISPLAY': ':0', 01:54:49 INFO - 'HOME': '/home/cltbld', 01:54:49 INFO - 'LANG': 'en_US.UTF-8', 01:54:49 INFO - 'LANGUAGE': 'en_US:en', 01:54:49 INFO - 'LOGNAME': 'cltbld', 01:54:49 INFO - 'MAIL': '/var/mail/cltbld', 01:54:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:54:49 INFO - 'MOZ_NO_REMOTE': '1', 01:54:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:54:49 INFO - 'NO_EM_RESTART': '1', 01:54:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:54:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:54:49 INFO - 'PWD': '/builds/slave/test', 01:54:49 INFO - 'SHELL': '/bin/bash', 01:54:49 INFO - 'SHLVL': '1', 01:54:49 INFO - 'TERM': 'linux', 01:54:49 INFO - 'TMOUT': '86400', 01:54:49 INFO - 'USER': 'cltbld', 01:54:49 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', 01:54:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:54:49 INFO - '_': '/tools/buildbot/bin/python'} 01:54:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:54:49 INFO - Downloading/unpacking blobuploader==1.2.4 01:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:54:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:54:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:54:49 INFO - Downloading blobuploader-1.2.4.tar.gz 01:54:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:54:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:54:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:54:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:54:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:54:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:54:50 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 01:54:50 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 01:54:50 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 01:54:50 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 01:54:50 INFO - Downloading docopt-0.6.1.tar.gz 01:54:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:54:50 INFO - Installing collected packages: blobuploader, requests, docopt 01:54:50 INFO - Running setup.py install for blobuploader 01:54:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:54:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:54:50 INFO - Running setup.py install for requests 01:54:50 INFO - Running setup.py install for docopt 01:54:50 INFO - Successfully installed blobuploader requests docopt 01:54:50 INFO - Cleaning up... 01:54:50 INFO - Return code: 0 01:54:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:54:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:54:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:54:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d53a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efa090>, '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 0x1efad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef4f90>, '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': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', '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 01:54:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:54:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:54:50 INFO - Using env: {'DISPLAY': ':0', 01:54:50 INFO - 'HOME': '/home/cltbld', 01:54:50 INFO - 'LANG': 'en_US.UTF-8', 01:54:50 INFO - 'LANGUAGE': 'en_US:en', 01:54:50 INFO - 'LOGNAME': 'cltbld', 01:54:50 INFO - 'MAIL': '/var/mail/cltbld', 01:54:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:54:50 INFO - 'MOZ_NO_REMOTE': '1', 01:54:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:54:50 INFO - 'NO_EM_RESTART': '1', 01:54:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:54:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:54:50 INFO - 'PWD': '/builds/slave/test', 01:54:50 INFO - 'SHELL': '/bin/bash', 01:54:50 INFO - 'SHLVL': '1', 01:54:50 INFO - 'TERM': 'linux', 01:54:50 INFO - 'TMOUT': '86400', 01:54:50 INFO - 'USER': 'cltbld', 01:54:50 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', 01:54:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:54:50 INFO - '_': '/tools/buildbot/bin/python'} 01:54:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:54:51 INFO - Running setup.py (path:/tmp/pip-mxT2IK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:54:51 INFO - Running setup.py (path:/tmp/pip-Ik4ppI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:54:51 INFO - Running setup.py (path:/tmp/pip-SROXdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:54:51 INFO - Running setup.py (path:/tmp/pip-E3zGtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:54:51 INFO - Running setup.py (path:/tmp/pip-J28i8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:54:51 INFO - Running setup.py (path:/tmp/pip-OSL71z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:54:51 INFO - Running setup.py (path:/tmp/pip-aQMrhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:54:51 INFO - Running setup.py (path:/tmp/pip-FadCcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:54:51 INFO - Running setup.py (path:/tmp/pip-iAQU2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:54:51 INFO - Running setup.py (path:/tmp/pip-6ZaeKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:54:51 INFO - Running setup.py (path:/tmp/pip-JCLoxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:54:51 INFO - Running setup.py (path:/tmp/pip-Pp5W0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:54:52 INFO - Running setup.py (path:/tmp/pip-ZzRHv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:54:52 INFO - Running setup.py (path:/tmp/pip-9i0H5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:54:52 INFO - Running setup.py (path:/tmp/pip-0BbQ6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:54:52 INFO - Running setup.py (path:/tmp/pip-ExvoM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:54:52 INFO - Running setup.py (path:/tmp/pip-jsGCSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:54:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:54:52 INFO - Running setup.py install for manifestparser 01:54:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:54:52 INFO - Running setup.py install for mozcrash 01:54:52 INFO - Running setup.py install for mozdebug 01:54:52 INFO - Running setup.py install for mozdevice 01:54:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:54:52 INFO - Running setup.py install for mozfile 01:54:52 INFO - Running setup.py install for mozhttpd 01:54:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:54:52 INFO - Running setup.py install for mozinfo 01:54:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:54:53 INFO - Running setup.py install for mozInstall 01:54:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:54:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:54:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:54:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:54:53 INFO - Running setup.py install for mozleak 01:54:53 INFO - Running setup.py install for mozlog 01:54:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:54:53 INFO - Running setup.py install for moznetwork 01:54:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:54:53 INFO - Running setup.py install for mozprocess 01:54:53 INFO - Running setup.py install for mozprofile 01:54:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:54:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:54:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:54:53 INFO - Running setup.py install for mozrunner 01:54:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:54:53 INFO - Running setup.py install for mozscreenshot 01:54:53 INFO - Running setup.py install for moztest 01:54:53 INFO - Running setup.py install for mozversion 01:54:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:54:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:54:54 INFO - Cleaning up... 01:54:54 INFO - Return code: 0 01:54:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:54:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:54:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:54:54 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 0x1b8a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d53a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efa090>, '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 0x1efad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef4f90>, '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': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', '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 01:54:54 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 01:54:54 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 01:54:54 INFO - Using env: {'DISPLAY': ':0', 01:54:54 INFO - 'HOME': '/home/cltbld', 01:54:54 INFO - 'LANG': 'en_US.UTF-8', 01:54:54 INFO - 'LANGUAGE': 'en_US:en', 01:54:54 INFO - 'LOGNAME': 'cltbld', 01:54:54 INFO - 'MAIL': '/var/mail/cltbld', 01:54:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:54:54 INFO - 'MOZ_NO_REMOTE': '1', 01:54:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:54:54 INFO - 'NO_EM_RESTART': '1', 01:54:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:54:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:54:54 INFO - 'PWD': '/builds/slave/test', 01:54:54 INFO - 'SHELL': '/bin/bash', 01:54:54 INFO - 'SHLVL': '1', 01:54:54 INFO - 'TERM': 'linux', 01:54:54 INFO - 'TMOUT': '86400', 01:54:54 INFO - 'USER': 'cltbld', 01:54:54 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', 01:54:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:54:54 INFO - '_': '/tools/buildbot/bin/python'} 01:54:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:54:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:54:54 INFO - Running setup.py (path:/tmp/pip-eUARYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:54:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:54:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:54:54 INFO - Running setup.py (path:/tmp/pip-Z1nMsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:54:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:54:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:54:54 INFO - Running setup.py (path:/tmp/pip-28E9e6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:54:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:54:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:54:54 INFO - Running setup.py (path:/tmp/pip-aImVkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:54:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:54:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:54:54 INFO - Running setup.py (path:/tmp/pip-Xq6Wcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:54:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:54:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:54:54 INFO - Running setup.py (path:/tmp/pip-bGEr7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:54:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:54:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:54:54 INFO - Running setup.py (path:/tmp/pip-Oj4_vP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:54:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:54:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:54:54 INFO - Running setup.py (path:/tmp/pip-3PU_2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:54:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:54:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:54:54 INFO - Running setup.py (path:/tmp/pip-cocHpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:54:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:54:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:54:54 INFO - Running setup.py (path:/tmp/pip-n1FKIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:54:55 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)) 01:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:54:55 INFO - Running setup.py (path:/tmp/pip-0wlWyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:54:55 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)) 01:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:54:55 INFO - Running setup.py (path:/tmp/pip-fL2um_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:54:55 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)) 01:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:54:55 INFO - Running setup.py (path:/tmp/pip-nBakU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:54:55 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)) 01:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:54:55 INFO - Running setup.py (path:/tmp/pip-vd1Xh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:54:55 INFO - Running setup.py (path:/tmp/pip-gJz8Ym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:54:55 INFO - Running setup.py (path:/tmp/pip-Ppkneg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:54:55 INFO - Running setup.py (path:/tmp/pip-m2MFhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:54:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:54:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:54:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:54:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:54:56 INFO - Downloading blessings-1.6.tar.gz 01:54:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:54:56 INFO - Installing collected packages: blessings 01:54:56 INFO - Running setup.py install for blessings 01:54:56 INFO - Successfully installed blessings 01:54:56 INFO - Cleaning up... 01:54:56 INFO - Return code: 0 01:54:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:54:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:54:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:54:56 INFO - Reading from file tmpfile_stdout 01:54:56 INFO - Current package versions: 01:54:56 INFO - argparse == 1.2.1 01:54:56 INFO - blessings == 1.6 01:54:56 INFO - blobuploader == 1.2.4 01:54:56 INFO - docopt == 0.6.1 01:54:56 INFO - functools32 == 3.2.3-2 01:54:56 INFO - jsonschema == 2.5.1 01:54:56 INFO - manifestparser == 1.1 01:54:56 INFO - mozInstall == 1.13 01:54:56 INFO - mozcrash == 1.0 01:54:56 INFO - mozdebug == 0.1 01:54:56 INFO - mozdevice == 0.51 01:54:56 INFO - mozfile == 1.2 01:54:56 INFO - mozhttpd == 0.7 01:54:56 INFO - mozinfo == 0.10 01:54:56 INFO - mozleak == 0.1 01:54:56 INFO - mozlog == 3.5 01:54:56 INFO - moznetwork == 0.27 01:54:56 INFO - mozprocess == 0.25 01:54:56 INFO - mozprofile == 0.28 01:54:56 INFO - mozrunner == 6.13 01:54:56 INFO - mozscreenshot == 0.1 01:54:56 INFO - mozsystemmonitor == 0.3 01:54:56 INFO - moztest == 0.8 01:54:56 INFO - mozversion == 1.4 01:54:56 INFO - psutil == 3.1.1 01:54:56 INFO - requests == 1.2.3 01:54:56 INFO - wsgiref == 0.1.2 01:54:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:54:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:54:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:54:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d53a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efa090>, '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 0x1efad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef4f90>, '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': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', '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 01:54:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:54:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:54:56 INFO - Using env: {'DISPLAY': ':0', 01:54:56 INFO - 'HOME': '/home/cltbld', 01:54:56 INFO - 'LANG': 'en_US.UTF-8', 01:54:56 INFO - 'LANGUAGE': 'en_US:en', 01:54:56 INFO - 'LOGNAME': 'cltbld', 01:54:56 INFO - 'MAIL': '/var/mail/cltbld', 01:54:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:54:56 INFO - 'MOZ_NO_REMOTE': '1', 01:54:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:54:56 INFO - 'NO_EM_RESTART': '1', 01:54:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:54:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:54:56 INFO - 'PWD': '/builds/slave/test', 01:54:56 INFO - 'SHELL': '/bin/bash', 01:54:56 INFO - 'SHLVL': '1', 01:54:56 INFO - 'TERM': 'linux', 01:54:56 INFO - 'TMOUT': '86400', 01:54:56 INFO - 'USER': 'cltbld', 01:54:56 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', 01:54:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:54:56 INFO - '_': '/tools/buildbot/bin/python'} 01:54:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:54:56 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 01:54:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:54:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:54:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:54:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:54:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 01:54:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:54:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:54:58 INFO - Installing collected packages: simplejson 01:54:58 INFO - Running setup.py install for simplejson 01:54:58 INFO - building 'simplejson._speedups' extension 01:54:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 01:54:58 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 01:54:59 INFO - Successfully installed simplejson 01:54:59 INFO - Cleaning up... 01:54:59 INFO - Return code: 0 01:54:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:54:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:54:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d53a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ef6a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efa090>, '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 0x1efad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef4f90>, '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': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', '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 01:54:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:54:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:54:59 INFO - Using env: {'DISPLAY': ':0', 01:54:59 INFO - 'HOME': '/home/cltbld', 01:54:59 INFO - 'LANG': 'en_US.UTF-8', 01:54:59 INFO - 'LANGUAGE': 'en_US:en', 01:54:59 INFO - 'LOGNAME': 'cltbld', 01:54:59 INFO - 'MAIL': '/var/mail/cltbld', 01:54:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:54:59 INFO - 'MOZ_NO_REMOTE': '1', 01:54:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:54:59 INFO - 'NO_EM_RESTART': '1', 01:54:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:54:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:54:59 INFO - 'PWD': '/builds/slave/test', 01:54:59 INFO - 'SHELL': '/bin/bash', 01:54:59 INFO - 'SHLVL': '1', 01:54:59 INFO - 'TERM': 'linux', 01:54:59 INFO - 'TMOUT': '86400', 01:54:59 INFO - 'USER': 'cltbld', 01:54:59 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', 01:54:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:54:59 INFO - '_': '/tools/buildbot/bin/python'} 01:54:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:54:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:54:59 INFO - Cleaning up... 01:54:59 INFO - Return code: 0 01:54:59 INFO - Running post-action listener: _resource_record_post_action 01:54:59 INFO - Running post-action listener: _start_resource_monitoring 01:54:59 INFO - Starting resource monitoring. 01:54:59 INFO - [mozharness: 2017-09-06 08:54:59.376381Z] Finished create-virtualenv step (success) 01:54:59 INFO - [mozharness: 2017-09-06 08:54:59.376899Z] Running install step. 01:54:59 INFO - Running pre-action listener: _resource_record_pre_action 01:54:59 INFO - Running main action method: install 01:54:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:54:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:54:59 INFO - Reading from file tmpfile_stdout 01:54:59 INFO - Detecting whether we're running mozinstall >=1.0... 01:54:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:54:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:54:59 INFO - Reading from file tmpfile_stdout 01:54:59 INFO - Output received: 01:54:59 INFO - Usage: mozinstall [options] installer 01:54:59 INFO - Options: 01:54:59 INFO - -h, --help show this help message and exit 01:54:59 INFO - -d DEST, --destination=DEST 01:54:59 INFO - Directory to install application into. [default: 01:54:59 INFO - "/builds/slave/test"] 01:54:59 INFO - --app=APP Application being installed. [default: firefox] 01:54:59 INFO - mkdir: /builds/slave/test/build/application 01:54:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:54:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 01:55:16 INFO - Reading from file tmpfile_stdout 01:55:16 INFO - Output received: 01:55:16 INFO - /builds/slave/test/build/application/firefox/firefox 01:55:16 INFO - Running post-action listener: _resource_record_post_action 01:55:16 INFO - [mozharness: 2017-09-06 08:55:16.107145Z] Finished install step (success) 01:55:16 INFO - [mozharness: 2017-09-06 08:55:16.107395Z] Running setup-mitmproxy step. 01:55:16 INFO - Running pre-action listener: _resource_record_pre_action 01:55:16 INFO - Running main action method: setup_mitmproxy 01:55:16 INFO - Skipping: mitmproxy is not required 01:55:16 INFO - Running post-action listener: _resource_record_post_action 01:55:16 INFO - [mozharness: 2017-09-06 08:55:16.108504Z] Finished setup-mitmproxy step (success) 01:55:16 INFO - [mozharness: 2017-09-06 08:55:16.108722Z] Running run-tests step. 01:55:16 INFO - Running pre-action listener: _resource_record_pre_action 01:55:16 INFO - Running pre-action listener: _set_gcov_prefix 01:55:16 INFO - Running main action method: run_tests 01:55:16 WARNING - Try message not found. 01:55:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:55:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:55:16 INFO - Python 2.7.3 01:55:16 INFO - Return code: 0 01:55:16 INFO - grabbing minidump binary from tooltool 01:55:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:55:16 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1efa090>, '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 0x1efad80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ef4f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:55:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:55:16 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 01:55:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 01:55:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:55:16 INFO - Return code: 0 01:55:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:55:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:55:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:55:16 INFO - ENV: RUST_BACKTRACE is now full 01:55:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:55:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:55:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 01:55:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 01:55:16 INFO - Using env: {'DISPLAY': ':0', 01:55:16 INFO - 'HOME': '/home/cltbld', 01:55:16 INFO - 'LANG': 'en_US.UTF-8', 01:55:16 INFO - 'LANGUAGE': 'en_US:en', 01:55:16 INFO - 'LOGNAME': 'cltbld', 01:55:16 INFO - 'MAIL': '/var/mail/cltbld', 01:55:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:55:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:55:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:55:16 INFO - 'MOZ_NO_REMOTE': '1', 01:55:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:55:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:55:16 INFO - 'NO_EM_RESTART': '1', 01:55:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:55:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:55:16 INFO - 'PWD': '/builds/slave/test', 01:55:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:55:16 INFO - 'RUST_BACKTRACE': 'full', 01:55:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 01:55:16 INFO - 'SHELL': '/bin/bash', 01:55:16 INFO - 'SHLVL': '1', 01:55:16 INFO - 'TERM': 'linux', 01:55:16 INFO - 'TMOUT': '86400', 01:55:16 INFO - 'USER': 'cltbld', 01:55:16 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', 01:55:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:55:16 INFO - '_': '/tools/buildbot/bin/python'} 01:55:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 01:55:16 INFO - mozversion application_buildid: 20170906073832 01:55:16 INFO - mozversion application_changeset: b235fb79d6e017b9f47309cb06eb701c06b7e8d2 01:55:16 INFO - mozversion application_display_name: Nightly 01:55:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:55:16 INFO - mozversion application_name: Firefox 01:55:16 INFO - mozversion application_remotingname: firefox 01:55:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 01:55:16 INFO - mozversion application_vendor: Mozilla 01:55:16 INFO - mozversion application_version: 57.0a1 01:55:16 INFO - mozversion platform_buildid: 20170906073832 01:55:16 INFO - mozversion platform_changeset: b235fb79d6e017b9f47309cb06eb701c06b7e8d2 01:55:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 01:55:16 INFO - mozversion platform_version: 57.0a1 01:55:16 INFO - using testdate: 1504688116 01:55:16 INFO - actual date: 1504688116 01:55:16 INFO - starting webserver on 'localhost:34383' 01:55:16 INFO - SUITE-START | Running 2 tests 01:55:16 INFO - TEST-START | dromaeo_css 01:55:16 INFO - Initialising browser for dromaeo_css test... 01:55:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34383/getInfo.html -profile /tmp/tmpPoCFBa/profile 01:55:16 INFO - TEST-INFO | started process 25872 (/builds/slave/test/build/application/firefox/firefox http://localhost:34383/getInfo.html) 01:55:25 INFO - TEST-INFO | 25872: exit 0 01:55:26 INFO - Browser initialized. 01:55:26 INFO - Running cycle 1/1 for dromaeo_css test... 01:55:26 INFO - Using env: {'DISPLAY': ':0', 01:55:26 INFO - 'HOME': '/home/cltbld', 01:55:26 INFO - 'JSGC_DISABLE_POISONING': '1', 01:55:26 INFO - 'LANG': 'en_US.UTF-8', 01:55:26 INFO - 'LANGUAGE': 'en_US:en', 01:55:26 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 01:55:26 INFO - 'LOGNAME': 'cltbld', 01:55:26 INFO - 'MAIL': '/var/mail/cltbld', 01:55:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:55:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:55:26 INFO - 'MOZ_CRASHREPORTER': '1', 01:55:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:55:26 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 01:55:26 INFO - 'MOZ_NO_REMOTE': '1', 01:55:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:55:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:55:26 INFO - 'NO_EM_RESTART': '1', 01:55:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:55:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:55:26 INFO - 'PWD': '/builds/slave/test', 01:55:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:55:26 INFO - 'RUST_BACKTRACE': 'full', 01:55:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 01:55:26 INFO - 'SHELL': '/bin/bash', 01:55:26 INFO - 'SHLVL': '1', 01:55:26 INFO - 'TERM': 'linux', 01:55:26 INFO - 'TMOUT': '86400', 01:55:26 INFO - 'USER': 'cltbld', 01:55:26 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', 01:55:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:55:26 INFO - '_': '/tools/buildbot/bin/python'} 01:55:26 INFO - TEST-INFO | started process 26059 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPoCFBa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 01:55:26 INFO - PID 26059 | 01:55:26 INFO - PID 26059 | (/builds/slave/test/build/application/firefox/firefox:26111): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 01:55:26 INFO - PID 26059 | 01:55:37 INFO - PID 26059 | RSS: Main: 161738752 01:55:37 INFO - PID 26059 | 01:58:35 INFO - PID 26059 | Cycle 1(1): loaded http://localhost:34383/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34383/tests/dromaeo/cssquery-ext.html) 01:58:36 INFO - PID 26059 | RSS: Main: 166825984 01:58:36 INFO - PID 26059 | 02:01:37 INFO - PID 26059 | Cycle 1(1): loaded http://localhost:34383/tests/dromaeo/cssquery-ext.html (next: http://localhost:34383/tests/dromaeo/cssquery-jquery.html) 02:01:38 INFO - PID 26059 | RSS: Main: 163246080 02:01:38 INFO - PID 26059 | 02:04:39 INFO - PID 26059 | Cycle 1(1): loaded http://localhost:34383/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34383/tests/dromaeo/cssquery-mootools.html) 02:04:40 INFO - PID 26059 | RSS: Main: 165679104 02:04:40 INFO - PID 26059 | 02:07:42 INFO - PID 26059 | Cycle 1(1): loaded http://localhost:34383/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34383/tests/dromaeo/cssquery-prototype.html) 02:07:42 INFO - PID 26059 | RSS: Main: 164167680 02:07:42 INFO - PID 26059 | 02:10:45 INFO - PID 26059 | Cycle 1(1): loaded http://localhost:34383/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34383/tests/dromaeo/cssquery-yui.html) 02:10:46 INFO - PID 26059 | RSS: Main: 165523456 02:10:46 INFO - PID 26059 | 02:13:48 INFO - PID 26059 | Cycle 1(1): loaded http://localhost:34383/tests/dromaeo/cssquery-yui.html (next: http://localhost:34383/tests/dromaeo/cssquery-dojo.html) 02:13:48 INFO - PID 26059 | RSS: Main: 168599552 02:13:48 INFO - PID 26059 | 02:13:48 INFO - PID 26059 | __start_tp_report 02:13:48 INFO - PID 26059 | _x_x_mozilla_page_load 02:13:48 INFO - PID 26059 | _x_x_mozilla_page_load_details 02:13:48 INFO - PID 26059 | |i|pagename|runs| 02:13:48 INFO - PID 26059 | |0;dojo.html;3824.9235015299696;3908.6677632401243;3933.5083114610675;3938.1336106056674;3956.861509847156;6762.357576030276;6972.930270697291;6984.685689143988;7001.089858318424;7003;6778.051072849793;6907.412869906064;6912.066870972417;6919.996600492926;6956.721731130761;6066.363031881655;6194.845128871781;6251.343608921066;6269.498440124779;6331.620102793824;6232;6288.685565721696;6289.654069026202;6323.74705011799;6326.272478664943;6101.908471372933;6112.358202388752;6112.69436528174;6235.875282494325;6249.2500899892075;6151.384861513813;6162.383761623802;6225.7509699611965;6245.063240513914;6266.279377871558;6019.729112189918;6146.784862529835;6159.907601385983;6218.098375735538;6218.22272215973;6789.592624442549;6800.217974932896;6802.965985170043;6826.692798824065;6886.758963436306;7461.664225109829;7485.0643669541305;7574.734884279079;7595.772126836148;7607.467477276593;7556.65995030225;7561.735339263154;7579.962100189519;7640.5797681127515;7693.076830780286;6804.421624161952;6848.486363522756;6873.862522749517;7014.263502332233;7117.3238542338395;7454.515456495311;7621.618919054025;7630.7710768677025;7644.847103058019;7658.349040331523;7293.124825021031;7330.303621155982;7364.3003914628025;7369.22623124575;7451.3666338361845;7445.7766267012075;7494.925050749423;7497.66260518278;7577.0907491101425;7578.355839753573;7243.348098671145;7377.446691498159;7429.36850367714;7497.962510187414;7505.774826755206;7260.618456871526;7341.155767086747;7389.187189409161;7469.253074692488;7519.624018799038;6094.542909281821;6149.354317796657;6160.10678451622;6408.231012278557;6434.356564343622;5388.973055134699;5409.918851217235;5415.404305526389;5419.891602168013;5424.810131645373;6299.842503937347;6324.051392291193;6342.207224096988;6347.39699728525;6467.32093130224;7482.625868706543;7556.546607203585;7576.772696819105;7589.203133670995;7591.278828511283;6428.71070801815;6445.323241059715;6514.022896565459;6515.771947981798;6520.836979075466;6532.444742196872;6628.933710662928;6630.171228596426;6633.900491492535;6636.933630663728;6371.203599550056;7015.543989640657;7108.92891071093;7142.464315176382;7145.82135446618;7482.176960534337;7489.438292128221;7561.470697051153;7596.96201519011;7597.772066838003;7250.093738282715;7265.382442492447;7275.236100209402;7364.926350736639;7394.852102958018;7190.676419561237;7317.926820731937;7326.194118647051;7377;7420.257974202536;7178.425725941807;7301.306375894282;7312.305330993547;7344.375728062961;7375.557466552024;7001.08985831859;7001.474889383215;7084;7093.432525397852;7110.680019399036;6987.301269872972;7001.054857594159;7090.858182836211;7102.893456598052;7104.857902841811;7040.0847889774;7136.143662760502;7164.068671072936;7204.53170543913;7210.9278907210255;7234.421246300389;7310.415166786538;7367.300106489874;7368.668409921674;7374.6681399336085;6899.793006209822;7012.1935977363255;7056.082709247765;7138;7153.535020223669 02:13:48 INFO - PID 26059 | |1;ext.html;905.3662436294593;916.4134953629674;951.6145960885841;978.4276198423921;979.0992287095871;41913.371299430495;42734.57265427345;42865.142697146075;42918.785406073;42958.785206073924;39783.204335913266;39819.40270895939;39992;40022;40022.79988600053;7332;7416.184219735825;7420.628968551578;7430.256974302553;7432.628368581576;7305.159906610728;7356.337929586336;7359.190489046201;7359.77920662381;7396.70413183472;7746.38028957682;7904.565248911307;7905.090914544815;7907;7921.247481489249;7805.141434442206;7856.450048496607;7869.05571331441;7884.566348850811;7884.763457096296;7611.20082391352;7756.4182686298755;7763.262490063435;7991;8080.959595202045;39640.405393919114;39881.60118398837;39947.600523994966;39949.40075898864;40004.79997600023;3679.172186258097;3711.0907827582146;3740.869069582579;3746.550413950316;3748.775073495599;32872.50691239633;32941.50587741186;32984.175395615064;33045.83477082599;33099.83450082758;5430;5456.0997435423105;5458.2904222451225;5468;5474.534664553517;204470;207956;212549;214991;265037;53164;53182.46817531853;53343.73328133334;53357.733211333696;53577.464225358024;56532;56694;56895.163911068026;56962;57036.42963570394;16141.273642686107;16204.594885127966;16221.107839068729;16224.837751622332;16388.836111638968;13130.212187268795;13206.273654948878;13217.537386191438;13219.405126769318;13231.867681323256;15291.694166116615;15357.385704571916;15436.382544698086;15469.767953480707;15536;6234.594751341148;6238.844028899314;6256.5307601930035;6297.779577714758;6383.234011918599;1072.7371793910543;1081.2377274021796;1087.472575800736;1092.1208427216168;1105.9889401106047;25233.243002709947;25290.36774080663;25316.74683253144;25438;25475;1189.232944750631;1189.6550000499758;1197.3474456421272;1207.0102515936846;1214.0227117170587;13209.07536472457;13209.669758256121;13236.735265294641;13249.337533123306;13454.932725336506;292074;294207;294460;294892;295334;56087.43912560822;56246;56246.43753562412;56733.71633141808;56812.43187568154;1483.7700156475764;1489.8510148985015;1508.6379268975368;1510.9924450377678;1534.5549790560835;24816.255512334657;24847.627285591094;24967.87516062408;25093.121740738985;25098.567649847635;40538;40644;40893.38659920044;40910.9772255696;40965.38551921664;232394;233010;233049;233391;233559;33382.33235335328;34009;36829.44755828718;37624.81187594154;38085;46011.76994115008;46216.768916155204;46634.300485492065;46653.53346466492;46844.7657761709;33596.160095997795;33818.8309058463;34021.31957360789;34118.82940585281;34322.656773432944;32983.8350808254;33727.83136084304;33840.15399614931;34081.65918340785;34143.317133657685;39505.604943950195;39775.2044959105;39972.80013599913;40299.19401611893;40384.59615403808;32314;32759;32975;32979.50530741993;33078.50382244316;25180.24459266225;25181.496370072862;25255.98976040938;25503.931237006214;25676.614850777627 02:13:48 INFO - PID 26059 | |2;jquery.html;580.3123298890815;582.493230889126;585.4438283630552;589.7464090441111;610.5329422991412;23028.19401320954;23895.16366927158;23902.163424280112;23912.163074292403;23948.28155155344;22119.336419907428;25676.871615641896;25873.223803285884;25970.610440843404;25997.480050399026;6755.054292399068;6873.656317184146;6901.654917254143;6905.033295338657;6906.412954898839;6425.518086143535;6684;6698.296678848724;6731;6744.595324280559;6655.966720166392;6668.699908504129;6676.2989886062;6682.899756503656;6687.598744075371;6611.471082313404;6625.867482650344;6646.10277612521;6661.067450556926;6668.933310666928;6227.065940108974;6235.220597425322;6235.501159907197;6248.094026366151;6254.937450625526;28969;29916.70083299161;30445.934392296385;30589.847050764605;30649.233769155726;2234.318532847481;2267.7618850020676;2274.2040285899966;2274.4882401459704;2277;20460.795392046188;21002.474938126517;21008.684869726967;21107.894460527754;21176.788232117637;2355.7526459721653;2392.4258178037144;2409.3976505873534;2413.179518963561;2424.4787370715203;154167;156508;157453.21273393673;157455;157567.2121639396;15835.68328633421;16386.6722665546;16440.260188291555;16571.25429355682;17233.482995510156;25714.48571028569;25799.74200258011;25828.225153245432;25850.224493265232;25864.48271034569;10365.222608304408;10425.791484170426;10445.738856528496;10574.832159407302;10584.735381615366;8795.516246606498;9245.491497967541;9331.300152488591;9404.529773511296;9620.71137865865;8793.384463087523;9998.350107243006;10251.794964100676;10282.794344113076;10371.325863818978;3020.2298413904314;3035.3002318701597;3055.618047744032;3074.1853408846673;3134.4044631519937;1044.728370623628;1050.112654806687;1055.963041293551;1058.6188971970084;1063.1335461598771;21463.356099317047;22004.88997555002;22060;22130.446738831335;22228.444288892908;1105.7512059786645;1111.111111111108;1111.3165403276926;1117.7987962166726;1121.6971417717334;11710.414479276173;12113.636590902288;12224.1443098984;12248.938755306166;12262.325572093621;631239;636967;647155;650855;652229;129439.70560294465;133771.3311433456;134318;135336;136495;1210.8789121087646;1223.2293654997472;1236.5301185549613;1238.3498663201892;1251.6245126462024;19554;19735.802641973398;19965.401037968884;20067.898530524424;20173.394798156078;62265;62667;63407.6829615849;64429.35570644234;65248.67375663092;77745.61127194327;78207;78365.21634783807;81145;81693;12901;13407.731845363232;14996.475123371067;15456.690866182838;15932.283047263132;24364.390890227176;24421.38946526351;24545.87727061353;24631.63052554249;25440.618390723783;19910.50223744418;19959.301424450074;20243.79756202478;20360.490987725425;20440.386788396372;17719;17738;17839.910800446432;18033.639327213645;18182.27270909149;20545.48636284105;20684.379468615967;20847.37457876266;20999.475013124793;21123.471913202295;15533.223338833239;15577.922110389829;15619.76570351423;15643;15657.37370505167;19963;19974.1011654473;20104;20122.89938550298;20156.496087597927 02:13:48 INFO - PID 26059 | |3;mootools.html;1409.3587417724937;1491.1500444746491;1491.4705279625725;1499.7450433426322;1507.02796696131;16435.83564164358;16781.748273775887;17439.3024279029;17458.82541174591;17477.213525391355;16713.331466741314;17840.10799460025;17841.91079044606;17919.82080179201;17952.371666991658;2800.187945495804;2847.7437030667234;2851.7148285171525;2854.357769501866;2854.7002564730715;2761.254461295449;2797.2447439191405;2840.7159284071636;2896.884124635012;2907.4330505551425;2877.7410033097017;2908.476474234637;2910.3015276333635;2910.6652734935437;2912.417516496709;2775.4310366374843;2808;2808.0873716042265;2809.564517499781;2809.859507024661;2716.0493827160535;2737.630419893309;2742.287005378596;2742.7257274272415;2745.217612980291;16616.335346586122;16807.243674034697;16908.40820571274;16947.83052169475;16961.067141307223;7946.880796788053;8242.587870606445;8243.340532757366;8255.504669719776;8260.504369737775;5254.159334506498;5605.411431799684;5646.915296270559;5731.541476681856;5842.065269556851;2139.5079131799775;2150.0969592771075;2150.2474134053114;2224.5662095891307;2235.1506427557424;30212.8489357554;30437.695623043706;30441.695583044107;30447.543286850716;30479.085627431214;7063.187733410621;7163.10461192351;7184.389326907167;7349.55902645843;7363.705451781869;19361.903190484238;19383.709244361347;19387.418377448415;19391.4182574523;19419.514512137026;7975.361971042303;8095.190480951857;8103.392245581487;8124.837503249902;8150.7147249845975;7339.1926888043345;7395.926040739631;7411.703531858773;7498.212687667717;7621.047369078865;5456.7544460499375;5690.5447564194765;5733.480627633597;5746.511546518593;5747.166660834239;1474.0934325389808;1506.1866592040176;1648.4477699970644;1689.4509284482347;1708.2995971651562;981.1709105805583;1001.6344034427382;1001.8447140693204;1007.496251874063;1021.8313978193663;9853.310268281151;9902.900970990198;10015.949920250498;10021.79956400868;10183.134433573081;1058.349115294104;1076.208986394999;1081.297156848055;1083.1713738989522;1085.5006696919506;6081.878362432815;6148.6925653717135;6168.629882207082;6309.305976342599;6382.36176382358;24789.380265493506;25404.74595254061;25508.489830203664;25568.616470752953;25624.359391015;24236.87881560581;24904.12835550747;25090.37274068163;25349;25430;12960.611181664564;12978.675533111558;12984.545540906023;13094.476220951246;13100.475980960846;9546.90453095488;9715.854262186214;9746.415215087118;9926.454045027593;10006.799864002534;20923.895380523;21020.789792101885;21175.682364764234;21572.892135539223;21613.45966350791;22101.44746381289;22294.21970231034;22453;22551.77448225497;22978;13882.861171388156;14149.292535372984;14163.150210987375;14164.92917535447;14165.787513187517;15709.45016924402;15875;15976.760348595013;15978.680426391174;16091.114988675736;11340.6030788922;12571.497140114294;12723.681907952005;12900.225986440844;12975;12132.939335303266;12841.35793210317;12912.612621621367;12922.095453318178;13050.282234477196;10372.896271037494;10508.842367364648;10532.526036328536;10544.630937917136;10553.41956192386;8151.062627797642;8160.8775868360835;8204.917950820416;8240.876386854083;8272.255497005057;8272;8299.08710041907;8373.455725377831;8424.157584241771;8459.746207613782 02:13:48 INFO - PID 26059 | |4;prototype.html;524.5803357314147;531.9015982043322;567.2880534928664;567.7331654122562;569.0496870226721;6819.863602727943;6824.522283440161;6878.209005964316;6882.346177113179;6907.827304317382;6610.570312929668;6627.1716035495565;6713.832154196135;6732.663366831664;6793.150856142982;5497.642653227547;5527.889442211153;5534.422689533484;5552.361478429992;5635.267415236013;5384.138537833947;5464.371597266306;5537.446255374451;5565.332160140789;5566.610337276392;5480.808171713991;5504.422035686255;5523.9894257629485;5580.414056524068;5631.859203519924;5454.209139674725;5491.313585801755;5516.503514683678;5539.169124631298;5601.403599973988;5186.688798672054;5191.169412893956;5195.2986346843445;5224.63427560071;5245.423003469616;6710.261871194165;6728.4617230621925;6779.62712050837;6783.796486105425;6792.7773000859825;6562.146920900253;6579.98010308403;6670;6684.498662600325;6713.12729345182;7224.530405523677;7227.060482137341;7259.41924646027;7325.963370183168;7377.77866664001;5749.655020698771;5834.374531460817;5854.795082172103;5863.941360586382;5869.677167755772;7548.6225688715895;7576.810579735551;7634.541927484369;7693.269139431745;7722.806929826799;5999.970000149971;6009.669468179292;6013.428724271187;6052.273727152769;6108.450239478469;6141.907871381933;6181.258249010148;6194.349593292639;6229.906551401733;6240.625562466266;6470.09418681385;6485.805425837232;6555.508336874754;6679.799606011828;6742.292059333798;7222.241664625244;7257.996033827382;7288.380487658503;7354.595497247703;7368.815779605446;2005.9398218053482;2074.4917495213767;2079.781622929601;2115.5240070983905;2119.4383488375593;1554.2617256802928;1581.818090919536;1590.3400088963183;1606.6144125409817;1632.5755303620906;6101.725422356005;6193.380661933861;6204.162438070893;6262.624242545371;6270.874582508415;7035.613041282779;7102.928970710227;7223.530470519399;7312.817179570553;7371.88942165868;6217.471514921192;6262.34245404226;6293.874122517524;6324.482124290229;6332.081848131995;3807.4669546263544;3871.8064096795047;3892.5134358205223;3900.473436086149;3919.941200881989;7115.644217789089;7129.18014428337;7191.7842464726145;7220;7227.048696851925;7457.664405101784;7490.325870671557;7505.211952744992;7528;7581.924180758122;6961.721531138799;7026.754063607749;7166.462515311268;7169.820754481179;7214.71141154348;7327.780166595011;7366.889496657447;7403.444741644506;7424.740134095281;7426.962865185856;7301.160366557701;7323.2676732326345;7381.630918454163;7425.517341372793;7456.47142335809;7010.298970102949;7089.574625522485;7111.182214045451;7129.041447799313;7222.34998850085;5450.31871016123;5494.313210848644;5501.504864562208;5523.2819733436;5529.566990524157;7038.2961703829205;7204.56772593646;7301.342879140902;7418.516296740781;7440.3675687567165;6922.8615427692175;6939.861202775815;6971.6165610891885;7022.262662420372;7119.288071193047;6952.548084374702;6983;7050.801363768069;7068.041896537955;7086.255943125898;6917.723291068301;6929.792106236821;6932.445404367538;6933.341332573397;6986.860262794614;7025.5082144251455;7073.71705131789;7085.539439936388;7161.281292489869;7186.24544422849;6955.930440695528;6956.339147780953;6959.512834101564;6980.685869136003;7038.5776853386915 02:13:48 INFO - PID 26059 | |5;yui.html;896.2337201692553;926.2219735422244;938.0291398402651;946.6145313809201;1000.9499525023751;19089.618207635856;19465.416037518862;19608.411747647595;19782.802171978296;19791.406257812254;19396.999999999964;19484.805151948476;19552.511187220327;19666.803331966676;19819.801801981976;1040.4641609571058;1069.8395240713917;1079.3955384984395;1079.498033414462;1082.0099608857925;1067.311584028305;1086.5219303506433;1088.9564417423294;1090.4111779638986;1090.6727981605486;1142.2404101272643;1149.8505194324769;1150.091427772059;1150.8503894493704;1151.2919554474022;1143.9027682646943;1147.1912301827192;1147.9885201148006;1148.9540418383256;1149.781541507111;840.0381563110204;843.1189407069627;843.28320927212;843.6035063520136;843.7763992541982;19415.611687766166;19509.414717558353;19572;19573.51066223356;19928;22201;22663.546729065325;22671.659925101136;22893.427664308525;23659.408514787097;17990.910045449687;18170.4548863533;18238.17928193221;18269.63460730791;18404.44786656389;4101.241270364992;4124.711270211086;4138.8551400700835;4140.420341152241;4144.52337981133;150874.24562877225;152766;153551;153648;155550.22224888802;17475.47573572795;17849;17924.820751792577;17943.820561794215;18164.545886352684;32803.67196328054;33296.50055249173;33318.83340583282;33645.495317570254;33913.32173356519;8477.152284771473;8860.113988601088;9058.139476749699;9190.678326258549;9288.79245698055;4574.5882870541145;4715.976420117878;4738.19450693383;4754.263089221176;4757.857264282077;6111.663858487737;6181.907271390933;6189.35011823761;6218.844528886723;6234.781782637586;1243.241622610207;1247.1207798502035;1248.712796056912;1248.7252804383022;1261.1991385470292;734.1740541890373;739.0503203383627;746.805830484078;748.8914107454389;767.1293082351459;12915.483380664857;13357.332133393485;13892.513762018483;14072.085314454527;14387.640308992359;735.1655870586923;735.9852802943911;739.7078154129089;739.971439698819;751.537804250393;5964.067789839503;5966.76132954677;6098.054801505774;6112.266528016667;6121.693915304217;132028;134892;138021.85095099438;138596;139961.30019349838;121577;126085;126676;127861.36069319594;128048;100995;102097.48951255197;102867.48566257271;102935.4853225744;105031;11226.943865280622;11271.830922536332;11305.577344878118;11512.021478174449;11552.59565915189;52370.73814630903;52934.73532632312;54148.72925635504;54272.18591721048;54370.18444723411;67218.66390668016;67457.3254267451;68010;68217;69154.30845691479;12976.02679799;13144.474221031434;13292.601221963356;13293.069485135948;14387.92806035963;22022.88988555047;22490.662640060084;22491.88754056219;22744.20395286157;22993.540129197656;19833.801661983773;19859.801401985795;20034.298799541946;20176.42277639012;20443.4889127773;19740.703889441957;19805.80194198097;19843.702344464557;19864.90067549653;19965.301214457424;20121.798782011992;20143.79856201419;20175.79824201739;20253.59492810165;20310.695339569622;15010;15301.46444874424;15326.386944522093;15471.226438678246;15799.526014219591;14646.926765366105;15100.244987750788;15128.848711512745;15183.544493665207;15231.238438078273 02:13:48 INFO - PID 26059 | __end_tp_report 02:13:48 INFO - PID 26059 | __start_cc_report 02:13:48 INFO - PID 26059 | _x_x_mozilla_cycle_collect,343 02:13:48 INFO - PID 26059 | __end_cc_report 02:13:48 INFO - PID 26059 | __startTimestamp1504689228349__endTimestamp 02:13:48 INFO - PID 26059 | 02:13:48 INFO - PID 26059 | ------- Summary: start ------- 02:13:48 INFO - PID 26059 | Number of tests: 6 02:13:48 INFO - PID 26059 | 02:13:48 INFO - PID 26059 | [#0] dojo.html Cycles:165 Average:6840.92 Median:7040.08 stddev:758.13 (10.8%) stddev-sans-first:722.60 02:13:48 INFO - PID 26059 | Values: 3824.9 3908.7 3933.5 3938.1 3956.9 6762.4 6972.9 6984.7 7001.1 7003.0 6778.1 6907.4 6912.1 6920.0 6956.7 6066.4 6194.8 6251.3 6269.5 6331.6 6232.0 6288.7 6289.7 6323.7 6326.3 6101.9 6112.4 6112.7 6235.9 6249.3 6151.4 6162.4 6225.8 6245.1 6266.3 6019.7 6146.8 6159.9 6218.1 6218.2 6789.6 6800.2 6803.0 6826.7 6886.8 7461.7 7485.1 7574.7 7595.8 7607.5 7556.7 7561.7 7580.0 7640.6 7693.1 6804.4 6848.5 6873.9 7014.3 7117.3 7454.5 7621.6 7630.8 7644.8 7658.3 7293.1 7330.3 7364.3 7369.2 7451.4 7445.8 7494.9 7497.7 7577.1 7578.4 7243.3 7377.4 7429.4 7498.0 7505.8 7260.6 7341.2 7389.2 7469.3 7519.6 6094.5 6149.4 6160.1 6408.2 6434.4 5389.0 5409.9 5415.4 5419.9 5424.8 6299.8 6324.1 6342.2 6347.4 6467.3 7482.6 7556.5 7576.8 7589.2 7591.3 6428.7 6445.3 6514.0 6515.8 6520.8 6532.4 6628.9 6630.2 6633.9 6636.9 6371.2 7015.5 7108.9 7142.5 7145.8 7482.2 7489.4 7561.5 7597.0 7597.8 7250.1 7265.4 7275.2 7364.9 7394.9 7190.7 7317.9 7326.2 7377.0 7420.3 7178.4 7301.3 7312.3 7344.4 7375.6 7001.1 7001.5 7084.0 7093.4 7110.7 6987.3 7001.1 7090.9 7102.9 7104.9 7040.1 7136.1 7164.1 7204.5 7210.9 7234.4 7310.4 7367.3 7368.7 7374.7 6899.8 7012.2 7056.1 7138.0 7153.5 02:13:48 INFO - PID 26059 | 02:13:48 INFO - PID 26059 | [#1] ext.html Cycles:180 Average:42401.73 Median:3743.71 stddev:65604.32 (1752.4%) stddev-sans-first:65714.37 02:13:48 INFO - PID 26059 | Values: 905.4 916.4 951.6 978.4 979.1 41913.4 42734.6 42865.1 42918.8 42958.8 39783.2 39819.4 39992.0 40022.0 40022.8 7332.0 7416.2 7420.6 7430.3 7432.6 7305.2 7356.3 7359.2 7359.8 7396.7 7746.4 7904.6 7905.1 7907.0 7921.2 7805.1 7856.5 7869.1 7884.6 7884.8 7611.2 7756.4 7763.3 7991.0 8081.0 39640.4 39881.6 39947.6 39949.4 40004.8 3679.2 3711.1 3740.9 3746.6 3748.8 32872.5 32941.5 32984.2 33045.8 33099.8 5430.0 5456.1 5458.3 5468.0 5474.5 204470.0 207956.0 212549.0 214991.0 265037.0 53164.0 53182.5 53343.7 53357.7 53577.5 56532.0 56694.0 56895.2 56962.0 57036.4 16141.3 16204.6 16221.1 16224.8 16388.8 13130.2 13206.3 13217.5 13219.4 13231.9 15291.7 15357.4 15436.4 15469.8 15536.0 6234.6 6238.8 6256.5 6297.8 6383.2 1072.7 1081.2 1087.5 1092.1 1106.0 25233.2 25290.4 25316.7 25438.0 25475.0 1189.2 1189.7 1197.3 1207.0 1214.0 13209.1 13209.7 13236.7 13249.3 13454.9 292074.0 294207.0 294460.0 294892.0 295334.0 56087.4 56246.0 56246.4 56733.7 56812.4 1483.8 1489.9 1508.6 1511.0 1534.6 24816.3 24847.6 24967.9 25093.1 25098.6 40538.0 40644.0 40893.4 40911.0 40965.4 232394.0 233010.0 233049.0 233391.0 233559.0 33382.3 34009.0 36829.4 37624.8 38085.0 46011.8 46216.8 46634.3 46653.5 46844.8 33596.2 33818.8 34021.3 34118.8 34322.7 32983.8 33727.8 33840.2 34081.7 34143.3 39505.6 39775.2 39972.8 40299.2 40384.6 32314.0 32759.0 32975.0 32979.5 33078.5 25180.2 25181.5 25256.0 25503.9 25676.6 02:13:48 INFO - PID 26059 | 02:13:48 INFO - PID 26059 | [#2] jquery.html Cycles:180 Average:41160.11 Median:2270.98 stddev:107525.64 (4734.8%) stddev-sans-first:107784.11 02:13:48 INFO - PID 26059 | Values: 580.3 582.5 585.4 589.7 610.5 23028.2 23895.2 23902.2 23912.2 23948.3 22119.3 25676.9 25873.2 25970.6 25997.5 6755.1 6873.7 6901.7 6905.0 6906.4 6425.5 6684.0 6698.3 6731.0 6744.6 6656.0 6668.7 6676.3 6682.9 6687.6 6611.5 6625.9 6646.1 6661.1 6668.9 6227.1 6235.2 6235.5 6248.1 6254.9 28969.0 29916.7 30445.9 30589.8 30649.2 2234.3 2267.8 2274.2 2274.5 2277.0 20460.8 21002.5 21008.7 21107.9 21176.8 2355.8 2392.4 2409.4 2413.2 2424.5 154167.0 156508.0 157453.2 157455.0 157567.2 15835.7 16386.7 16440.3 16571.3 17233.5 25714.5 25799.7 25828.2 25850.2 25864.5 10365.2 10425.8 10445.7 10574.8 10584.7 8795.5 9245.5 9331.3 9404.5 9620.7 8793.4 9998.4 10251.8 10282.8 10371.3 3020.2 3035.3 3055.6 3074.2 3134.4 1044.7 1050.1 1056.0 1058.6 1063.1 21463.4 22004.9 22060.0 22130.4 22228.4 1105.8 1111.1 1111.3 1117.8 1121.7 11710.4 12113.6 12224.1 12248.9 12262.3 631239.0 636967.0 647155.0 650855.0 652229.0 129439.7 133771.3 134318.0 135336.0 136495.0 1210.9 1223.2 1236.5 1238.3 1251.6 19554.0 19735.8 19965.4 20067.9 20173.4 62265.0 62667.0 63407.7 64429.4 65248.7 77745.6 78207.0 78365.2 81145.0 81693.0 12901.0 13407.7 14996.5 15456.7 15932.3 24364.4 24421.4 24545.9 24631.6 25440.6 19910.5 19959.3 20243.8 20360.5 20440.4 17719.0 17738.0 17839.9 18033.6 18182.3 20545.5 20684.4 20847.4 20999.5 21123.5 15533.2 15577.9 15619.8 15643.0 15657.4 19963.0 19974.1 20104.0 20122.9 20156.5 02:13:48 INFO - PID 26059 | 02:13:48 INFO - PID 26059 | [#3] mootools.html Cycles:180 Average:10573.75 Median:24997.25 stddev:7787.57 (31.2%) stddev-sans-first:7778.98 02:13:48 INFO - PID 26059 | Values: 1409.4 1491.2 1491.5 1499.7 1507.0 16435.8 16781.7 17439.3 17458.8 17477.2 16713.3 17840.1 17841.9 17919.8 17952.4 2800.2 2847.7 2851.7 2854.4 2854.7 2761.3 2797.2 2840.7 2896.9 2907.4 2877.7 2908.5 2910.3 2910.7 2912.4 2775.4 2808.0 2808.1 2809.6 2809.9 2716.0 2737.6 2742.3 2742.7 2745.2 16616.3 16807.2 16908.4 16947.8 16961.1 7946.9 8242.6 8243.3 8255.5 8260.5 5254.2 5605.4 5646.9 5731.5 5842.1 2139.5 2150.1 2150.2 2224.6 2235.2 30212.8 30437.7 30441.7 30447.5 30479.1 7063.2 7163.1 7184.4 7349.6 7363.7 19361.9 19383.7 19387.4 19391.4 19419.5 7975.4 8095.2 8103.4 8124.8 8150.7 7339.2 7395.9 7411.7 7498.2 7621.0 5456.8 5690.5 5733.5 5746.5 5747.2 1474.1 1506.2 1648.4 1689.5 1708.3 981.2 1001.6 1001.8 1007.5 1021.8 9853.3 9902.9 10015.9 10021.8 10183.1 1058.3 1076.2 1081.3 1083.2 1085.5 6081.9 6148.7 6168.6 6309.3 6382.4 24789.4 25404.7 25508.5 25568.6 25624.4 24236.9 24904.1 25090.4 25349.0 25430.0 12960.6 12978.7 12984.5 13094.5 13100.5 9546.9 9715.9 9746.4 9926.5 10006.8 20923.9 21020.8 21175.7 21572.9 21613.5 22101.4 22294.2 22453.0 22551.8 22978.0 13882.9 14149.3 14163.2 14164.9 14165.8 15709.5 15875.0 15976.8 15978.7 16091.1 11340.6 12571.5 12723.7 12900.2 12975.0 12132.9 12841.4 12912.6 12922.1 13050.3 10372.9 10508.8 10532.5 10544.6 10553.4 8151.1 8160.9 8204.9 8240.9 8272.3 8272.0 8299.1 8373.5 8424.2 8459.7 02:13:48 INFO - PID 26059 | 02:13:48 INFO - PID 26059 | [#4] prototype.html Cycles:180 Average:6124.11 Median:6760.96 stddev:1648.25 (24.4%) stddev-sans-first:1598.39 02:13:48 INFO - PID 26059 | Values: 524.6 531.9 567.3 567.7 569.0 6819.9 6824.5 6878.2 6882.3 6907.8 6610.6 6627.2 6713.8 6732.7 6793.2 5497.6 5527.9 5534.4 5552.4 5635.3 5384.1 5464.4 5537.4 5565.3 5566.6 5480.8 5504.4 5524.0 5580.4 5631.9 5454.2 5491.3 5516.5 5539.2 5601.4 5186.7 5191.2 5195.3 5224.6 5245.4 6710.3 6728.5 6779.6 6783.8 6792.8 6562.1 6580.0 6670.0 6684.5 6713.1 7224.5 7227.1 7259.4 7326.0 7377.8 5749.7 5834.4 5854.8 5863.9 5869.7 7548.6 7576.8 7634.5 7693.3 7722.8 6000.0 6009.7 6013.4 6052.3 6108.5 6141.9 6181.3 6194.3 6229.9 6240.6 6470.1 6485.8 6555.5 6679.8 6742.3 7222.2 7258.0 7288.4 7354.6 7368.8 2005.9 2074.5 2079.8 2115.5 2119.4 1554.3 1581.8 1590.3 1606.6 1632.6 6101.7 6193.4 6204.2 6262.6 6270.9 7035.6 7102.9 7223.5 7312.8 7371.9 6217.5 6262.3 6293.9 6324.5 6332.1 3807.5 3871.8 3892.5 3900.5 3919.9 7115.6 7129.2 7191.8 7220.0 7227.0 7457.7 7490.3 7505.2 7528.0 7581.9 6961.7 7026.8 7166.5 7169.8 7214.7 7327.8 7366.9 7403.4 7424.7 7427.0 7301.2 7323.3 7381.6 7425.5 7456.5 7010.3 7089.6 7111.2 7129.0 7222.3 5450.3 5494.3 5501.5 5523.3 5529.6 7038.3 7204.6 7301.3 7418.5 7440.4 6922.9 6939.9 6971.6 7022.3 7119.3 6952.5 6983.0 7050.8 7068.0 7086.3 6917.7 6929.8 6932.4 6933.3 6986.9 7025.5 7073.7 7085.5 7161.3 7186.2 6955.9 6956.3 6959.5 6980.7 7038.6 02:13:48 INFO - PID 26059 | 02:13:48 INFO - PID 26059 | [#5] yui.html Cycles:180 Average:27281.15 Median:19637.61 stddev:39436.26 (200.8%) stddev-sans-first:39497.13 02:13:48 INFO - PID 26059 | Values: 896.2 926.2 938.0 946.6 1000.9 19089.6 19465.4 19608.4 19782.8 19791.4 19397.0 19484.8 19552.5 19666.8 19819.8 1040.5 1069.8 1079.4 1079.5 1082.0 1067.3 1086.5 1089.0 1090.4 1090.7 1142.2 1149.9 1150.1 1150.9 1151.3 1143.9 1147.2 1148.0 1149.0 1149.8 840.0 843.1 843.3 843.6 843.8 19415.6 19509.4 19572.0 19573.5 19928.0 22201.0 22663.5 22671.7 22893.4 23659.4 17990.9 18170.5 18238.2 18269.6 18404.4 4101.2 4124.7 4138.9 4140.4 4144.5 150874.2 152766.0 153551.0 153648.0 155550.2 17475.5 17849.0 17924.8 17943.8 18164.5 32803.7 33296.5 33318.8 33645.5 33913.3 8477.2 8860.1 9058.1 9190.7 9288.8 4574.6 4716.0 4738.2 4754.3 4757.9 6111.7 6181.9 6189.4 6218.8 6234.8 1243.2 1247.1 1248.7 1248.7 1261.2 734.2 739.1 746.8 748.9 767.1 12915.5 13357.3 13892.5 14072.1 14387.6 735.2 736.0 739.7 740.0 751.5 5964.1 5966.8 6098.1 6112.3 6121.7 132028.0 134892.0 138021.9 138596.0 139961.3 121577.0 126085.0 126676.0 127861.4 128048.0 100995.0 102097.5 102867.5 102935.5 105031.0 11226.9 11271.8 11305.6 11512.0 11552.6 52370.7 52934.7 54148.7 54272.2 54370.2 67218.7 67457.3 68010.0 68217.0 69154.3 12976.0 13144.5 13292.6 13293.1 14387.9 22022.9 22490.7 22491.9 22744.2 22993.5 19833.8 19859.8 20034.3 20176.4 20443.5 19740.7 19805.8 19843.7 19864.9 19965.3 20121.8 20143.8 20175.8 20253.6 20310.7 15010.0 15301.5 15326.4 15471.2 15799.5 14646.9 15100.2 15128.8 15183.5 15231.2 02:13:48 INFO - PID 26059 | -------- Summary: end -------- 02:13:48 INFO - PID 26059 | 02:13:49 INFO - TEST-INFO | 26059: exit 0 02:13:49 INFO - TEST-OK | dromaeo_css | took 1112592ms 02:13:49 INFO - TEST-START | kraken 02:13:49 INFO - Initialising browser for kraken test... 02:13:49 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34383/getInfo.html -profile /tmp/tmp1wrHjT/profile 02:13:49 INFO - TEST-INFO | started process 26432 (/builds/slave/test/build/application/firefox/firefox http://localhost:34383/getInfo.html) 02:13:55 INFO - TEST-INFO | 26432: exit 0 02:13:55 INFO - Browser initialized. 02:13:55 INFO - Running cycle 1/1 for kraken test... 02:13:55 INFO - Using env: {'DISPLAY': ':0', 02:13:55 INFO - 'HOME': '/home/cltbld', 02:13:55 INFO - 'JSGC_DISABLE_POISONING': '1', 02:13:55 INFO - 'LANG': 'en_US.UTF-8', 02:13:55 INFO - 'LANGUAGE': 'en_US:en', 02:13:55 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:13:55 INFO - 'LOGNAME': 'cltbld', 02:13:55 INFO - 'MAIL': '/var/mail/cltbld', 02:13:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:13:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:13:55 INFO - 'MOZ_CRASHREPORTER': '1', 02:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:55 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:13:55 INFO - 'MOZ_NO_REMOTE': '1', 02:13:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:13:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:13:55 INFO - 'NO_EM_RESTART': '1', 02:13:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:55 INFO - 'PWD': '/builds/slave/test', 02:13:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:13:55 INFO - 'RUST_BACKTRACE': 'full', 02:13:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:13:55 INFO - 'SHELL': '/bin/bash', 02:13:55 INFO - 'SHLVL': '1', 02:13:55 INFO - 'TERM': 'linux', 02:13:55 INFO - 'TMOUT': '86400', 02:13:55 INFO - 'USER': 'cltbld', 02:13:55 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991', 02:13:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:55 INFO - '_': '/tools/buildbot/bin/python'} 02:13:55 INFO - TEST-INFO | started process 26624 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1wrHjT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:13:55 INFO - PID 26624 | 02:13:55 INFO - PID 26624 | (/builds/slave/test/build/application/firefox/firefox:26675): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:13:55 INFO - PID 26624 | 02:14:06 INFO - PID 26624 | RSS: Main: 162131968 02:14:06 INFO - PID 26624 | 02:14:08 INFO - PID 26624 | Cycle 1(1): loaded http://localhost:34383/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34383/tests/kraken/driver.html?testName=audio-beat-detection) 02:14:09 INFO - PID 26624 | RSS: Main: 167718912 02:14:09 INFO - PID 26624 | 02:14:12 INFO - PID 26624 | Cycle 1(1): loaded http://localhost:34383/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34383/tests/kraken/driver.html?testName=audio-dft) 02:14:12 INFO - PID 26624 | RSS: Main: 172146688 02:14:12 INFO - PID 26624 | 02:14:15 INFO - PID 26624 | Cycle 1(1): loaded http://localhost:34383/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34383/tests/kraken/driver.html?testName=audio-fft) 02:14:16 INFO - PID 26624 | RSS: Main: 165257216 02:14:16 INFO - PID 26624 | 02:14:18 INFO - PID 26624 | Cycle 1(1): loaded http://localhost:34383/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34383/tests/kraken/driver.html?testName=audio-oscillator) 02:14:19 INFO - PID 26624 | RSS: Main: 166625280 02:14:19 INFO - PID 26624 | 02:14:21 INFO - PID 26624 | Cycle 1(1): loaded http://localhost:34383/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34383/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:14:21 INFO - PID 26624 | RSS: Main: 167124992 02:14:21 INFO - PID 26624 | 02:14:24 INFO - PID 26624 | Cycle 1(1): loaded http://localhost:34383/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34383/tests/kraken/driver.html?testName=imaging-darkroom) 02:14:25 INFO - PID 26624 | RSS: Main: 166776832 02:14:25 INFO - PID 26624 | 02:14:28 INFO - PID 26624 | Cycle 1(1): loaded http://localhost:34383/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34383/tests/kraken/driver.html?testName=imaging-desaturate) 02:14:29 INFO - PID 26624 | RSS: Main: 169918464 02:14:29 INFO - PID 26624 | 02:14:32 INFO - PID 26624 | Cycle 1(1): loaded http://localhost:34383/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34383/tests/kraken/driver.html?testName=json-parse-financial) 02:14:32 INFO - PID 26624 | RSS: Main: 168677376 02:14:32 INFO - PID 26624 | 02:14:34 INFO - PID 26624 | Cycle 1(1): loaded http://localhost:34383/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34383/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:14:35 INFO - PID 26624 | RSS: Main: 168755200 02:14:35 INFO - PID 26624 | 02:14:36 INFO - PID 26624 | Cycle 1(1): loaded http://localhost:34383/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34383/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:14:37 INFO - PID 26624 | RSS: Main: 168759296 02:14:37 INFO - PID 26624 | 02:14:39 INFO - PID 26624 | Cycle 1(1): loaded http://localhost:34383/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34383/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:14:39 INFO - PID 26624 | RSS: Main: 165830656 02:14:39 INFO - PID 26624 | 02:14:42 INFO - PID 26624 | Cycle 1(1): loaded http://localhost:34383/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34383/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:14:43 INFO - PID 26624 | RSS: Main: 165183488 02:14:43 INFO - PID 26624 | 02:14:45 INFO - PID 26624 | Cycle 1(1): loaded http://localhost:34383/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34383/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:14:46 INFO - PID 26624 | RSS: Main: 165584896 02:14:46 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | Cycle 1(1): loaded http://localhost:34383/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34383/tests/kraken/driver.html?testName=ai-astar) 02:14:48 INFO - PID 26624 | RSS: Main: 167591936 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | __start_tp_report 02:14:48 INFO - PID 26624 | _x_x_mozilla_page_load 02:14:48 INFO - PID 26624 | _x_x_mozilla_page_load_details 02:14:48 INFO - PID 26624 | |i|pagename|runs| 02:14:48 INFO - PID 26624 | |0;ai-astar;97;105;99;105;110;104;107;97;108;107 02:14:48 INFO - PID 26624 | |1;audio-beat-detection;140;109;146;185;112;148;148;112;166;114 02:14:48 INFO - PID 26624 | |2;audio-dft;160;161;161;161;169;162;162;167;163;167 02:14:48 INFO - PID 26624 | |3;audio-fft;74;73;73;169;73;73;74;73;177;73 02:14:48 INFO - PID 26624 | |4;audio-oscillator;78;78;132;79;79;77;129;77;82;120 02:14:48 INFO - PID 26624 | |5;imaging-gaussian-blur;113;114;114;114;114;114;114;115;113;113 02:14:48 INFO - PID 26624 | |6;imaging-darkroom;152;154;154;152;152;152;153;151;151;152 02:14:48 INFO - PID 26624 | |7;imaging-desaturate;97;94;93;94;93;94;93;94;95;95 02:14:48 INFO - PID 26624 | |8;json-parse-financial;46;46;51;44;49;49;45;50;44;46 02:14:48 INFO - PID 26624 | |9;json-stringify-tinderbox;36;37;36;36;36;36;36;36;36;36 02:14:48 INFO - PID 26624 | |10;stanford-crypto-aes;71;76;78;73;72;72;74;73;75;75 02:14:48 INFO - PID 26624 | |11;stanford-crypto-ccm;117;110;121;109;115;128;112;117;120;145 02:14:48 INFO - PID 26624 | |12;stanford-crypto-pbkdf2;165;170;154;154;155;157;156;157;159;159 02:14:48 INFO - PID 26624 | |13;stanford-crypto-sha256-iterative;62;62;62;61;64;63;66;61;59;58 02:14:48 INFO - PID 26624 | __end_tp_report 02:14:48 INFO - PID 26624 | __start_cc_report 02:14:48 INFO - PID 26624 | _x_x_mozilla_cycle_collect,901 02:14:48 INFO - PID 26624 | __end_cc_report 02:14:48 INFO - PID 26624 | __startTimestamp1504689288430__endTimestamp 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | ------- Summary: start ------- 02:14:48 INFO - PID 26624 | Number of tests: 14 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | [#0] ai-astar Cycles:10 Average:103.90 Median:109.00 stddev:4.65 (4.3%) stddev-sans-first:4.21 02:14:48 INFO - PID 26624 | Values: 97.0 105.0 99.0 105.0 110.0 104.0 107.0 97.0 108.0 107.0 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:147.00 stddev:25.88 (17.6%) stddev-sans-first:27.44 02:14:48 INFO - PID 26624 | Values: 140.0 109.0 146.0 185.0 112.0 148.0 148.0 112.0 166.0 114.0 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | [#2] audio-dft Cycles:10 Average:163.30 Median:162.50 stddev:3.16 (1.9%) stddev-sans-first:3.12 02:14:48 INFO - PID 26624 | Values: 160.0 161.0 161.0 161.0 169.0 162.0 162.0 167.0 163.0 167.0 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | [#3] audio-fft Cycles:10 Average:93.20 Median:73.00 stddev:42.10 (57.7%) stddev-sans-first:44.08 02:14:48 INFO - PID 26624 | Values: 74.0 73.0 73.0 169.0 73.0 73.0 74.0 73.0 177.0 73.0 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:78.00 stddev:23.62 (30.3%) stddev-sans-first:24.41 02:14:48 INFO - PID 26624 | Values: 78.0 78.0 132.0 79.0 79.0 77.0 129.0 77.0 82.0 120.0 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.63 (0.6%) stddev-sans-first:0.60 02:14:48 INFO - PID 26624 | Values: 113.0 114.0 114.0 114.0 114.0 114.0 114.0 115.0 113.0 113.0 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | [#6] imaging-darkroom Cycles:10 Average:152.30 Median:152.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 02:14:48 INFO - PID 26624 | Values: 152.0 154.0 154.0 152.0 152.0 152.0 153.0 151.0 151.0 152.0 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:1.23 (1.3%) stddev-sans-first:0.78 02:14:48 INFO - PID 26624 | Values: 97.0 94.0 93.0 94.0 93.0 94.0 93.0 94.0 95.0 95.0 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | [#8] json-parse-financial Cycles:10 Average:47.00 Median:47.50 stddev:2.54 (5.3%) stddev-sans-first:2.67 02:14:48 INFO - PID 26624 | Values: 46.0 46.0 51.0 44.0 49.0 49.0 45.0 50.0 44.0 46.0 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | [#9] json-stringify-tinderbox Cycles:10 Average:36.10 Median:36.00 stddev:0.32 (0.9%) stddev-sans-first:0.33 02:14:48 INFO - PID 26624 | Values: 36.0 37.0 36.0 36.0 36.0 36.0 36.0 36.0 36.0 36.0 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | [#10] stanford-crypto-aes Cycles:10 Average:73.90 Median:74.50 stddev:2.13 (2.9%) stddev-sans-first:1.99 02:14:48 INFO - PID 26624 | Values: 71.0 76.0 78.0 73.0 72.0 72.0 74.0 73.0 75.0 75.0 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | [#11] stanford-crypto-ccm Cycles:10 Average:119.40 Median:118.50 stddev:10.62 (9.0%) stddev-sans-first:11.22 02:14:48 INFO - PID 26624 | Values: 117.0 110.0 121.0 109.0 115.0 128.0 112.0 117.0 120.0 145.0 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.60 Median:158.00 stddev:5.15 (3.3%) stddev-sans-first:4.91 02:14:48 INFO - PID 26624 | Values: 165.0 170.0 154.0 154.0 155.0 157.0 156.0 157.0 159.0 159.0 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:2.30 (3.7%) stddev-sans-first:2.44 02:14:48 INFO - PID 26624 | Values: 62.0 62.0 62.0 61.0 64.0 63.0 66.0 61.0 59.0 58.0 02:14:48 INFO - PID 26624 | -------- Summary: end -------- 02:14:48 INFO - PID 26624 | 02:14:48 INFO - PID 26624 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 02:14:49 INFO - TEST-INFO | 26624: exit 0 02:14:49 INFO - TEST-OK | kraken | took 60234ms 02:14:49 INFO - SUITE-END | took 1172s 02:14:49 INFO - Completed test suite (00:19:33) 02:14:49 INFO - javascript benchmark 02:14:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9346.444872396603, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3824.9235015299696, 3908.6677632401243, 3933.5083114610675, 3938.1336106056674, 3956.861509847156, 6762.357576030276, 6972.930270697291, 6984.685689143988, 7001.089858318424, 7003.0, 6778.051072849793, 6907.412869906064, 6912.066870972417, 6919.996600492926, 6956.721731130761, 6066.363031881655, 6194.845128871781, 6251.343608921066, 6269.498440124779, 6331.620102793824, 6232.0, 6288.685565721696, 6289.654069026202, 6323.74705011799, 6326.272478664943, 6101.908471372933, 6112.358202388752, 6112.69436528174, 6235.875282494325, 6249.2500899892075, 6151.384861513813, 6162.383761623802, 6225.7509699611965, 6245.063240513914, 6266.279377871558, 6019.729112189918, 6146.784862529835, 6159.907601385983, 6218.098375735538, 6218.22272215973, 6789.592624442549, 6800.217974932896, 6802.965985170043, 6826.692798824065, 6886.758963436306, 7461.664225109829, 7485.0643669541305, 7574.734884279079, 7595.772126836148, 7607.467477276593, 7556.65995030225, 7561.735339263154, 7579.962100189519, 7640.5797681127515, 7693.076830780286, 6804.421624161952, 6848.486363522756, 6873.862522749517, 7014.263502332233, 7117.3238542338395, 7454.515456495311, 7621.618919054025, 7630.7710768677025, 7644.847103058019, 7658.349040331523, 7293.124825021031, 7330.303621155982, 7364.3003914628025, 7369.22623124575, 7451.3666338361845, 7445.7766267012075, 7494.925050749423, 7497.66260518278, 7577.0907491101425, 7578.355839753573, 7243.348098671145, 7377.446691498159, 7429.36850367714, 7497.962510187414, 7505.774826755206, 7260.618456871526, 7341.155767086747, 7389.187189409161, 7469.253074692488, 7519.624018799038, 6094.542909281821, 6149.354317796657, 6160.10678451622, 6408.231012278557, 6434.356564343622, 5388.973055134699, 5409.918851217235, 5415.404305526389, 5419.891602168013, 5424.810131645373, 6299.842503937347, 6324.051392291193, 6342.207224096988, 6347.39699728525, 6467.32093130224, 7482.625868706543, 7556.546607203585, 7576.772696819105, 7589.203133670995, 7591.278828511283, 6428.71070801815, 6445.323241059715, 6514.022896565459, 6515.771947981798, 6520.836979075466, 6532.444742196872, 6628.933710662928, 6630.171228596426, 6633.900491492535, 6636.933630663728, 6371.203599550056, 7015.543989640657, 7108.92891071093, 7142.464315176382, 7145.82135446618, 7482.176960534337, 7489.438292128221, 7561.470697051153, 7596.96201519011, 7597.772066838003, 7250.093738282715, 7265.382442492447, 7275.236100209402, 7364.926350736639, 7394.852102958018, 7190.676419561237, 7317.926820731937, 7326.194118647051, 7377.0, 7420.257974202536, 7178.425725941807, 7301.306375894282, 7312.305330993547, 7344.375728062961, 7375.557466552024, 7001.08985831859, 7001.474889383215, 7084.0, 7093.432525397852, 7110.680019399036, 6987.301269872972, 7001.054857594159, 7090.858182836211, 7102.893456598052, 7104.857902841811, 7040.0847889774, 7136.143662760502, 7164.068671072936, 7204.53170543913, 7210.9278907210255, 7234.421246300389, 7310.415166786538, 7367.300106489874, 7368.668409921674, 7374.6681399336085, 6899.793006209822, 7012.1935977363255, 7056.082709247765, 7138.0, 7153.535020223669], "value": 6791.310603605429, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [905.3662436294593, 916.4134953629674, 951.6145960885841, 978.4276198423921, 979.0992287095871, 41913.371299430495, 42734.57265427345, 42865.142697146075, 42918.785406073, 42958.785206073924, 39783.204335913266, 39819.40270895939, 39992.0, 40022.0, 40022.79988600053, 7332.0, 7416.184219735825, 7420.628968551578, 7430.256974302553, 7432.628368581576, 7305.159906610728, 7356.337929586336, 7359.190489046201, 7359.77920662381, 7396.70413183472, 7746.38028957682, 7904.565248911307, 7905.090914544815, 7907.0, 7921.247481489249, 7805.141434442206, 7856.450048496607, 7869.05571331441, 7884.566348850811, 7884.763457096296, 7611.20082391352, 7756.4182686298755, 7763.262490063435, 7991.0, 8080.959595202045, 39640.405393919114, 39881.60118398837, 39947.600523994966, 39949.40075898864, 40004.79997600023, 3679.172186258097, 3711.0907827582146, 3740.869069582579, 3746.550413950316, 3748.775073495599, 32872.50691239633, 32941.50587741186, 32984.175395615064, 33045.83477082599, 33099.83450082758, 5430.0, 5456.0997435423105, 5458.2904222451225, 5468.0, 5474.534664553517, 204470.0, 207956.0, 212549.0, 214991.0, 265037.0, 53164.0, 53182.46817531853, 53343.73328133334, 53357.733211333696, 53577.464225358024, 56532.0, 56694.0, 56895.163911068026, 56962.0, 57036.42963570394, 16141.273642686107, 16204.594885127966, 16221.107839068729, 16224.837751622332, 16388.836111638968, 13130.212187268795, 13206.273654948878, 13217.537386191438, 13219.405126769318, 13231.867681323256, 15291.694166116615, 15357.385704571916, 15436.382544698086, 15469.767953480707, 15536.0, 6234.594751341148, 6238.844028899314, 6256.5307601930035, 6297.779577714758, 6383.234011918599, 1072.7371793910543, 1081.2377274021796, 1087.472575800736, 1092.1208427216168, 1105.9889401106047, 25233.243002709947, 25290.36774080663, 25316.74683253144, 25438.0, 25475.0, 1189.232944750631, 1189.6550000499758, 1197.3474456421272, 1207.0102515936846, 1214.0227117170587, 13209.07536472457, 13209.669758256121, 13236.735265294641, 13249.337533123306, 13454.932725336506, 292074.0, 294207.0, 294460.0, 294892.0, 295334.0, 56087.43912560822, 56246.0, 56246.43753562412, 56733.71633141808, 56812.43187568154, 1483.7700156475764, 1489.8510148985015, 1508.6379268975368, 1510.9924450377678, 1534.5549790560835, 24816.255512334657, 24847.627285591094, 24967.87516062408, 25093.121740738985, 25098.567649847635, 40538.0, 40644.0, 40893.38659920044, 40910.9772255696, 40965.38551921664, 232394.0, 233010.0, 233049.0, 233391.0, 233559.0, 33382.33235335328, 34009.0, 36829.44755828718, 37624.81187594154, 38085.0, 46011.76994115008, 46216.768916155204, 46634.300485492065, 46653.53346466492, 46844.7657761709, 33596.160095997795, 33818.8309058463, 34021.31957360789, 34118.82940585281, 34322.656773432944, 32983.8350808254, 33727.83136084304, 33840.15399614931, 34081.65918340785, 34143.317133657685, 39505.604943950195, 39775.2044959105, 39972.80013599913, 40299.19401611893, 40384.59615403808, 32314.0, 32759.0, 32975.0, 32979.50530741993, 33078.50382244316, 25180.24459266225, 25181.496370072862, 25255.98976040938, 25503.931237006214, 25676.614850777627], "value": 18269.88364388692, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.3123298890815, 582.493230889126, 585.4438283630552, 589.7464090441111, 610.5329422991412, 23028.19401320954, 23895.16366927158, 23902.163424280112, 23912.163074292403, 23948.28155155344, 22119.336419907428, 25676.871615641896, 25873.223803285884, 25970.610440843404, 25997.480050399026, 6755.054292399068, 6873.656317184146, 6901.654917254143, 6905.033295338657, 6906.412954898839, 6425.518086143535, 6684.0, 6698.296678848724, 6731.0, 6744.595324280559, 6655.966720166392, 6668.699908504129, 6676.2989886062, 6682.899756503656, 6687.598744075371, 6611.471082313404, 6625.867482650344, 6646.10277612521, 6661.067450556926, 6668.933310666928, 6227.065940108974, 6235.220597425322, 6235.501159907197, 6248.094026366151, 6254.937450625526, 28969.0, 29916.70083299161, 30445.934392296385, 30589.847050764605, 30649.233769155726, 2234.318532847481, 2267.7618850020676, 2274.2040285899966, 2274.4882401459704, 2277.0, 20460.795392046188, 21002.474938126517, 21008.684869726967, 21107.894460527754, 21176.788232117637, 2355.7526459721653, 2392.4258178037144, 2409.3976505873534, 2413.179518963561, 2424.4787370715203, 154167.0, 156508.0, 157453.21273393673, 157455.0, 157567.2121639396, 15835.68328633421, 16386.6722665546, 16440.260188291555, 16571.25429355682, 17233.482995510156, 25714.48571028569, 25799.74200258011, 25828.225153245432, 25850.224493265232, 25864.48271034569, 10365.222608304408, 10425.791484170426, 10445.738856528496, 10574.832159407302, 10584.735381615366, 8795.516246606498, 9245.491497967541, 9331.300152488591, 9404.529773511296, 9620.71137865865, 8793.384463087523, 9998.350107243006, 10251.794964100676, 10282.794344113076, 10371.325863818978, 3020.2298413904314, 3035.3002318701597, 3055.618047744032, 3074.1853408846673, 3134.4044631519937, 1044.728370623628, 1050.112654806687, 1055.963041293551, 1058.6188971970084, 1063.1335461598771, 21463.356099317047, 22004.88997555002, 22060.0, 22130.446738831335, 22228.444288892908, 1105.7512059786645, 1111.111111111108, 1111.3165403276926, 1117.7987962166726, 1121.6971417717334, 11710.414479276173, 12113.636590902288, 12224.1443098984, 12248.938755306166, 12262.325572093621, 631239.0, 636967.0, 647155.0, 650855.0, 652229.0, 129439.70560294465, 133771.3311433456, 134318.0, 135336.0, 136495.0, 1210.8789121087646, 1223.2293654997472, 1236.5301185549613, 1238.3498663201892, 1251.6245126462024, 19554.0, 19735.802641973398, 19965.401037968884, 20067.898530524424, 20173.394798156078, 62265.0, 62667.0, 63407.6829615849, 64429.35570644234, 65248.67375663092, 77745.61127194327, 78207.0, 78365.21634783807, 81145.0, 81693.0, 12901.0, 13407.731845363232, 14996.475123371067, 15456.690866182838, 15932.283047263132, 24364.390890227176, 24421.38946526351, 24545.87727061353, 24631.63052554249, 25440.618390723783, 19910.50223744418, 19959.301424450074, 20243.79756202478, 20360.490987725425, 20440.386788396372, 17719.0, 17738.0, 17839.910800446432, 18033.639327213645, 18182.27270909149, 20545.48636284105, 20684.379468615967, 20847.37457876266, 20999.475013124793, 21123.471913202295, 15533.223338833239, 15577.922110389829, 15619.76570351423, 15643.0, 15657.37370505167, 19963.0, 19974.1011654473, 20104.0, 20122.89938550298, 20156.496087597927], "value": 12859.172107735423, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.3587417724937, 1491.1500444746491, 1491.4705279625725, 1499.7450433426322, 1507.02796696131, 16435.83564164358, 16781.748273775887, 17439.3024279029, 17458.82541174591, 17477.213525391355, 16713.331466741314, 17840.10799460025, 17841.91079044606, 17919.82080179201, 17952.371666991658, 2800.187945495804, 2847.7437030667234, 2851.7148285171525, 2854.357769501866, 2854.7002564730715, 2761.254461295449, 2797.2447439191405, 2840.7159284071636, 2896.884124635012, 2907.4330505551425, 2877.7410033097017, 2908.476474234637, 2910.3015276333635, 2910.6652734935437, 2912.417516496709, 2775.4310366374843, 2808.0, 2808.0873716042265, 2809.564517499781, 2809.859507024661, 2716.0493827160535, 2737.630419893309, 2742.287005378596, 2742.7257274272415, 2745.217612980291, 16616.335346586122, 16807.243674034697, 16908.40820571274, 16947.83052169475, 16961.067141307223, 7946.880796788053, 8242.587870606445, 8243.340532757366, 8255.504669719776, 8260.504369737775, 5254.159334506498, 5605.411431799684, 5646.915296270559, 5731.541476681856, 5842.065269556851, 2139.5079131799775, 2150.0969592771075, 2150.2474134053114, 2224.5662095891307, 2235.1506427557424, 30212.8489357554, 30437.695623043706, 30441.695583044107, 30447.543286850716, 30479.085627431214, 7063.187733410621, 7163.10461192351, 7184.389326907167, 7349.55902645843, 7363.705451781869, 19361.903190484238, 19383.709244361347, 19387.418377448415, 19391.4182574523, 19419.514512137026, 7975.361971042303, 8095.190480951857, 8103.392245581487, 8124.837503249902, 8150.7147249845975, 7339.1926888043345, 7395.926040739631, 7411.703531858773, 7498.212687667717, 7621.047369078865, 5456.7544460499375, 5690.5447564194765, 5733.480627633597, 5746.511546518593, 5747.166660834239, 1474.0934325389808, 1506.1866592040176, 1648.4477699970644, 1689.4509284482347, 1708.2995971651562, 981.1709105805583, 1001.6344034427382, 1001.8447140693204, 1007.496251874063, 1021.8313978193663, 9853.310268281151, 9902.900970990198, 10015.949920250498, 10021.79956400868, 10183.134433573081, 1058.349115294104, 1076.208986394999, 1081.297156848055, 1083.1713738989522, 1085.5006696919506, 6081.878362432815, 6148.6925653717135, 6168.629882207082, 6309.305976342599, 6382.36176382358, 24789.380265493506, 25404.74595254061, 25508.489830203664, 25568.616470752953, 25624.359391015, 24236.87881560581, 24904.12835550747, 25090.37274068163, 25349.0, 25430.0, 12960.611181664564, 12978.675533111558, 12984.545540906023, 13094.476220951246, 13100.475980960846, 9546.90453095488, 9715.854262186214, 9746.415215087118, 9926.454045027593, 10006.799864002534, 20923.895380523, 21020.789792101885, 21175.682364764234, 21572.892135539223, 21613.45966350791, 22101.44746381289, 22294.21970231034, 22453.0, 22551.77448225497, 22978.0, 13882.861171388156, 14149.292535372984, 14163.150210987375, 14164.92917535447, 14165.787513187517, 15709.45016924402, 15875.0, 15976.760348595013, 15978.680426391174, 16091.114988675736, 11340.6030788922, 12571.497140114294, 12723.681907952005, 12900.225986440844, 12975.0, 12132.939335303266, 12841.35793210317, 12912.612621621367, 12922.095453318178, 13050.282234477196, 10372.896271037494, 10508.842367364648, 10532.526036328536, 10544.630937917136, 10553.41956192386, 8151.062627797642, 8160.8775868360835, 8204.917950820416, 8240.876386854083, 8272.255497005057, 8272.0, 8299.08710041907, 8373.455725377831, 8424.157584241771, 8459.746207613782], "value": 7417.856635422757, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [524.5803357314147, 531.9015982043322, 567.2880534928664, 567.7331654122562, 569.0496870226721, 6819.863602727943, 6824.522283440161, 6878.209005964316, 6882.346177113179, 6907.827304317382, 6610.570312929668, 6627.1716035495565, 6713.832154196135, 6732.663366831664, 6793.150856142982, 5497.642653227547, 5527.889442211153, 5534.422689533484, 5552.361478429992, 5635.267415236013, 5384.138537833947, 5464.371597266306, 5537.446255374451, 5565.332160140789, 5566.610337276392, 5480.808171713991, 5504.422035686255, 5523.9894257629485, 5580.414056524068, 5631.859203519924, 5454.209139674725, 5491.313585801755, 5516.503514683678, 5539.169124631298, 5601.403599973988, 5186.688798672054, 5191.169412893956, 5195.2986346843445, 5224.63427560071, 5245.423003469616, 6710.261871194165, 6728.4617230621925, 6779.62712050837, 6783.796486105425, 6792.7773000859825, 6562.146920900253, 6579.98010308403, 6670.0, 6684.498662600325, 6713.12729345182, 7224.530405523677, 7227.060482137341, 7259.41924646027, 7325.963370183168, 7377.77866664001, 5749.655020698771, 5834.374531460817, 5854.795082172103, 5863.941360586382, 5869.677167755772, 7548.6225688715895, 7576.810579735551, 7634.541927484369, 7693.269139431745, 7722.806929826799, 5999.970000149971, 6009.669468179292, 6013.428724271187, 6052.273727152769, 6108.450239478469, 6141.907871381933, 6181.258249010148, 6194.349593292639, 6229.906551401733, 6240.625562466266, 6470.09418681385, 6485.805425837232, 6555.508336874754, 6679.799606011828, 6742.292059333798, 7222.241664625244, 7257.996033827382, 7288.380487658503, 7354.595497247703, 7368.815779605446, 2005.9398218053482, 2074.4917495213767, 2079.781622929601, 2115.5240070983905, 2119.4383488375593, 1554.2617256802928, 1581.818090919536, 1590.3400088963183, 1606.6144125409817, 1632.5755303620906, 6101.725422356005, 6193.380661933861, 6204.162438070893, 6262.624242545371, 6270.874582508415, 7035.613041282779, 7102.928970710227, 7223.530470519399, 7312.817179570553, 7371.88942165868, 6217.471514921192, 6262.34245404226, 6293.874122517524, 6324.482124290229, 6332.081848131995, 3807.4669546263544, 3871.8064096795047, 3892.5134358205223, 3900.473436086149, 3919.941200881989, 7115.644217789089, 7129.18014428337, 7191.7842464726145, 7220.0, 7227.048696851925, 7457.664405101784, 7490.325870671557, 7505.211952744992, 7528.0, 7581.924180758122, 6961.721531138799, 7026.754063607749, 7166.462515311268, 7169.820754481179, 7214.71141154348, 7327.780166595011, 7366.889496657447, 7403.444741644506, 7424.740134095281, 7426.962865185856, 7301.160366557701, 7323.2676732326345, 7381.630918454163, 7425.517341372793, 7456.47142335809, 7010.298970102949, 7089.574625522485, 7111.182214045451, 7129.041447799313, 7222.34998850085, 5450.31871016123, 5494.313210848644, 5501.504864562208, 5523.2819733436, 5529.566990524157, 7038.2961703829205, 7204.56772593646, 7301.342879140902, 7418.516296740781, 7440.3675687567165, 6922.8615427692175, 6939.861202775815, 6971.6165610891885, 7022.262662420372, 7119.288071193047, 6952.548084374702, 6983.0, 7050.801363768069, 7068.041896537955, 7086.255943125898, 6917.723291068301, 6929.792106236821, 6932.445404367538, 6933.341332573397, 6986.860262794614, 7025.5082144251455, 7073.71705131789, 7085.539439936388, 7161.281292489869, 7186.24544422849, 6955.930440695528, 6956.339147780953, 6959.512834101564, 6980.685869136003, 7038.5776853386915], "value": 5649.207994852116, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [896.2337201692553, 926.2219735422244, 938.0291398402651, 946.6145313809201, 1000.9499525023751, 19089.618207635856, 19465.416037518862, 19608.411747647595, 19782.802171978296, 19791.406257812254, 19396.999999999964, 19484.805151948476, 19552.511187220327, 19666.803331966676, 19819.801801981976, 1040.4641609571058, 1069.8395240713917, 1079.3955384984395, 1079.498033414462, 1082.0099608857925, 1067.311584028305, 1086.5219303506433, 1088.9564417423294, 1090.4111779638986, 1090.6727981605486, 1142.2404101272643, 1149.8505194324769, 1150.091427772059, 1150.8503894493704, 1151.2919554474022, 1143.9027682646943, 1147.1912301827192, 1147.9885201148006, 1148.9540418383256, 1149.781541507111, 840.0381563110204, 843.1189407069627, 843.28320927212, 843.6035063520136, 843.7763992541982, 19415.611687766166, 19509.414717558353, 19572.0, 19573.51066223356, 19928.0, 22201.0, 22663.546729065325, 22671.659925101136, 22893.427664308525, 23659.408514787097, 17990.910045449687, 18170.4548863533, 18238.17928193221, 18269.63460730791, 18404.44786656389, 4101.241270364992, 4124.711270211086, 4138.8551400700835, 4140.420341152241, 4144.52337981133, 150874.24562877225, 152766.0, 153551.0, 153648.0, 155550.22224888802, 17475.47573572795, 17849.0, 17924.820751792577, 17943.820561794215, 18164.545886352684, 32803.67196328054, 33296.50055249173, 33318.83340583282, 33645.495317570254, 33913.32173356519, 8477.152284771473, 8860.113988601088, 9058.139476749699, 9190.678326258549, 9288.79245698055, 4574.5882870541145, 4715.976420117878, 4738.19450693383, 4754.263089221176, 4757.857264282077, 6111.663858487737, 6181.907271390933, 6189.35011823761, 6218.844528886723, 6234.781782637586, 1243.241622610207, 1247.1207798502035, 1248.712796056912, 1248.7252804383022, 1261.1991385470292, 734.1740541890373, 739.0503203383627, 746.805830484078, 748.8914107454389, 767.1293082351459, 12915.483380664857, 13357.332133393485, 13892.513762018483, 14072.085314454527, 14387.640308992359, 735.1655870586923, 735.9852802943911, 739.7078154129089, 739.971439698819, 751.537804250393, 5964.067789839503, 5966.76132954677, 6098.054801505774, 6112.266528016667, 6121.693915304217, 132028.0, 134892.0, 138021.85095099438, 138596.0, 139961.30019349838, 121577.0, 126085.0, 126676.0, 127861.36069319594, 128048.0, 100995.0, 102097.48951255197, 102867.48566257271, 102935.4853225744, 105031.0, 11226.943865280622, 11271.830922536332, 11305.577344878118, 11512.021478174449, 11552.59565915189, 52370.73814630903, 52934.73532632312, 54148.72925635504, 54272.18591721048, 54370.18444723411, 67218.66390668016, 67457.3254267451, 68010.0, 68217.0, 69154.30845691479, 12976.02679799, 13144.474221031434, 13292.601221963356, 13293.069485135948, 14387.92806035963, 22022.88988555047, 22490.662640060084, 22491.88754056219, 22744.20395286157, 22993.540129197656, 19833.801661983773, 19859.801401985795, 20034.298799541946, 20176.42277639012, 20443.4889127773, 19740.703889441957, 19805.80194198097, 19843.702344464557, 19864.90067549653, 19965.301214457424, 20121.798782011992, 20143.79856201419, 20175.79824201739, 20253.59492810165, 20310.695339569622, 15010.0, 15301.46444874424, 15326.386944522093, 15471.226438678246, 15799.526014219591, 14646.926765366105, 15100.244987750788, 15128.848711512745, 15183.544493665207, 15231.238438078273], "value": 9969.850189827228, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1448.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 105.0, 99.0, 105.0, 110.0, 104.0, 107.0, 97.0, 108.0, 107.0], "value": 103.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 109.0, 146.0, 185.0, 112.0, 148.0, 148.0, 112.0, 166.0, 114.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 161.0, 169.0, 162.0, 162.0, 167.0, 163.0, 167.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 169.0, 73.0, 73.0, 74.0, 73.0, 177.0, 73.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 132.0, 79.0, 79.0, 77.0, 129.0, 77.0, 82.0, 120.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0, 113.0, 113.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 154.0, 154.0, 152.0, 152.0, 152.0, 153.0, 151.0, 151.0, 152.0], "value": 152.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 94.0, 93.0, 94.0, 93.0, 94.0, 93.0, 94.0, 95.0, 95.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 46.0, 51.0, 44.0, 49.0, 49.0, 45.0, 50.0, 44.0, 46.0], "value": 47.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 37.0, 36.0, 36.0, 36.0, 36.0, 36.0, 36.0, 36.0, 36.0], "value": 36.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 76.0, 78.0, 73.0, 72.0, 72.0, 74.0, 73.0, 75.0, 75.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 110.0, 121.0, 109.0, 115.0, 128.0, 112.0, 117.0, 120.0, 145.0], "value": 119.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 170.0, 154.0, 154.0, 155.0, 157.0, 156.0, 157.0, 159.0, 159.0], "value": 158.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 61.0, 64.0, 63.0, 66.0, 61.0, 59.0, 58.0], "value": 61.8, "unit": "score"}]}]} 02:14:49 INFO - Return code: 0 02:14:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:14:49 INFO - # TBPL SUCCESS # 02:14:49 INFO - Running post-action listener: _package_coverage_data 02:14:49 INFO - Running post-action listener: _resource_record_post_action 02:14:49 INFO - [mozharness: 2017-09-06 09:14:49.743434Z] Finished run-tests step (success) 02:14:49 INFO - Running post-run listener: _resource_record_post_run 02:14:49 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 8192; Write bytes: 401502208; Read time: 540; Write time: 716272 02:14:49 INFO - TinderboxPrint: CPU usage
13.1% 02:14:49 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 540 02:14:49 INFO - TinderboxPrint: I/O write bytes / time
401,502,208 / 716,272 02:14:49 INFO - TinderboxPrint: CPU idle
8,243.8 (86.9%) 02:14:49 INFO - TinderboxPrint: CPU user
1,187.6 (12.5%) 02:14:49 INFO - TinderboxPrint: Swap in / out
0 / 0 02:14:49 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 17207296; Read time: 0; Write time: 20924 02:14:49 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 02:14:49 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 8192; Write bytes: 383795200; Read time: 540; Write time: 695340 02:14:50 INFO - Running post-run listener: _upload_blobber_files 02:14:50 INFO - Blob upload gear active. 02:14:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:14:50 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 02:14:50 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'] 02:14:50 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 02:14:50 INFO - (blobuploader) - INFO - Open directory for files ... 02:14:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:14:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:14:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:14:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:14:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:14:51 INFO - (blobuploader) - INFO - Done attempting. 02:14:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:14:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:14:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:14:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:14:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:14:58 INFO - (blobuploader) - INFO - Done attempting. 02:14:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:14:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:14:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:14:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:14:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:14:59 INFO - (blobuploader) - INFO - Done attempting. 02:14:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:14:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:14:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:15:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:15:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:15:00 INFO - (blobuploader) - INFO - Done attempting. 02:15:00 INFO - (blobuploader) - INFO - Iteration through files over. 02:15:00 INFO - Return code: 0 02:15:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:15:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:15:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c446ee790e9e3345428d80a4da15995ce7461a89e94f47632682cb2b69f1ba815d2cb4fb051ebf087af7dea60e7b4d60cc015254a06463aac1e6dd42807edfbc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e4dde414646aad90cc53631b7de268c951712eb27c005c4e4c59c3a513bc61426114026de49d5a34be586bac719b3c89f5dce3f853af6802d5d1fe329f7bcb0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c64d02ffa7ff9e87acbd6fd8cff5cc09ae1e86135303a16c09637decf73aa437511ac6a531560dd857fee73bf5d49df9c363f42d112ca1dbc300b865e6f65bc8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e67a309d9c89251f5b62bd5ee9f73eff5a4d4978a08f229cf88548374ebd811b4b282d42439e9cf5611f24d5e67542c4a1380dcb367af1cca6c8db4c5078b26"} 02:15:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:15:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:15:00 INFO - Contents: 02:15:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c446ee790e9e3345428d80a4da15995ce7461a89e94f47632682cb2b69f1ba815d2cb4fb051ebf087af7dea60e7b4d60cc015254a06463aac1e6dd42807edfbc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e4dde414646aad90cc53631b7de268c951712eb27c005c4e4c59c3a513bc61426114026de49d5a34be586bac719b3c89f5dce3f853af6802d5d1fe329f7bcb0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c64d02ffa7ff9e87acbd6fd8cff5cc09ae1e86135303a16c09637decf73aa437511ac6a531560dd857fee73bf5d49df9c363f42d112ca1dbc300b865e6f65bc8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e67a309d9c89251f5b62bd5ee9f73eff5a4d4978a08f229cf88548374ebd811b4b282d42439e9cf5611f24d5e67542c4a1380dcb367af1cca6c8db4c5078b26"} 02:15:00 INFO - Running post-run listener: copy_logs_to_upload_dir 02:15:00 INFO - Copying logs to upload dir... 02:15:00 INFO - mkdir: /builds/slave/test/build/upload/logs 02:15:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.748833 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-09-06 02:15:00.657192) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-06 02:15:00.660289) ========= 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=8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c446ee790e9e3345428d80a4da15995ce7461a89e94f47632682cb2b69f1ba815d2cb4fb051ebf087af7dea60e7b4d60cc015254a06463aac1e6dd42807edfbc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e4dde414646aad90cc53631b7de268c951712eb27c005c4e4c59c3a513bc61426114026de49d5a34be586bac719b3c89f5dce3f853af6802d5d1fe329f7bcb0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c64d02ffa7ff9e87acbd6fd8cff5cc09ae1e86135303a16c09637decf73aa437511ac6a531560dd857fee73bf5d49df9c363f42d112ca1dbc300b865e6f65bc8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e67a309d9c89251f5b62bd5ee9f73eff5a4d4978a08f229cf88548374ebd811b4b282d42439e9cf5611f24d5e67542c4a1380dcb367af1cca6c8db4c5078b26"} build_url:https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011307 build_url: 'https://queue.taskcluster.net/v1/task/T_xb_5lOTcid8TTYpSDIPw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c446ee790e9e3345428d80a4da15995ce7461a89e94f47632682cb2b69f1ba815d2cb4fb051ebf087af7dea60e7b4d60cc015254a06463aac1e6dd42807edfbc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e4dde414646aad90cc53631b7de268c951712eb27c005c4e4c59c3a513bc61426114026de49d5a34be586bac719b3c89f5dce3f853af6802d5d1fe329f7bcb0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c64d02ffa7ff9e87acbd6fd8cff5cc09ae1e86135303a16c09637decf73aa437511ac6a531560dd857fee73bf5d49df9c363f42d112ca1dbc300b865e6f65bc8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e67a309d9c89251f5b62bd5ee9f73eff5a4d4978a08f229cf88548374ebd811b4b282d42439e9cf5611f24d5e67542c4a1380dcb367af1cca6c8db4c5078b26"}' ========= master_lag: 0.15 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-06 02:15:00.817038) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 02:15:00.817366) ========= 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=8783db0840cc980e80bb0bd400000236-1504688049.318327-1827869991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-06 02:15:00.836763) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-06 02:15:00.837071) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-06 02:15:00.837375) ========= ========= Total master_lag: 0.42 =========