builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-011 starttime: 1506897985.89 results: success (0) revision: 44643fce30b43a8981535c335aaccb45006e456b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-01 15:46:25.885808) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-01 15:46:25.886255) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-01 15:46:25.886578) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-01 15:46:26.100485) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 15:46:26.100760) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-01 15:46:26-- 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.4M=0s 2017-10-01 15:46:26 (41.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151906 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 15:46:26.299477) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 15:46:26.299769) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016088 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 15:46:26.367553) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 15:46:26.367849) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 44643fce30b43a8981535c335aaccb45006e456b --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 44643fce30b43a8981535c335aaccb45006e456b --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=5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-01 15:46:26,481 truncating revision to first 12 chars 2017-10-01 15:46:26,481 Setting DEBUG logging. 2017-10-01 15:46:26,481 attempt 1/10 2017-10-01 15:46:26,481 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/44643fce30b4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-01 15:46:26,736 unpacking tar archive at: mozilla-central-44643fce30b4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.577308 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 15:46:26.961291) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-01 15:46:26.964595) ========= script_repo_revision: 44643fce30b43a8981535c335aaccb45006e456b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-01 15:46:26.964963) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-01 15:46:26.965229) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-01 15:46:26.987422) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-10-01 15:46:26.987732) ========= /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=5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:46:27 INFO - MultiFileLogger online at 20171001 15:46:27 in /builds/slave/test 15:46:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:46:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:46:27 INFO - {'append_to_log': False, 15:46:27 INFO - 'base_work_dir': '/builds/slave/test', 15:46:27 INFO - 'blob_upload_branch': 'Firefox', 15:46:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:46:27 INFO - 'branch': 'Firefox', 15:46:27 INFO - 'buildbot_json_path': 'buildprops.json', 15:46:27 INFO - 'code_coverage': False, 15:46:27 INFO - 'config_files': ('talos/linux_config.py',), 15:46:27 INFO - 'default_actions': ('clobber', 15:46:27 INFO - 'read-buildbot-config', 15:46:27 INFO - 'download-and-extract', 15:46:27 INFO - 'populate-webroot', 15:46:27 INFO - 'create-virtualenv', 15:46:27 INFO - 'install', 15:46:27 INFO - 'setup-mitmproxy', 15:46:27 INFO - 'run-tests'), 15:46:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:46:27 INFO - 'disable_ccov_upload': False, 15:46:27 INFO - 'disable_stylo': False, 15:46:27 INFO - 'download_minidump_stackwalk': True, 15:46:27 INFO - 'download_symbols': 'ondemand', 15:46:27 INFO - 'enable_stylo': False, 15:46:27 INFO - 'enable_webrender': False, 15:46:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:46:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:46:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:46:27 INFO - 'gecko_profile': False, 15:46:27 INFO - 'gecko_profile_interval': 0, 15:46:27 INFO - 'installer_path': 'installer.exe', 15:46:27 INFO - 'log_level': 'info', 15:46:27 INFO - 'log_name': 'talos', 15:46:27 INFO - 'log_to_console': True, 15:46:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:46:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:46:27 INFO - 'opt_config_files': (), 15:46:27 INFO - 'pip_index': False, 15:46:27 INFO - 'suite': 'dromaeojs-e10s', 15:46:27 INFO - 'system_bits': '32', 15:46:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:46:27 INFO - 'title': 'talos-linux64-ix-011', 15:46:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:46:27 INFO - 'use_talos_json': True, 15:46:27 INFO - 'verify': 'False', 15:46:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:46:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:46:27 INFO - 'work_dir': 'build'} 15:46:27 INFO - [mozharness: 2017-10-01 22:46:27.342972Z] Running clobber step. 15:46:27 INFO - Running pre-action listener: _resource_record_pre_action 15:46:27 INFO - Running main action method: clobber 15:46:27 INFO - rmtree: /builds/slave/test/build 15:46:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:46:28 INFO - Running post-action listener: _resource_record_post_action 15:46:28 INFO - [mozharness: 2017-10-01 22:46:28.306833Z] Finished clobber step (success) 15:46:28 INFO - [mozharness: 2017-10-01 22:46:28.306929Z] Running read-buildbot-config step. 15:46:28 INFO - Running pre-action listener: _resource_record_pre_action 15:46:28 INFO - Running main action method: read_buildbot_config 15:46:28 INFO - Using buildbot properties: 15:46:28 INFO - { 15:46:28 INFO - "project": "", 15:46:28 INFO - "product": "firefox", 15:46:28 INFO - "who": "archaeopteryx@coole-files.de", 15:46:28 INFO - "installer_path": "public/build/target.tar.bz2", 15:46:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 15:46:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:46:28 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 15:46:28 INFO - "stage_platform": "linux64-qr", 15:46:28 INFO - "basedir": "/builds/slave/test", 15:46:28 INFO - "buildnumber": 115, 15:46:28 INFO - "platform": "ubuntu64_hw_qr", 15:46:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:46:28 INFO - "slavebuilddir": "test", 15:46:28 INFO - "taskId": "ZVU4-nuaT3anwmA9obvutg", 15:46:28 INFO - "branch": "mozilla-central", 15:46:28 INFO - "script_repo_revision": "production", 15:46:28 INFO - "revision": "44643fce30b43a8981535c335aaccb45006e456b", 15:46:28 INFO - "slavename": "talos-linux64-ix-011", 15:46:28 INFO - "repo_path": "mozilla-central" 15:46:28 INFO - } 15:46:28 INFO - Finding installer, test and symbols from parent task. 15:46:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZVU4-nuaT3anwmA9obvutg'}, attempt #1 15:46:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZVU4-nuaT3anwmA9obvutg'}, attempt #1 15:46:29 INFO - Task dependencies: RpAtbshBTzOTjzSa-o9ISQ 15:46:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ'}, attempt #1 15:46:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.tar.bz2 15:46:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.test_packages.json 15:46:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.crashreporter-symbols.zip 15:46:29 INFO - Running post-action listener: _resource_record_post_action 15:46:29 INFO - [mozharness: 2017-10-01 22:46:29.622338Z] Finished read-buildbot-config step (success) 15:46:29 INFO - [mozharness: 2017-10-01 22:46:29.622556Z] Running download-and-extract step. 15:46:29 INFO - Running pre-action listener: _resource_record_pre_action 15:46:29 INFO - Running main action method: download_and_extract 15:46:29 INFO - mkdir: /builds/slave/test/build/tests 15:46:29 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')]} 15:46:29 INFO - trying https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.test_packages.json 15:46:29 INFO - Downloading https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:46:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:46:30 INFO - Downloaded 989 bytes. 15:46:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:46:30 INFO - Using the following test package requirements: 15:46:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:46:30 INFO - u'common': [u'target.common.tests.zip'], 15:46:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:46:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:46:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:46:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:46:30 INFO - u'mozbase': [u'target.common.tests.zip'], 15:46:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:46:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:46:30 INFO - u'web-platform': [u'target.common.tests.zip', 15:46:30 INFO - u'target.web-platform.tests.tar.gz'], 15:46:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:46:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:46:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.common.tests.zip 15:46:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:46:30 INFO - Fetch https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.common.tests.zip into memory 15:46:32 INFO - Content-Length response header: 55366756 15:46:32 INFO - Bytes received: 55366756 15:46:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.talos.tests.zip 15:46:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:46:43 INFO - Fetch https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.talos.tests.zip into memory 15:46:43 INFO - Content-Length response header: 14522385 15:46:43 INFO - Bytes received: 14522385 15:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:44 INFO - trying https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.tar.bz2 15:46:44 INFO - Downloading https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:46:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:46:46 INFO - Downloaded 61519623 bytes. 15:46:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.tar.bz2 15:46:46 INFO - mkdir: /builds/slave/test/properties 15:46:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:46:46 INFO - Writing to file /builds/slave/test/properties/build_url 15:46:46 INFO - Contents: 15:46:46 INFO - build_url:https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.tar.bz2 15:46:46 INFO - Running post-action listener: _resource_record_post_action 15:46:46 INFO - Running post-action listener: find_tests_for_verification 15:46:46 INFO - Running post-action listener: set_extra_try_arguments 15:46:46 INFO - [mozharness: 2017-10-01 22:46:46.561942Z] Finished download-and-extract step (success) 15:46:46 INFO - [mozharness: 2017-10-01 22:46:46.562090Z] Running populate-webroot step. 15:46:46 INFO - Running pre-action listener: _resource_record_pre_action 15:46:46 INFO - Running main action method: populate_webroot 15:46:46 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:46:46 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:46:46 INFO - 'tests': ['tresize', 15:46:46 INFO - 'tcanvasmark']}, 15:46:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:46:46 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:46:46 INFO - 'tests': ['dromaeo_css', 15:46:46 INFO - 'kraken']}, 15:46:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:46:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:46 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:46:46 INFO - 'talos_options': ['--disable-stylo'], 15:46:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:46:46 INFO - 'tests': ['damp', 'tps']}, 15:46:46 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:46:46 INFO - 'talos_options': ['--disable-stylo'], 15:46:46 INFO - 'tests': ['damp', 'tps']}, 15:46:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:46:46 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:46:46 INFO - 'tests': ['dromaeo_dom']}, 15:46:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:46:46 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:46:46 INFO - 'tests': ['basic_compositor_video', 15:46:46 INFO - 'glvideo']}, 15:46:46 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:46:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:46:46 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:46:46 INFO - 'talos_options': ['--disable-stylo'], 15:46:46 INFO - 'tests': ['ts_paint_webext', 15:46:46 INFO - 'tp5o_webext']}, 15:46:46 INFO - 'other-e10s': {'tests': ['a11yr', 15:46:46 INFO - 'ts_paint', 15:46:46 INFO - 'tpaint', 15:46:46 INFO - 'sessionrestore', 15:46:46 INFO - 'sessionrestore_many_windows', 15:46:46 INFO - 'sessionrestore_no_auto_restore', 15:46:46 INFO - 'tabpaint', 15:46:46 INFO - 'cpstartup']}, 15:46:46 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:46:46 INFO - 'tests': ['a11yr', 15:46:46 INFO - 'ts_paint', 15:46:46 INFO - 'tpaint', 15:46:46 INFO - 'sessionrestore', 15:46:46 INFO - 'sessionrestore_many_windows', 15:46:46 INFO - 'sessionrestore_no_auto_restore', 15:46:46 INFO - 'tabpaint', 15:46:46 INFO - 'cpstartup']}, 15:46:46 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 15:46:46 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 15:46:46 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:46:46 INFO - 'tests': ['perf_reftest_singletons']}, 15:46:46 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:46:46 INFO - 'tests': ['perf_reftest']}, 15:46:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:46:46 INFO - 'tsvgr_opacity', 15:46:46 INFO - 'tart', 15:46:46 INFO - 'tscrollx', 15:46:46 INFO - 'tsvg_static']}, 15:46:46 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:46:46 INFO - 'tests': ['tsvgx', 15:46:46 INFO - 'tsvgr_opacity', 15:46:46 INFO - 'tart', 15:46:46 INFO - 'tscrollx', 15:46:46 INFO - 'tsvg_static']}, 15:46:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:46:46 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:46:46 INFO - 'talos_options': ['--disable-stylo'], 15:46:46 INFO - 'tests': ['tp5o']}, 15:46:46 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:46:46 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:46:46 INFO - 'talos_options': ['--mitmproxy', 15:46:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:46:46 INFO - '--firstNonBlankPaint'], 15:46:46 INFO - 'tests': ['tp6_google', 15:46:46 INFO - 'tp6_youtube', 15:46:46 INFO - 'tp6_amazon', 15:46:46 INFO - 'tp6_facebook']}, 15:46:46 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:46:46 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:46:46 INFO - 'talos_options': ['--disable-stylo', 15:46:46 INFO - '--mitmproxy', 15:46:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:46:46 INFO - '--firstNonBlankPaint'], 15:46:46 INFO - 'tests': ['tp6_google', 15:46:46 INFO - 'tp6_youtube', 15:46:46 INFO - 'tp6_amazon', 15:46:46 INFO - 'tp6_facebook']}, 15:46:46 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:46:46 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:46:46 INFO - 'talos_options': ['--stylo-threads=1', 15:46:46 INFO - '--mitmproxy', 15:46:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:46:46 INFO - '--firstNonBlankPaint'], 15:46:46 INFO - 'tests': ['tp6_google', 15:46:46 INFO - 'tp6_youtube', 15:46:46 INFO - 'tp6_amazon', 15:46:46 INFO - 'tp6_facebook']}, 15:46:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:46:46 INFO - 'talos_options': ['--xperf_path', 15:46:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:46 INFO - 'tests': ['tp5n']}, 15:46:46 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:46:46 INFO - 'talos_options': ['--disable-stylo', 15:46:46 INFO - '--xperf_path', 15:46:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:46 INFO - 'tests': ['tp5n']}}} 15:46:46 INFO - Running post-action listener: _resource_record_post_action 15:46:46 INFO - [mozharness: 2017-10-01 22:46:46.579590Z] Finished populate-webroot step (success) 15:46:46 INFO - [mozharness: 2017-10-01 22:46:46.579679Z] Running create-virtualenv step. 15:46:46 INFO - Running pre-action listener: _resource_record_pre_action 15:46:46 INFO - Running main action method: create_virtualenv 15:46:46 INFO - Creating virtualenv /builds/slave/test/build/venv 15:46:46 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:46:46 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:46:46 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:46:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:46:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:46:49 INFO - Installing distribute.............................................................................................................................................................................................done. 15:46:49 INFO - Installing pip...............done. 15:46:49 INFO - Return code: 0 15:46:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:46:50 INFO - Reading from file tmpfile_stdout 15:46:50 INFO - Output received: 15:46:50 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:46:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ad60>, '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 0x266ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x266bef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', '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 15:46:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:46:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:46:50 INFO - Using env: {'DISPLAY': ':0', 15:46:50 INFO - 'HOME': '/home/cltbld', 15:46:50 INFO - 'LANG': 'en_US.UTF-8', 15:46:50 INFO - 'LANGUAGE': 'en_US:en', 15:46:50 INFO - 'LOGNAME': 'cltbld', 15:46:50 INFO - 'MAIL': '/var/mail/cltbld', 15:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:50 INFO - 'MOZ_NO_REMOTE': '1', 15:46:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:50 INFO - 'NO_EM_RESTART': '1', 15:46:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:50 INFO - 'PWD': '/builds/slave/test', 15:46:50 INFO - 'SHELL': '/bin/bash', 15:46:50 INFO - 'SHLVL': '1', 15:46:50 INFO - 'TERM': 'linux', 15:46:50 INFO - 'TMOUT': '86400', 15:46:50 INFO - 'USER': 'cltbld', 15:46:50 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', 15:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:50 INFO - '_': '/tools/buildbot/bin/python'} 15:46:51 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:46:51 INFO - Downloading/unpacking pip>=1.5 15:46:51 INFO - Running setup.py egg_info for package pip 15:46:51 INFO - warning: no files found matching 'pip/cacert.pem' 15:46:51 INFO - warning: no files found matching '*.html' under directory 'docs' 15:46:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:46:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:46:51 INFO - Installing collected packages: pip 15:46:51 INFO - Found existing installation: pip 1.1 15:46:51 INFO - Uninstalling pip: 15:46:51 INFO - Successfully uninstalled pip 15:46:51 INFO - Running setup.py install for pip 15:46:51 INFO - warning: no files found matching 'pip/cacert.pem' 15:46:51 INFO - warning: no files found matching '*.html' under directory 'docs' 15:46:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:46:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:46:51 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:46:51 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:46:51 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:46:51 INFO - Successfully installed pip 15:46:51 INFO - Cleaning up... 15:46:51 INFO - Return code: 0 15:46:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:46:51 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')]} 15:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:51 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')]} 15:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:51 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 0x23001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ad60>, '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 0x266ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x266bef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', '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 15:46:51 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 15:46:51 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 15:46:51 INFO - Using env: {'DISPLAY': ':0', 15:46:51 INFO - 'HOME': '/home/cltbld', 15:46:51 INFO - 'LANG': 'en_US.UTF-8', 15:46:51 INFO - 'LANGUAGE': 'en_US:en', 15:46:51 INFO - 'LOGNAME': 'cltbld', 15:46:51 INFO - 'MAIL': '/var/mail/cltbld', 15:46:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:51 INFO - 'MOZ_NO_REMOTE': '1', 15:46:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:51 INFO - 'NO_EM_RESTART': '1', 15:46:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:51 INFO - 'PWD': '/builds/slave/test', 15:46:51 INFO - 'SHELL': '/bin/bash', 15:46:51 INFO - 'SHLVL': '1', 15:46:51 INFO - 'TERM': 'linux', 15:46:51 INFO - 'TMOUT': '86400', 15:46:51 INFO - 'USER': 'cltbld', 15:46:51 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', 15:46:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:51 INFO - '_': '/tools/buildbot/bin/python'} 15:46:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:51 INFO - Downloading/unpacking psutil>=3.1.1 15:46:51 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 15:46:51 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 15:46:51 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 15:46:51 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 15:46:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:46:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:46:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:46:52 INFO - Installing collected packages: psutil 15:46:52 INFO - Running setup.py install for psutil 15:46:52 INFO - building 'psutil._psutil_linux' extension 15:46:52 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 15:46:53 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 15:46:54 INFO - building 'psutil._psutil_posix' extension 15:46:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:46:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:46:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:46:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:46:54 INFO - Successfully installed psutil 15:46:54 INFO - Cleaning up... 15:46:54 INFO - Return code: 0 15:46:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ad60>, '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 0x266ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x266bef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', '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 15:46:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:46:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:46:54 INFO - Using env: {'DISPLAY': ':0', 15:46:54 INFO - 'HOME': '/home/cltbld', 15:46:54 INFO - 'LANG': 'en_US.UTF-8', 15:46:54 INFO - 'LANGUAGE': 'en_US:en', 15:46:54 INFO - 'LOGNAME': 'cltbld', 15:46:54 INFO - 'MAIL': '/var/mail/cltbld', 15:46:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:54 INFO - 'MOZ_NO_REMOTE': '1', 15:46:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:54 INFO - 'NO_EM_RESTART': '1', 15:46:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:54 INFO - 'PWD': '/builds/slave/test', 15:46:54 INFO - 'SHELL': '/bin/bash', 15:46:54 INFO - 'SHLVL': '1', 15:46:54 INFO - 'TERM': 'linux', 15:46:54 INFO - 'TMOUT': '86400', 15:46:54 INFO - 'USER': 'cltbld', 15:46:54 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', 15:46:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:54 INFO - '_': '/tools/buildbot/bin/python'} 15:46:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:46:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:46:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:46:55 INFO - Installing collected packages: mozsystemmonitor 15:46:55 INFO - Running setup.py install for mozsystemmonitor 15:46:55 INFO - Successfully installed mozsystemmonitor 15:46:55 INFO - Cleaning up... 15:46:55 INFO - Return code: 0 15:46:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ad60>, '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 0x266ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x266bef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', '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 15:46:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:46:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:46:55 INFO - Using env: {'DISPLAY': ':0', 15:46:55 INFO - 'HOME': '/home/cltbld', 15:46:55 INFO - 'LANG': 'en_US.UTF-8', 15:46:55 INFO - 'LANGUAGE': 'en_US:en', 15:46:55 INFO - 'LOGNAME': 'cltbld', 15:46:55 INFO - 'MAIL': '/var/mail/cltbld', 15:46:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:55 INFO - 'MOZ_NO_REMOTE': '1', 15:46:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:55 INFO - 'NO_EM_RESTART': '1', 15:46:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:55 INFO - 'PWD': '/builds/slave/test', 15:46:55 INFO - 'SHELL': '/bin/bash', 15:46:55 INFO - 'SHLVL': '1', 15:46:55 INFO - 'TERM': 'linux', 15:46:55 INFO - 'TMOUT': '86400', 15:46:55 INFO - 'USER': 'cltbld', 15:46:55 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', 15:46:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:55 INFO - '_': '/tools/buildbot/bin/python'} 15:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:56 INFO - Downloading/unpacking jsonschema==2.5.1 15:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:46:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:57 INFO - Downloading functools32-3.2.3-2.tar.gz 15:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:46:57 INFO - warning: no files found matching '*.txt' 15:46:57 INFO - no previously-included directories found matching 'build' 15:46:57 INFO - no previously-included directories found matching 'dist' 15:46:57 INFO - no previously-included directories found matching '.git*' 15:46:57 INFO - Installing collected packages: jsonschema, functools32 15:46:57 INFO - Running setup.py install for functools32 15:46:57 INFO - warning: no files found matching '*.txt' 15:46:57 INFO - no previously-included directories found matching 'build' 15:46:57 INFO - no previously-included directories found matching 'dist' 15:46:57 INFO - no previously-included directories found matching '.git*' 15:46:57 INFO - Successfully installed jsonschema functools32 15:46:57 INFO - Cleaning up... 15:46:57 INFO - Return code: 0 15:46:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ad60>, '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 0x266ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x266bef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', '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 15:46:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:46:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:46:57 INFO - Using env: {'DISPLAY': ':0', 15:46:57 INFO - 'HOME': '/home/cltbld', 15:46:57 INFO - 'LANG': 'en_US.UTF-8', 15:46:57 INFO - 'LANGUAGE': 'en_US:en', 15:46:57 INFO - 'LOGNAME': 'cltbld', 15:46:57 INFO - 'MAIL': '/var/mail/cltbld', 15:46:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:57 INFO - 'MOZ_NO_REMOTE': '1', 15:46:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:57 INFO - 'NO_EM_RESTART': '1', 15:46:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:57 INFO - 'PWD': '/builds/slave/test', 15:46:57 INFO - 'SHELL': '/bin/bash', 15:46:57 INFO - 'SHLVL': '1', 15:46:57 INFO - 'TERM': 'linux', 15:46:57 INFO - 'TMOUT': '86400', 15:46:57 INFO - 'USER': 'cltbld', 15:46:57 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', 15:46:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:57 INFO - '_': '/tools/buildbot/bin/python'} 15:46:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:46:57 INFO - Cleaning up... 15:46:57 INFO - Return code: 0 15:46:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ad60>, '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 0x266ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x266bef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', '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 15:46:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:46:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:46:57 INFO - Using env: {'DISPLAY': ':0', 15:46:57 INFO - 'HOME': '/home/cltbld', 15:46:57 INFO - 'LANG': 'en_US.UTF-8', 15:46:57 INFO - 'LANGUAGE': 'en_US:en', 15:46:57 INFO - 'LOGNAME': 'cltbld', 15:46:57 INFO - 'MAIL': '/var/mail/cltbld', 15:46:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:57 INFO - 'MOZ_NO_REMOTE': '1', 15:46:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:57 INFO - 'NO_EM_RESTART': '1', 15:46:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:57 INFO - 'PWD': '/builds/slave/test', 15:46:57 INFO - 'SHELL': '/bin/bash', 15:46:57 INFO - 'SHLVL': '1', 15:46:57 INFO - 'TERM': 'linux', 15:46:57 INFO - 'TMOUT': '86400', 15:46:57 INFO - 'USER': 'cltbld', 15:46:57 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', 15:46:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:57 INFO - '_': '/tools/buildbot/bin/python'} 15:46:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:58 INFO - Downloading/unpacking blobuploader==1.2.4 15:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:59 INFO - Downloading blobuploader-1.2.4.tar.gz 15:46:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:46:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:46:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:59 INFO - Downloading docopt-0.6.1.tar.gz 15:46:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:47:00 INFO - Installing collected packages: blobuploader, requests, docopt 15:47:00 INFO - Running setup.py install for blobuploader 15:47:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:47:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:47:00 INFO - Running setup.py install for requests 15:47:00 INFO - Running setup.py install for docopt 15:47:00 INFO - Successfully installed blobuploader requests docopt 15:47:00 INFO - Cleaning up... 15:47:00 INFO - Return code: 0 15:47:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ad60>, '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 0x266ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x266bef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', '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 15:47:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:47:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:47:00 INFO - Using env: {'DISPLAY': ':0', 15:47:00 INFO - 'HOME': '/home/cltbld', 15:47:00 INFO - 'LANG': 'en_US.UTF-8', 15:47:00 INFO - 'LANGUAGE': 'en_US:en', 15:47:00 INFO - 'LOGNAME': 'cltbld', 15:47:00 INFO - 'MAIL': '/var/mail/cltbld', 15:47:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:00 INFO - 'MOZ_NO_REMOTE': '1', 15:47:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:00 INFO - 'NO_EM_RESTART': '1', 15:47:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:00 INFO - 'PWD': '/builds/slave/test', 15:47:00 INFO - 'SHELL': '/bin/bash', 15:47:00 INFO - 'SHLVL': '1', 15:47:00 INFO - 'TERM': 'linux', 15:47:00 INFO - 'TMOUT': '86400', 15:47:00 INFO - 'USER': 'cltbld', 15:47:00 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', 15:47:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:00 INFO - '_': '/tools/buildbot/bin/python'} 15:47:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:47:00 INFO - Running setup.py (path:/tmp/pip-wVx8X7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:47:00 INFO - Running setup.py (path:/tmp/pip-NtT7dz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:47:00 INFO - Running setup.py (path:/tmp/pip-AAKUHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:47:01 INFO - Running setup.py (path:/tmp/pip-Gv7OXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:47:01 INFO - Running setup.py (path:/tmp/pip-pUWJCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:47:01 INFO - Running setup.py (path:/tmp/pip-Vm9TRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:47:01 INFO - Running setup.py (path:/tmp/pip-jIkwew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:47:01 INFO - Running setup.py (path:/tmp/pip-5zPtWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:47:01 INFO - Running setup.py (path:/tmp/pip-kUmEsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:47:01 INFO - Running setup.py (path:/tmp/pip-tb3ZVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:47:01 INFO - Running setup.py (path:/tmp/pip-rVjeHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:47:01 INFO - Running setup.py (path:/tmp/pip-cgNirS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:47:01 INFO - Running setup.py (path:/tmp/pip-EPuvIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:47:01 INFO - Running setup.py (path:/tmp/pip-FDq1Dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:01 INFO - Running setup.py (path:/tmp/pip-5AtgWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:47:01 INFO - Running setup.py (path:/tmp/pip-ApYS2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:47:01 INFO - Running setup.py (path:/tmp/pip-S0JJpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:47:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:47:02 INFO - Running setup.py install for manifestparser 15:47:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Running setup.py install for mozcrash 15:47:02 INFO - Running setup.py install for mozdebug 15:47:02 INFO - Running setup.py install for mozdevice 15:47:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Running setup.py install for mozfile 15:47:02 INFO - Running setup.py install for mozhttpd 15:47:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Running setup.py install for mozinfo 15:47:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Running setup.py install for mozInstall 15:47:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:47:02 INFO - Running setup.py install for mozleak 15:47:02 INFO - Running setup.py install for mozlog 15:47:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:47:03 INFO - Running setup.py install for moznetwork 15:47:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:47:03 INFO - Running setup.py install for mozprocess 15:47:03 INFO - Running setup.py install for mozprofile 15:47:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:47:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:47:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:47:03 INFO - Running setup.py install for mozrunner 15:47:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:47:03 INFO - Running setup.py install for mozscreenshot 15:47:03 INFO - Running setup.py install for moztest 15:47:03 INFO - Running setup.py install for mozversion 15:47:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:47:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:47:03 INFO - Cleaning up... 15:47:03 INFO - Return code: 0 15:47:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ad60>, '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 0x266ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x266bef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', '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 15:47:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:47:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:47:03 INFO - Using env: {'DISPLAY': ':0', 15:47:03 INFO - 'HOME': '/home/cltbld', 15:47:03 INFO - 'LANG': 'en_US.UTF-8', 15:47:03 INFO - 'LANGUAGE': 'en_US:en', 15:47:03 INFO - 'LOGNAME': 'cltbld', 15:47:03 INFO - 'MAIL': '/var/mail/cltbld', 15:47:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:03 INFO - 'MOZ_NO_REMOTE': '1', 15:47:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:03 INFO - 'NO_EM_RESTART': '1', 15:47:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:03 INFO - 'PWD': '/builds/slave/test', 15:47:03 INFO - 'SHELL': '/bin/bash', 15:47:03 INFO - 'SHLVL': '1', 15:47:03 INFO - 'TERM': 'linux', 15:47:03 INFO - 'TMOUT': '86400', 15:47:03 INFO - 'USER': 'cltbld', 15:47:03 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', 15:47:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:03 INFO - '_': '/tools/buildbot/bin/python'} 15:47:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:47:03 INFO - Running setup.py (path:/tmp/pip-ekxhK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:47:04 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)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:47:04 INFO - Running setup.py (path:/tmp/pip-sot_Rg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:47:04 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)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:47:04 INFO - Running setup.py (path:/tmp/pip-8A7qri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:47:04 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)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:47:04 INFO - Running setup.py (path:/tmp/pip-9HmgzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:47:04 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)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:47:04 INFO - Running setup.py (path:/tmp/pip-8IEzhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:47:04 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)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:47:04 INFO - Running setup.py (path:/tmp/pip-P2JUP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:47:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:47:04 INFO - Running setup.py (path:/tmp/pip-3Y22yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:47:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:47:04 INFO - Running setup.py (path:/tmp/pip-gKeft4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:47:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:47:04 INFO - Running setup.py (path:/tmp/pip-7O42B5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:47:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:47:04 INFO - Running setup.py (path:/tmp/pip-hJFLIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:47:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:47:04 INFO - Running setup.py (path:/tmp/pip-cEO8tq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:47:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:47:04 INFO - Running setup.py (path:/tmp/pip-qTd9kC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:47:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:47:04 INFO - Running setup.py (path:/tmp/pip-Z9S8Rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:47:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:47:04 INFO - Running setup.py (path:/tmp/pip-ryUraB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:47:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:04 INFO - Running setup.py (path:/tmp/pip-D3eGny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:05 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)) 15:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:47:05 INFO - Running setup.py (path:/tmp/pip-cwddP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:47:05 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)) 15:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:47:05 INFO - Running setup.py (path:/tmp/pip-t8986D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:47:05 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)) 15:47:05 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)) 15:47:05 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)) 15:47:05 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)) 15:47:05 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)) 15:47:05 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)) 15:47:05 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)) 15:47:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:47:05 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 15:47:05 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 15:47:05 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 15:47:05 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 15:47:06 INFO - Downloading blessings-1.6.tar.gz 15:47:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:47:06 INFO - Installing collected packages: blessings 15:47:06 INFO - Running setup.py install for blessings 15:47:06 INFO - Successfully installed blessings 15:47:06 INFO - Cleaning up... 15:47:06 INFO - Return code: 0 15:47:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:47:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:47:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:47:06 INFO - Reading from file tmpfile_stdout 15:47:06 INFO - Current package versions: 15:47:06 INFO - argparse == 1.2.1 15:47:06 INFO - blessings == 1.6 15:47:06 INFO - blobuploader == 1.2.4 15:47:06 INFO - docopt == 0.6.1 15:47:06 INFO - functools32 == 3.2.3-2 15:47:06 INFO - jsonschema == 2.5.1 15:47:06 INFO - manifestparser == 1.1 15:47:06 INFO - mozInstall == 1.13 15:47:06 INFO - mozcrash == 1.0 15:47:06 INFO - mozdebug == 0.1 15:47:06 INFO - mozdevice == 0.51 15:47:06 INFO - mozfile == 1.2 15:47:06 INFO - mozhttpd == 0.7 15:47:06 INFO - mozinfo == 0.10 15:47:06 INFO - mozleak == 0.1 15:47:06 INFO - mozlog == 3.5 15:47:06 INFO - moznetwork == 0.27 15:47:06 INFO - mozprocess == 0.25 15:47:06 INFO - mozprofile == 0.28 15:47:06 INFO - mozrunner == 6.13 15:47:06 INFO - mozscreenshot == 0.1 15:47:06 INFO - mozsystemmonitor == 0.3 15:47:06 INFO - moztest == 0.8 15:47:06 INFO - mozversion == 1.4 15:47:06 INFO - psutil == 3.1.1 15:47:06 INFO - requests == 1.2.3 15:47:06 INFO - wsgiref == 0.1.2 15:47:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ad60>, '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 0x266ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x266bef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', '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 15:47:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:47:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:47:06 INFO - Using env: {'DISPLAY': ':0', 15:47:06 INFO - 'HOME': '/home/cltbld', 15:47:06 INFO - 'LANG': 'en_US.UTF-8', 15:47:06 INFO - 'LANGUAGE': 'en_US:en', 15:47:06 INFO - 'LOGNAME': 'cltbld', 15:47:06 INFO - 'MAIL': '/var/mail/cltbld', 15:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:06 INFO - 'MOZ_NO_REMOTE': '1', 15:47:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:06 INFO - 'NO_EM_RESTART': '1', 15:47:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:06 INFO - 'PWD': '/builds/slave/test', 15:47:06 INFO - 'SHELL': '/bin/bash', 15:47:06 INFO - 'SHLVL': '1', 15:47:06 INFO - 'TERM': 'linux', 15:47:06 INFO - 'TMOUT': '86400', 15:47:06 INFO - 'USER': 'cltbld', 15:47:06 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', 15:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:06 INFO - '_': '/tools/buildbot/bin/python'} 15:47:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:47:07 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)) 15:47:07 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)) 15:47:07 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)) 15:47:07 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)) 15:47:07 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)) 15:47:07 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)) 15:47:07 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)) 15:47:07 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)) 15:47:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:47:07 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 15:47:07 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 15:47:07 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 15:47:07 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 15:47:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:47:08 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)) 15:47:08 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)) 15:47:08 INFO - Installing collected packages: simplejson 15:47:08 INFO - Running setup.py install for simplejson 15:47:08 INFO - building 'simplejson._speedups' extension 15:47:08 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 15:47:09 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 15:47:09 INFO - Successfully installed simplejson 15:47:09 INFO - Cleaning up... 15:47:09 INFO - Return code: 0 15:47:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:47:09 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')]} 15:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:09 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')]} 15:47:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:09 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 0x23001f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24c8b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266d9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ad60>, '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 0x266ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x266bef0>, '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': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', '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 15:47:09 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 15:47:09 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 15:47:09 INFO - Using env: {'DISPLAY': ':0', 15:47:09 INFO - 'HOME': '/home/cltbld', 15:47:09 INFO - 'LANG': 'en_US.UTF-8', 15:47:09 INFO - 'LANGUAGE': 'en_US:en', 15:47:09 INFO - 'LOGNAME': 'cltbld', 15:47:09 INFO - 'MAIL': '/var/mail/cltbld', 15:47:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:09 INFO - 'MOZ_NO_REMOTE': '1', 15:47:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:09 INFO - 'NO_EM_RESTART': '1', 15:47:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:09 INFO - 'PWD': '/builds/slave/test', 15:47:09 INFO - 'SHELL': '/bin/bash', 15:47:09 INFO - 'SHLVL': '1', 15:47:09 INFO - 'TERM': 'linux', 15:47:09 INFO - 'TMOUT': '86400', 15:47:09 INFO - 'USER': 'cltbld', 15:47:09 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', 15:47:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:09 INFO - '_': '/tools/buildbot/bin/python'} 15:47:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:47:09 INFO - Cleaning up... 15:47:09 INFO - Return code: 0 15:47:09 INFO - Running post-action listener: _resource_record_post_action 15:47:09 INFO - Running post-action listener: _start_resource_monitoring 15:47:09 INFO - Starting resource monitoring. 15:47:09 INFO - [mozharness: 2017-10-01 22:47:09.520582Z] Finished create-virtualenv step (success) 15:47:09 INFO - [mozharness: 2017-10-01 22:47:09.521619Z] Running install step. 15:47:09 INFO - Running pre-action listener: _resource_record_pre_action 15:47:09 INFO - Running main action method: install 15:47:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:47:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:47:09 INFO - Reading from file tmpfile_stdout 15:47:09 INFO - Detecting whether we're running mozinstall >=1.0... 15:47:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:47:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:47:09 INFO - Reading from file tmpfile_stdout 15:47:09 INFO - Output received: 15:47:09 INFO - Usage: mozinstall [options] installer 15:47:09 INFO - Options: 15:47:09 INFO - -h, --help show this help message and exit 15:47:09 INFO - -d DEST, --destination=DEST 15:47:09 INFO - Directory to install application into. [default: 15:47:09 INFO - "/builds/slave/test"] 15:47:09 INFO - --app=APP Application being installed. [default: firefox] 15:47:09 INFO - mkdir: /builds/slave/test/build/application 15:47:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:47:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:47:27 INFO - Reading from file tmpfile_stdout 15:47:27 INFO - Output received: 15:47:27 INFO - /builds/slave/test/build/application/firefox/firefox 15:47:27 INFO - Running post-action listener: _resource_record_post_action 15:47:27 INFO - [mozharness: 2017-10-01 22:47:27.616521Z] Finished install step (success) 15:47:27 INFO - [mozharness: 2017-10-01 22:47:27.616772Z] Running setup-mitmproxy step. 15:47:27 INFO - Running pre-action listener: _resource_record_pre_action 15:47:27 INFO - Running main action method: setup_mitmproxy 15:47:27 INFO - Skipping: mitmproxy is not required 15:47:27 INFO - Running post-action listener: _resource_record_post_action 15:47:27 INFO - [mozharness: 2017-10-01 22:47:27.617894Z] Finished setup-mitmproxy step (success) 15:47:27 INFO - [mozharness: 2017-10-01 22:47:27.618129Z] Running run-tests step. 15:47:27 INFO - Running pre-action listener: _resource_record_pre_action 15:47:27 INFO - Running pre-action listener: _set_gcov_prefix 15:47:27 INFO - Running main action method: run_tests 15:47:27 WARNING - Try message not found. 15:47:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:47:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:47:27 INFO - Python 2.7.3 15:47:27 INFO - Return code: 0 15:47:27 INFO - grabbing minidump binary from tooltool 15:47: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')]} 15:47:27 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x266ad60>, '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 0x266ba60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x266bef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:47:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:47:27 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:47:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:47:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:47:27 INFO - Return code: 0 15:47:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:47:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:47:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:47:27 INFO - ENV: RUST_BACKTRACE is now full 15:47:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:47:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:47:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 15:47:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 15:47:27 INFO - Using env: {'DISPLAY': ':0', 15:47:27 INFO - 'HOME': '/home/cltbld', 15:47:27 INFO - 'LANG': 'en_US.UTF-8', 15:47:27 INFO - 'LANGUAGE': 'en_US:en', 15:47:27 INFO - 'LOGNAME': 'cltbld', 15:47:27 INFO - 'MAIL': '/var/mail/cltbld', 15:47:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:47:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:47:27 INFO - 'MOZ_ACCELERATED': '1', 15:47:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:27 INFO - 'MOZ_NO_REMOTE': '1', 15:47:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:47:27 INFO - 'MOZ_WEBRENDER': '1', 15:47:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:27 INFO - 'NO_EM_RESTART': '1', 15:47:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:27 INFO - 'PWD': '/builds/slave/test', 15:47:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:47:27 INFO - 'RUST_BACKTRACE': 'full', 15:47:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:47:27 INFO - 'SHELL': '/bin/bash', 15:47:27 INFO - 'SHLVL': '1', 15:47:27 INFO - 'TERM': 'linux', 15:47:27 INFO - 'TMOUT': '86400', 15:47:27 INFO - 'USER': 'cltbld', 15:47:27 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', 15:47:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:27 INFO - '_': '/tools/buildbot/bin/python'} 15:47:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 15:47:28 INFO - mozversion application_buildid: 20171001215204 15:47:28 INFO - mozversion application_changeset: 44643fce30b43a8981535c335aaccb45006e456b 15:47:28 INFO - mozversion application_display_name: FirefoxNightly 15:47:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:47:28 INFO - mozversion application_name: Firefox 15:47:28 INFO - mozversion application_remotingname: firefox 15:47:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:47:28 INFO - mozversion application_vendor: Mozilla 15:47:28 INFO - mozversion application_version: 58.0a1 15:47:28 INFO - mozversion platform_buildid: 20171001215204 15:47:28 INFO - mozversion platform_changeset: 44643fce30b43a8981535c335aaccb45006e456b 15:47:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:47:28 INFO - mozversion platform_version: 58.0a1 15:47:28 INFO - using testdate: 1506898048 15:47:28 INFO - actual date: 1506898048 15:47:28 INFO - starting webserver on 'localhost:37156' 15:47:28 INFO - SUITE-START | Running 2 tests 15:47:28 INFO - TEST-START | dromaeo_css 15:47:28 INFO - Initialising browser for dromaeo_css test... 15:47:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37156/getInfo.html -profile /tmp/tmpQe3GLj/profile 15:47:28 INFO - TEST-INFO | started process 3869 (/builds/slave/test/build/application/firefox/firefox http://localhost:37156/getInfo.html) 15:47:38 INFO - TEST-INFO | 3869: exit 0 15:47:38 INFO - Browser initialized. 15:47:38 INFO - Running cycle 1/1 for dromaeo_css test... 15:47:38 INFO - Using env: {'DISPLAY': ':0', 15:47:38 INFO - 'HOME': '/home/cltbld', 15:47:38 INFO - 'JSGC_DISABLE_POISONING': '1', 15:47:38 INFO - 'LANG': 'en_US.UTF-8', 15:47:38 INFO - 'LANGUAGE': 'en_US:en', 15:47:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:47:38 INFO - 'LOGNAME': 'cltbld', 15:47:38 INFO - 'MAIL': '/var/mail/cltbld', 15:47:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:47:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:47:38 INFO - 'MOZ_ACCELERATED': '1', 15:47:38 INFO - 'MOZ_CRASHREPORTER': '1', 15:47:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:47:38 INFO - 'MOZ_NO_REMOTE': '1', 15:47:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:47:38 INFO - 'MOZ_WEBRENDER': '1', 15:47:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:38 INFO - 'NO_EM_RESTART': '1', 15:47:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:38 INFO - 'PWD': '/builds/slave/test', 15:47:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:47:38 INFO - 'RUST_BACKTRACE': 'full', 15:47:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:47:38 INFO - 'SHELL': '/bin/bash', 15:47:38 INFO - 'SHLVL': '1', 15:47:38 INFO - 'TERM': 'linux', 15:47:38 INFO - 'TMOUT': '86400', 15:47:38 INFO - 'USER': 'cltbld', 15:47:38 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', 15:47:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:38 INFO - '_': '/tools/buildbot/bin/python'} 15:47:38 INFO - TEST-INFO | started process 4116 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQe3GLj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:47:39 INFO - PID 4116 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:47:39 INFO - PID 4116 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:47:39 INFO - PID 4116 | 15:47:39 INFO - PID 4116 | (/builds/slave/test/build/application/firefox/firefox:4175): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:47:39 INFO - PID 4116 | 15:47:50 INFO - PID 4116 | RSS: Main: 186642432 15:47:50 INFO - PID 4116 | 15:50:47 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37156/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37156/tests/dromaeo/cssquery-ext.html) 15:50:49 INFO - PID 4116 | RSS: Main: 185868288 15:50:49 INFO - PID 4116 | 15:53:51 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37156/tests/dromaeo/cssquery-ext.html (next: http://localhost:37156/tests/dromaeo/cssquery-jquery.html) 15:53:52 INFO - PID 4116 | RSS: Main: 185012224 15:53:52 INFO - PID 4116 | 15:56:54 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37156/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37156/tests/dromaeo/cssquery-mootools.html) 15:56:55 INFO - PID 4116 | RSS: Main: 186253312 15:56:55 INFO - PID 4116 | 15:59:57 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37156/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37156/tests/dromaeo/cssquery-prototype.html) 15:59:57 INFO - PID 4116 | RSS: Main: 185831424 15:59:57 INFO - PID 4116 | 16:03:01 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37156/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37156/tests/dromaeo/cssquery-yui.html) 16:03:02 INFO - PID 4116 | RSS: Main: 188641280 16:03:02 INFO - PID 4116 | 16:06:04 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37156/tests/dromaeo/cssquery-yui.html (next: http://localhost:37156/tests/dromaeo/cssquery-dojo.html) 16:06:04 INFO - PID 4116 | RSS: Main: 190197760 16:06:04 INFO - PID 4116 | 16:06:04 INFO - PID 4116 | __start_tp_report 16:06:04 INFO - PID 4116 | _x_x_mozilla_page_load 16:06:04 INFO - PID 4116 | _x_x_mozilla_page_load_details 16:06:04 INFO - PID 4116 | |i|pagename|runs| 16:06:04 INFO - PID 4116 | |0;dojo.html;3106.301082256492;3173.4763763978945;3173.682631736826;3235.158858696737;3261.0542942546663;6151.04658777889;6199.070139479075;6256.84357891053;6259.8122056338325;6336.968315158423;6171.62970221787;6190.535709821759;6230;6234.812955611327;6258.436740693348;5638.689872057035;5661.60368774186;5672;5672.631278966854;5673.347565030012;5699.487046165844;5721.056025755766;5771.653700777967;5821.476067153955;5842.795502157425;5774.624649397776;5821.243238379026;5823.359430462646;5828.388019257981;5899.557533185029;5788.82633520995;5823.88352232953;5841;5881.206037184989;5903.734331955073;5529.944700552983;5667.0366037773265;5669.688167150805;5737.196792449061;5828.242328497311;6221.564490485668;6248.875022499525;6290.056491526262;6314.400131987454;6396.456301214403;6418.133551970471;6666.533342666015;6668.666566671647;6756.662166891636;6789.287124851919;6586.37429444202;6644.43522300605;6746.763863264762;6780.864382712318;6787.389134977826;6179.938200618026;6204.503639708812;6216.3162052174675;6239.095331176954;6247.718852651642;6841.076454678654;6841.692123854438;6856.725730970706;6922.06552115468;6946.0622815920215;6385.233771947396;6393.392627700361;6480.157579514727;6489.318621544669;6519.348065193442;6539.901901471481;6550.443212327005;6607.504437167232;6646.302138275508;6646.368594983469;6571.835704107436;6585.9012114818315;6635.336466353326;6716.1604799400075;6763.7632682855865;6607.504437167232;6716.597004179765;6746.22418421878;6749.763758268437;6793.354631310018;5165.14775062118;5197.922031169535;5259.921101183485;5290.312259406252;5309.814156504503;4462.709923854939;4558.612517936012;4588.128255631486;4592.770361481913;4659.4175728034;5606.271184745957;5624.690642014647;5629.268195134607;5630.127330263816;5661.518770904519;6504.317046710122;6518.120053792677;6555.606663600104;6697.6986035628515;6778.593284402951;5740.512056475246;5798.478136967693;5811.244538210006;5825.0388685866355;5837.591368604157;5561.082421400504;5718.056520674126;5775.797847075416;5806.129080637854;5819.854503637443;6388.201474815648;6402.711877965503;6404.935950640527;6405.807825765141;6420.390062944013;6538.934610653832;6633.6019838809825;6635.270120286765;6661.267260601427;6667.166604174478;5671.291088613923;6304.936950630435;6383.39357761012;6397.104405383157;6409.070684750778;6476.125723027331;6601.207855057231;6624.171978502687;6636.867262654817;6644.302348253557;6612.669366531558;6673.599584024974;6728.865422691617;6747.460203183636;6787.89818152738;6477.255115661571;6488.416042556192;6560.671966401756;6581.703823327866;6583.96708016476;6329.240491141092;6415.06981487691;6418.358164183544;6440.194975628046;6540.934590654223;6356.269029061717;6362.840928976812;6362.840928976812;6367.140436041261;6384.521160913043;5881.845701396603;6436.774712885027;6486;6503.154589903472;6512.218533775977;6195.2875419325555;6230.221222347207;6394.136791533084;6397.520185986163;6400.775972841115 16:06:04 INFO - PID 4116 | |1;ext.html;864.6757465950269;883.8011447424334;896.426287176207;900.3472482450225;901.0403919825382;39876.40185397218;40329.39505907414;43384;43559;43890.341644875276;39518.40722389166;39593.40609890854;39670.8016459918;39811.20377592446;39933.800330998376;7278.599677017775;7381.151167615712;7397.63011849408;7416.184219735825;7896.486728362638;7891.8421631567335;8091.109977902426;8094.919050809505;8110.351171906263;8114.837703245931;8078.39412044099;8137.75586732396;8139.348852091818;8146.144654811248;8152.18478152186;8015.158408367125;8044.919550804476;8057.919420805775;8062.838743225103;8104.837903241932;8076.3942704297415;8076.959615201945;8086.636101375393;8087.150849160812;8136.837263254702;38700.225995480214;38808.611913880784;39238;39341;39431.21137577261;3472.461768425898;3493.7030352420074;3521.753477256593;3530.064532898784;3538.5930617979006;32360.676393236237;32572.348553028805;32673.50989735156;32812.17969550756;33021.83489082539;4913.361263035818;5108.770105345268;5110.10573149701;5195.376554813409;5278.894422111574;197512;198026.99999999854;198157;199642;200523;42424;49420.75289623529;49526.50473495291;49741;49948.25077623838;44660.3300950486;51723.482765171866;52428.73785631047;52699;52816.73591632017;16305;16403.343866245454;16497.17514124289;16628.916855415646;16631.50105496813;13337.399817008258;13462.124961877642;13515.053946223867;13524.59426217215;13529.729405411837;14664.973451858481;14726;14728.852711472962;14851.925740371229;14858.554243372717;5628;5795.478406943395;5849.707514624251;5880.26496687914;6028.216331876828;968;1004.2819384140374;1006.2503434940869;1017.2218253036433;1023.6366090038078;22985.425364366023;23279.41801454977;23323.183688571156;23444.06223751065;23472.413189670053;1102.779444111181;1110.4170310586844;1126.55238019145;1134.9602763903388;1138.032672228599;12238.632841014783;12259.509619615115;12279.631611051684;12293.93853030747;12297.70874058211;221723;222996;223121;223420;224954;52790;53096;53342.73328633332;53636.19545706818;53835.461645383046;1413.2509769821804;1449.6883170118474;1457.774045023023;1462.3127130248765;1462.978055329171;22749.772502275428;22983.655245171;23015.309540713806;23201.187958421375;23376.298711038697;36545.26909461849;36604.63395366012;36706;36707.44938825866;36761.63238367689;193260;193409;193576;193780;193791;30323;33034.83482582572;33885.830570846985;35466.468002980495;35616;41639.37540936949;42974.355384668626;43777.12445751131;44165;44392.33411498766;32316.83841580777;32521;32524.83737581297;32532;32672.673273266962;31431.68568314379;31740.523892142097;31988.36023279475;32091.19772005625;32187.51718724268;34139.82930085334;35192;35321;35595.466068008485;35673.64326356807;31090.68909310878;31318.217044574067;31384.529232061996;31480.898168563992;31568.368632627677;23189.652155218024;23600.17399391013;23758.287251382488;24074.39814004664;24194 16:06:04 INFO - PID 4116 | |2;jquery.html;584.8216294030325;588.2294194605065;591.2934043817637;604.483166892307;620.8509957610172;22201;22963.885180574096;23092;23139.07443702252;23172;23896;25019.874900625473;25259.494810103788;25446.87276563619;25616.359591010187;6481.708323125459;6570.310117437672;6619.867602647944;6619.973904044857;6660.833479163011;5393.81121660742;5436.184572314165;5447.237386765856;5478.068728316196;6385.489160867142;6609.867802643944;6609.900851487231;6611.603303801763;6617.073609694647;6625.0724898514245;6592.406683398493;6593.86812263752;6600.405963463288;6622.03980422836;6669.866602667968;5978.163057172;6131;6135.6625385603775;6178.783742568989;6197.163382943335;27576.310592235153;27973.860130699217;28173.57739633907;28210.71789282122;28247.717522824714;1948.9805101949082;1992.3624440179149;1995.6108558831033;1998.5003749062735;1998.880067195973;19043.714344284846;19063.809361906344;19510.80489195104;19568.02159892014;19727.80272197274;2486.167134010109;2494.463690306593;2533.8353007054484;2542.0594380079306;2543.8346507476954;120554.3972280133;125363.37318313442;134626;138643.30678346645;145527.2723636396;14647;14733.410663573552;14755.45067767869;14840.176942493732;14948.850511494746;23190.884045579664;23320.183793567143;23482.295531134092;23539.529209415716;23589.52820943606;10296.279260451696;10371.688849334532;10375.16998640107;10434.287085357862;10452.68641940743;9469.195118414875;9600.279979001463;9635.084666956627;9817.509124543743;9824.65613703517;8896.154865287788;8942.195202431813;8970.955145224232;9113.453192808453;9280.582373793195;2539.6444497770326;2603.8177327586886;2656.5351063564185;2659.4415172813538;2759.9172024839286;933.5425641435647;937.4796987671771;967.3325405470204;972.1008067537499;978.1196922769565;18519.814801851808;18819.247230110643;19330.516737081405;19555;19837.504062398268;1027.198784947742;1031.4842578710645;1034.1941654710586;1034.4310629154086;1049.8005378978123;10445.425501597485;10698.73253168677;10727.678169654922;10770.784584308427;11015.173861960386;425970;436937;442948;443214;444965;115551;115649;124043;125494.37252813678;126007;1151.7638884028754;1176.4176732517458;1247.7629250442387;1258.8230004945406;1267.4296566545092;20052.197912083935;20314.28899988493;20465.897670511553;20675.63653072019;20714;57763.711181443825;59256;59791.40208598032;60652;61578;76137.23862761301;76389.61805190939;77029.22970770221;77614.61192694001;77925;14502.927485362507;14505.129692218501;14769.704605908037;14808.407663693763;14855.702885942004;21948;22066;22408.775912240668;22467.775322247224;22945;18155.636887262444;18230.635387292445;18273.634527309645;18285.54286142857;18564.628707426047;16199.271032803788;16388.672226555165;16574.419895303607;16896.32414703398;16938.15309234558;18621.534461638566;18673.71989420187;18826.717599236297;18849.90575047116;18980.715289270396;13982.930085349915;14404.783928240875;14424.639384015063;14446.638834029234;14457.060291081047;17900.731489027934;18378.264869405073;18409.079546022378;18414;18460.35388761387 16:06:04 INFO - PID 4116 | |3;mootools.html;1300.960971170865;1331.5738963531674;1347.3196036001823;1352.6821197018705;1353.1407556201812;14452.855471445295;14922.955393122485;15007;15055.924720376397;15061.92469037656;14658.926705366459;14794.334254958525;14804.925975370108;14826.406943722264;14833.777493337608;2635.524106500361;2748.1068652687854;2754.2838861895852;2756.9035083772073;2759.0343379817004;2723.360010397555;2747.8900843966317;2750.601162831388;2757.420941602256;2770.861456927156;2691.771199448049;2695.4339588686303;2789.428167225714;2789.8326100433937;2795.51078561252;2790.9860450697615;2793.9301517462022;2812.7327903849205;2814.9584653678066;2817.690054094048;2663.2942270298195;2677.9598306025423;2691.5020721166647;2713.02331160782;2730.085421383819;14358.138511689333;14616.12303261797;14700.852991470056;14703.779443308358;14803.333849976669;7594.392448604153;7957.005374328209;7957.442978991473;7958.08482024563;7964.044314682275;5505.449455054462;5526.944730552683;5594.524465420444;5624.55003599711;5632.493075623213;1938.621968716101;1953.433504283756;1953.765548134233;1970.6846904495355;2022.9291974780958;25645.871770641214;25876.87061564699;26015.21954341373;26022.479550409076;26072.73927260722;6863.828404289833;6888.586684798928;6950.930490695129;6980.336867997532;7050.894236586455;16535.503934881734;16668.083255420825;16687.833121668627;16710.08094554811;16734.24695888688;7767.495112817649;7855.842883142305;7901.209879012053;7903.841923161504;7916.881246781303;7251.673674684652;7294.161171465244;7304.5252058616015;7322.304381083789;7331.303526165006;5052.191649336089;5163.328767260232;5186.818461353834;5190.792368305226;5220.086484865209;1282.2562913510142;1326.157889740022;1489.8733607643255;1500.7073620643869;1543.2900865601948;893.1247377569888;902.3818684201343;928.6192661008954;930.1256818590504;940.1397721085131;8305.169483051646;8398.118197589287;8399.8320033599;8529.360297977553;8529.872051919227;961.4759955824093;1005.4319309590059;1014.5130337438071;1020.6223697232072;1021.126936469308;4537.727736335831;4771;5313.388960269541;5357.946420535744;5505.03661859173;20432.59134817325;21120.78879211188;21163.68254476184;21279.680804787942;21664.675029874565;20291.695624565644;20799.546017950368;21016.054277557545;21122.47193820136;21145.04847281876;10436.2694611378;10976.890231097746;11023.559057637605;11073.833892491619;11202;8452.197041281068;8526.957365213135;8533.701320453754;8620.52587107715;8620.568971551524;17445.214965326846;18035;18235;18295.085245737395;18295.817041829945;18222.088895555433;18611.34860279938;18926;18994.335198267996;19175.904120479307;10641.467926603793;10679.359238445719;10727.624533141303;10736.51685674131;10788.514516846604;13383.799243011168;13526.59420217395;13607.251601161635;13665.795013074612;13729.382177802223;11107.83338249943;11424;11630.883691163319;11699.649010529698;11709.941450292694;11222.55109795599;11277.605283815026;11313;11340.20618556693;11356.148288878532;9376.34365594429;9404.106609872051;9436.764080898032;9439.103285187895;9530;6896.655167241718;6994.33553812387;7096.467764917548;7122.857542849218;7141.892871607034;7159.176694680178;7278.526895751971;7390.29792169743;7400.370968467525;7405.407567394704 16:06:04 INFO - PID 4116 | |4;prototype.html;509.8495943696609;516.718388478279;536.3938749213389;546.7976848566026;548.4488089470082;6055.697215139242;6131.448169664734;6171.2903016153095;6193.163922870411;6241;5953.345132035483;6001.66990815505;6049.304330002051;6111.7860874869275;6126.571140020201;5088;5095.719735414551;5127.384713834345;5146.459621739719;5149.922751158736;5112.8977420451565;5130.153524668425;5135.998480296325;5143;5168;4653;4916.803327866881;4928.827491037814;4948.257761335793;5007.399112106552;4902.583280421169;4943.728094954776;4944.9258261126115;4977.278294647274;5007.849764507052;4427.601515863587;4452.643788496913;4454.48773391061;4459.888502787424;4481.5070342262325;5737;5957.970210148965;6045.972184728606;6075.2405949256345;6108.5418593605655;5590.720463976825;5632.633878797865;5655.860997146582;5657.434256574351;5679.7160141993145;6348.968255158741;6388.361163883621;6516.5237209860425;6538.803835884931;6551.80344589663;4657;4799.928001079987;4818.662948469989;4848.442429120626;4914.533119353656;6584.572002819801;6597.538172327939;6699.59802411852;6753.3921947025;6842.247352791189;4889.242167464049;4964.180910149785;4968.677035992649;4974.328465657162;5014.548690617789;5052.444231134572;5060.114479965991;5062.316587260746;5064.41759197697;5070.721110338893;5632.2678051853;5647.971760141173;5671.326958547238;5679.432056794288;5679.517241034476;6406.67966601668;6459.677016149174;6497.415232629086;6513.668410507054;6567.9671601641685;1729.2477772168895;1737.3137610643726;1754.1229385307347;1756.666233415647;1764.7264673975553;1129.9096072314196;1227.3679055286534;1245.4457766294092;1248.3259040118417;1248.7627350803318;5470.507654311131;5522.337319521683;5609.719514024282;5642.097264437671;5647.548196144298;6477.676116194266;6486.935130648633;6490.253620833664;6533.575317604435;6609.735610575619;5500.862478437991;5512.834614961557;5530.446955304518;5569.387367389665;5628.155776633538;3432.4164891968426;3442.862285508602;3491.389006923778;3498.6676265754713;3521.894343169709;6295.18162638854;6325.620462772248;6346.619202847752;6369.904451433232;6382.489400847921;6622.536422450382;6677.699503522256;6682.866342673119;6763.458923286125;6767.627780472121;5878.617889837147;6203.844903877259;6237.438630523275;6245.781397651061;6314.24229092521;6370.1718776558755;6393.29673735898;6435.292117867125;6528.9020664689115;6584.539082264196;6390.073439351361;6412.711427985659;6434.613923164625;6463.612183269019;6491;6293.244810622571;6383.234011918599;6390.616563006234;6399.968000159969;6406.743730250738;4920.704757714549;4944.381952255968;4956.256561515802;4959.652824302264;4994;6336.271328797247;6490.415862572391;6543.858947705224;6568.507361947777;6575.309592492719;6134.325224225421;6167.259928808572;6305.90541141874;6454.741810327534;6463.482921366185;6092.268927728701;6302.275238347649;6337.049442583465;6372.139761132188;6389;6210.534209934182;6262.248530176408;6296.842578935563;6316.693977059109;6359.618422894641;6313.621182729051;6356.682165891594;6396.872062558816;6454.903176452263;6555.475561955128;6154.5384096192065;6218.347073557392;6301.6219026858535;6347.270063942521;6359.491240700825 16:06:04 INFO - PID 4116 | |5;yui.html;885.6014793342997;887.3344991256557;905.7101727447215;917.8164367126576;918.1919910478775;18856.81143188568;19044.238230470764;19333.323333683325;19427.708584371227;19569.51076223095;18410.171542280597;18660.720089198672;19047.52381190471;19437.31969381072;19555.90222048888;937.8593211018368;954.5561313988994;970.2286682087722;970.4322971061937;970.9271804614682;989.9356541824794;1000.8348622477318;1001.1240164855751;1001.484235618657;1001.644416232238;930.1767935377172;1020.8979102089805;1027.4451796030173;1028.6245520385044;1029.0841151375243;1019.1642852860657;1019.1846522781744;1019.765453945593;1020.3418794877336;1020.4744556553418;718.192033961793;744.6276861569215;748.4274529984566;754.2156157596094;756.8826831841073;18932.810671893243;18999.145038473303;19035.714464282908;19063.90468047651;19377.224911003545;21973.67039494409;22098.668519972212;22674.88662556693;22721.204757833562;22778.430539236488;17124.31502739889;17309.39417120395;17456.214470348867;17858.91070544652;17930.731039034425;3758.567764707039;3768.3593789055926;3789.5263092113487;3791.829367678461;3796.202797412519;144647;148016.2599187008;148023;148165;149943.250283749;16872.66254674924;17241.137943103047;17389.47831565055;17404.39084632032;17412.39056633037;31192.532112018336;31952.840235799136;32769.50845737316;32832.50751238733;32959.340813184084;8811.251043661232;8895.866562001574;9063.909360906438;9077.409968352036;9089.909100908906;4364.75993820343;4424.6681498887065;4435.733855968652;4439.223135951195;4479.3057076153245;5362.91955620666;5473.562115030788;5475.4524547544925;5476.069068258405;5479.424660410679;1035.7669524357111;1053.931494452858;1074.8656417947757;1079.7084787107437;1082.1613249731363;669.3841665667597;670.8993650952299;691.2776148924386;698.6646409723361;706.2443185791154;11157.274777139623;11168;11880.702982425335;12107.576234831738;12258.877411225774;658.0031252652236;668.5921587831419;681.2574294019444;688.8622275544911;694.9131358580178;5458.181272809031;5505.421930697299;5540.833774986757;5620.606557540933;5700.572457065737;120729;124257.37871310585;128322.35838820746;129097;129267;111564.44217778859;111813;112588;113977;118823;100159;100865.49567252117;101577.49211254044;103945.48027259816;104840.47579762204;10342.276040677081;10490.265681402534;10552.366857988545;10601.946990265;10703.785924281627;43348;44437;45623.543764563256;45740.54259457363;46562.534374657174;57512.42487575238;59360.703196483744;59476.70261648664;59838.70080649569;60061.099083512905;15498.84501154974;15526.689466210839;15545.922270388575;15720.21398930026;15763.921180394025;17428.738568921224;17598.29606815713;17762.46712598624;17855.642887142443;18133.818661813213;14917.403303868157;14969.401223951356;15190.924045380145;15207.239638017834;15385.076895386313;16126.919365403568;16211.675766484841;16572.66854662924;16711.247993840065;16913.91543042277;15064.32210550506;15227.695446091238;15249.695006100037;15334.846651533342;15468.5359439217;11990.100742444178;12111.697207569881;12128.939355303168;12280;12343.259404435763;12162.966147877529;12224.266544007389;12241.938790305992;12374.13381063317;12401 16:06:04 INFO - PID 4116 | __end_tp_report 16:06:04 INFO - PID 4116 | __start_cc_report 16:06:04 INFO - PID 4116 | _x_x_mozilla_cycle_collect,302 16:06:04 INFO - PID 4116 | __end_cc_report 16:06:04 INFO - PID 4116 | __startTimestamp1506899164357__endTimestamp 16:06:04 INFO - PID 4116 | 16:06:04 INFO - PID 4116 | ------- Summary: start ------- 16:06:04 INFO - PID 4116 | Number of tests: 6 16:06:04 INFO - PID 4116 | 16:06:04 INFO - PID 4116 | [#0] dojo.html Cycles:165 Average:6115.98 Median:6362.84 stddev:720.82 (11.3%) stddev-sans-first:683.27 16:06:04 INFO - PID 4116 | Values: 3106.3 3173.5 3173.7 3235.2 3261.1 6151.0 6199.1 6256.8 6259.8 6337.0 6171.6 6190.5 6230.0 6234.8 6258.4 5638.7 5661.6 5672.0 5672.6 5673.3 5699.5 5721.1 5771.7 5821.5 5842.8 5774.6 5821.2 5823.4 5828.4 5899.6 5788.8 5823.9 5841.0 5881.2 5903.7 5529.9 5667.0 5669.7 5737.2 5828.2 6221.6 6248.9 6290.1 6314.4 6396.5 6418.1 6666.5 6668.7 6756.7 6789.3 6586.4 6644.4 6746.8 6780.9 6787.4 6179.9 6204.5 6216.3 6239.1 6247.7 6841.1 6841.7 6856.7 6922.1 6946.1 6385.2 6393.4 6480.2 6489.3 6519.3 6539.9 6550.4 6607.5 6646.3 6646.4 6571.8 6585.9 6635.3 6716.2 6763.8 6607.5 6716.6 6746.2 6749.8 6793.4 5165.1 5197.9 5259.9 5290.3 5309.8 4462.7 4558.6 4588.1 4592.8 4659.4 5606.3 5624.7 5629.3 5630.1 5661.5 6504.3 6518.1 6555.6 6697.7 6778.6 5740.5 5798.5 5811.2 5825.0 5837.6 5561.1 5718.1 5775.8 5806.1 5819.9 6388.2 6402.7 6404.9 6405.8 6420.4 6538.9 6633.6 6635.3 6661.3 6667.2 5671.3 6304.9 6383.4 6397.1 6409.1 6476.1 6601.2 6624.2 6636.9 6644.3 6612.7 6673.6 6728.9 6747.5 6787.9 6477.3 6488.4 6560.7 6581.7 6584.0 6329.2 6415.1 6418.4 6440.2 6540.9 6356.3 6362.8 6362.8 6367.1 6384.5 5881.8 6436.8 6486.0 6503.2 6512.2 6195.3 6230.2 6394.1 6397.5 6400.8 16:06:04 INFO - PID 4116 | 16:06:04 INFO - PID 4116 | [#1] ext.html Cycles:180 Average:37562.80 Median:19356.88 stddev:53187.46 (274.8%) stddev-sans-first:53265.28 16:06:04 INFO - PID 4116 | Values: 864.7 883.8 896.4 900.3 901.0 39876.4 40329.4 43384.0 43559.0 43890.3 39518.4 39593.4 39670.8 39811.2 39933.8 7278.6 7381.2 7397.6 7416.2 7896.5 7891.8 8091.1 8094.9 8110.4 8114.8 8078.4 8137.8 8139.3 8146.1 8152.2 8015.2 8044.9 8057.9 8062.8 8104.8 8076.4 8077.0 8086.6 8087.2 8136.8 38700.2 38808.6 39238.0 39341.0 39431.2 3472.5 3493.7 3521.8 3530.1 3538.6 32360.7 32572.3 32673.5 32812.2 33021.8 4913.4 5108.8 5110.1 5195.4 5278.9 197512.0 198027.0 198157.0 199642.0 200523.0 42424.0 49420.8 49526.5 49741.0 49948.3 44660.3 51723.5 52428.7 52699.0 52816.7 16305.0 16403.3 16497.2 16628.9 16631.5 13337.4 13462.1 13515.1 13524.6 13529.7 14665.0 14726.0 14728.9 14851.9 14858.6 5628.0 5795.5 5849.7 5880.3 6028.2 968.0 1004.3 1006.3 1017.2 1023.6 22985.4 23279.4 23323.2 23444.1 23472.4 1102.8 1110.4 1126.6 1135.0 1138.0 12238.6 12259.5 12279.6 12293.9 12297.7 221723.0 222996.0 223121.0 223420.0 224954.0 52790.0 53096.0 53342.7 53636.2 53835.5 1413.3 1449.7 1457.8 1462.3 1463.0 22749.8 22983.7 23015.3 23201.2 23376.3 36545.3 36604.6 36706.0 36707.4 36761.6 193260.0 193409.0 193576.0 193780.0 193791.0 30323.0 33034.8 33885.8 35466.5 35616.0 41639.4 42974.4 43777.1 44165.0 44392.3 32316.8 32521.0 32524.8 32532.0 32672.7 31431.7 31740.5 31988.4 32091.2 32187.5 34139.8 35192.0 35321.0 35595.5 35673.6 31090.7 31318.2 31384.5 31480.9 31568.4 23189.7 23600.2 23758.3 24074.4 24194.0 16:06:04 INFO - PID 4116 | 16:06:04 INFO - PID 4116 | [#2] jquery.html Cycles:180 Average:33581.64 Median:22954.44 stddev:74879.04 (326.2%) stddev-sans-first:75048.11 16:06:04 INFO - PID 4116 | Values: 584.8 588.2 591.3 604.5 620.9 22201.0 22963.9 23092.0 23139.1 23172.0 23896.0 25019.9 25259.5 25446.9 25616.4 6481.7 6570.3 6619.9 6620.0 6660.8 5393.8 5436.2 5447.2 5478.1 6385.5 6609.9 6609.9 6611.6 6617.1 6625.1 6592.4 6593.9 6600.4 6622.0 6669.9 5978.2 6131.0 6135.7 6178.8 6197.2 27576.3 27973.9 28173.6 28210.7 28247.7 1949.0 1992.4 1995.6 1998.5 1998.9 19043.7 19063.8 19510.8 19568.0 19727.8 2486.2 2494.5 2533.8 2542.1 2543.8 120554.4 125363.4 134626.0 138643.3 145527.3 14647.0 14733.4 14755.5 14840.2 14948.9 23190.9 23320.2 23482.3 23539.5 23589.5 10296.3 10371.7 10375.2 10434.3 10452.7 9469.2 9600.3 9635.1 9817.5 9824.7 8896.2 8942.2 8971.0 9113.5 9280.6 2539.6 2603.8 2656.5 2659.4 2759.9 933.5 937.5 967.3 972.1 978.1 18519.8 18819.2 19330.5 19555.0 19837.5 1027.2 1031.5 1034.2 1034.4 1049.8 10445.4 10698.7 10727.7 10770.8 11015.2 425970.0 436937.0 442948.0 443214.0 444965.0 115551.0 115649.0 124043.0 125494.4 126007.0 1151.8 1176.4 1247.8 1258.8 1267.4 20052.2 20314.3 20465.9 20675.6 20714.0 57763.7 59256.0 59791.4 60652.0 61578.0 76137.2 76389.6 77029.2 77614.6 77925.0 14502.9 14505.1 14769.7 14808.4 14855.7 21948.0 22066.0 22408.8 22467.8 22945.0 18155.6 18230.6 18273.6 18285.5 18564.6 16199.3 16388.7 16574.4 16896.3 16938.2 18621.5 18673.7 18826.7 18849.9 18980.7 13982.9 14404.8 14424.6 14446.6 14457.1 17900.7 18378.3 18409.1 18414.0 18460.4 16:06:04 INFO - PID 4116 | 16:06:04 INFO - PID 4116 | [#3] mootools.html Cycles:180 Average:9190.36 Median:2670.63 stddev:6471.35 (242.3%) stddev-sans-first:6462.35 16:06:04 INFO - PID 4116 | Values: 1301.0 1331.6 1347.3 1352.7 1353.1 14452.9 14923.0 15007.0 15055.9 15061.9 14658.9 14794.3 14804.9 14826.4 14833.8 2635.5 2748.1 2754.3 2756.9 2759.0 2723.4 2747.9 2750.6 2757.4 2770.9 2691.8 2695.4 2789.4 2789.8 2795.5 2791.0 2793.9 2812.7 2815.0 2817.7 2663.3 2678.0 2691.5 2713.0 2730.1 14358.1 14616.1 14700.9 14703.8 14803.3 7594.4 7957.0 7957.4 7958.1 7964.0 5505.4 5526.9 5594.5 5624.6 5632.5 1938.6 1953.4 1953.8 1970.7 2022.9 25645.9 25876.9 26015.2 26022.5 26072.7 6863.8 6888.6 6950.9 6980.3 7050.9 16535.5 16668.1 16687.8 16710.1 16734.2 7767.5 7855.8 7901.2 7903.8 7916.9 7251.7 7294.2 7304.5 7322.3 7331.3 5052.2 5163.3 5186.8 5190.8 5220.1 1282.3 1326.2 1489.9 1500.7 1543.3 893.1 902.4 928.6 930.1 940.1 8305.2 8398.1 8399.8 8529.4 8529.9 961.5 1005.4 1014.5 1020.6 1021.1 4537.7 4771.0 5313.4 5357.9 5505.0 20432.6 21120.8 21163.7 21279.7 21664.7 20291.7 20799.5 21016.1 21122.5 21145.0 10436.3 10976.9 11023.6 11073.8 11202.0 8452.2 8527.0 8533.7 8620.5 8620.6 17445.2 18035.0 18235.0 18295.1 18295.8 18222.1 18611.3 18926.0 18994.3 19175.9 10641.5 10679.4 10727.6 10736.5 10788.5 13383.8 13526.6 13607.3 13665.8 13729.4 11107.8 11424.0 11630.9 11699.6 11709.9 11222.6 11277.6 11313.0 11340.2 11356.1 9376.3 9404.1 9436.8 9439.1 9530.0 6896.7 6994.3 7096.5 7122.9 7141.9 7159.2 7278.5 7390.3 7400.4 7405.4 16:06:04 INFO - PID 4116 | 16:06:04 INFO - PID 4116 | [#4] prototype.html Cycles:180 Average:5408.57 Median:6047.64 stddev:1488.23 (24.6%) stddev-sans-first:1446.27 16:06:04 INFO - PID 4116 | Values: 509.8 516.7 536.4 546.8 548.4 6055.7 6131.4 6171.3 6193.2 6241.0 5953.3 6001.7 6049.3 6111.8 6126.6 5088.0 5095.7 5127.4 5146.5 5149.9 5112.9 5130.2 5136.0 5143.0 5168.0 4653.0 4916.8 4928.8 4948.3 5007.4 4902.6 4943.7 4944.9 4977.3 5007.8 4427.6 4452.6 4454.5 4459.9 4481.5 5737.0 5958.0 6046.0 6075.2 6108.5 5590.7 5632.6 5655.9 5657.4 5679.7 6349.0 6388.4 6516.5 6538.8 6551.8 4657.0 4799.9 4818.7 4848.4 4914.5 6584.6 6597.5 6699.6 6753.4 6842.2 4889.2 4964.2 4968.7 4974.3 5014.5 5052.4 5060.1 5062.3 5064.4 5070.7 5632.3 5648.0 5671.3 5679.4 5679.5 6406.7 6459.7 6497.4 6513.7 6568.0 1729.2 1737.3 1754.1 1756.7 1764.7 1129.9 1227.4 1245.4 1248.3 1248.8 5470.5 5522.3 5609.7 5642.1 5647.5 6477.7 6486.9 6490.3 6533.6 6609.7 5500.9 5512.8 5530.4 5569.4 5628.2 3432.4 3442.9 3491.4 3498.7 3521.9 6295.2 6325.6 6346.6 6369.9 6382.5 6622.5 6677.7 6682.9 6763.5 6767.6 5878.6 6203.8 6237.4 6245.8 6314.2 6370.2 6393.3 6435.3 6528.9 6584.5 6390.1 6412.7 6434.6 6463.6 6491.0 6293.2 6383.2 6390.6 6400.0 6406.7 4920.7 4944.4 4956.3 4959.7 4994.0 6336.3 6490.4 6543.9 6568.5 6575.3 6134.3 6167.3 6305.9 6454.7 6463.5 6092.3 6302.3 6337.0 6372.1 6389.0 6210.5 6262.2 6296.8 6316.7 6359.6 6313.6 6356.7 6396.9 6454.9 6555.5 6154.5 6218.3 6301.6 6347.3 6359.5 16:06:04 INFO - PID 4116 | 16:06:04 INFO - PID 4116 | [#5] yui.html Cycles:180 Average:25131.11 Median:17894.82 stddev:37112.62 (207.4%) stddev-sans-first:37172.08 16:06:04 INFO - PID 4116 | Values: 885.6 887.3 905.7 917.8 918.2 18856.8 19044.2 19333.3 19427.7 19569.5 18410.2 18660.7 19047.5 19437.3 19555.9 937.9 954.6 970.2 970.4 970.9 989.9 1000.8 1001.1 1001.5 1001.6 930.2 1020.9 1027.4 1028.6 1029.1 1019.2 1019.2 1019.8 1020.3 1020.5 718.2 744.6 748.4 754.2 756.9 18932.8 18999.1 19035.7 19063.9 19377.2 21973.7 22098.7 22674.9 22721.2 22778.4 17124.3 17309.4 17456.2 17858.9 17930.7 3758.6 3768.4 3789.5 3791.8 3796.2 144647.0 148016.3 148023.0 148165.0 149943.3 16872.7 17241.1 17389.5 17404.4 17412.4 31192.5 31952.8 32769.5 32832.5 32959.3 8811.3 8895.9 9063.9 9077.4 9089.9 4364.8 4424.7 4435.7 4439.2 4479.3 5362.9 5473.6 5475.5 5476.1 5479.4 1035.8 1053.9 1074.9 1079.7 1082.2 669.4 670.9 691.3 698.7 706.2 11157.3 11168.0 11880.7 12107.6 12258.9 658.0 668.6 681.3 688.9 694.9 5458.2 5505.4 5540.8 5620.6 5700.6 120729.0 124257.4 128322.4 129097.0 129267.0 111564.4 111813.0 112588.0 113977.0 118823.0 100159.0 100865.5 101577.5 103945.5 104840.5 10342.3 10490.3 10552.4 10601.9 10703.8 43348.0 44437.0 45623.5 45740.5 46562.5 57512.4 59360.7 59476.7 59838.7 60061.1 15498.8 15526.7 15545.9 15720.2 15763.9 17428.7 17598.3 17762.5 17855.6 18133.8 14917.4 14969.4 15190.9 15207.2 15385.1 16126.9 16211.7 16572.7 16711.2 16913.9 15064.3 15227.7 15249.7 15334.8 15468.5 11990.1 12111.7 12128.9 12280.0 12343.3 12163.0 12224.3 12241.9 12374.1 12401.0 16:06:04 INFO - PID 4116 | -------- Summary: end -------- 16:06:04 INFO - PID 4116 | 16:06:05 INFO - TEST-INFO | 4116: exit 0 16:06:05 INFO - TEST-OK | dromaeo_css | took 1117007ms 16:06:05 INFO - TEST-START | kraken 16:06:05 INFO - Initialising browser for kraken test... 16:06:05 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37156/getInfo.html -profile /tmp/tmpQyNKjw/profile 16:06:05 INFO - TEST-INFO | started process 4519 (/builds/slave/test/build/application/firefox/firefox http://localhost:37156/getInfo.html) 16:06:11 INFO - TEST-INFO | 4519: exit 0 16:06:11 INFO - Browser initialized. 16:06:11 INFO - Running cycle 1/1 for kraken test... 16:06:11 INFO - Using env: {'DISPLAY': ':0', 16:06:11 INFO - 'HOME': '/home/cltbld', 16:06:11 INFO - 'JSGC_DISABLE_POISONING': '1', 16:06:11 INFO - 'LANG': 'en_US.UTF-8', 16:06:11 INFO - 'LANGUAGE': 'en_US:en', 16:06:11 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:06:11 INFO - 'LOGNAME': 'cltbld', 16:06:11 INFO - 'MAIL': '/var/mail/cltbld', 16:06:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:06:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:06:11 INFO - 'MOZ_ACCELERATED': '1', 16:06:11 INFO - 'MOZ_CRASHREPORTER': '1', 16:06:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:11 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:06:11 INFO - 'MOZ_NO_REMOTE': '1', 16:06:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:06:11 INFO - 'MOZ_WEBRENDER': '1', 16:06:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:11 INFO - 'NO_EM_RESTART': '1', 16:06:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:06:11 INFO - 'PWD': '/builds/slave/test', 16:06:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:06:11 INFO - 'RUST_BACKTRACE': 'full', 16:06:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:06:11 INFO - 'SHELL': '/bin/bash', 16:06:11 INFO - 'SHLVL': '1', 16:06:11 INFO - 'TERM': 'linux', 16:06:11 INFO - 'TMOUT': '86400', 16:06:11 INFO - 'USER': 'cltbld', 16:06:11 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799', 16:06:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:11 INFO - '_': '/tools/buildbot/bin/python'} 16:06:11 INFO - TEST-INFO | started process 4763 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQyNKjw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:06:11 INFO - PID 4763 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:06:12 INFO - PID 4763 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:06:12 INFO - PID 4763 | 16:06:12 INFO - PID 4763 | (/builds/slave/test/build/application/firefox/firefox:4822): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:06:12 INFO - PID 4763 | 16:06:22 INFO - PID 4763 | RSS: Main: 194310144 16:06:22 INFO - PID 4763 | 16:06:25 INFO - PID 4763 | Cycle 1(1): loaded http://localhost:37156/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37156/tests/kraken/driver.html?testName=audio-beat-detection) 16:06:26 INFO - PID 4763 | RSS: Main: 192110592 16:06:26 INFO - PID 4763 | 16:06:28 INFO - PID 4763 | Cycle 1(1): loaded http://localhost:37156/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37156/tests/kraken/driver.html?testName=audio-dft) 16:06:29 INFO - PID 4763 | RSS: Main: 192409600 16:06:29 INFO - PID 4763 | 16:06:32 INFO - PID 4763 | Cycle 1(1): loaded http://localhost:37156/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37156/tests/kraken/driver.html?testName=audio-fft) 16:06:33 INFO - PID 4763 | RSS: Main: 188653568 16:06:33 INFO - PID 4763 | 16:06:35 INFO - PID 4763 | Cycle 1(1): loaded http://localhost:37156/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37156/tests/kraken/driver.html?testName=audio-oscillator) 16:06:35 INFO - PID 4763 | RSS: Main: 188157952 16:06:35 INFO - PID 4763 | 16:06:38 INFO - PID 4763 | Cycle 1(1): loaded http://localhost:37156/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37156/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:06:38 INFO - PID 4763 | RSS: Main: 188792832 16:06:38 INFO - PID 4763 | 16:06:41 INFO - PID 4763 | Cycle 1(1): loaded http://localhost:37156/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37156/tests/kraken/driver.html?testName=imaging-darkroom) 16:06:42 INFO - PID 4763 | RSS: Main: 190930944 16:06:42 INFO - PID 4763 | 16:06:46 INFO - PID 4763 | Cycle 1(1): loaded http://localhost:37156/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37156/tests/kraken/driver.html?testName=imaging-desaturate) 16:06:46 INFO - PID 4763 | RSS: Main: 191442944 16:06:46 INFO - PID 4763 | 16:06:49 INFO - PID 4763 | Cycle 1(1): loaded http://localhost:37156/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37156/tests/kraken/driver.html?testName=json-parse-financial) 16:06:50 INFO - PID 4763 | RSS: Main: 191725568 16:06:50 INFO - PID 4763 | 16:06:52 INFO - PID 4763 | Cycle 1(1): loaded http://localhost:37156/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37156/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:06:52 INFO - PID 4763 | RSS: Main: 191766528 16:06:52 INFO - PID 4763 | 16:06:54 INFO - PID 4763 | Cycle 1(1): loaded http://localhost:37156/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37156/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:06:55 INFO - PID 4763 | RSS: Main: 189063168 16:06:55 INFO - PID 4763 | 16:06:57 INFO - PID 4763 | Cycle 1(1): loaded http://localhost:37156/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37156/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:06:57 INFO - PID 4763 | RSS: Main: 188776448 16:06:57 INFO - PID 4763 | 16:07:00 INFO - PID 4763 | Cycle 1(1): loaded http://localhost:37156/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:07:01 INFO - PID 4763 | RSS: Main: 188964864 16:07:01 INFO - PID 4763 | 16:07:04 INFO - PID 4763 | Cycle 1(1): loaded http://localhost:37156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:07:04 INFO - PID 4763 | RSS: Main: 188891136 16:07:04 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | Cycle 1(1): loaded http://localhost:37156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37156/tests/kraken/driver.html?testName=ai-astar) 16:07:06 INFO - PID 4763 | RSS: Main: 190300160 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | __start_tp_report 16:07:06 INFO - PID 4763 | _x_x_mozilla_page_load 16:07:06 INFO - PID 4763 | _x_x_mozilla_page_load_details 16:07:06 INFO - PID 4763 | |i|pagename|runs| 16:07:06 INFO - PID 4763 | |0;ai-astar;108;111;108;108;116;109;109;107;112;118 16:07:06 INFO - PID 4763 | |1;audio-beat-detection;117;119;139;129;117;117;139;115;145;124 16:07:06 INFO - PID 4763 | |2;audio-dft;166;171;163;163;167;166;174;166;165;165 16:07:06 INFO - PID 4763 | |3;audio-fft;82;82;74;77;75;75;84;79;81;82 16:07:06 INFO - PID 4763 | |4;audio-oscillator;79;82;79;79;78;85;82;78;82;85 16:07:06 INFO - PID 4763 | |5;imaging-gaussian-blur;118;112;125;112;116;118;122;113;118;118 16:07:06 INFO - PID 4763 | |6;imaging-darkroom;152;152;147;154;149;147;148;149;151;152 16:07:06 INFO - PID 4763 | |7;imaging-desaturate;99;95;94;97;95;100;103;101;100;94 16:07:06 INFO - PID 4763 | |8;json-parse-financial;50;54;52;52;51;51;60;56;47;48 16:07:06 INFO - PID 4763 | |9;json-stringify-tinderbox;37;46;37;41;41;45;39;37;38;47 16:07:06 INFO - PID 4763 | |10;stanford-crypto-aes;76;78;77;79;81;76;77;72;76;78 16:07:06 INFO - PID 4763 | |11;stanford-crypto-ccm;110;112;113;127;112;115;121;110;113;110 16:07:06 INFO - PID 4763 | |12;stanford-crypto-pbkdf2;164;168;165;170;168;162;165;166;164;171 16:07:06 INFO - PID 4763 | |13;stanford-crypto-sha256-iterative;66;68;64;66;74;67;74;63;64;64 16:07:06 INFO - PID 4763 | __end_tp_report 16:07:06 INFO - PID 4763 | __start_cc_report 16:07:06 INFO - PID 4763 | _x_x_mozilla_cycle_collect,763 16:07:06 INFO - PID 4763 | __end_cc_report 16:07:06 INFO - PID 4763 | __startTimestamp1506899226743__endTimestamp 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | ------- Summary: start ------- 16:07:06 INFO - PID 4763 | Number of tests: 14 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | [#0] ai-astar Cycles:10 Average:110.60 Median:110.00 stddev:3.72 (3.4%) stddev-sans-first:3.82 16:07:06 INFO - PID 4763 | Values: 108.0 111.0 108.0 108.0 116.0 109.0 109.0 107.0 112.0 118.0 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | [#1] audio-beat-detection Cycles:10 Average:126.10 Median:126.50 stddev:11.18 (8.8%) stddev-sans-first:11.36 16:07:06 INFO - PID 4763 | Values: 117.0 119.0 139.0 129.0 117.0 117.0 139.0 115.0 145.0 124.0 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | [#2] audio-dft Cycles:10 Average:166.60 Median:166.00 stddev:3.44 (2.1%) stddev-sans-first:3.64 16:07:06 INFO - PID 4763 | Values: 166.0 171.0 163.0 163.0 167.0 166.0 174.0 166.0 165.0 165.0 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | [#3] audio-fft Cycles:10 Average:79.10 Median:81.50 stddev:3.60 (4.4%) stddev-sans-first:3.67 16:07:06 INFO - PID 4763 | Values: 82.0 82.0 74.0 77.0 75.0 75.0 84.0 79.0 81.0 82.0 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:82.00 stddev:2.69 (3.3%) stddev-sans-first:2.76 16:07:06 INFO - PID 4763 | Values: 79.0 82.0 79.0 79.0 78.0 85.0 82.0 78.0 82.0 85.0 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:118.00 stddev:4.21 (3.6%) stddev-sans-first:4.46 16:07:06 INFO - PID 4763 | Values: 118.0 112.0 125.0 112.0 116.0 118.0 122.0 113.0 118.0 118.0 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | [#6] imaging-darkroom Cycles:10 Average:150.10 Median:151.50 stddev:2.42 (1.6%) stddev-sans-first:2.47 16:07:06 INFO - PID 4763 | Values: 152.0 152.0 147.0 154.0 149.0 147.0 148.0 149.0 151.0 152.0 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | [#7] imaging-desaturate Cycles:10 Average:97.80 Median:94.50 stddev:3.22 (3.4%) stddev-sans-first:3.39 16:07:06 INFO - PID 4763 | Values: 99.0 95.0 94.0 97.0 95.0 100.0 103.0 101.0 100.0 94.0 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.00 stddev:3.81 (7.3%) stddev-sans-first:3.97 16:07:06 INFO - PID 4763 | Values: 50.0 54.0 52.0 52.0 51.0 51.0 60.0 56.0 47.0 48.0 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | [#9] json-stringify-tinderbox Cycles:10 Average:40.80 Median:41.00 stddev:3.91 (9.5%) stddev-sans-first:3.90 16:07:06 INFO - PID 4763 | Values: 37.0 46.0 37.0 41.0 41.0 45.0 39.0 37.0 38.0 47.0 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | [#10] stanford-crypto-aes Cycles:10 Average:77.00 Median:77.50 stddev:2.36 (3.0%) stddev-sans-first:2.47 16:07:06 INFO - PID 4763 | Values: 76.0 78.0 77.0 79.0 81.0 76.0 77.0 72.0 76.0 78.0 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | [#11] stanford-crypto-ccm Cycles:10 Average:114.30 Median:113.00 stddev:5.54 (4.9%) stddev-sans-first:5.65 16:07:06 INFO - PID 4763 | Values: 110.0 112.0 113.0 127.0 112.0 115.0 121.0 110.0 113.0 110.0 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.30 Median:167.00 stddev:2.87 (1.7%) stddev-sans-first:2.92 16:07:06 INFO - PID 4763 | Values: 164.0 168.0 165.0 170.0 168.0 162.0 165.0 166.0 164.0 171.0 16:07:06 INFO - PID 4763 | 16:07:06 INFO - PID 4763 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:66.50 stddev:4.00 (6.0%) stddev-sans-first:4.23 16:07:06 INFO - PID 4763 | Values: 66.0 68.0 64.0 66.0 74.0 67.0 74.0 63.0 64.0 64.0 16:07:06 INFO - PID 4763 | -------- Summary: end -------- 16:07:06 INFO - PID 4763 | 16:07:07 INFO - PID 4763 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:07:07 INFO - TEST-INFO | 4763: exit 0 16:07:07 INFO - TEST-OK | kraken | took 62555ms 16:07:07 INFO - SUITE-END | took 1179s 16:07:07 INFO - Completed test suite (00:19:39) 16:07:07 INFO - javascript benchmark 16:07:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8490.05271337644, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3106.301082256492, 3173.4763763978945, 3173.682631736826, 3235.158858696737, 3261.0542942546663, 6151.04658777889, 6199.070139479075, 6256.84357891053, 6259.8122056338325, 6336.968315158423, 6171.62970221787, 6190.535709821759, 6230.0, 6234.812955611327, 6258.436740693348, 5638.689872057035, 5661.60368774186, 5672.0, 5672.631278966854, 5673.347565030012, 5699.487046165844, 5721.056025755766, 5771.653700777967, 5821.476067153955, 5842.795502157425, 5774.624649397776, 5821.243238379026, 5823.359430462646, 5828.388019257981, 5899.557533185029, 5788.82633520995, 5823.88352232953, 5841.0, 5881.206037184989, 5903.734331955073, 5529.944700552983, 5667.0366037773265, 5669.688167150805, 5737.196792449061, 5828.242328497311, 6221.564490485668, 6248.875022499525, 6290.056491526262, 6314.400131987454, 6396.456301214403, 6418.133551970471, 6666.533342666015, 6668.666566671647, 6756.662166891636, 6789.287124851919, 6586.37429444202, 6644.43522300605, 6746.763863264762, 6780.864382712318, 6787.389134977826, 6179.938200618026, 6204.503639708812, 6216.3162052174675, 6239.095331176954, 6247.718852651642, 6841.076454678654, 6841.692123854438, 6856.725730970706, 6922.06552115468, 6946.0622815920215, 6385.233771947396, 6393.392627700361, 6480.157579514727, 6489.318621544669, 6519.348065193442, 6539.901901471481, 6550.443212327005, 6607.504437167232, 6646.302138275508, 6646.368594983469, 6571.835704107436, 6585.9012114818315, 6635.336466353326, 6716.1604799400075, 6763.7632682855865, 6607.504437167232, 6716.597004179765, 6746.22418421878, 6749.763758268437, 6793.354631310018, 5165.14775062118, 5197.922031169535, 5259.921101183485, 5290.312259406252, 5309.814156504503, 4462.709923854939, 4558.612517936012, 4588.128255631486, 4592.770361481913, 4659.4175728034, 5606.271184745957, 5624.690642014647, 5629.268195134607, 5630.127330263816, 5661.518770904519, 6504.317046710122, 6518.120053792677, 6555.606663600104, 6697.6986035628515, 6778.593284402951, 5740.512056475246, 5798.478136967693, 5811.244538210006, 5825.0388685866355, 5837.591368604157, 5561.082421400504, 5718.056520674126, 5775.797847075416, 5806.129080637854, 5819.854503637443, 6388.201474815648, 6402.711877965503, 6404.935950640527, 6405.807825765141, 6420.390062944013, 6538.934610653832, 6633.6019838809825, 6635.270120286765, 6661.267260601427, 6667.166604174478, 5671.291088613923, 6304.936950630435, 6383.39357761012, 6397.104405383157, 6409.070684750778, 6476.125723027331, 6601.207855057231, 6624.171978502687, 6636.867262654817, 6644.302348253557, 6612.669366531558, 6673.599584024974, 6728.865422691617, 6747.460203183636, 6787.89818152738, 6477.255115661571, 6488.416042556192, 6560.671966401756, 6581.703823327866, 6583.96708016476, 6329.240491141092, 6415.06981487691, 6418.358164183544, 6440.194975628046, 6540.934590654223, 6356.269029061717, 6362.840928976812, 6362.840928976812, 6367.140436041261, 6384.521160913043, 5881.845701396603, 6436.774712885027, 6486.0, 6503.154589903472, 6512.218533775977, 6195.2875419325555, 6230.221222347207, 6394.136791533084, 6397.520185986163, 6400.775972841115], "value": 6062.868649322644, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [864.6757465950269, 883.8011447424334, 896.426287176207, 900.3472482450225, 901.0403919825382, 39876.40185397218, 40329.39505907414, 43384.0, 43559.0, 43890.341644875276, 39518.40722389166, 39593.40609890854, 39670.8016459918, 39811.20377592446, 39933.800330998376, 7278.599677017775, 7381.151167615712, 7397.63011849408, 7416.184219735825, 7896.486728362638, 7891.8421631567335, 8091.109977902426, 8094.919050809505, 8110.351171906263, 8114.837703245931, 8078.39412044099, 8137.75586732396, 8139.348852091818, 8146.144654811248, 8152.18478152186, 8015.158408367125, 8044.919550804476, 8057.919420805775, 8062.838743225103, 8104.837903241932, 8076.3942704297415, 8076.959615201945, 8086.636101375393, 8087.150849160812, 8136.837263254702, 38700.225995480214, 38808.611913880784, 39238.0, 39341.0, 39431.21137577261, 3472.461768425898, 3493.7030352420074, 3521.753477256593, 3530.064532898784, 3538.5930617979006, 32360.676393236237, 32572.348553028805, 32673.50989735156, 32812.17969550756, 33021.83489082539, 4913.361263035818, 5108.770105345268, 5110.10573149701, 5195.376554813409, 5278.894422111574, 197512.0, 198026.99999999854, 198157.0, 199642.0, 200523.0, 42424.0, 49420.75289623529, 49526.50473495291, 49741.0, 49948.25077623838, 44660.3300950486, 51723.482765171866, 52428.73785631047, 52699.0, 52816.73591632017, 16305.0, 16403.343866245454, 16497.17514124289, 16628.916855415646, 16631.50105496813, 13337.399817008258, 13462.124961877642, 13515.053946223867, 13524.59426217215, 13529.729405411837, 14664.973451858481, 14726.0, 14728.852711472962, 14851.925740371229, 14858.554243372717, 5628.0, 5795.478406943395, 5849.707514624251, 5880.26496687914, 6028.216331876828, 968.0, 1004.2819384140374, 1006.2503434940869, 1017.2218253036433, 1023.6366090038078, 22985.425364366023, 23279.41801454977, 23323.183688571156, 23444.06223751065, 23472.413189670053, 1102.779444111181, 1110.4170310586844, 1126.55238019145, 1134.9602763903388, 1138.032672228599, 12238.632841014783, 12259.509619615115, 12279.631611051684, 12293.93853030747, 12297.70874058211, 221723.0, 222996.0, 223121.0, 223420.0, 224954.0, 52790.0, 53096.0, 53342.73328633332, 53636.19545706818, 53835.461645383046, 1413.2509769821804, 1449.6883170118474, 1457.774045023023, 1462.3127130248765, 1462.978055329171, 22749.772502275428, 22983.655245171, 23015.309540713806, 23201.187958421375, 23376.298711038697, 36545.26909461849, 36604.63395366012, 36706.0, 36707.44938825866, 36761.63238367689, 193260.0, 193409.0, 193576.0, 193780.0, 193791.0, 30323.0, 33034.83482582572, 33885.830570846985, 35466.468002980495, 35616.0, 41639.37540936949, 42974.355384668626, 43777.12445751131, 44165.0, 44392.33411498766, 32316.83841580777, 32521.0, 32524.83737581297, 32532.0, 32672.673273266962, 31431.68568314379, 31740.523892142097, 31988.36023279475, 32091.19772005625, 32187.51718724268, 34139.82930085334, 35192.0, 35321.0, 35595.466068008485, 35673.64326356807, 31090.68909310878, 31318.217044574067, 31384.529232061996, 31480.898168563992, 31568.368632627677, 23189.652155218024, 23600.17399391013, 23758.287251382488, 24074.39814004664, 24194.0], "value": 17299.316786659438, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.8216294030325, 588.2294194605065, 591.2934043817637, 604.483166892307, 620.8509957610172, 22201.0, 22963.885180574096, 23092.0, 23139.07443702252, 23172.0, 23896.0, 25019.874900625473, 25259.494810103788, 25446.87276563619, 25616.359591010187, 6481.708323125459, 6570.310117437672, 6619.867602647944, 6619.973904044857, 6660.833479163011, 5393.81121660742, 5436.184572314165, 5447.237386765856, 5478.068728316196, 6385.489160867142, 6609.867802643944, 6609.900851487231, 6611.603303801763, 6617.073609694647, 6625.0724898514245, 6592.406683398493, 6593.86812263752, 6600.405963463288, 6622.03980422836, 6669.866602667968, 5978.163057172, 6131.0, 6135.6625385603775, 6178.783742568989, 6197.163382943335, 27576.310592235153, 27973.860130699217, 28173.57739633907, 28210.71789282122, 28247.717522824714, 1948.9805101949082, 1992.3624440179149, 1995.6108558831033, 1998.5003749062735, 1998.880067195973, 19043.714344284846, 19063.809361906344, 19510.80489195104, 19568.02159892014, 19727.80272197274, 2486.167134010109, 2494.463690306593, 2533.8353007054484, 2542.0594380079306, 2543.8346507476954, 120554.3972280133, 125363.37318313442, 134626.0, 138643.30678346645, 145527.2723636396, 14647.0, 14733.410663573552, 14755.45067767869, 14840.176942493732, 14948.850511494746, 23190.884045579664, 23320.183793567143, 23482.295531134092, 23539.529209415716, 23589.52820943606, 10296.279260451696, 10371.688849334532, 10375.16998640107, 10434.287085357862, 10452.68641940743, 9469.195118414875, 9600.279979001463, 9635.084666956627, 9817.509124543743, 9824.65613703517, 8896.154865287788, 8942.195202431813, 8970.955145224232, 9113.453192808453, 9280.582373793195, 2539.6444497770326, 2603.8177327586886, 2656.5351063564185, 2659.4415172813538, 2759.9172024839286, 933.5425641435647, 937.4796987671771, 967.3325405470204, 972.1008067537499, 978.1196922769565, 18519.814801851808, 18819.247230110643, 19330.516737081405, 19555.0, 19837.504062398268, 1027.198784947742, 1031.4842578710645, 1034.1941654710586, 1034.4310629154086, 1049.8005378978123, 10445.425501597485, 10698.73253168677, 10727.678169654922, 10770.784584308427, 11015.173861960386, 425970.0, 436937.0, 442948.0, 443214.0, 444965.0, 115551.0, 115649.0, 124043.0, 125494.37252813678, 126007.0, 1151.7638884028754, 1176.4176732517458, 1247.7629250442387, 1258.8230004945406, 1267.4296566545092, 20052.197912083935, 20314.28899988493, 20465.897670511553, 20675.63653072019, 20714.0, 57763.711181443825, 59256.0, 59791.40208598032, 60652.0, 61578.0, 76137.23862761301, 76389.61805190939, 77029.22970770221, 77614.61192694001, 77925.0, 14502.927485362507, 14505.129692218501, 14769.704605908037, 14808.407663693763, 14855.702885942004, 21948.0, 22066.0, 22408.775912240668, 22467.775322247224, 22945.0, 18155.636887262444, 18230.635387292445, 18273.634527309645, 18285.54286142857, 18564.628707426047, 16199.271032803788, 16388.672226555165, 16574.419895303607, 16896.32414703398, 16938.15309234558, 18621.534461638566, 18673.71989420187, 18826.717599236297, 18849.90575047116, 18980.715289270396, 13982.930085349915, 14404.783928240875, 14424.639384015063, 14446.638834029234, 14457.060291081047, 17900.731489027934, 18378.264869405073, 18409.079546022378, 18414.0, 18460.35388761387], "value": 11963.617578063015, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.960971170865, 1331.5738963531674, 1347.3196036001823, 1352.6821197018705, 1353.1407556201812, 14452.855471445295, 14922.955393122485, 15007.0, 15055.924720376397, 15061.92469037656, 14658.926705366459, 14794.334254958525, 14804.925975370108, 14826.406943722264, 14833.777493337608, 2635.524106500361, 2748.1068652687854, 2754.2838861895852, 2756.9035083772073, 2759.0343379817004, 2723.360010397555, 2747.8900843966317, 2750.601162831388, 2757.420941602256, 2770.861456927156, 2691.771199448049, 2695.4339588686303, 2789.428167225714, 2789.8326100433937, 2795.51078561252, 2790.9860450697615, 2793.9301517462022, 2812.7327903849205, 2814.9584653678066, 2817.690054094048, 2663.2942270298195, 2677.9598306025423, 2691.5020721166647, 2713.02331160782, 2730.085421383819, 14358.138511689333, 14616.12303261797, 14700.852991470056, 14703.779443308358, 14803.333849976669, 7594.392448604153, 7957.005374328209, 7957.442978991473, 7958.08482024563, 7964.044314682275, 5505.449455054462, 5526.944730552683, 5594.524465420444, 5624.55003599711, 5632.493075623213, 1938.621968716101, 1953.433504283756, 1953.765548134233, 1970.6846904495355, 2022.9291974780958, 25645.871770641214, 25876.87061564699, 26015.21954341373, 26022.479550409076, 26072.73927260722, 6863.828404289833, 6888.586684798928, 6950.930490695129, 6980.336867997532, 7050.894236586455, 16535.503934881734, 16668.083255420825, 16687.833121668627, 16710.08094554811, 16734.24695888688, 7767.495112817649, 7855.842883142305, 7901.209879012053, 7903.841923161504, 7916.881246781303, 7251.673674684652, 7294.161171465244, 7304.5252058616015, 7322.304381083789, 7331.303526165006, 5052.191649336089, 5163.328767260232, 5186.818461353834, 5190.792368305226, 5220.086484865209, 1282.2562913510142, 1326.157889740022, 1489.8733607643255, 1500.7073620643869, 1543.2900865601948, 893.1247377569888, 902.3818684201343, 928.6192661008954, 930.1256818590504, 940.1397721085131, 8305.169483051646, 8398.118197589287, 8399.8320033599, 8529.360297977553, 8529.872051919227, 961.4759955824093, 1005.4319309590059, 1014.5130337438071, 1020.6223697232072, 1021.126936469308, 4537.727736335831, 4771.0, 5313.388960269541, 5357.946420535744, 5505.03661859173, 20432.59134817325, 21120.78879211188, 21163.68254476184, 21279.680804787942, 21664.675029874565, 20291.695624565644, 20799.546017950368, 21016.054277557545, 21122.47193820136, 21145.04847281876, 10436.2694611378, 10976.890231097746, 11023.559057637605, 11073.833892491619, 11202.0, 8452.197041281068, 8526.957365213135, 8533.701320453754, 8620.52587107715, 8620.568971551524, 17445.214965326846, 18035.0, 18235.0, 18295.085245737395, 18295.817041829945, 18222.088895555433, 18611.34860279938, 18926.0, 18994.335198267996, 19175.904120479307, 10641.467926603793, 10679.359238445719, 10727.624533141303, 10736.51685674131, 10788.514516846604, 13383.799243011168, 13526.59420217395, 13607.251601161635, 13665.795013074612, 13729.382177802223, 11107.83338249943, 11424.0, 11630.883691163319, 11699.649010529698, 11709.941450292694, 11222.55109795599, 11277.605283815026, 11313.0, 11340.20618556693, 11356.148288878532, 9376.34365594429, 9404.106609872051, 9436.764080898032, 9439.103285187895, 9530.0, 6896.655167241718, 6994.33553812387, 7096.467764917548, 7122.857542849218, 7141.892871607034, 7159.176694680178, 7278.526895751971, 7390.29792169743, 7400.370968467525, 7405.407567394704], "value": 6633.089402449664, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.8495943696609, 516.718388478279, 536.3938749213389, 546.7976848566026, 548.4488089470082, 6055.697215139242, 6131.448169664734, 6171.2903016153095, 6193.163922870411, 6241.0, 5953.345132035483, 6001.66990815505, 6049.304330002051, 6111.7860874869275, 6126.571140020201, 5088.0, 5095.719735414551, 5127.384713834345, 5146.459621739719, 5149.922751158736, 5112.8977420451565, 5130.153524668425, 5135.998480296325, 5143.0, 5168.0, 4653.0, 4916.803327866881, 4928.827491037814, 4948.257761335793, 5007.399112106552, 4902.583280421169, 4943.728094954776, 4944.9258261126115, 4977.278294647274, 5007.849764507052, 4427.601515863587, 4452.643788496913, 4454.48773391061, 4459.888502787424, 4481.5070342262325, 5737.0, 5957.970210148965, 6045.972184728606, 6075.2405949256345, 6108.5418593605655, 5590.720463976825, 5632.633878797865, 5655.860997146582, 5657.434256574351, 5679.7160141993145, 6348.968255158741, 6388.361163883621, 6516.5237209860425, 6538.803835884931, 6551.80344589663, 4657.0, 4799.928001079987, 4818.662948469989, 4848.442429120626, 4914.533119353656, 6584.572002819801, 6597.538172327939, 6699.59802411852, 6753.3921947025, 6842.247352791189, 4889.242167464049, 4964.180910149785, 4968.677035992649, 4974.328465657162, 5014.548690617789, 5052.444231134572, 5060.114479965991, 5062.316587260746, 5064.41759197697, 5070.721110338893, 5632.2678051853, 5647.971760141173, 5671.326958547238, 5679.432056794288, 5679.517241034476, 6406.67966601668, 6459.677016149174, 6497.415232629086, 6513.668410507054, 6567.9671601641685, 1729.2477772168895, 1737.3137610643726, 1754.1229385307347, 1756.666233415647, 1764.7264673975553, 1129.9096072314196, 1227.3679055286534, 1245.4457766294092, 1248.3259040118417, 1248.7627350803318, 5470.507654311131, 5522.337319521683, 5609.719514024282, 5642.097264437671, 5647.548196144298, 6477.676116194266, 6486.935130648633, 6490.253620833664, 6533.575317604435, 6609.735610575619, 5500.862478437991, 5512.834614961557, 5530.446955304518, 5569.387367389665, 5628.155776633538, 3432.4164891968426, 3442.862285508602, 3491.389006923778, 3498.6676265754713, 3521.894343169709, 6295.18162638854, 6325.620462772248, 6346.619202847752, 6369.904451433232, 6382.489400847921, 6622.536422450382, 6677.699503522256, 6682.866342673119, 6763.458923286125, 6767.627780472121, 5878.617889837147, 6203.844903877259, 6237.438630523275, 6245.781397651061, 6314.24229092521, 6370.1718776558755, 6393.29673735898, 6435.292117867125, 6528.9020664689115, 6584.539082264196, 6390.073439351361, 6412.711427985659, 6434.613923164625, 6463.612183269019, 6491.0, 6293.244810622571, 6383.234011918599, 6390.616563006234, 6399.968000159969, 6406.743730250738, 4920.704757714549, 4944.381952255968, 4956.256561515802, 4959.652824302264, 4994.0, 6336.271328797247, 6490.415862572391, 6543.858947705224, 6568.507361947777, 6575.309592492719, 6134.325224225421, 6167.259928808572, 6305.90541141874, 6454.741810327534, 6463.482921366185, 6092.268927728701, 6302.275238347649, 6337.049442583465, 6372.139761132188, 6389.0, 6210.534209934182, 6262.248530176408, 6296.842578935563, 6316.693977059109, 6359.618422894641, 6313.621182729051, 6356.682165891594, 6396.872062558816, 6454.903176452263, 6555.475561955128, 6154.5384096192065, 6218.347073557392, 6301.6219026858535, 6347.270063942521, 6359.491240700825], "value": 4977.39909952652, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [885.6014793342997, 887.3344991256557, 905.7101727447215, 917.8164367126576, 918.1919910478775, 18856.81143188568, 19044.238230470764, 19333.323333683325, 19427.708584371227, 19569.51076223095, 18410.171542280597, 18660.720089198672, 19047.52381190471, 19437.31969381072, 19555.90222048888, 937.8593211018368, 954.5561313988994, 970.2286682087722, 970.4322971061937, 970.9271804614682, 989.9356541824794, 1000.8348622477318, 1001.1240164855751, 1001.484235618657, 1001.644416232238, 930.1767935377172, 1020.8979102089805, 1027.4451796030173, 1028.6245520385044, 1029.0841151375243, 1019.1642852860657, 1019.1846522781744, 1019.765453945593, 1020.3418794877336, 1020.4744556553418, 718.192033961793, 744.6276861569215, 748.4274529984566, 754.2156157596094, 756.8826831841073, 18932.810671893243, 18999.145038473303, 19035.714464282908, 19063.90468047651, 19377.224911003545, 21973.67039494409, 22098.668519972212, 22674.88662556693, 22721.204757833562, 22778.430539236488, 17124.31502739889, 17309.39417120395, 17456.214470348867, 17858.91070544652, 17930.731039034425, 3758.567764707039, 3768.3593789055926, 3789.5263092113487, 3791.829367678461, 3796.202797412519, 144647.0, 148016.2599187008, 148023.0, 148165.0, 149943.250283749, 16872.66254674924, 17241.137943103047, 17389.47831565055, 17404.39084632032, 17412.39056633037, 31192.532112018336, 31952.840235799136, 32769.50845737316, 32832.50751238733, 32959.340813184084, 8811.251043661232, 8895.866562001574, 9063.909360906438, 9077.409968352036, 9089.909100908906, 4364.75993820343, 4424.6681498887065, 4435.733855968652, 4439.223135951195, 4479.3057076153245, 5362.91955620666, 5473.562115030788, 5475.4524547544925, 5476.069068258405, 5479.424660410679, 1035.7669524357111, 1053.931494452858, 1074.8656417947757, 1079.7084787107437, 1082.1613249731363, 669.3841665667597, 670.8993650952299, 691.2776148924386, 698.6646409723361, 706.2443185791154, 11157.274777139623, 11168.0, 11880.702982425335, 12107.576234831738, 12258.877411225774, 658.0031252652236, 668.5921587831419, 681.2574294019444, 688.8622275544911, 694.9131358580178, 5458.181272809031, 5505.421930697299, 5540.833774986757, 5620.606557540933, 5700.572457065737, 120729.0, 124257.37871310585, 128322.35838820746, 129097.0, 129267.0, 111564.44217778859, 111813.0, 112588.0, 113977.0, 118823.0, 100159.0, 100865.49567252117, 101577.49211254044, 103945.48027259816, 104840.47579762204, 10342.276040677081, 10490.265681402534, 10552.366857988545, 10601.946990265, 10703.785924281627, 43348.0, 44437.0, 45623.543764563256, 45740.54259457363, 46562.534374657174, 57512.42487575238, 59360.703196483744, 59476.70261648664, 59838.70080649569, 60061.099083512905, 15498.84501154974, 15526.689466210839, 15545.922270388575, 15720.21398930026, 15763.921180394025, 17428.738568921224, 17598.29606815713, 17762.46712598624, 17855.642887142443, 18133.818661813213, 14917.403303868157, 14969.401223951356, 15190.924045380145, 15207.239638017834, 15385.076895386313, 16126.919365403568, 16211.675766484841, 16572.66854662924, 16711.247993840065, 16913.91543042277, 15064.32210550506, 15227.695446091238, 15249.695006100037, 15334.846651533342, 15468.5359439217, 11990.100742444178, 12111.697207569881, 12128.939355303168, 12280.0, 12343.259404435763, 12162.966147877529, 12224.266544007389, 12241.938790305992, 12374.13381063317, 12401.0], "value": 9039.564104764471, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1445.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 111.0, 108.0, 108.0, 116.0, 109.0, 109.0, 107.0, 112.0, 118.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 119.0, 139.0, 129.0, 117.0, 117.0, 139.0, 115.0, 145.0, 124.0], "value": 126.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 171.0, 163.0, 163.0, 167.0, 166.0, 174.0, 166.0, 165.0, 165.0], "value": 166.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 74.0, 77.0, 75.0, 75.0, 84.0, 79.0, 81.0, 82.0], "value": 79.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 79.0, 79.0, 78.0, 85.0, 82.0, 78.0, 82.0, 85.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 112.0, 125.0, 112.0, 116.0, 118.0, 122.0, 113.0, 118.0, 118.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 147.0, 154.0, 149.0, 147.0, 148.0, 149.0, 151.0, 152.0], "value": 150.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 95.0, 94.0, 97.0, 95.0, 100.0, 103.0, 101.0, 100.0, 94.0], "value": 97.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 54.0, 52.0, 52.0, 51.0, 51.0, 60.0, 56.0, 47.0, 48.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 46.0, 37.0, 41.0, 41.0, 45.0, 39.0, 37.0, 38.0, 47.0], "value": 40.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 77.0, 79.0, 81.0, 76.0, 77.0, 72.0, 76.0, 78.0], "value": 77.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 113.0, 127.0, 112.0, 115.0, 121.0, 110.0, 113.0, 110.0], "value": 114.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 168.0, 165.0, 170.0, 168.0, 162.0, 165.0, 166.0, 164.0, 171.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 64.0, 66.0, 74.0, 67.0, 74.0, 63.0, 64.0, 64.0], "value": 67.0, "unit": "score"}]}]} 16:07:07 INFO - Return code: 0 16:07:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:07:08 INFO - # TBPL SUCCESS # 16:07:08 INFO - Running post-action listener: _package_coverage_data 16:07:08 INFO - Running post-action listener: _resource_record_post_action 16:07:08 INFO - [mozharness: 2017-10-01 23:07:08.011471Z] Finished run-tests step (success) 16:07:08 INFO - Running post-run listener: _resource_record_post_run 16:07:08 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 14729216; Write bytes: 337514496; Read time: 5604; Write time: 320928 16:07:08 INFO - TinderboxPrint: CPU usage
13.2% 16:07:08 INFO - TinderboxPrint: I/O read bytes / time
14,729,216 / 5,604 16:07:08 INFO - TinderboxPrint: I/O write bytes / time
337,514,496 / 320,928 16:07:08 INFO - TinderboxPrint: CPU idle
8,282.9 (86.7%) 16:07:08 INFO - TinderboxPrint: CPU user
1,197.9 (12.5%) 16:07:08 INFO - TinderboxPrint: Swap in / out
0 / 0 16:07:08 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12455936; Read time: 0; Write time: 1024 16:07:08 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:07:08 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 12361728; Write bytes: 325058560; Read time: 5240; Write time: 319904 16:07:08 INFO - Running post-run listener: _upload_blobber_files 16:07:08 INFO - Blob upload gear active. 16:07:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:07:08 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:07:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:07:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:07:09 INFO - (blobuploader) - INFO - Open directory for files ... 16:07:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:07:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:07:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:09 INFO - (blobuploader) - INFO - Done attempting. 16:07:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:07:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:07:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:10 INFO - (blobuploader) - INFO - Done attempting. 16:07:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:07:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:07:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:14 INFO - (blobuploader) - INFO - Done attempting. 16:07:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:07:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:07:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:07:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:07:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:07:15 INFO - (blobuploader) - INFO - Done attempting. 16:07:15 INFO - (blobuploader) - INFO - Iteration through files over. 16:07:15 INFO - Return code: 0 16:07:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:07:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:07:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbb043c2d3ee2106231681cefd015b9b56f4eee83c6abf245c7f14f34d29bd99d84c9c1a944f52dfa39036cd61d0adf4a0c18d18e2eec22123302dd4a9f45f88", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a374f03204fb7da8c93a404a5362fadb87dc0cfaba7f9e9a04d05e23e12f957f5ba4a57f0a49e62be50bf86c043850ecf11ac502d07d688c6baf7bff26de2c26", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d0a590fee73ee389039689a41d6ac205196e147bde3efbcf9ef0407548d6246083360b88de76061e3479bec6adf0ce4ee441082c89dcda9272723c7aa6df67f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acba36ca83a59d41d69401979351e4ffccd76be7c6b9ff803b0a12800d6c37be6f31b1e7b0ddc0f20b712f0927af2d5803644cf927f6678b86ed89498649a208"} 16:07:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:07:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:07:15 INFO - Contents: 16:07:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbb043c2d3ee2106231681cefd015b9b56f4eee83c6abf245c7f14f34d29bd99d84c9c1a944f52dfa39036cd61d0adf4a0c18d18e2eec22123302dd4a9f45f88", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a374f03204fb7da8c93a404a5362fadb87dc0cfaba7f9e9a04d05e23e12f957f5ba4a57f0a49e62be50bf86c043850ecf11ac502d07d688c6baf7bff26de2c26", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d0a590fee73ee389039689a41d6ac205196e147bde3efbcf9ef0407548d6246083360b88de76061e3479bec6adf0ce4ee441082c89dcda9272723c7aa6df67f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acba36ca83a59d41d69401979351e4ffccd76be7c6b9ff803b0a12800d6c37be6f31b1e7b0ddc0f20b712f0927af2d5803644cf927f6678b86ed89498649a208"} 16:07:15 INFO - Running post-run listener: copy_logs_to_upload_dir 16:07:15 INFO - Copying logs to upload dir... 16:07:15 INFO - mkdir: /builds/slave/test/build/upload/logs 16:07:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.043326 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-10-01 16:07:15.121752) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-01 16:07:15.124883) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799 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/fbb043c2d3ee2106231681cefd015b9b56f4eee83c6abf245c7f14f34d29bd99d84c9c1a944f52dfa39036cd61d0adf4a0c18d18e2eec22123302dd4a9f45f88", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a374f03204fb7da8c93a404a5362fadb87dc0cfaba7f9e9a04d05e23e12f957f5ba4a57f0a49e62be50bf86c043850ecf11ac502d07d688c6baf7bff26de2c26", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d0a590fee73ee389039689a41d6ac205196e147bde3efbcf9ef0407548d6246083360b88de76061e3479bec6adf0ce4ee441082c89dcda9272723c7aa6df67f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acba36ca83a59d41d69401979351e4ffccd76be7c6b9ff803b0a12800d6c37be6f31b1e7b0ddc0f20b712f0927af2d5803644cf927f6678b86ed89498649a208"} build_url:https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011258 build_url: 'https://queue.taskcluster.net/v1/task/RpAtbshBTzOTjzSa-o9ISQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fbb043c2d3ee2106231681cefd015b9b56f4eee83c6abf245c7f14f34d29bd99d84c9c1a944f52dfa39036cd61d0adf4a0c18d18e2eec22123302dd4a9f45f88", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a374f03204fb7da8c93a404a5362fadb87dc0cfaba7f9e9a04d05e23e12f957f5ba4a57f0a49e62be50bf86c043850ecf11ac502d07d688c6baf7bff26de2c26", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d0a590fee73ee389039689a41d6ac205196e147bde3efbcf9ef0407548d6246083360b88de76061e3479bec6adf0ce4ee441082c89dcda9272723c7aa6df67f", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acba36ca83a59d41d69401979351e4ffccd76be7c6b9ff803b0a12800d6c37be6f31b1e7b0ddc0f20b712f0927af2d5803644cf927f6678b86ed89498649a208"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-01 16:07:15.156478) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 16:07:15.156821) ========= 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=5cf47e208febbcd81da23b2e0000026f-1506886206.380242-340188799 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004525 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-01 16:07:15.238443) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-01 16:07:15.238834) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-01 16:07:15.239139) ========= ========= Total master_lag: 0.30 =========