builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-007 starttime: 1506538849.58 results: success (0) revision: 756e10aa8bbd416cbc49b7739f78fb81d5525477 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 12:00:49.575626) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 12:00:49.576126) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 12:00:49.576414) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 12:00:49.593795) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 12:00:49.594193) ========= 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=923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-27 12:00:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 41.7M=0s 2017-09-27 12:00:49 (41.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130148 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 12:00:49.741754) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 12:00:49.742166) ========= 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=923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017292 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 12:00:49.794054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 12:00:49.794709) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 756e10aa8bbd416cbc49b7739f78fb81d5525477 --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 756e10aa8bbd416cbc49b7739f78fb81d5525477 --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=923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-27 12:00:49,887 truncating revision to first 12 chars 2017-09-27 12:00:49,887 Setting DEBUG logging. 2017-09-27 12:00:49,887 attempt 1/10 2017-09-27 12:00:49,887 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/756e10aa8bbd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-27 12:00:50,288 unpacking tar archive at: mozilla-central-756e10aa8bbd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.686413 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 12:00:50.497826) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 12:00:50.498156) ========= script_repo_revision: 756e10aa8bbd416cbc49b7739f78fb81d5525477 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 12:00:50.498550) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 12:00:50.498824) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 12:00:50.512767) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2017-09-27 12:00:50.513167) ========= /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=923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:00:50 INFO - MultiFileLogger online at 20170927 12:00:50 in /builds/slave/test 12:00:50 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 12:00:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:00:50 INFO - {'append_to_log': False, 12:00:50 INFO - 'base_work_dir': '/builds/slave/test', 12:00:50 INFO - 'blob_upload_branch': 'Firefox', 12:00:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:00:50 INFO - 'branch': 'Firefox', 12:00:50 INFO - 'buildbot_json_path': 'buildprops.json', 12:00:50 INFO - 'code_coverage': False, 12:00:50 INFO - 'config_files': ('talos/linux_config.py',), 12:00:50 INFO - 'default_actions': ('clobber', 12:00:50 INFO - 'read-buildbot-config', 12:00:50 INFO - 'download-and-extract', 12:00:50 INFO - 'populate-webroot', 12:00:50 INFO - 'create-virtualenv', 12:00:50 INFO - 'install', 12:00:50 INFO - 'setup-mitmproxy', 12:00:50 INFO - 'run-tests'), 12:00:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:00:50 INFO - 'disable_ccov_upload': False, 12:00:50 INFO - 'disable_stylo': False, 12:00:50 INFO - 'download_minidump_stackwalk': True, 12:00:50 INFO - 'download_symbols': 'ondemand', 12:00:50 INFO - 'enable_stylo': False, 12:00:50 INFO - 'enable_webrender': False, 12:00:50 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:00:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:00:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:00:50 INFO - 'gecko_profile': False, 12:00:50 INFO - 'gecko_profile_interval': 0, 12:00:50 INFO - 'installer_path': 'installer.exe', 12:00:50 INFO - 'log_level': 'info', 12:00:50 INFO - 'log_name': 'talos', 12:00:50 INFO - 'log_to_console': True, 12:00:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:00:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:00:50 INFO - 'opt_config_files': (), 12:00:50 INFO - 'pip_index': False, 12:00:50 INFO - 'suite': 'dromaeojs-e10s', 12:00:50 INFO - 'system_bits': '32', 12:00:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:00:50 INFO - 'title': 'talos-linux64-ix-007', 12:00:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:00:50 INFO - 'use_talos_json': True, 12:00:50 INFO - 'verify': 'False', 12:00:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:00:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:00:50 INFO - 'work_dir': 'build'} 12:00:50 INFO - [mozharness: 2017-09-27 19:00:50.743905Z] Running clobber step. 12:00:50 INFO - Running pre-action listener: _resource_record_pre_action 12:00:50 INFO - Running main action method: clobber 12:00:50 INFO - rmtree: /builds/slave/test/build 12:00:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:00:51 INFO - Running post-action listener: _resource_record_post_action 12:00:51 INFO - [mozharness: 2017-09-27 19:00:51.866600Z] Finished clobber step (success) 12:00:51 INFO - [mozharness: 2017-09-27 19:00:51.866697Z] Running read-buildbot-config step. 12:00:51 INFO - Running pre-action listener: _resource_record_pre_action 12:00:51 INFO - Running main action method: read_buildbot_config 12:00:51 INFO - Using buildbot properties: 12:00:51 INFO - { 12:00:51 INFO - "project": "", 12:00:51 INFO - "product": "firefox", 12:00:51 INFO - "who": "ffxbld@noreply.mozilla.org", 12:00:51 INFO - "installer_path": "public/build/target.tar.bz2", 12:00:51 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:00:51 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:00:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 12:00:51 INFO - "stage_platform": "linux64-pgo", 12:00:51 INFO - "basedir": "/builds/slave/test", 12:00:51 INFO - "buildnumber": 211, 12:00:51 INFO - "platform": "ubuntu64_hw", 12:00:51 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:00:51 INFO - "slavebuilddir": "test", 12:00:51 INFO - "taskId": "SznHbUXiSCK4RPNkyTw1gA", 12:00:51 INFO - "branch": "mozilla-central", 12:00:51 INFO - "script_repo_revision": "production", 12:00:51 INFO - "revision": "756e10aa8bbd416cbc49b7739f78fb81d5525477", 12:00:51 INFO - "slavename": "talos-linux64-ix-007", 12:00:51 INFO - "repo_path": "mozilla-central" 12:00:51 INFO - } 12:00:51 INFO - Finding installer, test and symbols from parent task. 12:00:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SznHbUXiSCK4RPNkyTw1gA'}, attempt #1 12:00:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SznHbUXiSCK4RPNkyTw1gA'}, attempt #1 12:00:52 INFO - Task dependencies: PBHu4AnVT_OJapiiUJnugg 12:00:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg'}, attempt #1 12:00:53 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.tar.bz2 12:00:53 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.test_packages.json 12:00:53 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.crashreporter-symbols.zip 12:00:53 INFO - Running post-action listener: _resource_record_post_action 12:00:53 INFO - [mozharness: 2017-09-27 19:00:53.261814Z] Finished read-buildbot-config step (success) 12:00:53 INFO - [mozharness: 2017-09-27 19:00:53.262032Z] Running download-and-extract step. 12:00:53 INFO - Running pre-action listener: _resource_record_pre_action 12:00:53 INFO - Running main action method: download_and_extract 12:00:53 INFO - mkdir: /builds/slave/test/build/tests 12:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:53 INFO - trying https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.test_packages.json 12:00:53 INFO - Downloading https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:00:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:00:55 INFO - Downloaded 989 bytes. 12:00:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:00:55 INFO - Using the following test package requirements: 12:00:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:00:55 INFO - u'common': [u'target.common.tests.zip'], 12:00:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:00:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:00:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:00:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:00:55 INFO - u'mozbase': [u'target.common.tests.zip'], 12:00:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:00:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:00:55 INFO - u'web-platform': [u'target.common.tests.zip', 12:00:55 INFO - u'target.web-platform.tests.tar.gz'], 12:00:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:00:55 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:00:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.common.tests.zip 12:00:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:00:55 INFO - Fetch https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.common.tests.zip into memory 12:00:56 INFO - Content-Length response header: 55348979 12:00:56 INFO - Bytes received: 55348979 12:01:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.talos.tests.zip 12:01:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:01:06 INFO - Fetch https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.talos.tests.zip into memory 12:01:07 INFO - Content-Length response header: 14513665 12:01:07 INFO - Bytes received: 14513665 12:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:08 INFO - trying https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.tar.bz2 12:01:08 INFO - Downloading https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:01:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:01:09 INFO - Downloaded 62317430 bytes. 12:01:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.tar.bz2 12:01:09 INFO - mkdir: /builds/slave/test/properties 12:01:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:01:09 INFO - Writing to file /builds/slave/test/properties/build_url 12:01:09 INFO - Contents: 12:01:09 INFO - build_url:https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.tar.bz2 12:01:09 INFO - Running post-action listener: _resource_record_post_action 12:01:09 INFO - Running post-action listener: find_tests_for_verification 12:01:09 INFO - Running post-action listener: set_extra_try_arguments 12:01:09 INFO - [mozharness: 2017-09-27 19:01:09.412914Z] Finished download-and-extract step (success) 12:01:09 INFO - [mozharness: 2017-09-27 19:01:09.413012Z] Running populate-webroot step. 12:01:09 INFO - Running pre-action listener: _resource_record_pre_action 12:01:09 INFO - Running main action method: populate_webroot 12:01:09 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:01:09 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:09 INFO - 'tests': ['tresize', 12:01:09 INFO - 'tcanvasmark']}, 12:01:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:01:09 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:09 INFO - 'tests': ['dromaeo_css', 12:01:09 INFO - 'kraken']}, 12:01:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:01:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:09 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:01:09 INFO - 'talos_options': ['--disable-stylo'], 12:01:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:01:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:01:09 INFO - 'tests': ['damp', 'tps']}, 12:01:09 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:01:09 INFO - 'talos_options': ['--disable-stylo'], 12:01:09 INFO - 'tests': ['damp', 'tps']}, 12:01:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:01:09 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:09 INFO - 'tests': ['dromaeo_dom']}, 12:01:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:01:09 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:09 INFO - 'tests': ['basic_compositor_video', 12:01:09 INFO - 'glvideo']}, 12:01:09 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:01:09 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:01:09 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:01:09 INFO - 'talos_options': ['--disable-stylo'], 12:01:09 INFO - 'tests': ['ts_paint_webext', 12:01:09 INFO - 'tp5o_webext']}, 12:01:09 INFO - 'other-e10s': {'tests': ['a11yr', 12:01:09 INFO - 'ts_paint', 12:01:09 INFO - 'tpaint', 12:01:09 INFO - 'sessionrestore', 12:01:09 INFO - 'sessionrestore_many_windows', 12:01:09 INFO - 'sessionrestore_no_auto_restore', 12:01:09 INFO - 'tabpaint']}, 12:01:09 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:09 INFO - 'tests': ['a11yr', 12:01:09 INFO - 'ts_paint', 12:01:09 INFO - 'tpaint', 12:01:09 INFO - 'sessionrestore', 12:01:09 INFO - 'sessionrestore_many_windows', 12:01:09 INFO - 'sessionrestore_no_auto_restore', 12:01:09 INFO - 'tabpaint']}, 12:01:09 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 12:01:09 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:01:09 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:09 INFO - 'tests': ['perf_reftest_singletons']}, 12:01:09 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:09 INFO - 'tests': ['perf_reftest']}, 12:01:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:01:09 INFO - 'tsvgr_opacity', 12:01:09 INFO - 'tart', 12:01:09 INFO - 'tscrollx', 12:01:09 INFO - 'tsvg_static']}, 12:01:09 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:01:09 INFO - 'tests': ['tsvgx', 12:01:09 INFO - 'tsvgr_opacity', 12:01:09 INFO - 'tart', 12:01:09 INFO - 'tscrollx', 12:01:09 INFO - 'tsvg_static']}, 12:01:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:01:09 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:01:09 INFO - 'talos_options': ['--disable-stylo'], 12:01:09 INFO - 'tests': ['tp5o']}, 12:01:09 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:01:09 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:01:09 INFO - 'talos_options': ['--mitmproxy', 12:01:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:01:09 INFO - '--firstNonBlankPaint'], 12:01:09 INFO - 'tests': ['tp6_google', 12:01:09 INFO - 'tp6_youtube', 12:01:09 INFO - 'tp6_amazon', 12:01:09 INFO - 'tp6_facebook']}, 12:01:09 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:01:09 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:01:09 INFO - 'talos_options': ['--disable-stylo', 12:01:09 INFO - '--mitmproxy', 12:01:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:01:09 INFO - '--firstNonBlankPaint'], 12:01:09 INFO - 'tests': ['tp6_google', 12:01:09 INFO - 'tp6_youtube', 12:01:09 INFO - 'tp6_amazon', 12:01:09 INFO - 'tp6_facebook']}, 12:01:09 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:01:09 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:01:09 INFO - 'talos_options': ['--stylo-threads=1', 12:01:09 INFO - '--mitmproxy', 12:01:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:01:09 INFO - '--firstNonBlankPaint'], 12:01:09 INFO - 'tests': ['tp6_google', 12:01:09 INFO - 'tp6_youtube', 12:01:09 INFO - 'tp6_amazon', 12:01:09 INFO - 'tp6_facebook']}, 12:01:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:01:09 INFO - 'talos_options': ['--xperf_path', 12:01:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:09 INFO - 'tests': ['tp5n']}, 12:01:09 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:01:09 INFO - 'talos_options': ['--disable-stylo', 12:01:09 INFO - '--xperf_path', 12:01:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:01:09 INFO - 'tests': ['tp5n']}}} 12:01:09 INFO - Running post-action listener: _resource_record_post_action 12:01:09 INFO - [mozharness: 2017-09-27 19:01:09.428300Z] Finished populate-webroot step (success) 12:01:09 INFO - [mozharness: 2017-09-27 19:01:09.428390Z] Running create-virtualenv step. 12:01:09 INFO - Running pre-action listener: _resource_record_pre_action 12:01:09 INFO - Running main action method: create_virtualenv 12:01:09 INFO - Creating virtualenv /builds/slave/test/build/venv 12:01:09 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:01:09 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:01:09 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:01:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:01:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:01:10 INFO - Installing distribute.............................................................................................................................................................................................done. 12:01:10 INFO - Installing pip...............done. 12:01:10 INFO - Return code: 0 12:01:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:01:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:01:10 INFO - Reading from file tmpfile_stdout 12:01:10 INFO - Output received: 12:01:10 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:01:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x276b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2933b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad89c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad5d60>, '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 0x2ad6a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ad6ef0>, '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': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', '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 12:01:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:01:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:01:10 INFO - Using env: {'DISPLAY': ':0', 12:01:10 INFO - 'HOME': '/home/cltbld', 12:01:10 INFO - 'LANG': 'en_US.UTF-8', 12:01:10 INFO - 'LANGUAGE': 'en_US:en', 12:01:10 INFO - 'LOGNAME': 'cltbld', 12:01:10 INFO - 'MAIL': '/var/mail/cltbld', 12:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:10 INFO - 'MOZ_NO_REMOTE': '1', 12:01:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:10 INFO - 'NO_EM_RESTART': '1', 12:01:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:10 INFO - 'PWD': '/builds/slave/test', 12:01:10 INFO - 'SHELL': '/bin/bash', 12:01:10 INFO - 'SHLVL': '1', 12:01:10 INFO - 'TERM': 'linux', 12:01:10 INFO - 'TMOUT': '86400', 12:01:10 INFO - 'USER': 'cltbld', 12:01:10 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', 12:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:10 INFO - '_': '/tools/buildbot/bin/python'} 12:01:11 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:01:11 INFO - Downloading/unpacking pip>=1.5 12:01:11 INFO - Running setup.py egg_info for package pip 12:01:11 INFO - warning: no files found matching 'pip/cacert.pem' 12:01:11 INFO - warning: no files found matching '*.html' under directory 'docs' 12:01:11 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:01:11 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:01:11 INFO - Installing collected packages: pip 12:01:11 INFO - Found existing installation: pip 1.1 12:01:11 INFO - Uninstalling pip: 12:01:11 INFO - Successfully uninstalled pip 12:01:11 INFO - Running setup.py install for pip 12:01:11 INFO - warning: no files found matching 'pip/cacert.pem' 12:01:11 INFO - warning: no files found matching '*.html' under directory 'docs' 12:01:11 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:01:11 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:01:11 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:01:11 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:01:11 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:01:11 INFO - Successfully installed pip 12:01:11 INFO - Cleaning up... 12:01:11 INFO - Return code: 0 12:01:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:01:11 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')]} 12:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:11 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')]} 12:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:11 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 0x276b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2933b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad89c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad5d60>, '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 0x2ad6a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ad6ef0>, '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': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', '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 12:01:11 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 12:01:11 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 12:01:11 INFO - Using env: {'DISPLAY': ':0', 12:01:11 INFO - 'HOME': '/home/cltbld', 12:01:11 INFO - 'LANG': 'en_US.UTF-8', 12:01:11 INFO - 'LANGUAGE': 'en_US:en', 12:01:11 INFO - 'LOGNAME': 'cltbld', 12:01:11 INFO - 'MAIL': '/var/mail/cltbld', 12:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:11 INFO - 'MOZ_NO_REMOTE': '1', 12:01:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:11 INFO - 'NO_EM_RESTART': '1', 12:01:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:11 INFO - 'PWD': '/builds/slave/test', 12:01:11 INFO - 'SHELL': '/bin/bash', 12:01:11 INFO - 'SHLVL': '1', 12:01:11 INFO - 'TERM': 'linux', 12:01:11 INFO - 'TMOUT': '86400', 12:01:11 INFO - 'USER': 'cltbld', 12:01:11 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', 12:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:11 INFO - '_': '/tools/buildbot/bin/python'} 12:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:11 INFO - Downloading/unpacking psutil>=3.1.1 12:01:11 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 12:01:11 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 12:01:11 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 12:01:11 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 12:01:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:01:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:01:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:01:13 INFO - Installing collected packages: psutil 12:01:13 INFO - Running setup.py install for psutil 12:01:13 INFO - building 'psutil._psutil_linux' extension 12:01:13 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 12:01:13 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 12:01:13 INFO - building 'psutil._psutil_posix' extension 12:01:13 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 12:01:13 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 12:01:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:01:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:01:13 INFO - Successfully installed psutil 12:01:13 INFO - Cleaning up... 12:01:13 INFO - Return code: 0 12:01:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:01:13 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')]} 12:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:13 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')]} 12:01:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:13 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 0x276b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2933b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad89c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad5d60>, '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 0x2ad6a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ad6ef0>, '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': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', '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 12:01:13 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 12:01:13 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 12:01:13 INFO - Using env: {'DISPLAY': ':0', 12:01:13 INFO - 'HOME': '/home/cltbld', 12:01:13 INFO - 'LANG': 'en_US.UTF-8', 12:01:13 INFO - 'LANGUAGE': 'en_US:en', 12:01:13 INFO - 'LOGNAME': 'cltbld', 12:01:13 INFO - 'MAIL': '/var/mail/cltbld', 12:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:13 INFO - 'MOZ_NO_REMOTE': '1', 12:01:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:13 INFO - 'NO_EM_RESTART': '1', 12:01:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:13 INFO - 'PWD': '/builds/slave/test', 12:01:13 INFO - 'SHELL': '/bin/bash', 12:01:13 INFO - 'SHLVL': '1', 12:01:13 INFO - 'TERM': 'linux', 12:01:13 INFO - 'TMOUT': '86400', 12:01:13 INFO - 'USER': 'cltbld', 12:01:13 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', 12:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:13 INFO - '_': '/tools/buildbot/bin/python'} 12:01:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:01:13 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 12:01:13 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 12:01:13 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 12:01:13 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 12:01:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:01:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:01:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:01:15 INFO - Installing collected packages: mozsystemmonitor 12:01:15 INFO - Running setup.py install for mozsystemmonitor 12:01:15 INFO - Successfully installed mozsystemmonitor 12:01:15 INFO - Cleaning up... 12:01:15 INFO - Return code: 0 12:01:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:01: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')]} 12:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01: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')]} 12:01:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:15 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 0x276b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2933b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad89c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad5d60>, '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 0x2ad6a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ad6ef0>, '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': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', '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 12:01:15 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 12:01:15 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 12:01:15 INFO - Using env: {'DISPLAY': ':0', 12:01:15 INFO - 'HOME': '/home/cltbld', 12:01:15 INFO - 'LANG': 'en_US.UTF-8', 12:01:15 INFO - 'LANGUAGE': 'en_US:en', 12:01:15 INFO - 'LOGNAME': 'cltbld', 12:01:15 INFO - 'MAIL': '/var/mail/cltbld', 12:01:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:15 INFO - 'MOZ_NO_REMOTE': '1', 12:01:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:15 INFO - 'NO_EM_RESTART': '1', 12:01:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:15 INFO - 'PWD': '/builds/slave/test', 12:01:15 INFO - 'SHELL': '/bin/bash', 12:01:15 INFO - 'SHLVL': '1', 12:01:15 INFO - 'TERM': 'linux', 12:01:15 INFO - 'TMOUT': '86400', 12:01:15 INFO - 'USER': 'cltbld', 12:01:15 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', 12:01:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:15 INFO - '_': '/tools/buildbot/bin/python'} 12:01:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:16 INFO - Downloading/unpacking jsonschema==2.5.1 12:01:16 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 12:01:16 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 12:01:16 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 12:01:16 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 12:01:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:01:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:01:18 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 12:01:18 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 12:01:18 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 12:01:18 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 12:01:18 INFO - Downloading functools32-3.2.3-2.tar.gz 12:01:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:01:18 INFO - warning: no files found matching '*.txt' 12:01:18 INFO - no previously-included directories found matching 'build' 12:01:18 INFO - no previously-included directories found matching 'dist' 12:01:18 INFO - no previously-included directories found matching '.git*' 12:01:18 INFO - Installing collected packages: jsonschema, functools32 12:01:18 INFO - Running setup.py install for functools32 12:01:18 INFO - warning: no files found matching '*.txt' 12:01:18 INFO - no previously-included directories found matching 'build' 12:01:18 INFO - no previously-included directories found matching 'dist' 12:01:18 INFO - no previously-included directories found matching '.git*' 12:01:18 INFO - Successfully installed jsonschema functools32 12:01:18 INFO - Cleaning up... 12:01:18 INFO - Return code: 0 12:01:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:01:18 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')]} 12:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:18 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')]} 12:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:18 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 0x276b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2933b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad89c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad5d60>, '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 0x2ad6a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ad6ef0>, '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': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', '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 12:01:18 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 12:01:18 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 12:01:18 INFO - Using env: {'DISPLAY': ':0', 12:01:18 INFO - 'HOME': '/home/cltbld', 12:01:18 INFO - 'LANG': 'en_US.UTF-8', 12:01:18 INFO - 'LANGUAGE': 'en_US:en', 12:01:18 INFO - 'LOGNAME': 'cltbld', 12:01:18 INFO - 'MAIL': '/var/mail/cltbld', 12:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:18 INFO - 'MOZ_NO_REMOTE': '1', 12:01:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:18 INFO - 'NO_EM_RESTART': '1', 12:01:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:18 INFO - 'PWD': '/builds/slave/test', 12:01:18 INFO - 'SHELL': '/bin/bash', 12:01:18 INFO - 'SHLVL': '1', 12:01:18 INFO - 'TERM': 'linux', 12:01:18 INFO - 'TMOUT': '86400', 12:01:18 INFO - 'USER': 'cltbld', 12:01:18 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', 12:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:18 INFO - '_': '/tools/buildbot/bin/python'} 12:01:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:01:19 INFO - Cleaning up... 12:01:19 INFO - Return code: 0 12:01:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:01:19 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')]} 12:01:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:19 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')]} 12:01:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:19 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 0x276b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2933b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad89c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad5d60>, '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 0x2ad6a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ad6ef0>, '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': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', '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 12:01:19 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 12:01:19 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 12:01:19 INFO - Using env: {'DISPLAY': ':0', 12:01:19 INFO - 'HOME': '/home/cltbld', 12:01:19 INFO - 'LANG': 'en_US.UTF-8', 12:01:19 INFO - 'LANGUAGE': 'en_US:en', 12:01:19 INFO - 'LOGNAME': 'cltbld', 12:01:19 INFO - 'MAIL': '/var/mail/cltbld', 12:01:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:19 INFO - 'MOZ_NO_REMOTE': '1', 12:01:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:19 INFO - 'NO_EM_RESTART': '1', 12:01:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:19 INFO - 'PWD': '/builds/slave/test', 12:01:19 INFO - 'SHELL': '/bin/bash', 12:01:19 INFO - 'SHLVL': '1', 12:01:19 INFO - 'TERM': 'linux', 12:01:19 INFO - 'TMOUT': '86400', 12:01:19 INFO - 'USER': 'cltbld', 12:01:19 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', 12:01:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:19 INFO - '_': '/tools/buildbot/bin/python'} 12:01:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:19 INFO - Downloading/unpacking blobuploader==1.2.4 12:01:19 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 12:01:19 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 12:01:19 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 12:01:19 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 12:01:20 INFO - Downloading blobuploader-1.2.4.tar.gz 12:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:01:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:01:20 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 12:01:20 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 12:01:20 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 12:01:20 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 12:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:01:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:01:20 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 12:01:20 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 12:01:20 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 12:01:20 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 12:01:20 INFO - Downloading docopt-0.6.1.tar.gz 12:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:01:21 INFO - Installing collected packages: blobuploader, requests, docopt 12:01:21 INFO - Running setup.py install for blobuploader 12:01:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:01:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:01:21 INFO - Running setup.py install for requests 12:01:21 INFO - Running setup.py install for docopt 12:01:21 INFO - Successfully installed blobuploader requests docopt 12:01:21 INFO - Cleaning up... 12:01:21 INFO - Return code: 0 12:01:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:21 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 0x276b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2933b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad89c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad5d60>, '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 0x2ad6a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ad6ef0>, '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': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', '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 12:01:21 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 12:01:21 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 12:01:21 INFO - Using env: {'DISPLAY': ':0', 12:01:21 INFO - 'HOME': '/home/cltbld', 12:01:21 INFO - 'LANG': 'en_US.UTF-8', 12:01:21 INFO - 'LANGUAGE': 'en_US:en', 12:01:21 INFO - 'LOGNAME': 'cltbld', 12:01:21 INFO - 'MAIL': '/var/mail/cltbld', 12:01:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:21 INFO - 'MOZ_NO_REMOTE': '1', 12:01:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:21 INFO - 'NO_EM_RESTART': '1', 12:01:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:21 INFO - 'PWD': '/builds/slave/test', 12:01:21 INFO - 'SHELL': '/bin/bash', 12:01:21 INFO - 'SHLVL': '1', 12:01:21 INFO - 'TERM': 'linux', 12:01:21 INFO - 'TMOUT': '86400', 12:01:21 INFO - 'USER': 'cltbld', 12:01:21 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', 12:01:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:21 INFO - '_': '/tools/buildbot/bin/python'} 12:01:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:01:21 INFO - Running setup.py (path:/tmp/pip-dsE84h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:01:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:01:21 INFO - Running setup.py (path:/tmp/pip-DpLab1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:01:22 INFO - Running setup.py (path:/tmp/pip-Rt2Ix9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:01:22 INFO - Running setup.py (path:/tmp/pip-PItSFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:01:22 INFO - Running setup.py (path:/tmp/pip-PrE719-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:01:22 INFO - Running setup.py (path:/tmp/pip-ghFDfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:01:22 INFO - Running setup.py (path:/tmp/pip-9O_lSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:01:22 INFO - Running setup.py (path:/tmp/pip-JVewku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:01:22 INFO - Running setup.py (path:/tmp/pip-ZcncQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:01:22 INFO - Running setup.py (path:/tmp/pip-dryvH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:01:22 INFO - Running setup.py (path:/tmp/pip-aG1mZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:01:22 INFO - Running setup.py (path:/tmp/pip-KIWQUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:01:22 INFO - Running setup.py (path:/tmp/pip-p_8Keo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:01:22 INFO - Running setup.py (path:/tmp/pip-UM_QTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:22 INFO - Running setup.py (path:/tmp/pip-usBKLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:01:22 INFO - Running setup.py (path:/tmp/pip-h9Fmym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:01:22 INFO - Running setup.py (path:/tmp/pip-whVHKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:01:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:01:23 INFO - Running setup.py install for manifestparser 12:01:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:01:23 INFO - Running setup.py install for mozcrash 12:01:23 INFO - Running setup.py install for mozdebug 12:01:23 INFO - Running setup.py install for mozdevice 12:01:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:01:23 INFO - Running setup.py install for mozfile 12:01:23 INFO - Running setup.py install for mozhttpd 12:01:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:01:23 INFO - Running setup.py install for mozinfo 12:01:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:01:23 INFO - Running setup.py install for mozInstall 12:01:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:01:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:01:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:01:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:01:23 INFO - Running setup.py install for mozleak 12:01:23 INFO - Running setup.py install for mozlog 12:01:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:01:24 INFO - Running setup.py install for moznetwork 12:01:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:01:24 INFO - Running setup.py install for mozprocess 12:01:24 INFO - Running setup.py install for mozprofile 12:01:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:01:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:01:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:01:24 INFO - Running setup.py install for mozrunner 12:01:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:01:24 INFO - Running setup.py install for mozscreenshot 12:01:24 INFO - Running setup.py install for moztest 12:01:24 INFO - Running setup.py install for mozversion 12:01:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:01:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:01:24 INFO - Cleaning up... 12:01:24 INFO - Return code: 0 12:01:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:24 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 0x276b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2933b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad89c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad5d60>, '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 0x2ad6a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ad6ef0>, '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': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', '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 12:01:24 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 12:01:24 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 12:01:24 INFO - Using env: {'DISPLAY': ':0', 12:01:24 INFO - 'HOME': '/home/cltbld', 12:01:24 INFO - 'LANG': 'en_US.UTF-8', 12:01:24 INFO - 'LANGUAGE': 'en_US:en', 12:01:24 INFO - 'LOGNAME': 'cltbld', 12:01:24 INFO - 'MAIL': '/var/mail/cltbld', 12:01:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:24 INFO - 'MOZ_NO_REMOTE': '1', 12:01:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:24 INFO - 'NO_EM_RESTART': '1', 12:01:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:24 INFO - 'PWD': '/builds/slave/test', 12:01:24 INFO - 'SHELL': '/bin/bash', 12:01:24 INFO - 'SHLVL': '1', 12:01:24 INFO - 'TERM': 'linux', 12:01:24 INFO - 'TMOUT': '86400', 12:01:24 INFO - 'USER': 'cltbld', 12:01:24 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', 12:01:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:24 INFO - '_': '/tools/buildbot/bin/python'} 12:01:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:01:25 INFO - Running setup.py (path:/tmp/pip-bHMNCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:01:25 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)) 12:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:01:25 INFO - Running setup.py (path:/tmp/pip-AKc299-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:01:25 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)) 12:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:01:25 INFO - Running setup.py (path:/tmp/pip-EicsVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:01:25 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)) 12:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:01:25 INFO - Running setup.py (path:/tmp/pip-Ql73g8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:01:25 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)) 12:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:01:25 INFO - Running setup.py (path:/tmp/pip-hwXUsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:01:25 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)) 12:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:01:25 INFO - Running setup.py (path:/tmp/pip-G8HYEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:01:25 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)) 12:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:01:25 INFO - Running setup.py (path:/tmp/pip-khBFtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:01:25 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)) 12:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:01:25 INFO - Running setup.py (path:/tmp/pip-Qvv6CJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:01:25 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)) 12:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:01:25 INFO - Running setup.py (path:/tmp/pip-57in0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:01:25 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)) 12:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:01:25 INFO - Running setup.py (path:/tmp/pip-u3GBe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:01:25 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)) 12:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:01:25 INFO - Running setup.py (path:/tmp/pip-l_q3AR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:01:25 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)) 12:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:01:25 INFO - Running setup.py (path:/tmp/pip-Le9MkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:01:25 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)) 12:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:01:25 INFO - Running setup.py (path:/tmp/pip-dZC4RI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:01:25 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)) 12:01:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:01:25 INFO - Running setup.py (path:/tmp/pip-sLnl0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:01:26 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)) 12:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:26 INFO - Running setup.py (path:/tmp/pip-9mo91V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:26 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)) 12:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:01:26 INFO - Running setup.py (path:/tmp/pip-sZbAO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:01:26 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)) 12:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:01:26 INFO - Running setup.py (path:/tmp/pip-HLdreE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:01:26 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)) 12:01:26 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)) 12:01:26 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)) 12:01:26 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)) 12:01:26 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)) 12:01:26 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)) 12:01:26 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)) 12:01:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:01:26 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 12:01:26 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 12:01:26 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 12:01:26 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 12:01:27 INFO - Downloading blessings-1.6.tar.gz 12:01:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:01:27 INFO - Installing collected packages: blessings 12:01:27 INFO - Running setup.py install for blessings 12:01:27 INFO - Successfully installed blessings 12:01:27 INFO - Cleaning up... 12:01:27 INFO - Return code: 0 12:01:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:01:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:01:27 INFO - Reading from file tmpfile_stdout 12:01:27 INFO - Current package versions: 12:01:27 INFO - argparse == 1.2.1 12:01:27 INFO - blessings == 1.6 12:01:27 INFO - blobuploader == 1.2.4 12:01:27 INFO - docopt == 0.6.1 12:01:27 INFO - functools32 == 3.2.3-2 12:01:27 INFO - jsonschema == 2.5.1 12:01:27 INFO - manifestparser == 1.1 12:01:27 INFO - mozInstall == 1.13 12:01:27 INFO - mozcrash == 1.0 12:01:27 INFO - mozdebug == 0.1 12:01:27 INFO - mozdevice == 0.51 12:01:27 INFO - mozfile == 1.2 12:01:27 INFO - mozhttpd == 0.7 12:01:27 INFO - mozinfo == 0.10 12:01:27 INFO - mozleak == 0.1 12:01:27 INFO - mozlog == 3.5 12:01:27 INFO - moznetwork == 0.27 12:01:27 INFO - mozprocess == 0.25 12:01:27 INFO - mozprofile == 0.28 12:01:27 INFO - mozrunner == 6.13 12:01:27 INFO - mozscreenshot == 0.1 12:01:27 INFO - mozsystemmonitor == 0.3 12:01:27 INFO - moztest == 0.8 12:01:27 INFO - mozversion == 1.4 12:01:27 INFO - psutil == 3.1.1 12:01:27 INFO - requests == 1.2.3 12:01:27 INFO - wsgiref == 0.1.2 12:01:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:27 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 0x276b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2933b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad89c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad5d60>, '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 0x2ad6a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ad6ef0>, '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': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', '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 12:01:27 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 12:01:27 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 12:01:27 INFO - Using env: {'DISPLAY': ':0', 12:01:27 INFO - 'HOME': '/home/cltbld', 12:01:27 INFO - 'LANG': 'en_US.UTF-8', 12:01:27 INFO - 'LANGUAGE': 'en_US:en', 12:01:27 INFO - 'LOGNAME': 'cltbld', 12:01:27 INFO - 'MAIL': '/var/mail/cltbld', 12:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:27 INFO - 'MOZ_NO_REMOTE': '1', 12:01:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:27 INFO - 'NO_EM_RESTART': '1', 12:01:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:27 INFO - 'PWD': '/builds/slave/test', 12:01:27 INFO - 'SHELL': '/bin/bash', 12:01:27 INFO - 'SHLVL': '1', 12:01:27 INFO - 'TERM': 'linux', 12:01:27 INFO - 'TMOUT': '86400', 12:01:27 INFO - 'USER': 'cltbld', 12:01:27 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', 12:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:27 INFO - '_': '/tools/buildbot/bin/python'} 12:01:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:28 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)) 12:01:28 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)) 12:01:28 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)) 12:01:28 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)) 12:01:28 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)) 12:01:28 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)) 12:01:28 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)) 12:01:28 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)) 12:01:28 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)) 12:01:28 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:01:28 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 12:01:28 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 12:01:28 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 12:01:28 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 12:01:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:01:29 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)) 12:01:29 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)) 12:01:29 INFO - Installing collected packages: simplejson 12:01:29 INFO - Running setup.py install for simplejson 12:01:29 INFO - building 'simplejson._speedups' extension 12:01:29 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 12:01:30 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 12:01:30 INFO - Successfully installed simplejson 12:01:30 INFO - Cleaning up... 12:01:30 INFO - Return code: 0 12:01:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:30 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 0x276b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2933b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad89c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad5d60>, '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 0x2ad6a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ad6ef0>, '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': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', '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 12:01:30 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 12:01:30 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 12:01:30 INFO - Using env: {'DISPLAY': ':0', 12:01:30 INFO - 'HOME': '/home/cltbld', 12:01:30 INFO - 'LANG': 'en_US.UTF-8', 12:01:30 INFO - 'LANGUAGE': 'en_US:en', 12:01:30 INFO - 'LOGNAME': 'cltbld', 12:01:30 INFO - 'MAIL': '/var/mail/cltbld', 12:01:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:30 INFO - 'MOZ_NO_REMOTE': '1', 12:01:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:30 INFO - 'NO_EM_RESTART': '1', 12:01:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:30 INFO - 'PWD': '/builds/slave/test', 12:01:30 INFO - 'SHELL': '/bin/bash', 12:01:30 INFO - 'SHLVL': '1', 12:01:30 INFO - 'TERM': 'linux', 12:01:30 INFO - 'TMOUT': '86400', 12:01:30 INFO - 'USER': 'cltbld', 12:01:30 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', 12:01:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:30 INFO - '_': '/tools/buildbot/bin/python'} 12:01:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:01:30 INFO - Cleaning up... 12:01:30 INFO - Return code: 0 12:01:30 INFO - Running post-action listener: _resource_record_post_action 12:01:30 INFO - Running post-action listener: _start_resource_monitoring 12:01:30 INFO - Starting resource monitoring. 12:01:30 INFO - [mozharness: 2017-09-27 19:01:30.537067Z] Finished create-virtualenv step (success) 12:01:30 INFO - [mozharness: 2017-09-27 19:01:30.537656Z] Running install step. 12:01:30 INFO - Running pre-action listener: _resource_record_pre_action 12:01:30 INFO - Running main action method: install 12:01:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:01:30 INFO - Reading from file tmpfile_stdout 12:01:30 INFO - Detecting whether we're running mozinstall >=1.0... 12:01:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:01:30 INFO - Reading from file tmpfile_stdout 12:01:30 INFO - Output received: 12:01:30 INFO - Usage: mozinstall [options] installer 12:01:30 INFO - Options: 12:01:30 INFO - -h, --help show this help message and exit 12:01:30 INFO - -d DEST, --destination=DEST 12:01:30 INFO - Directory to install application into. [default: 12:01:30 INFO - "/builds/slave/test"] 12:01:30 INFO - --app=APP Application being installed. [default: firefox] 12:01:30 INFO - mkdir: /builds/slave/test/build/application 12:01:30 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'] 12:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:01:48 INFO - Reading from file tmpfile_stdout 12:01:48 INFO - Output received: 12:01:48 INFO - /builds/slave/test/build/application/firefox/firefox 12:01:48 INFO - Running post-action listener: _resource_record_post_action 12:01:48 INFO - [mozharness: 2017-09-27 19:01:48.535398Z] Finished install step (success) 12:01:48 INFO - [mozharness: 2017-09-27 19:01:48.535740Z] Running setup-mitmproxy step. 12:01:48 INFO - Running pre-action listener: _resource_record_pre_action 12:01:48 INFO - Running main action method: setup_mitmproxy 12:01:48 INFO - Skipping: mitmproxy is not required 12:01:48 INFO - Running post-action listener: _resource_record_post_action 12:01:48 INFO - [mozharness: 2017-09-27 19:01:48.537477Z] Finished setup-mitmproxy step (success) 12:01:48 INFO - [mozharness: 2017-09-27 19:01:48.537794Z] Running run-tests step. 12:01:48 INFO - Running pre-action listener: _resource_record_pre_action 12:01:48 INFO - Running pre-action listener: _set_gcov_prefix 12:01:48 INFO - Running main action method: run_tests 12:01:48 WARNING - Try message not found. 12:01:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:01:48 INFO - Python 2.7.3 12:01:48 INFO - Return code: 0 12:01:48 INFO - grabbing minidump binary from tooltool 12:01: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')]} 12:01:48 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 0x2ad5d60>, '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 0x2ad6a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ad6ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:01:48 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 12:01:48 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 12:01:48 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 12:01:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:01:48 INFO - Return code: 0 12:01:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:01:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:01:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:01:48 INFO - ENV: RUST_BACKTRACE is now full 12:01:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:01:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:01:48 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/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 12:01:48 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/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-007 --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 12:01:48 INFO - Using env: {'DISPLAY': ':0', 12:01:48 INFO - 'HOME': '/home/cltbld', 12:01:48 INFO - 'LANG': 'en_US.UTF-8', 12:01:48 INFO - 'LANGUAGE': 'en_US:en', 12:01:48 INFO - 'LOGNAME': 'cltbld', 12:01:48 INFO - 'MAIL': '/var/mail/cltbld', 12:01:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:01:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:48 INFO - 'MOZ_NO_REMOTE': '1', 12:01:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:01:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:48 INFO - 'NO_EM_RESTART': '1', 12:01:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:48 INFO - 'PWD': '/builds/slave/test', 12:01:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:01:48 INFO - 'RUST_BACKTRACE': 'full', 12:01:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:01:48 INFO - 'SHELL': '/bin/bash', 12:01:48 INFO - 'SHLVL': '1', 12:01:48 INFO - 'TERM': 'linux', 12:01:48 INFO - 'TMOUT': '86400', 12:01:48 INFO - 'USER': 'cltbld', 12:01:48 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', 12:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:48 INFO - '_': '/tools/buildbot/bin/python'} 12:01:48 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/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 12:01:48 INFO - mozversion application_buildid: 20170927173830 12:01:48 INFO - mozversion application_changeset: 756e10aa8bbd416cbc49b7739f78fb81d5525477 12:01:48 INFO - mozversion application_display_name: Nightly 12:01:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:01:48 INFO - mozversion application_name: Firefox 12:01:48 INFO - mozversion application_remotingname: firefox 12:01:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:01:48 INFO - mozversion application_vendor: Mozilla 12:01:48 INFO - mozversion application_version: 58.0a1 12:01:48 INFO - mozversion platform_buildid: 20170927173830 12:01:48 INFO - mozversion platform_changeset: 756e10aa8bbd416cbc49b7739f78fb81d5525477 12:01:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:01:48 INFO - mozversion platform_version: 58.0a1 12:01:48 INFO - using testdate: 1506538908 12:01:48 INFO - actual date: 1506538908 12:01:48 INFO - starting webserver on 'localhost:48689' 12:01:48 INFO - SUITE-START | Running 2 tests 12:01:48 INFO - TEST-START | dromaeo_css 12:01:48 INFO - Initialising browser for dromaeo_css test... 12:01:48 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48689/getInfo.html -profile /tmp/tmpI9JKyW/profile 12:01:48 INFO - TEST-INFO | started process 14170 (/builds/slave/test/build/application/firefox/firefox http://localhost:48689/getInfo.html) 12:01:53 INFO - TEST-INFO | 14170: exit 0 12:01:53 INFO - Browser initialized. 12:01:53 INFO - Running cycle 1/1 for dromaeo_css test... 12:01:53 INFO - Using env: {'DISPLAY': ':0', 12:01:53 INFO - 'HOME': '/home/cltbld', 12:01:53 INFO - 'JSGC_DISABLE_POISONING': '1', 12:01:54 INFO - 'LANG': 'en_US.UTF-8', 12:01:54 INFO - 'LANGUAGE': 'en_US:en', 12:01:54 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:01:54 INFO - 'LOGNAME': 'cltbld', 12:01:54 INFO - 'MAIL': '/var/mail/cltbld', 12:01:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:01:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:01:54 INFO - 'MOZ_CRASHREPORTER': '1', 12:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:54 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:01:54 INFO - 'MOZ_NO_REMOTE': '1', 12:01:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:01:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:01:54 INFO - 'NO_EM_RESTART': '1', 12:01:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:54 INFO - 'PWD': '/builds/slave/test', 12:01:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:01:54 INFO - 'RUST_BACKTRACE': 'full', 12:01:54 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:01:54 INFO - 'SHELL': '/bin/bash', 12:01:54 INFO - 'SHLVL': '1', 12:01:54 INFO - 'TERM': 'linux', 12:01:54 INFO - 'TMOUT': '86400', 12:01:54 INFO - 'USER': 'cltbld', 12:01:54 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', 12:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:54 INFO - '_': '/tools/buildbot/bin/python'} 12:01:54 INFO - TEST-INFO | started process 14408 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpI9JKyW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:01:54 INFO - PID 14408 | 12:01:54 INFO - PID 14408 | (/builds/slave/test/build/application/firefox/firefox:14457): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:01:54 INFO - PID 14408 | 12:02:05 INFO - PID 14408 | RSS: Main: 144928768 12:02:05 INFO - PID 14408 | 12:05:01 INFO - PID 14408 | Cycle 1(1): loaded http://localhost:48689/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48689/tests/dromaeo/cssquery-ext.html) 12:05:02 INFO - PID 14408 | RSS: Main: 146247680 12:05:02 INFO - PID 14408 | 12:08:04 INFO - PID 14408 | Cycle 1(1): loaded http://localhost:48689/tests/dromaeo/cssquery-ext.html (next: http://localhost:48689/tests/dromaeo/cssquery-jquery.html) 12:08:05 INFO - PID 14408 | RSS: Main: 146350080 12:08:05 INFO - PID 14408 | 12:11:06 INFO - PID 14408 | Cycle 1(1): loaded http://localhost:48689/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48689/tests/dromaeo/cssquery-mootools.html) 12:11:07 INFO - PID 14408 | RSS: Main: 147959808 12:11:07 INFO - PID 14408 | 12:14:08 INFO - PID 14408 | Cycle 1(1): loaded http://localhost:48689/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48689/tests/dromaeo/cssquery-prototype.html) 12:14:09 INFO - PID 14408 | RSS: Main: 145231872 12:14:09 INFO - PID 14408 | 12:17:12 INFO - PID 14408 | Cycle 1(1): loaded http://localhost:48689/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48689/tests/dromaeo/cssquery-yui.html) 12:17:13 INFO - PID 14408 | RSS: Main: 147767296 12:17:13 INFO - PID 14408 | 12:20:14 INFO - PID 14408 | Cycle 1(1): loaded http://localhost:48689/tests/dromaeo/cssquery-yui.html (next: http://localhost:48689/tests/dromaeo/cssquery-dojo.html) 12:20:14 INFO - PID 14408 | RSS: Main: 150343680 12:20:14 INFO - PID 14408 | 12:20:14 INFO - PID 14408 | __start_tp_report 12:20:14 INFO - PID 14408 | _x_x_mozilla_page_load 12:20:14 INFO - PID 14408 | _x_x_mozilla_page_load_details 12:20:14 INFO - PID 14408 | |i|pagename|runs| 12:20:14 INFO - PID 14408 | |0;dojo.html;3840.865569705061;3860.7297489175767;3872.8838134855955;3895.590962948892;3928.5678575356706;6219.9689001554925;6280.183576135096;6309.716062777175;6322.083297921804;6412.134361861159;6228.813135605917;6279.780207692732;6302.842428939267;6451.29035806061;6462.159919210508;5605.803796867111;5699.088145896658;5731.713414329288;5755.136729490568;5768.942310576904;205.6578712130574;5416.350037995446;5615.859603509924;5708.857278568027;5742.339630942432;5626.156076588503;5630.943690563104;5690.317161940573;5728.856778580527;5747.683877386742;5634.183043458716;5635.5773317001385;5716.28546431696;5722.227499287626;5762.769489220426;5626.409227031144;5629.774809007635;5707.1724599933195;5714.428557144252;5722.427757224286;6225.3774622537385;6292.2134733158355;6319.304876463585;6367.968160159169;6395.616263024233;6732.899006514906;6796.898046529306;6814.965925170342;6817;6828.146481689789;6871.278515755874;6915.89626155608;6930.13373328334;6936;6958.617276049815;6232.719527621222;6301.8109456716375;6380.3619638035825;6413.3266007069515;6418.775342862978;7082.822929426702;7152.141742990874;7174.139103307636;7180.030695855992;7186.496945213889;6761.22245941723;6798.286179951133;6842.589444633338;6855.897161542581;6869.450443964471;6778.966105169541;6803.251642319341;6867.107276054081;6895.4138898194215;6913.2049814270995;6883.827904302332;6901.482388820858;6912.516123871382;6921.515493915377;6937.9653101735175;6853.725850965906;6857.142857142857;6943.305669433016;6947.548409353376;6949.652517374111;4969.403671559436;4973.602111831117;5029.497050295015;5030.673006254582;5036.269740887551;4494.235979883428;4519.615832654261;4532.4334458192725;4546.74992875395;4577.771111444415;6338.619682819046;6389.2652344980925;6391.169148010822;6391.296957334689;6393.264774550986;6834.965825170942;6841.452683785285;6846.315368463114;6859.039734437183;6942.826429339307;6214.906776398358;6297.748090076346;6304.936950630435;6370.872582548416;6384.361563843579;5464.3442786865835;5540.252065971123;5549.223108764776;5576.052071147915;5585.218069470277;7072.8939065914055;7085.92914070863;7162.997180394749;7170.928290717026;7184.856302873914;6726.529142959946;6728.058071869844;6763.289854565298;6855.451563874978;6860.588364698134;7130.465215108991;7138.643067846483;7143.535670181422;7143.9285607143265;7154.2130365658695;6761.3238676132;6788.932110679028;6803.727850885909;6827.317268273133;6870.690818913061;6687.063811066552;6811.659417029228;6847.34950179752;6858.897116543156;6875.209350924508;6569.343065693392;6599.307072757291;6699.464042876461;6712.630805305755;6712.899306510504;6594.538382313192;6619.536632435684;6662.300458451987;6679.632620205935;6700.296468870698;6407.006913928349;6438.227412710505;6500.414962653384;6524.054012168304;6535.379138981789;6624.470042396693;6688.765893193715;6719.361660642231;6733.764318248838;6741.865162696817;6425.421712045938;6450.8064758057335;6542.280349161683;6554.377334153248;6555.672216389066 12:20:14 INFO - PID 14408 | |1;ext.html;945.2626950978236;956.9234461243101;975.878015248094;1009.9848502272465;1011.1809434358175;43522;44066.67799966002;44085.338719919186;44106.55893441065;44155.116897662025;40132.59867401332;44581.7770911145;44875.77562112193;44884.775576122156;45410.77294613522;8334.458260213081;8482.74551763445;8487.40588158829;8490.617922193485;8496.957515212445;8322.16778322169;8444.915550844506;8445;8451.661933522651;8529.786755331104;8502.78743031426;8546.52994085325;8562.529060901648;8584.828303433927;8588.269997050258;8486.363522735788;8525.488470691747;8534;8553.743387698316;8560.957195214047;8276.586170691442;8291.129431409676;8304.584770761438;8306.958465207696;8364;38347.61652383468;38636.420453693216;38813.223735525135;38850.417243741365;39037;3767.3407153747985;3814.6376094271277;3815.1415931415486;3815.9427608585893;3816.790076545789;33739.32521349559;33746.49380259298;33766;33836.15409614755;33849.83075084633;5330.333708286465;5407.053765591006;5460.426655201226;5518.889622207534;5689.317281926154;227922;228354;228511;228922;229339;52894.735526322125;52932;52989.7350513245;53007.73496132572;53031.469685302654;56210;56218.437815622136;56228.718856406274;56302.15546766802;56444;16492.17539123039;16564.75152872708;16587.170641467877;16589.751153732705;16599.253033613517;13348;13465.124766890121;13476.326183690777;13513.121647093105;13528.864711352957;14035.78946315806;14332;14383.208923509308;14441.422343106367;14567.490137845125;5420.891582168413;5455.5908306877145;5491.697956612425;5495.477929596762;5495.697736624524;1117.284937639911;1117.6758739965483;1123.8707548631849;1129.3957732613012;1130.1636788776411;23712.28863134109;24019.51960960771;24024.87987560051;24037.75962240355;24047.398815029414;1221.987780122205;1229.655696405008;1239.7954337534206;1240.7332423528985;1241.069198101424;12963.870361296455;13073.411696473682;13166.934165329303;13286.3356832158;13297.202167869958;284850;287050;287818;287820;288149;57525.42474575285;63838;64888;64996.67501662461;65073.67463162654;1514.6062023873872;1536.109056747084;1551.6663917257845;1553.4718195813477;1555.898866573669;30336.393272134876;30533.23666908345;30625.081247562608;30726.539101913044;30752.538711919788;39834;39902;40186.598134018284;40204;40207.59792402039;244757;245288;245930;246069;246820;35507;35746;35759;35776.64223357733;35820.462693060144;43719;44561.33158002698;45059.32411013772;45077.54922450733;45091.32363014492;32902;33043.66956330406;33151.50272745959;33328;33630;32780.1804954878;33145.66854331522;33587.66412335845;33805.492917606745;33813.830930845186;38554.421683674205;38883.80558097304;39158.6084139155;39159;39230;32583;32992.83503582467;33596.66403335935;33625.83187084049;33926;23779.64330535078;23941;24429.25896985435;24530.50938981246;24758.50482990294 12:20:14 INFO - PID 14408 | |2;jquery.html;624.7313655128293;627.3444250757957;627.5356236385073;637.9808605741823;648.2512697834002;23276;23508.412289692766;23706.76293237067;23805.76194238057;25174;25984;26929.32676683084;26943.595846062275;26988.5951710724;27024.45951080977;6613.570117942342;6813.863722725542;6827.931720682805;6828.0440738296675;6833.4191593714595;6414.166158399425;6460.709268082947;6718.42893354063;6722.394984451398;6732.9663351683175;6677.799666010027;6680.7327706891665;6687.06381106643;6689.732410703566;6690.799276021728;6474.902876456857;6666.799996000103;6681.766138185165;6687.899681504757;6791.422729068036;6296.118543403927;6315.305316415237;6338.58799178052;6369;6381.1066450696935;31155.68844311573;31681;31743.206419839687;31782.205444863834;31884;2304.1129165271323;2320.8955596998007;2326.430024643973;2333.2300340887637;2337.5091230841545;18883.150258238413;19280.614387712307;20568.177272909215;20676.896615516976;20744.06651700677;2489.726130125685;2539.6444497770326;2583.225032490245;2597.844129352226;2598.337423956898;130836.34581827125;135694;148415;154620;155009.22495387564;16144.919275403783;17205.655886882192;17229.65540689179;17268.568285792706;17474.737878931825;26859.731402686113;26867.059652912445;26891.596626050625;26898.5965210522;26929.172436894234;10147.036031576989;10202.744931376776;10250.948745256375;10281.691549253534;10288.691339259834;8989.146031127033;9039.412438191497;9076.410033347944;9079.591418386188;9102.908970910206;10083.798324033625;10279.897201028043;10285.382877027403;10299.53652085658;10305.072543471124;2937.221636266391;2952.217662319487;2973.7807498925335;2981.7167369099902;3033.393321335742;1034.4207243943415;1066.2003497376968;1069.4438891776233;1070.341739829999;1072.5495291977388;20730.585388292453;20757.169713211304;20817.791822081886;21096.578068438546;21208.469788255108;1123.269874581512;1141.5547936304847;1143.1255089856268;1157.4444266752007;1158.0619698044611;11480.425978701032;11551.191416600925;11571.247868888495;11765.588204412976;11767;600247;610628;614908;626495;629350;132171.33914330558;134500;135933;136151;136703;1231.956881509143;1272.179444258448;1326.0982531878221;1354.5056054540023;1359.9116057456233;22792.31623051311;22834.31497055091;23097.307080787603;23194.3041708749;23201.76798231996;63840.68079659572;65393.346066538725;65664;66194.33805661881;67993;83248;84855.15144848473;85035;85341.57329213314;85427.57286213529;15875.603109922344;16241.269142888363;16427.17864106766;16473.258703358617;16475.258613362188;24325.270241892773;24472.755272447048;24617.63073553934;24770.628440573768;24831.751682482944;19896.204151834343;20209.79790202138;20344.694829577864;20400.89799551052;20543.383698488473;17454.738178927073;17657.470275891745;17719.379821706178;17856.375026873997;18507.90746046261;18943.43169704911;19996.20015199376;20083.19667213295;20182.78903265809;20304.79695203029;15872.20638968024;16043.679126417639;16088.839111609203;16156.11141387188;16209.756853646968;19861.702074469184;19872.304469343504;20099.19603215855;20114.09486573137;20183.495412614215 12:20:14 INFO - PID 14408 | |3;mootools.html;1405.550223928343;1451.216343174685;1452.8183977002875;1466.2961778346387;1473.3075454536365;16910.83089169108;17631.294748210057;17643.73534396985;17685.38101166458;17694.55763605909;17034.488965331027;17057;17074.573135671613;17085.40201092962;17293.567660808487;2780.638516992793;2826.830390176596;2873.568964655308;2918.1391489510647;2920.956185657217;2858.7427131558156;2907.7964542482036;2910.8690108945148;2920.7809414293906;2929.487339715552;2780.79144064195;2863.7136286371406;2882.7549658278967;2883.8269703817837;2886.8556572171415;2863.1553691660906;2883.668378136499;2885.220990332609;2885.2209903326193;2886.480433521965;2730.331068888115;2744.684361298436;2810.4519618674162;2815.619891314667;2818.746312831855;16725.330986760517;16730.49808505747;16771;16825.915870420693;16834.915825420918;8508.957455212747;8809.295256379539;8813.38306318558;8816.338774591866;8817.603207855598;5818.738156782955;5943.3165186003735;5994.670293133876;6051.546134039921;6080.908786368208;1993.9601207975904;1996.710476980844;2008.4777957730948;2010.3064442767222;2079.3450063230034;31122.688773112204;31233.843830780927;31296.37407251865;31580.210494737585;31672.208194795083;7376;7478.326950574475;7480.5885676287235;7540.208278130827;7598.506097103671;19891.10490027923;20038.699419508717;20049.799502004793;20094;20123.0944607493;8352.70765523216;8481.957590212009;8484.40609157353;8491.363147763943;8500.319974402033;7534.133574638877;7736.071671399468;7773.961130194313;7838.804029899298;7842.058952925686;5562.499375056264;5752.07966725322;5760.971195144081;5809.651420914759;5830.76676932918;1625.1061915946182;1676.2624445244053;1679.1688114383458;1683.0827199176479;1694.3984885365765;911.9270458363314;934.1172591900589;937.7561833923093;942.9434233945985;945.5366870233536;8122.796930076677;8148.592570371457;8167.224113709188;8204.753857384288;8259.132791056973;780.867252567065;811.4117264982958;815.6003558256411;823.7528741377563;824.3199360527589;4871.391076115486;4923;4982.8754281143265;5880.235569376039;6010.278766548042;24692.629610555858;25643.48713025729;25655.358616034377;25903.611445828326;25926.740732592432;24686.753132468446;24765.62851557228;25081;25462.363440913756;25800.7099645017;12729.108962372546;12772.10595258323;12813.615591532269;13031.478740850449;13049.151805132637;9582.90417095848;9706.272029597667;9777.315587909063;9833.311668183158;9926.553305101432;20925.686114708595;21020;21359.67960480563;21686.6746998792;21859.562808744053;22400;22496.21263255778;22555.323340299816;22794.544109117393;22823.201187959006;12080.09399295074;12302.507899684271;12326.32205228721;12329;12331.383430828602;15554.84445155534;15990.600234993753;16022.439214627431;16199.109049002418;16225.67548649044;11997.980171685505;12047.75904481923;12183.390830458618;12467.31429771335;12656;12094.39528023613;12133.514659413524;12163.574274900337;12224.572139975058;12447.875521244672;10878.891211087788;10953.178511611502;10957.780844383227;11080.833787493355;11111.666650000512;8033.919660803317;8078.272955433804;8105.311048560939;8194.877076843972;8201.466904651179;8270.503769773833;8344.916550834414;8353.54055526952;8358.58207089631;8413.368997325102 12:20:14 INFO - PID 14408 | |4;prototype.html;545.2230571435706;565.9886802263954;581.1863391252247;583.5390041866925;583.7256489449957;6266.717997690103;6290.874182516352;6301.873962520758;6324.525660575458;6379.202599675041;5984.730687119079;6026.608270462428;6203.286622038467;6214.53390995676;6267.780627678032;4863.392075990501;5088.623783546166;5095.949040509594;5098.643094983353;5102.719350435743;4952.405711314647;4957.553820156185;4958.322505846839;4963.379577552788;4982.626303027269;4954.108260513106;4957.925631115536;4966.652334336598;4982;4996.150654388763;4924.581410580105;4989.301497790312;5005.49945005502;5033.899322013539;5041.899162016776;4564.360989461478;4567.2920697291975;4574.725516469022;4591.494935557086;4595.2188128018315;5958.612690175168;6146.78486252979;6179.66011869347;6212.596181248205;6301.180846489973;5808.8838223235725;5847.912281315784;5855.970720146414;5886.352501224862;5911.586188966774;6611.570247933869;6623.701933413008;6644.900326495106;6668.3659954318255;6678.998150277449;4868.756562171877;5045.949540504585;5052.621053421009;5060.49395060491;5071.264666623356;6706.631135287557;6734.696938637724;6844.349786770299;6999.895001574981;7000.264972177899;5182.766775495036;5194.92207616886;5199.7140157291715;5245.632805703564;5250.107481728115;5232.764525596357;5301.734913254322;5305.657435815411;5334.093204155303;5344.786208551614;5852.941470585325;5860.677662728505;5903.321118071477;5908.20239267702;5913.911291330547;6648.769977554071;6681.432078273401;6683.732650693918;6695.4308883745425;6704.457974665854;1810.7415346334171;1815.5551889786823;1819.1904602451782;1830.1764206107305;1831.5329590954489;1236.3756303066893;1288.664947113757;1301.9739605207842;1306.79744639581;1307.9149855259377;6179;6197.1324014637985;6246.187995560639;6276;6284;6524.445422139172;6696.363845434676;6717.059611654372;6720.596764194164;6793;6154.676744499954;6216.502679785606;6218.595791273553;6273.278572964167;6280.591761535486;3555.6977656899453;3570.785752854837;3577.4276115821863;3600.9279814403567;3605.369060414417;6972.616506092214;7097.574145551283;7111.502194846415;7126.714931402686;7131.89302160468;6898.206466318793;6902;6916.23921368649;6978.731912128578;6982.3242421818395;7002.794119852332;7016.824579385556;7063.788086357417;7071.858562828817;7077.9646101770195;6715.764948226789;6749.076513193912;6761.93238067613;6766.695498702472;6802.115724955724;6632.602043877383;6643.169603799525;6660.866782664417;6662.800115996528;6685.431738302299;7009.369156775914;7050;7055.717771289091;7066.752663656747;7080.645967701697;5154.716490593053;5162.741862906914;5186.196139598368;5186.740662966761;5189.974050129877;6594.878201124297;6743.932560674527;6761.932380676327;6825.829354266183;6865.5537390069485;6231.626102433868;6562.606243625398;6600.405963463311;6614.206295244601;6653.700583473658;6475.643839588868;6518.57629254097;6568.967155164193;6569.211694596679;6599.208095028627;6335.303116657072;6350.650714210763;6436.420722135031;6467.805965821033;6488.12410324619;6521.771737989147;6629.204495460575;6634.369734875372;6657.367550082734;6673.833154171185;6335.58818676803;6354.364563543609;6359.438714319996;6430.356964303719;6469.385408386196 12:20:14 INFO - PID 14408 | |5;yui.html;987.925905557083;990.064389152251;997.6607953295878;1013.1287093099928;1023.6673081248593;18183.4544963651;20546.794532054675;21341.679874801892;21344.35966920995;21355.57288854222;19503.707444388347;19618.803811961876;19621.803781962175;19645.31241406551;19781.406557803253;1041.666666666667;1043.911267542258;1049.2026060194269;1049.275999560301;1049.3494033699117;1067.5676351077786;1071.6892101290616;1072.1101485766794;1073.0557109743415;1083.2850318789601;1068.3322923173016;1106.4191299567751;1121.2823792772633;1135.5117299561186;1140.7262257058287;1104.6962085426492;1105.4362275239646;1107.125370956943;1107.24153954541;1108.9944550277278;792.8414317136596;812.7358608452276;815.0423252678139;816.5713000674634;822.2928281677753;19361.612767744708;19373.709394359095;19475.513112172168;19504.219831206734;19510.219591216333;22504.32487025392;22549.436264093365;23263.651045234335;23535;23704;18028.391245949184;18134.637307253783;18496.98266595337;18560.071996400125;18618.627627447375;3986.920261594752;3997.8200980955926;4008.6793056555407;4034.536028356718;4036.535798383194;130840;162232;162509;164709;167584;14159.78760318596;14303.07030043044;14457;14529.491467798576;14578.198199098939;24271;24507.38731531726;25130.37174070663;25180.496390072098;25239.495210095694;9715.805683886283;9741.123298903132;9772.315937884421;9805.509724513746;9851.261155413373;4660.86017419478;4748.121597504472;4774.618030557547;4799.928001079987;4804.783784729696;6087.726052327567;6104.511639068864;6153.446189842935;6191.349908259658;6195.597286176384;1198.101423932051;1199.4422593494066;1201.4233168079197;1215.914885957992;1216.750566133958;700.8493173967621;727.858067676798;730.9195988441268;743.4164181117796;754.7320701151123;12718.618441446772;13199.47629850995;13593.116447430886;13606.115602485806;13700.45198192081;728.6320408193828;736.8194792275926;740.6407892172292;746.4289818289013;763.1719584251039;5926.496247818984;5961.403859614091;5996.760129594854;6015.639061656315;6023.397660234029;146034;150292.2485387566;150986;151604;153496;127204.36397817952;132481.67518324693;133438;133465;137860;112248;112484;114359;116105;116620.41689791497;13530.769461346861;13682.384292706653;13862.584122476343;13869.583912482642;13874.236916969663;52684.73657631687;53283.46716532785;53963;54353.184702228704;54679.72660136674;94004;94795;96074.51962740142;96374;97050;20512.487187820425;20588.897055514626;20608.690869636666;20642.27752028672;20654.586908262052;21719.239826605994;22450;22822.65766013478;23035.424114396603;23403.76596234016;21715.565688686453;21787.78212217921;21814.89092554527;21964.121435143054;22120.778792211873;18292.359767408077;18468.44594662162;19027.33404330842;19596.60806783828;19732.112054957273;18693.158807853408;18729.90635046816;18793.53016174552;18948.242070317032;19937;12431;12514.749705005666;12769.87230127724;13245.139065960682;13471.057026008086;12274.509019639114;12746.10777245584;12846.935765321114;12920.160189587647;14762.409503619734 12:20:14 INFO - PID 14408 | __end_tp_report 12:20:14 INFO - PID 14408 | __start_cc_report 12:20:14 INFO - PID 14408 | _x_x_mozilla_cycle_collect,285 12:20:14 INFO - PID 14408 | __end_cc_report 12:20:14 INFO - PID 14408 | __startTimestamp1506540014938__endTimestamp 12:20:14 INFO - PID 14408 | 12:20:14 INFO - PID 14408 | ------- Summary: start ------- 12:20:14 INFO - PID 14408 | Number of tests: 6 12:20:14 INFO - PID 14408 | 12:20:14 INFO - PID 14408 | [#0] dojo.html Cycles:165 Average:6279.42 Median:6542.28 stddev:894.60 (13.7%) stddev-sans-first:876.65 12:20:14 INFO - PID 14408 | Values: 3840.9 3860.7 3872.9 3895.6 3928.6 6220.0 6280.2 6309.7 6322.1 6412.1 6228.8 6279.8 6302.8 6451.3 6462.2 5605.8 5699.1 5731.7 5755.1 5768.9 205.7 5416.4 5615.9 5708.9 5742.3 5626.2 5630.9 5690.3 5728.9 5747.7 5634.2 5635.6 5716.3 5722.2 5762.8 5626.4 5629.8 5707.2 5714.4 5722.4 6225.4 6292.2 6319.3 6368.0 6395.6 6732.9 6796.9 6815.0 6817.0 6828.1 6871.3 6915.9 6930.1 6936.0 6958.6 6232.7 6301.8 6380.4 6413.3 6418.8 7082.8 7152.1 7174.1 7180.0 7186.5 6761.2 6798.3 6842.6 6855.9 6869.5 6779.0 6803.3 6867.1 6895.4 6913.2 6883.8 6901.5 6912.5 6921.5 6938.0 6853.7 6857.1 6943.3 6947.5 6949.7 4969.4 4973.6 5029.5 5030.7 5036.3 4494.2 4519.6 4532.4 4546.7 4577.8 6338.6 6389.3 6391.2 6391.3 6393.3 6835.0 6841.5 6846.3 6859.0 6942.8 6214.9 6297.7 6304.9 6370.9 6384.4 5464.3 5540.3 5549.2 5576.1 5585.2 7072.9 7085.9 7163.0 7170.9 7184.9 6726.5 6728.1 6763.3 6855.5 6860.6 7130.5 7138.6 7143.5 7143.9 7154.2 6761.3 6788.9 6803.7 6827.3 6870.7 6687.1 6811.7 6847.3 6858.9 6875.2 6569.3 6599.3 6699.5 6712.6 6712.9 6594.5 6619.5 6662.3 6679.6 6700.3 6407.0 6438.2 6500.4 6524.1 6535.4 6624.5 6688.8 6719.4 6733.8 6741.9 6425.4 6450.8 6542.3 6554.4 6555.7 12:20:14 INFO - PID 14408 | 12:20:14 INFO - PID 14408 | [#1] ext.html Cycles:180 Average:43120.07 Median:35798.55 stddev:66388.69 (185.5%) stddev-sans-first:66499.40 12:20:14 INFO - PID 14408 | Values: 945.3 956.9 975.9 1010.0 1011.2 43522.0 44066.7 44085.3 44106.6 44155.1 40132.6 44581.8 44875.8 44884.8 45410.8 8334.5 8482.7 8487.4 8490.6 8497.0 8322.2 8444.9 8445.0 8451.7 8529.8 8502.8 8546.5 8562.5 8584.8 8588.3 8486.4 8525.5 8534.0 8553.7 8561.0 8276.6 8291.1 8304.6 8307.0 8364.0 38347.6 38636.4 38813.2 38850.4 39037.0 3767.3 3814.6 3815.1 3815.9 3816.8 33739.3 33746.5 33766.0 33836.2 33849.8 5330.3 5407.1 5460.4 5518.9 5689.3 227922.0 228354.0 228511.0 228922.0 229339.0 52894.7 52932.0 52989.7 53007.7 53031.5 56210.0 56218.4 56228.7 56302.2 56444.0 16492.2 16564.8 16587.2 16589.8 16599.3 13348.0 13465.1 13476.3 13513.1 13528.9 14035.8 14332.0 14383.2 14441.4 14567.5 5420.9 5455.6 5491.7 5495.5 5495.7 1117.3 1117.7 1123.9 1129.4 1130.2 23712.3 24019.5 24024.9 24037.8 24047.4 1222.0 1229.7 1239.8 1240.7 1241.1 12963.9 13073.4 13166.9 13286.3 13297.2 284850.0 287050.0 287818.0 287820.0 288149.0 57525.4 63838.0 64888.0 64996.7 65073.7 1514.6 1536.1 1551.7 1553.5 1555.9 30336.4 30533.2 30625.1 30726.5 30752.5 39834.0 39902.0 40186.6 40204.0 40207.6 244757.0 245288.0 245930.0 246069.0 246820.0 35507.0 35746.0 35759.0 35776.6 35820.5 43719.0 44561.3 45059.3 45077.5 45091.3 32902.0 33043.7 33151.5 33328.0 33630.0 32780.2 33145.7 33587.7 33805.5 33813.8 38554.4 38883.8 39158.6 39159.0 39230.0 32583.0 32992.8 33596.7 33625.8 33926.0 23779.6 23941.0 24429.3 24530.5 24758.5 12:20:14 INFO - PID 14408 | 12:20:14 INFO - PID 14408 | [#2] jquery.html Cycles:180 Average:40512.81 Median:12761.33 stddev:102994.23 (807.1%) stddev-sans-first:103239.61 12:20:14 INFO - PID 14408 | Values: 624.7 627.3 627.5 638.0 648.3 23276.0 23508.4 23706.8 23805.8 25174.0 25984.0 26929.3 26943.6 26988.6 27024.5 6613.6 6813.9 6827.9 6828.0 6833.4 6414.2 6460.7 6718.4 6722.4 6733.0 6677.8 6680.7 6687.1 6689.7 6690.8 6474.9 6666.8 6681.8 6687.9 6791.4 6296.1 6315.3 6338.6 6369.0 6381.1 31155.7 31681.0 31743.2 31782.2 31884.0 2304.1 2320.9 2326.4 2333.2 2337.5 18883.2 19280.6 20568.2 20676.9 20744.1 2489.7 2539.6 2583.2 2597.8 2598.3 130836.3 135694.0 148415.0 154620.0 155009.2 16144.9 17205.7 17229.7 17268.6 17474.7 26859.7 26867.1 26891.6 26898.6 26929.2 10147.0 10202.7 10250.9 10281.7 10288.7 8989.1 9039.4 9076.4 9079.6 9102.9 10083.8 10279.9 10285.4 10299.5 10305.1 2937.2 2952.2 2973.8 2981.7 3033.4 1034.4 1066.2 1069.4 1070.3 1072.5 20730.6 20757.2 20817.8 21096.6 21208.5 1123.3 1141.6 1143.1 1157.4 1158.1 11480.4 11551.2 11571.2 11765.6 11767.0 600247.0 610628.0 614908.0 626495.0 629350.0 132171.3 134500.0 135933.0 136151.0 136703.0 1232.0 1272.2 1326.1 1354.5 1359.9 22792.3 22834.3 23097.3 23194.3 23201.8 63840.7 65393.3 65664.0 66194.3 67993.0 83248.0 84855.2 85035.0 85341.6 85427.6 15875.6 16241.3 16427.2 16473.3 16475.3 24325.3 24472.8 24617.6 24770.6 24831.8 19896.2 20209.8 20344.7 20400.9 20543.4 17454.7 17657.5 17719.4 17856.4 18507.9 18943.4 19996.2 20083.2 20182.8 20304.8 15872.2 16043.7 16088.8 16156.1 16209.8 19861.7 19872.3 20099.2 20114.1 20183.5 12:20:14 INFO - PID 14408 | 12:20:14 INFO - PID 14408 | [#3] mootools.html Cycles:180 Average:10535.12 Median:2817.18 stddev:7895.01 (280.2%) stddev-sans-first:7887.36 12:20:14 INFO - PID 14408 | Values: 1405.6 1451.2 1452.8 1466.3 1473.3 16910.8 17631.3 17643.7 17685.4 17694.6 17034.5 17057.0 17074.6 17085.4 17293.6 2780.6 2826.8 2873.6 2918.1 2921.0 2858.7 2907.8 2910.9 2920.8 2929.5 2780.8 2863.7 2882.8 2883.8 2886.9 2863.2 2883.7 2885.2 2885.2 2886.5 2730.3 2744.7 2810.5 2815.6 2818.7 16725.3 16730.5 16771.0 16825.9 16834.9 8509.0 8809.3 8813.4 8816.3 8817.6 5818.7 5943.3 5994.7 6051.5 6080.9 1994.0 1996.7 2008.5 2010.3 2079.3 31122.7 31233.8 31296.4 31580.2 31672.2 7376.0 7478.3 7480.6 7540.2 7598.5 19891.1 20038.7 20049.8 20094.0 20123.1 8352.7 8482.0 8484.4 8491.4 8500.3 7534.1 7736.1 7774.0 7838.8 7842.1 5562.5 5752.1 5761.0 5809.7 5830.8 1625.1 1676.3 1679.2 1683.1 1694.4 911.9 934.1 937.8 942.9 945.5 8122.8 8148.6 8167.2 8204.8 8259.1 780.9 811.4 815.6 823.8 824.3 4871.4 4923.0 4982.9 5880.2 6010.3 24692.6 25643.5 25655.4 25903.6 25926.7 24686.8 24765.6 25081.0 25462.4 25800.7 12729.1 12772.1 12813.6 13031.5 13049.2 9582.9 9706.3 9777.3 9833.3 9926.6 20925.7 21020.0 21359.7 21686.7 21859.6 22400.0 22496.2 22555.3 22794.5 22823.2 12080.1 12302.5 12326.3 12329.0 12331.4 15554.8 15990.6 16022.4 16199.1 16225.7 11998.0 12047.8 12183.4 12467.3 12656.0 12094.4 12133.5 12163.6 12224.6 12447.9 10878.9 10953.2 10957.8 11080.8 11111.7 8033.9 8078.3 8105.3 8194.9 8201.5 8270.5 8344.9 8353.5 8358.6 8413.4 12:20:14 INFO - PID 14408 | 12:20:14 INFO - PID 14408 | [#4] prototype.html Cycles:180 Average:5640.20 Median:6267.25 stddev:1574.55 (25.1%) stddev-sans-first:1531.83 12:20:14 INFO - PID 14408 | Values: 545.2 566.0 581.2 583.5 583.7 6266.7 6290.9 6301.9 6324.5 6379.2 5984.7 6026.6 6203.3 6214.5 6267.8 4863.4 5088.6 5095.9 5098.6 5102.7 4952.4 4957.6 4958.3 4963.4 4982.6 4954.1 4957.9 4966.7 4982.0 4996.2 4924.6 4989.3 5005.5 5033.9 5041.9 4564.4 4567.3 4574.7 4591.5 4595.2 5958.6 6146.8 6179.7 6212.6 6301.2 5808.9 5847.9 5856.0 5886.4 5911.6 6611.6 6623.7 6644.9 6668.4 6679.0 4868.8 5045.9 5052.6 5060.5 5071.3 6706.6 6734.7 6844.3 6999.9 7000.3 5182.8 5194.9 5199.7 5245.6 5250.1 5232.8 5301.7 5305.7 5334.1 5344.8 5852.9 5860.7 5903.3 5908.2 5913.9 6648.8 6681.4 6683.7 6695.4 6704.5 1810.7 1815.6 1819.2 1830.2 1831.5 1236.4 1288.7 1302.0 1306.8 1307.9 6179.0 6197.1 6246.2 6276.0 6284.0 6524.4 6696.4 6717.1 6720.6 6793.0 6154.7 6216.5 6218.6 6273.3 6280.6 3555.7 3570.8 3577.4 3600.9 3605.4 6972.6 7097.6 7111.5 7126.7 7131.9 6898.2 6902.0 6916.2 6978.7 6982.3 7002.8 7016.8 7063.8 7071.9 7078.0 6715.8 6749.1 6761.9 6766.7 6802.1 6632.6 6643.2 6660.9 6662.8 6685.4 7009.4 7050.0 7055.7 7066.8 7080.6 5154.7 5162.7 5186.2 5186.7 5190.0 6594.9 6743.9 6761.9 6825.8 6865.6 6231.6 6562.6 6600.4 6614.2 6653.7 6475.6 6518.6 6569.0 6569.2 6599.2 6335.3 6350.7 6436.4 6467.8 6488.1 6521.8 6629.2 6634.4 6657.4 6673.8 6335.6 6354.4 6359.4 6430.4 6469.4 12:20:14 INFO - PID 14408 | 12:20:14 INFO - PID 14408 | [#5] yui.html Cycles:180 Average:28916.62 Median:19553.41 stddev:43038.38 (220.1%) stddev-sans-first:43108.02 12:20:14 INFO - PID 14408 | Values: 987.9 990.1 997.7 1013.1 1023.7 18183.5 20546.8 21341.7 21344.4 21355.6 19503.7 19618.8 19621.8 19645.3 19781.4 1041.7 1043.9 1049.2 1049.3 1049.3 1067.6 1071.7 1072.1 1073.1 1083.3 1068.3 1106.4 1121.3 1135.5 1140.7 1104.7 1105.4 1107.1 1107.2 1109.0 792.8 812.7 815.0 816.6 822.3 19361.6 19373.7 19475.5 19504.2 19510.2 22504.3 22549.4 23263.7 23535.0 23704.0 18028.4 18134.6 18497.0 18560.1 18618.6 3986.9 3997.8 4008.7 4034.5 4036.5 130840.0 162232.0 162509.0 164709.0 167584.0 14159.8 14303.1 14457.0 14529.5 14578.2 24271.0 24507.4 25130.4 25180.5 25239.5 9715.8 9741.1 9772.3 9805.5 9851.3 4660.9 4748.1 4774.6 4799.9 4804.8 6087.7 6104.5 6153.4 6191.3 6195.6 1198.1 1199.4 1201.4 1215.9 1216.8 700.8 727.9 730.9 743.4 754.7 12718.6 13199.5 13593.1 13606.1 13700.5 728.6 736.8 740.6 746.4 763.2 5926.5 5961.4 5996.8 6015.6 6023.4 146034.0 150292.2 150986.0 151604.0 153496.0 127204.4 132481.7 133438.0 133465.0 137860.0 112248.0 112484.0 114359.0 116105.0 116620.4 13530.8 13682.4 13862.6 13869.6 13874.2 52684.7 53283.5 53963.0 54353.2 54679.7 94004.0 94795.0 96074.5 96374.0 97050.0 20512.5 20588.9 20608.7 20642.3 20654.6 21719.2 22450.0 22822.7 23035.4 23403.8 21715.6 21787.8 21814.9 21964.1 22120.8 18292.4 18468.4 19027.3 19596.6 19732.1 18693.2 18729.9 18793.5 18948.2 19937.0 12431.0 12514.7 12769.9 13245.1 13471.1 12274.5 12746.1 12846.9 12920.2 14762.4 12:20:14 INFO - PID 14408 | -------- Summary: end -------- 12:20:14 INFO - PID 14408 | 12:20:15 INFO - TEST-INFO | 14408: exit 0 12:20:15 INFO - TEST-OK | dromaeo_css | took 1106661ms 12:20:15 INFO - TEST-START | kraken 12:20:15 INFO - Initialising browser for kraken test... 12:20:15 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48689/getInfo.html -profile /tmp/tmpTgUtwk/profile 12:20:15 INFO - TEST-INFO | started process 14781 (/builds/slave/test/build/application/firefox/firefox http://localhost:48689/getInfo.html) 12:20:20 INFO - TEST-INFO | 14781: exit 0 12:20:20 INFO - Browser initialized. 12:20:20 INFO - Running cycle 1/1 for kraken test... 12:20:20 INFO - Using env: {'DISPLAY': ':0', 12:20:20 INFO - 'HOME': '/home/cltbld', 12:20:20 INFO - 'JSGC_DISABLE_POISONING': '1', 12:20:20 INFO - 'LANG': 'en_US.UTF-8', 12:20:20 INFO - 'LANGUAGE': 'en_US:en', 12:20:20 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:20:20 INFO - 'LOGNAME': 'cltbld', 12:20:20 INFO - 'MAIL': '/var/mail/cltbld', 12:20:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:20:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:20:20 INFO - 'MOZ_CRASHREPORTER': '1', 12:20:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:20 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:20:20 INFO - 'MOZ_NO_REMOTE': '1', 12:20:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:20:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:20:20 INFO - 'NO_EM_RESTART': '1', 12:20:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:20:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:20 INFO - 'PWD': '/builds/slave/test', 12:20:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:20:20 INFO - 'RUST_BACKTRACE': 'full', 12:20:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:20:20 INFO - 'SHELL': '/bin/bash', 12:20:20 INFO - 'SHLVL': '1', 12:20:20 INFO - 'TERM': 'linux', 12:20:20 INFO - 'TMOUT': '86400', 12:20:20 INFO - 'USER': 'cltbld', 12:20:20 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146', 12:20:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:20 INFO - '_': '/tools/buildbot/bin/python'} 12:20:20 INFO - TEST-INFO | started process 15022 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTgUtwk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:20:20 INFO - PID 15022 | 12:20:20 INFO - PID 15022 | (/builds/slave/test/build/application/firefox/firefox:15072): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:20:20 INFO - PID 15022 | 12:20:31 INFO - PID 15022 | RSS: Main: 145260544 12:20:31 INFO - PID 15022 | 12:20:33 INFO - PID 15022 | Cycle 1(1): loaded http://localhost:48689/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48689/tests/kraken/driver.html?testName=audio-beat-detection) 12:20:34 INFO - PID 15022 | RSS: Main: 150638592 12:20:34 INFO - PID 15022 | 12:20:37 INFO - PID 15022 | Cycle 1(1): loaded http://localhost:48689/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48689/tests/kraken/driver.html?testName=audio-dft) 12:20:37 INFO - PID 15022 | RSS: Main: 151511040 12:20:37 INFO - PID 15022 | 12:20:40 INFO - PID 15022 | Cycle 1(1): loaded http://localhost:48689/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48689/tests/kraken/driver.html?testName=audio-fft) 12:20:41 INFO - PID 15022 | RSS: Main: 147894272 12:20:41 INFO - PID 15022 | 12:20:43 INFO - PID 15022 | Cycle 1(1): loaded http://localhost:48689/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48689/tests/kraken/driver.html?testName=audio-oscillator) 12:20:44 INFO - PID 15022 | RSS: Main: 148369408 12:20:44 INFO - PID 15022 | 12:20:46 INFO - PID 15022 | Cycle 1(1): loaded http://localhost:48689/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48689/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:20:46 INFO - PID 15022 | RSS: Main: 148762624 12:20:46 INFO - PID 15022 | 12:20:49 INFO - PID 15022 | Cycle 1(1): loaded http://localhost:48689/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48689/tests/kraken/driver.html?testName=imaging-darkroom) 12:20:50 INFO - PID 15022 | RSS: Main: 149127168 12:20:50 INFO - PID 15022 | 12:20:53 INFO - PID 15022 | Cycle 1(1): loaded http://localhost:48689/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48689/tests/kraken/driver.html?testName=imaging-desaturate) 12:20:54 INFO - PID 15022 | RSS: Main: 150175744 12:20:54 INFO - PID 15022 | 12:20:57 INFO - PID 15022 | Cycle 1(1): loaded http://localhost:48689/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48689/tests/kraken/driver.html?testName=json-parse-financial) 12:20:58 INFO - PID 15022 | RSS: Main: 151281664 12:20:58 INFO - PID 15022 | 12:20:59 INFO - PID 15022 | Cycle 1(1): loaded http://localhost:48689/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48689/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:21:00 INFO - PID 15022 | RSS: Main: 151154688 12:21:00 INFO - PID 15022 | 12:21:01 INFO - PID 15022 | Cycle 1(1): loaded http://localhost:48689/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48689/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:21:02 INFO - PID 15022 | RSS: Main: 151023616 12:21:02 INFO - PID 15022 | 12:21:04 INFO - PID 15022 | Cycle 1(1): loaded http://localhost:48689/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48689/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:21:05 INFO - PID 15022 | RSS: Main: 148193280 12:21:05 INFO - PID 15022 | 12:21:07 INFO - PID 15022 | Cycle 1(1): loaded http://localhost:48689/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48689/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:21:08 INFO - PID 15022 | RSS: Main: 148062208 12:21:08 INFO - PID 15022 | 12:21:10 INFO - PID 15022 | Cycle 1(1): loaded http://localhost:48689/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48689/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:21:11 INFO - PID 15022 | RSS: Main: 148656128 12:21:11 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | Cycle 1(1): loaded http://localhost:48689/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48689/tests/kraken/driver.html?testName=ai-astar) 12:21:13 INFO - PID 15022 | RSS: Main: 149999616 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | __start_tp_report 12:21:13 INFO - PID 15022 | _x_x_mozilla_page_load 12:21:13 INFO - PID 15022 | _x_x_mozilla_page_load_details 12:21:13 INFO - PID 15022 | |i|pagename|runs| 12:21:13 INFO - PID 15022 | |0;ai-astar;106;105;106;105;105;106;108;106;106;107 12:21:13 INFO - PID 15022 | |1;audio-beat-detection;112;113;116;112;111;115;116;115;113;120 12:21:13 INFO - PID 15022 | |2;audio-dft;164;162;161;164;163;164;163;166;171;170 12:21:13 INFO - PID 15022 | |3;audio-fft;77;74;73;74;73;74;81;74;74;74 12:21:13 INFO - PID 15022 | |4;audio-oscillator;79;79;86;77;77;77;78;77;77;78 12:21:13 INFO - PID 15022 | |5;imaging-gaussian-blur;111;114;113;114;115;117;115;114;114;115 12:21:13 INFO - PID 15022 | |6;imaging-darkroom;150;149;150;149;150;150;150;150;150;149 12:21:13 INFO - PID 15022 | |7;imaging-desaturate;95;100;94;94;94;94;98;95;95;95 12:21:13 INFO - PID 15022 | |8;json-parse-financial;46;43;45;45;44;42;43;44;43;44 12:21:13 INFO - PID 15022 | |9;json-stringify-tinderbox;36;37;36;37;37;37;36;38;39;37 12:21:13 INFO - PID 15022 | |10;stanford-crypto-aes;74;71;71;71;73;73;72;73;71;70 12:21:13 INFO - PID 15022 | |11;stanford-crypto-ccm;107;104;108;104;103;109;109;112;117;108 12:21:13 INFO - PID 15022 | |12;stanford-crypto-pbkdf2;159;150;158;154;152;150;149;162;152;149 12:21:13 INFO - PID 15022 | |13;stanford-crypto-sha256-iterative;63;61;59;80;61;58;60;61;57;62 12:21:13 INFO - PID 15022 | __end_tp_report 12:21:13 INFO - PID 15022 | __start_cc_report 12:21:13 INFO - PID 15022 | _x_x_mozilla_cycle_collect,691 12:21:13 INFO - PID 15022 | __end_cc_report 12:21:13 INFO - PID 15022 | __startTimestamp1506540073400__endTimestamp 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | ------- Summary: start ------- 12:21:13 INFO - PID 15022 | Number of tests: 14 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | [#0] ai-astar Cycles:10 Average:106.00 Median:106.00 stddev:0.94 (0.9%) stddev-sans-first:1.00 12:21:13 INFO - PID 15022 | Values: 106.0 105.0 106.0 105.0 105.0 106.0 108.0 106.0 106.0 107.0 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | [#1] audio-beat-detection Cycles:10 Average:114.30 Median:115.00 stddev:2.67 (2.3%) stddev-sans-first:2.70 12:21:13 INFO - PID 15022 | Values: 112.0 113.0 116.0 112.0 111.0 115.0 116.0 115.0 113.0 120.0 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | [#2] audio-dft Cycles:10 Average:164.80 Median:164.00 stddev:3.29 (2.0%) stddev-sans-first:3.48 12:21:13 INFO - PID 15022 | Values: 164.0 162.0 161.0 164.0 163.0 164.0 163.0 166.0 171.0 170.0 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | [#3] audio-fft Cycles:10 Average:74.80 Median:74.00 stddev:2.44 (3.3%) stddev-sans-first:2.46 12:21:13 INFO - PID 15022 | Values: 77.0 74.0 73.0 74.0 73.0 74.0 81.0 74.0 74.0 74.0 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | [#4] audio-oscillator Cycles:10 Average:78.50 Median:78.00 stddev:2.76 (3.5%) stddev-sans-first:2.92 12:21:13 INFO - PID 15022 | Values: 79.0 79.0 86.0 77.0 77.0 77.0 78.0 77.0 77.0 78.0 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.50 stddev:1.55 (1.4%) stddev-sans-first:1.13 12:21:13 INFO - PID 15022 | Values: 111.0 114.0 113.0 114.0 115.0 117.0 115.0 114.0 114.0 115.0 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:150.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 12:21:13 INFO - PID 15022 | Values: 150.0 149.0 150.0 149.0 150.0 150.0 150.0 150.0 150.0 149.0 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:2.01 (2.1%) stddev-sans-first:2.13 12:21:13 INFO - PID 15022 | Values: 95.0 100.0 94.0 94.0 94.0 94.0 98.0 95.0 95.0 95.0 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | [#8] json-parse-financial Cycles:10 Average:43.90 Median:44.00 stddev:1.20 (2.7%) stddev-sans-first:1.00 12:21:13 INFO - PID 15022 | Values: 46.0 43.0 45.0 45.0 44.0 42.0 43.0 44.0 43.0 44.0 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | [#9] json-stringify-tinderbox Cycles:10 Average:37.00 Median:37.00 stddev:0.94 (2.5%) stddev-sans-first:0.93 12:21:13 INFO - PID 15022 | Values: 36.0 37.0 36.0 37.0 37.0 37.0 36.0 38.0 39.0 37.0 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | [#10] stanford-crypto-aes Cycles:10 Average:71.90 Median:72.50 stddev:1.29 (1.8%) stddev-sans-first:1.12 12:21:13 INFO - PID 15022 | Values: 74.0 71.0 71.0 71.0 73.0 73.0 72.0 73.0 71.0 70.0 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | [#11] stanford-crypto-ccm Cycles:10 Average:108.10 Median:108.50 stddev:4.18 (3.8%) stddev-sans-first:4.41 12:21:13 INFO - PID 15022 | Values: 107.0 104.0 108.0 104.0 103.0 109.0 109.0 112.0 117.0 108.0 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.50 Median:153.00 stddev:4.62 (3.0%) stddev-sans-first:4.46 12:21:13 INFO - PID 15022 | Values: 159.0 150.0 158.0 154.0 152.0 150.0 149.0 162.0 152.0 149.0 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:61.00 stddev:6.51 (10.7%) stddev-sans-first:6.90 12:21:13 INFO - PID 15022 | Values: 63.0 61.0 59.0 80.0 61.0 58.0 60.0 61.0 57.0 62.0 12:21:13 INFO - PID 15022 | -------- Summary: end -------- 12:21:13 INFO - PID 15022 | 12:21:13 INFO - PID 15022 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:21:13 INFO - TEST-INFO | 15022: exit 0 12:21:13 INFO - TEST-OK | kraken | took 58400ms 12:21:13 INFO - SUITE-END | took 1165s 12:21:14 INFO - Completed test suite (00:19:25) 12:21:14 INFO - javascript benchmark 12:21:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9126.072123861966, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3840.865569705061, 3860.7297489175767, 3872.8838134855955, 3895.590962948892, 3928.5678575356706, 6219.9689001554925, 6280.183576135096, 6309.716062777175, 6322.083297921804, 6412.134361861159, 6228.813135605917, 6279.780207692732, 6302.842428939267, 6451.29035806061, 6462.159919210508, 5605.803796867111, 5699.088145896658, 5731.713414329288, 5755.136729490568, 5768.942310576904, 205.6578712130574, 5416.350037995446, 5615.859603509924, 5708.857278568027, 5742.339630942432, 5626.156076588503, 5630.943690563104, 5690.317161940573, 5728.856778580527, 5747.683877386742, 5634.183043458716, 5635.5773317001385, 5716.28546431696, 5722.227499287626, 5762.769489220426, 5626.409227031144, 5629.774809007635, 5707.1724599933195, 5714.428557144252, 5722.427757224286, 6225.3774622537385, 6292.2134733158355, 6319.304876463585, 6367.968160159169, 6395.616263024233, 6732.899006514906, 6796.898046529306, 6814.965925170342, 6817.0, 6828.146481689789, 6871.278515755874, 6915.89626155608, 6930.13373328334, 6936.0, 6958.617276049815, 6232.719527621222, 6301.8109456716375, 6380.3619638035825, 6413.3266007069515, 6418.775342862978, 7082.822929426702, 7152.141742990874, 7174.139103307636, 7180.030695855992, 7186.496945213889, 6761.22245941723, 6798.286179951133, 6842.589444633338, 6855.897161542581, 6869.450443964471, 6778.966105169541, 6803.251642319341, 6867.107276054081, 6895.4138898194215, 6913.2049814270995, 6883.827904302332, 6901.482388820858, 6912.516123871382, 6921.515493915377, 6937.9653101735175, 6853.725850965906, 6857.142857142857, 6943.305669433016, 6947.548409353376, 6949.652517374111, 4969.403671559436, 4973.602111831117, 5029.497050295015, 5030.673006254582, 5036.269740887551, 4494.235979883428, 4519.615832654261, 4532.4334458192725, 4546.74992875395, 4577.771111444415, 6338.619682819046, 6389.2652344980925, 6391.169148010822, 6391.296957334689, 6393.264774550986, 6834.965825170942, 6841.452683785285, 6846.315368463114, 6859.039734437183, 6942.826429339307, 6214.906776398358, 6297.748090076346, 6304.936950630435, 6370.872582548416, 6384.361563843579, 5464.3442786865835, 5540.252065971123, 5549.223108764776, 5576.052071147915, 5585.218069470277, 7072.8939065914055, 7085.92914070863, 7162.997180394749, 7170.928290717026, 7184.856302873914, 6726.529142959946, 6728.058071869844, 6763.289854565298, 6855.451563874978, 6860.588364698134, 7130.465215108991, 7138.643067846483, 7143.535670181422, 7143.9285607143265, 7154.2130365658695, 6761.3238676132, 6788.932110679028, 6803.727850885909, 6827.317268273133, 6870.690818913061, 6687.063811066552, 6811.659417029228, 6847.34950179752, 6858.897116543156, 6875.209350924508, 6569.343065693392, 6599.307072757291, 6699.464042876461, 6712.630805305755, 6712.899306510504, 6594.538382313192, 6619.536632435684, 6662.300458451987, 6679.632620205935, 6700.296468870698, 6407.006913928349, 6438.227412710505, 6500.414962653384, 6524.054012168304, 6535.379138981789, 6624.470042396693, 6688.765893193715, 6719.361660642231, 6733.764318248838, 6741.865162696817, 6425.421712045938, 6450.8064758057335, 6542.280349161683, 6554.377334153248, 6555.672216389066], "value": 6219.458344510945, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [945.2626950978236, 956.9234461243101, 975.878015248094, 1009.9848502272465, 1011.1809434358175, 43522.0, 44066.67799966002, 44085.338719919186, 44106.55893441065, 44155.116897662025, 40132.59867401332, 44581.7770911145, 44875.77562112193, 44884.775576122156, 45410.77294613522, 8334.458260213081, 8482.74551763445, 8487.40588158829, 8490.617922193485, 8496.957515212445, 8322.16778322169, 8444.915550844506, 8445.0, 8451.661933522651, 8529.786755331104, 8502.78743031426, 8546.52994085325, 8562.529060901648, 8584.828303433927, 8588.269997050258, 8486.363522735788, 8525.488470691747, 8534.0, 8553.743387698316, 8560.957195214047, 8276.586170691442, 8291.129431409676, 8304.584770761438, 8306.958465207696, 8364.0, 38347.61652383468, 38636.420453693216, 38813.223735525135, 38850.417243741365, 39037.0, 3767.3407153747985, 3814.6376094271277, 3815.1415931415486, 3815.9427608585893, 3816.790076545789, 33739.32521349559, 33746.49380259298, 33766.0, 33836.15409614755, 33849.83075084633, 5330.333708286465, 5407.053765591006, 5460.426655201226, 5518.889622207534, 5689.317281926154, 227922.0, 228354.0, 228511.0, 228922.0, 229339.0, 52894.735526322125, 52932.0, 52989.7350513245, 53007.73496132572, 53031.469685302654, 56210.0, 56218.437815622136, 56228.718856406274, 56302.15546766802, 56444.0, 16492.17539123039, 16564.75152872708, 16587.170641467877, 16589.751153732705, 16599.253033613517, 13348.0, 13465.124766890121, 13476.326183690777, 13513.121647093105, 13528.864711352957, 14035.78946315806, 14332.0, 14383.208923509308, 14441.422343106367, 14567.490137845125, 5420.891582168413, 5455.5908306877145, 5491.697956612425, 5495.477929596762, 5495.697736624524, 1117.284937639911, 1117.6758739965483, 1123.8707548631849, 1129.3957732613012, 1130.1636788776411, 23712.28863134109, 24019.51960960771, 24024.87987560051, 24037.75962240355, 24047.398815029414, 1221.987780122205, 1229.655696405008, 1239.7954337534206, 1240.7332423528985, 1241.069198101424, 12963.870361296455, 13073.411696473682, 13166.934165329303, 13286.3356832158, 13297.202167869958, 284850.0, 287050.0, 287818.0, 287820.0, 288149.0, 57525.42474575285, 63838.0, 64888.0, 64996.67501662461, 65073.67463162654, 1514.6062023873872, 1536.109056747084, 1551.6663917257845, 1553.4718195813477, 1555.898866573669, 30336.393272134876, 30533.23666908345, 30625.081247562608, 30726.539101913044, 30752.538711919788, 39834.0, 39902.0, 40186.598134018284, 40204.0, 40207.59792402039, 244757.0, 245288.0, 245930.0, 246069.0, 246820.0, 35507.0, 35746.0, 35759.0, 35776.64223357733, 35820.462693060144, 43719.0, 44561.33158002698, 45059.32411013772, 45077.54922450733, 45091.32363014492, 32902.0, 33043.66956330406, 33151.50272745959, 33328.0, 33630.0, 32780.1804954878, 33145.66854331522, 33587.66412335845, 33805.492917606745, 33813.830930845186, 38554.421683674205, 38883.80558097304, 39158.6084139155, 39159.0, 39230.0, 32583.0, 32992.83503582467, 33596.66403335935, 33625.83187084049, 33926.0, 23779.64330535078, 23941.0, 24429.25896985435, 24530.50938981246, 24758.50482990294], "value": 18649.94149553312, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [624.7313655128293, 627.3444250757957, 627.5356236385073, 637.9808605741823, 648.2512697834002, 23276.0, 23508.412289692766, 23706.76293237067, 23805.76194238057, 25174.0, 25984.0, 26929.32676683084, 26943.595846062275, 26988.5951710724, 27024.45951080977, 6613.570117942342, 6813.863722725542, 6827.931720682805, 6828.0440738296675, 6833.4191593714595, 6414.166158399425, 6460.709268082947, 6718.42893354063, 6722.394984451398, 6732.9663351683175, 6677.799666010027, 6680.7327706891665, 6687.06381106643, 6689.732410703566, 6690.799276021728, 6474.902876456857, 6666.799996000103, 6681.766138185165, 6687.899681504757, 6791.422729068036, 6296.118543403927, 6315.305316415237, 6338.58799178052, 6369.0, 6381.1066450696935, 31155.68844311573, 31681.0, 31743.206419839687, 31782.205444863834, 31884.0, 2304.1129165271323, 2320.8955596998007, 2326.430024643973, 2333.2300340887637, 2337.5091230841545, 18883.150258238413, 19280.614387712307, 20568.177272909215, 20676.896615516976, 20744.06651700677, 2489.726130125685, 2539.6444497770326, 2583.225032490245, 2597.844129352226, 2598.337423956898, 130836.34581827125, 135694.0, 148415.0, 154620.0, 155009.22495387564, 16144.919275403783, 17205.655886882192, 17229.65540689179, 17268.568285792706, 17474.737878931825, 26859.731402686113, 26867.059652912445, 26891.596626050625, 26898.5965210522, 26929.172436894234, 10147.036031576989, 10202.744931376776, 10250.948745256375, 10281.691549253534, 10288.691339259834, 8989.146031127033, 9039.412438191497, 9076.410033347944, 9079.591418386188, 9102.908970910206, 10083.798324033625, 10279.897201028043, 10285.382877027403, 10299.53652085658, 10305.072543471124, 2937.221636266391, 2952.217662319487, 2973.7807498925335, 2981.7167369099902, 3033.393321335742, 1034.4207243943415, 1066.2003497376968, 1069.4438891776233, 1070.341739829999, 1072.5495291977388, 20730.585388292453, 20757.169713211304, 20817.791822081886, 21096.578068438546, 21208.469788255108, 1123.269874581512, 1141.5547936304847, 1143.1255089856268, 1157.4444266752007, 1158.0619698044611, 11480.425978701032, 11551.191416600925, 11571.247868888495, 11765.588204412976, 11767.0, 600247.0, 610628.0, 614908.0, 626495.0, 629350.0, 132171.33914330558, 134500.0, 135933.0, 136151.0, 136703.0, 1231.956881509143, 1272.179444258448, 1326.0982531878221, 1354.5056054540023, 1359.9116057456233, 22792.31623051311, 22834.31497055091, 23097.307080787603, 23194.3041708749, 23201.76798231996, 63840.68079659572, 65393.346066538725, 65664.0, 66194.33805661881, 67993.0, 83248.0, 84855.15144848473, 85035.0, 85341.57329213314, 85427.57286213529, 15875.603109922344, 16241.269142888363, 16427.17864106766, 16473.258703358617, 16475.258613362188, 24325.270241892773, 24472.755272447048, 24617.63073553934, 24770.628440573768, 24831.751682482944, 19896.204151834343, 20209.79790202138, 20344.694829577864, 20400.89799551052, 20543.383698488473, 17454.738178927073, 17657.470275891745, 17719.379821706178, 17856.375026873997, 18507.90746046261, 18943.43169704911, 19996.20015199376, 20083.19667213295, 20182.78903265809, 20304.79695203029, 15872.20638968024, 16043.679126417639, 16088.839111609203, 16156.11141387188, 16209.756853646968, 19861.702074469184, 19872.304469343504, 20099.19603215855, 20114.09486573137, 20183.495412614215], "value": 13038.680190019533, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1405.550223928343, 1451.216343174685, 1452.8183977002875, 1466.2961778346387, 1473.3075454536365, 16910.83089169108, 17631.294748210057, 17643.73534396985, 17685.38101166458, 17694.55763605909, 17034.488965331027, 17057.0, 17074.573135671613, 17085.40201092962, 17293.567660808487, 2780.638516992793, 2826.830390176596, 2873.568964655308, 2918.1391489510647, 2920.956185657217, 2858.7427131558156, 2907.7964542482036, 2910.8690108945148, 2920.7809414293906, 2929.487339715552, 2780.79144064195, 2863.7136286371406, 2882.7549658278967, 2883.8269703817837, 2886.8556572171415, 2863.1553691660906, 2883.668378136499, 2885.220990332609, 2885.2209903326193, 2886.480433521965, 2730.331068888115, 2744.684361298436, 2810.4519618674162, 2815.619891314667, 2818.746312831855, 16725.330986760517, 16730.49808505747, 16771.0, 16825.915870420693, 16834.915825420918, 8508.957455212747, 8809.295256379539, 8813.38306318558, 8816.338774591866, 8817.603207855598, 5818.738156782955, 5943.3165186003735, 5994.670293133876, 6051.546134039921, 6080.908786368208, 1993.9601207975904, 1996.710476980844, 2008.4777957730948, 2010.3064442767222, 2079.3450063230034, 31122.688773112204, 31233.843830780927, 31296.37407251865, 31580.210494737585, 31672.208194795083, 7376.0, 7478.326950574475, 7480.5885676287235, 7540.208278130827, 7598.506097103671, 19891.10490027923, 20038.699419508717, 20049.799502004793, 20094.0, 20123.0944607493, 8352.70765523216, 8481.957590212009, 8484.40609157353, 8491.363147763943, 8500.319974402033, 7534.133574638877, 7736.071671399468, 7773.961130194313, 7838.804029899298, 7842.058952925686, 5562.499375056264, 5752.07966725322, 5760.971195144081, 5809.651420914759, 5830.76676932918, 1625.1061915946182, 1676.2624445244053, 1679.1688114383458, 1683.0827199176479, 1694.3984885365765, 911.9270458363314, 934.1172591900589, 937.7561833923093, 942.9434233945985, 945.5366870233536, 8122.796930076677, 8148.592570371457, 8167.224113709188, 8204.753857384288, 8259.132791056973, 780.867252567065, 811.4117264982958, 815.6003558256411, 823.7528741377563, 824.3199360527589, 4871.391076115486, 4923.0, 4982.8754281143265, 5880.235569376039, 6010.278766548042, 24692.629610555858, 25643.48713025729, 25655.358616034377, 25903.611445828326, 25926.740732592432, 24686.753132468446, 24765.62851557228, 25081.0, 25462.363440913756, 25800.7099645017, 12729.108962372546, 12772.10595258323, 12813.615591532269, 13031.478740850449, 13049.151805132637, 9582.90417095848, 9706.272029597667, 9777.315587909063, 9833.311668183158, 9926.553305101432, 20925.686114708595, 21020.0, 21359.67960480563, 21686.6746998792, 21859.562808744053, 22400.0, 22496.21263255778, 22555.323340299816, 22794.544109117393, 22823.201187959006, 12080.09399295074, 12302.507899684271, 12326.32205228721, 12329.0, 12331.383430828602, 15554.84445155534, 15990.600234993753, 16022.439214627431, 16199.109049002418, 16225.67548649044, 11997.980171685505, 12047.75904481923, 12183.390830458618, 12467.31429771335, 12656.0, 12094.39528023613, 12133.514659413524, 12163.574274900337, 12224.572139975058, 12447.875521244672, 10878.891211087788, 10953.178511611502, 10957.780844383227, 11080.833787493355, 11111.666650000512, 8033.919660803317, 8078.272955433804, 8105.311048560939, 8194.877076843972, 8201.466904651179, 8270.503769773833, 8344.916550834414, 8353.54055526952, 8358.58207089631, 8413.368997325102], "value": 7306.37632501857, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.2230571435706, 565.9886802263954, 581.1863391252247, 583.5390041866925, 583.7256489449957, 6266.717997690103, 6290.874182516352, 6301.873962520758, 6324.525660575458, 6379.202599675041, 5984.730687119079, 6026.608270462428, 6203.286622038467, 6214.53390995676, 6267.780627678032, 4863.392075990501, 5088.623783546166, 5095.949040509594, 5098.643094983353, 5102.719350435743, 4952.405711314647, 4957.553820156185, 4958.322505846839, 4963.379577552788, 4982.626303027269, 4954.108260513106, 4957.925631115536, 4966.652334336598, 4982.0, 4996.150654388763, 4924.581410580105, 4989.301497790312, 5005.49945005502, 5033.899322013539, 5041.899162016776, 4564.360989461478, 4567.2920697291975, 4574.725516469022, 4591.494935557086, 4595.2188128018315, 5958.612690175168, 6146.78486252979, 6179.66011869347, 6212.596181248205, 6301.180846489973, 5808.8838223235725, 5847.912281315784, 5855.970720146414, 5886.352501224862, 5911.586188966774, 6611.570247933869, 6623.701933413008, 6644.900326495106, 6668.3659954318255, 6678.998150277449, 4868.756562171877, 5045.949540504585, 5052.621053421009, 5060.49395060491, 5071.264666623356, 6706.631135287557, 6734.696938637724, 6844.349786770299, 6999.895001574981, 7000.264972177899, 5182.766775495036, 5194.92207616886, 5199.7140157291715, 5245.632805703564, 5250.107481728115, 5232.764525596357, 5301.734913254322, 5305.657435815411, 5334.093204155303, 5344.786208551614, 5852.941470585325, 5860.677662728505, 5903.321118071477, 5908.20239267702, 5913.911291330547, 6648.769977554071, 6681.432078273401, 6683.732650693918, 6695.4308883745425, 6704.457974665854, 1810.7415346334171, 1815.5551889786823, 1819.1904602451782, 1830.1764206107305, 1831.5329590954489, 1236.3756303066893, 1288.664947113757, 1301.9739605207842, 1306.79744639581, 1307.9149855259377, 6179.0, 6197.1324014637985, 6246.187995560639, 6276.0, 6284.0, 6524.445422139172, 6696.363845434676, 6717.059611654372, 6720.596764194164, 6793.0, 6154.676744499954, 6216.502679785606, 6218.595791273553, 6273.278572964167, 6280.591761535486, 3555.6977656899453, 3570.785752854837, 3577.4276115821863, 3600.9279814403567, 3605.369060414417, 6972.616506092214, 7097.574145551283, 7111.502194846415, 7126.714931402686, 7131.89302160468, 6898.206466318793, 6902.0, 6916.23921368649, 6978.731912128578, 6982.3242421818395, 7002.794119852332, 7016.824579385556, 7063.788086357417, 7071.858562828817, 7077.9646101770195, 6715.764948226789, 6749.076513193912, 6761.93238067613, 6766.695498702472, 6802.115724955724, 6632.602043877383, 6643.169603799525, 6660.866782664417, 6662.800115996528, 6685.431738302299, 7009.369156775914, 7050.0, 7055.717771289091, 7066.752663656747, 7080.645967701697, 5154.716490593053, 5162.741862906914, 5186.196139598368, 5186.740662966761, 5189.974050129877, 6594.878201124297, 6743.932560674527, 6761.932380676327, 6825.829354266183, 6865.5537390069485, 6231.626102433868, 6562.606243625398, 6600.405963463311, 6614.206295244601, 6653.700583473658, 6475.643839588868, 6518.57629254097, 6568.967155164193, 6569.211694596679, 6599.208095028627, 6335.303116657072, 6350.650714210763, 6436.420722135031, 6467.805965821033, 6488.12410324619, 6521.771737989147, 6629.204495460575, 6634.369734875372, 6657.367550082734, 6673.833154171185, 6335.58818676803, 6354.364563543609, 6359.438714319996, 6430.356964303719, 6469.385408386196], "value": 5189.470579926842, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [987.925905557083, 990.064389152251, 997.6607953295878, 1013.1287093099928, 1023.6673081248593, 18183.4544963651, 20546.794532054675, 21341.679874801892, 21344.35966920995, 21355.57288854222, 19503.707444388347, 19618.803811961876, 19621.803781962175, 19645.31241406551, 19781.406557803253, 1041.666666666667, 1043.911267542258, 1049.2026060194269, 1049.275999560301, 1049.3494033699117, 1067.5676351077786, 1071.6892101290616, 1072.1101485766794, 1073.0557109743415, 1083.2850318789601, 1068.3322923173016, 1106.4191299567751, 1121.2823792772633, 1135.5117299561186, 1140.7262257058287, 1104.6962085426492, 1105.4362275239646, 1107.125370956943, 1107.24153954541, 1108.9944550277278, 792.8414317136596, 812.7358608452276, 815.0423252678139, 816.5713000674634, 822.2928281677753, 19361.612767744708, 19373.709394359095, 19475.513112172168, 19504.219831206734, 19510.219591216333, 22504.32487025392, 22549.436264093365, 23263.651045234335, 23535.0, 23704.0, 18028.391245949184, 18134.637307253783, 18496.98266595337, 18560.071996400125, 18618.627627447375, 3986.920261594752, 3997.8200980955926, 4008.6793056555407, 4034.536028356718, 4036.535798383194, 130840.0, 162232.0, 162509.0, 164709.0, 167584.0, 14159.78760318596, 14303.07030043044, 14457.0, 14529.491467798576, 14578.198199098939, 24271.0, 24507.38731531726, 25130.37174070663, 25180.496390072098, 25239.495210095694, 9715.805683886283, 9741.123298903132, 9772.315937884421, 9805.509724513746, 9851.261155413373, 4660.86017419478, 4748.121597504472, 4774.618030557547, 4799.928001079987, 4804.783784729696, 6087.726052327567, 6104.511639068864, 6153.446189842935, 6191.349908259658, 6195.597286176384, 1198.101423932051, 1199.4422593494066, 1201.4233168079197, 1215.914885957992, 1216.750566133958, 700.8493173967621, 727.858067676798, 730.9195988441268, 743.4164181117796, 754.7320701151123, 12718.618441446772, 13199.47629850995, 13593.116447430886, 13606.115602485806, 13700.45198192081, 728.6320408193828, 736.8194792275926, 740.6407892172292, 746.4289818289013, 763.1719584251039, 5926.496247818984, 5961.403859614091, 5996.760129594854, 6015.639061656315, 6023.397660234029, 146034.0, 150292.2485387566, 150986.0, 151604.0, 153496.0, 127204.36397817952, 132481.67518324693, 133438.0, 133465.0, 137860.0, 112248.0, 112484.0, 114359.0, 116105.0, 116620.41689791497, 13530.769461346861, 13682.384292706653, 13862.584122476343, 13869.583912482642, 13874.236916969663, 52684.73657631687, 53283.46716532785, 53963.0, 54353.184702228704, 54679.72660136674, 94004.0, 94795.0, 96074.51962740142, 96374.0, 97050.0, 20512.487187820425, 20588.897055514626, 20608.690869636666, 20642.27752028672, 20654.586908262052, 21719.239826605994, 22450.0, 22822.65766013478, 23035.424114396603, 23403.76596234016, 21715.565688686453, 21787.78212217921, 21814.89092554527, 21964.121435143054, 22120.778792211873, 18292.359767408077, 18468.44594662162, 19027.33404330842, 19596.60806783828, 19732.112054957273, 18693.158807853408, 18729.90635046816, 18793.53016174552, 18948.242070317032, 19937.0, 12431.0, 12514.749705005666, 12769.87230127724, 13245.139065960682, 13471.057026008086, 12274.509019639114, 12746.10777245584, 12846.935765321114, 12920.160189587647, 14762.409503619734], "value": 10073.664697040995, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1374.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 106.0, 105.0, 105.0, 106.0, 108.0, 106.0, 106.0, 107.0], "value": 106.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 116.0, 112.0, 111.0, 115.0, 116.0, 115.0, 113.0, 120.0], "value": 114.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 161.0, 164.0, 163.0, 164.0, 163.0, 166.0, 171.0, 170.0], "value": 164.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 73.0, 74.0, 73.0, 74.0, 81.0, 74.0, 74.0, 74.0], "value": 74.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 86.0, 77.0, 77.0, 77.0, 78.0, 77.0, 77.0, 78.0], "value": 78.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 114.0, 113.0, 114.0, 115.0, 117.0, 115.0, 114.0, 114.0, 115.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 150.0, 149.0, 150.0, 150.0, 150.0, 150.0, 150.0, 149.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 100.0, 94.0, 94.0, 94.0, 94.0, 98.0, 95.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 43.0, 45.0, 45.0, 44.0, 42.0, 43.0, 44.0, 43.0, 44.0], "value": 43.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 37.0, 36.0, 37.0, 37.0, 37.0, 36.0, 38.0, 39.0, 37.0], "value": 37.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 71.0, 71.0, 71.0, 73.0, 73.0, 72.0, 73.0, 71.0, 70.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 104.0, 108.0, 104.0, 103.0, 109.0, 109.0, 112.0, 117.0, 108.0], "value": 108.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 150.0, 158.0, 154.0, 152.0, 150.0, 149.0, 162.0, 152.0, 149.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 59.0, 80.0, 61.0, 58.0, 60.0, 61.0, 57.0, 62.0], "value": 62.2, "unit": "score"}]}]} 12:21:14 INFO - Return code: 0 12:21:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:21:14 INFO - # TBPL SUCCESS # 12:21:14 INFO - Running post-action listener: _package_coverage_data 12:21:14 INFO - Running post-action listener: _resource_record_post_action 12:21:14 INFO - [mozharness: 2017-09-27 19:21:14.168352Z] Finished run-tests step (success) 12:21:14 INFO - Running post-run listener: _resource_record_post_run 12:21:14 INFO - Total resource usage - Wall time: 1183s; CPU: 13.0%; Read bytes: 4096; Write bytes: 332615680; Read time: 12; Write time: 228968 12:21:14 INFO - TinderboxPrint: CPU usage
13.1% 12:21:14 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 12:21:14 INFO - TinderboxPrint: I/O write bytes / time
332,615,680 / 228,968 12:21:14 INFO - TinderboxPrint: CPU idle
8,198.3 (86.9%) 12:21:14 INFO - TinderboxPrint: CPU user
1,186.4 (12.6%) 12:21:14 INFO - TinderboxPrint: Swap in / out
0 / 0 12:21:14 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10342400; Read time: 0; Write time: 1276 12:21:14 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:21:14 INFO - run-tests - Wall time: 1166s; CPU: 13.0%; Read bytes: 4096; Write bytes: 322273280; Read time: 12; Write time: 227692 12:21:14 INFO - Running post-run listener: _upload_blobber_files 12:21:14 INFO - Blob upload gear active. 12:21:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:21:14 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 12:21:14 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'] 12:21:14 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 12:21:15 INFO - (blobuploader) - INFO - Open directory for files ... 12:21:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:21:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:21:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:16 INFO - (blobuploader) - INFO - Done attempting. 12:21:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:21:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:21:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:19 INFO - (blobuploader) - INFO - Done attempting. 12:21:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:21:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:21:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:20 INFO - (blobuploader) - INFO - Done attempting. 12:21:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:21:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:21:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:21 INFO - (blobuploader) - INFO - Done attempting. 12:21:21 INFO - (blobuploader) - INFO - Iteration through files over. 12:21:21 INFO - Return code: 0 12:21:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:21:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:21:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12f9a2c783daaef5a441eacd36f3e7b0cb7956277ab4d102be357ca5dcdf5a591916ad8e4b36b39387bb4a941f0001b6ca54d5d682890a07c0e7aac8dcafa9ed", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1f446a50c38d78cf84764ea3a3ab6944a4b989ec37f134b6abbb0162d9cf1b8754bc77bad6bad2ce6d5a6ffabd03b09f84f7478bc2a827db028c85ba05c7564", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61153dd8e04d2ff3065dbe62a37eed4de0371b133153affc279249ed539cd48e8659c4e4dbb27ea06295c1fb25e75695a1fd54b0c699c9d706c18f9a95813754", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae034e37245859bf25a091f3191cc8a4a023511e72e5feb0f75f3ae2686f1402c7bb29fced3a6a2b7631ad45d51c72c2653b8ea6b66fe0a0d9523cb14e75a3e3"} 12:21:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:21:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:21:21 INFO - Contents: 12:21:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12f9a2c783daaef5a441eacd36f3e7b0cb7956277ab4d102be357ca5dcdf5a591916ad8e4b36b39387bb4a941f0001b6ca54d5d682890a07c0e7aac8dcafa9ed", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1f446a50c38d78cf84764ea3a3ab6944a4b989ec37f134b6abbb0162d9cf1b8754bc77bad6bad2ce6d5a6ffabd03b09f84f7478bc2a827db028c85ba05c7564", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61153dd8e04d2ff3065dbe62a37eed4de0371b133153affc279249ed539cd48e8659c4e4dbb27ea06295c1fb25e75695a1fd54b0c699c9d706c18f9a95813754", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae034e37245859bf25a091f3191cc8a4a023511e72e5feb0f75f3ae2686f1402c7bb29fced3a6a2b7631ad45d51c72c2653b8ea6b66fe0a0d9523cb14e75a3e3"} 12:21:21 INFO - Running post-run listener: copy_logs_to_upload_dir 12:21:21 INFO - Copying logs to upload dir... 12:21:21 INFO - mkdir: /builds/slave/test/build/upload/logs 12:21:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.195537 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2017-09-27 12:21:21.732758) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 12:21:21.735950) ========= 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=923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146 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/12f9a2c783daaef5a441eacd36f3e7b0cb7956277ab4d102be357ca5dcdf5a591916ad8e4b36b39387bb4a941f0001b6ca54d5d682890a07c0e7aac8dcafa9ed", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1f446a50c38d78cf84764ea3a3ab6944a4b989ec37f134b6abbb0162d9cf1b8754bc77bad6bad2ce6d5a6ffabd03b09f84f7478bc2a827db028c85ba05c7564", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61153dd8e04d2ff3065dbe62a37eed4de0371b133153affc279249ed539cd48e8659c4e4dbb27ea06295c1fb25e75695a1fd54b0c699c9d706c18f9a95813754", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae034e37245859bf25a091f3191cc8a4a023511e72e5feb0f75f3ae2686f1402c7bb29fced3a6a2b7631ad45d51c72c2653b8ea6b66fe0a0d9523cb14e75a3e3"} build_url:https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011442 build_url: 'https://queue.taskcluster.net/v1/task/PBHu4AnVT_OJapiiUJnugg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12f9a2c783daaef5a441eacd36f3e7b0cb7956277ab4d102be357ca5dcdf5a591916ad8e4b36b39387bb4a941f0001b6ca54d5d682890a07c0e7aac8dcafa9ed", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b1f446a50c38d78cf84764ea3a3ab6944a4b989ec37f134b6abbb0162d9cf1b8754bc77bad6bad2ce6d5a6ffabd03b09f84f7478bc2a827db028c85ba05c7564", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61153dd8e04d2ff3065dbe62a37eed4de0371b133153affc279249ed539cd48e8659c4e4dbb27ea06295c1fb25e75695a1fd54b0c699c9d706c18f9a95813754", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae034e37245859bf25a091f3191cc8a4a023511e72e5feb0f75f3ae2686f1402c7bb29fced3a6a2b7631ad45d51c72c2653b8ea6b66fe0a0d9523cb14e75a3e3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 12:21:21.765016) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 12:21:21.765377) ========= 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=923d835db2ba3686ebe126b700000192-1506538848.775454-1740892146 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004542 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 12:21:21.849884) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 12:21:21.850219) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 12:21:21.850630) ========= ========= Total master_lag: 0.19 =========