builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-086 starttime: 1505957758.29 results: success (0) revision: 61340c7debf6dccec3b863d1bc00426feac42fe7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 18:35:58.289262) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-20 18:35:58.289740) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 18:35:58.290011) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-20 18:35:58.312004) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:35:58.312369) ========= 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=438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-20 18:35:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 178K=0.07s 2017-09-20 18:35:58 (178 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.163327 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:35:58.492547) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:35:58.492875) ========= 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=438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017555 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:35:58.549785) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:35:58.550132) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 61340c7debf6dccec3b863d1bc00426feac42fe7 --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 61340c7debf6dccec3b863d1bc00426feac42fe7 --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=438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-20 18:35:58,534 truncating revision to first 12 chars 2017-09-20 18:35:58,534 Setting DEBUG logging. 2017-09-20 18:35:58,534 attempt 1/10 2017-09-20 18:35:58,534 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/61340c7debf6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-20 18:35:59,090 unpacking tar archive at: mozilla-central-61340c7debf6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.847345 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:35:59.417309) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 18:35:59.417737) ========= script_repo_revision: 61340c7debf6dccec3b863d1bc00426feac42fe7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-20 18:35:59.418117) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 18:35:59.418381) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-20 18:35:59.432547) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-20 18:35:59.432996) ========= /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=438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:35:59 INFO - MultiFileLogger online at 20170920 18:35:59 in /builds/slave/test 18:35:59 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 18:35:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:35:59 INFO - {'append_to_log': False, 18:35:59 INFO - 'base_work_dir': '/builds/slave/test', 18:35:59 INFO - 'blob_upload_branch': 'Firefox', 18:35:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:35:59 INFO - 'branch': 'Firefox', 18:35:59 INFO - 'buildbot_json_path': 'buildprops.json', 18:35:59 INFO - 'code_coverage': False, 18:35:59 INFO - 'config_files': ('talos/linux_config.py',), 18:35:59 INFO - 'default_actions': ('clobber', 18:35:59 INFO - 'read-buildbot-config', 18:35:59 INFO - 'download-and-extract', 18:35:59 INFO - 'populate-webroot', 18:35:59 INFO - 'create-virtualenv', 18:35:59 INFO - 'install', 18:35:59 INFO - 'setup-mitmproxy', 18:35:59 INFO - 'run-tests'), 18:35:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:35:59 INFO - 'disable_ccov_upload': False, 18:35:59 INFO - 'disable_stylo': False, 18:35:59 INFO - 'download_minidump_stackwalk': True, 18:35:59 INFO - 'download_symbols': 'ondemand', 18:35:59 INFO - 'enable_stylo': False, 18:35:59 INFO - 'enable_webrender': False, 18:35:59 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:35:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:35:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:35:59 INFO - 'gecko_profile': False, 18:35:59 INFO - 'gecko_profile_interval': 0, 18:35:59 INFO - 'installer_path': 'installer.exe', 18:35:59 INFO - 'log_level': 'info', 18:35:59 INFO - 'log_name': 'talos', 18:35:59 INFO - 'log_to_console': True, 18:35:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:35:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:35:59 INFO - 'opt_config_files': (), 18:35:59 INFO - 'pip_index': False, 18:35:59 INFO - 'suite': 'dromaeojs-e10s', 18:35:59 INFO - 'system_bits': '32', 18:35:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:35:59 INFO - 'title': 'talos-linux64-ix-086', 18:35:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:35:59 INFO - 'use_talos_json': True, 18:35:59 INFO - 'verify': 'False', 18:35:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:35:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:35:59 INFO - 'work_dir': 'build'} 18:35:59 INFO - [mozharness: 2017-09-21 01:35:59.564614Z] Running clobber step. 18:35:59 INFO - Running pre-action listener: _resource_record_pre_action 18:35:59 INFO - Running main action method: clobber 18:35:59 INFO - rmtree: /builds/slave/test/build 18:35:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:36:00 INFO - Running post-action listener: _resource_record_post_action 18:36:00 INFO - [mozharness: 2017-09-21 01:36:00.568394Z] Finished clobber step (success) 18:36:00 INFO - [mozharness: 2017-09-21 01:36:00.568494Z] Running read-buildbot-config step. 18:36:00 INFO - Running pre-action listener: _resource_record_pre_action 18:36:00 INFO - Running main action method: read_buildbot_config 18:36:00 INFO - Using buildbot properties: 18:36:00 INFO - { 18:36:00 INFO - "project": "", 18:36:00 INFO - "product": "firefox", 18:36:00 INFO - "who": "kwierso@gmail.com", 18:36:00 INFO - "installer_path": "public/build/target.tar.bz2", 18:36:00 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:36:00 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:36:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 18:36:00 INFO - "stage_platform": "linux64-pgo", 18:36:00 INFO - "basedir": "/builds/slave/test", 18:36:00 INFO - "buildnumber": 188, 18:36:00 INFO - "platform": "ubuntu64_hw", 18:36:00 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:36:00 INFO - "slavebuilddir": "test", 18:36:00 INFO - "taskId": "LT_3VqMvQfqFdAzxLEZgPg", 18:36:00 INFO - "branch": "mozilla-central", 18:36:00 INFO - "script_repo_revision": "production", 18:36:00 INFO - "revision": "61340c7debf6dccec3b863d1bc00426feac42fe7", 18:36:00 INFO - "slavename": "talos-linux64-ix-086", 18:36:00 INFO - "repo_path": "mozilla-central" 18:36:00 INFO - } 18:36:00 INFO - Finding installer, test and symbols from parent task. 18:36:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LT_3VqMvQfqFdAzxLEZgPg'}, attempt #1 18:36:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LT_3VqMvQfqFdAzxLEZgPg'}, attempt #1 18:36:01 INFO - Task dependencies: SUgHeWVwSNKoEh42Vq7Wvw 18:36:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw'}, attempt #1 18:36:01 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.tar.bz2 18:36:01 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.test_packages.json 18:36:01 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.crashreporter-symbols.zip 18:36:01 INFO - Running post-action listener: _resource_record_post_action 18:36:01 INFO - [mozharness: 2017-09-21 01:36:01.987938Z] Finished read-buildbot-config step (success) 18:36:01 INFO - [mozharness: 2017-09-21 01:36:01.988156Z] Running download-and-extract step. 18:36:01 INFO - Running pre-action listener: _resource_record_pre_action 18:36:01 INFO - Running main action method: download_and_extract 18:36:01 INFO - mkdir: /builds/slave/test/build/tests 18:36:01 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')]} 18:36:01 INFO - trying https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.test_packages.json 18:36:01 INFO - Downloading https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:36:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:36:02 INFO - Downloaded 989 bytes. 18:36:02 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:36:02 INFO - Using the following test package requirements: 18:36:02 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:36:02 INFO - u'common': [u'target.common.tests.zip'], 18:36:02 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:36:02 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:36:02 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:36:02 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:36:02 INFO - u'mozbase': [u'target.common.tests.zip'], 18:36:02 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:36:02 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:36:02 INFO - u'web-platform': [u'target.common.tests.zip', 18:36:02 INFO - u'target.web-platform.tests.tar.gz'], 18:36:02 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:36:02 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 18:36:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.common.tests.zip 18:36:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:36:02 INFO - Fetch https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.common.tests.zip into memory 18:36:06 INFO - Content-Length response header: 55326845 18:36:06 INFO - Bytes received: 55326845 18:36:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.talos.tests.zip 18:36:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:36:17 INFO - Fetch https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.talos.tests.zip into memory 18:36:18 INFO - Content-Length response header: 14512509 18:36:18 INFO - Bytes received: 14512509 18:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:19 INFO - trying https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.tar.bz2 18:36:19 INFO - Downloading https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:36:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:36:21 INFO - Downloaded 62378767 bytes. 18:36:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.tar.bz2 18:36:21 INFO - mkdir: /builds/slave/test/properties 18:36:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:36:21 INFO - Writing to file /builds/slave/test/properties/build_url 18:36:21 INFO - Contents: 18:36:21 INFO - build_url:https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.tar.bz2 18:36:21 INFO - Running post-action listener: _resource_record_post_action 18:36:21 INFO - Running post-action listener: find_tests_for_verification 18:36:21 INFO - Running post-action listener: set_extra_try_arguments 18:36:21 INFO - [mozharness: 2017-09-21 01:36:21.514830Z] Finished download-and-extract step (success) 18:36:21 INFO - [mozharness: 2017-09-21 01:36:21.514927Z] Running populate-webroot step. 18:36:21 INFO - Running pre-action listener: _resource_record_pre_action 18:36:21 INFO - Running main action method: populate_webroot 18:36:21 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:36:21 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:36:21 INFO - 'tests': ['tresize', 18:36:21 INFO - 'tcanvasmark']}, 18:36:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:36:21 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:36:21 INFO - 'tests': ['dromaeo_css', 18:36:21 INFO - 'kraken']}, 18:36:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:36:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:36:21 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:36:21 INFO - 'talos_options': ['--disable-stylo'], 18:36:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:36:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:36:21 INFO - 'tests': ['damp', 'tps']}, 18:36:21 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:36:21 INFO - 'talos_options': ['--disable-stylo'], 18:36:21 INFO - 'tests': ['damp', 'tps']}, 18:36:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:36:21 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:36:21 INFO - 'tests': ['dromaeo_dom']}, 18:36:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:36:21 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:36:21 INFO - 'tests': ['basic_compositor_video', 18:36:21 INFO - 'glvideo']}, 18:36:21 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:36:21 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:36:21 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:36:21 INFO - 'talos_options': ['--disable-stylo'], 18:36:21 INFO - 'tests': ['ts_paint_webext', 18:36:21 INFO - 'tp5o_webext']}, 18:36:21 INFO - 'other-e10s': {'tests': ['a11yr', 18:36:21 INFO - 'ts_paint', 18:36:21 INFO - 'tpaint', 18:36:21 INFO - 'sessionrestore', 18:36:21 INFO - 'sessionrestore_many_windows', 18:36:21 INFO - 'sessionrestore_no_auto_restore', 18:36:21 INFO - 'tabpaint']}, 18:36:21 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:36:21 INFO - 'tests': ['a11yr', 18:36:21 INFO - 'ts_paint', 18:36:21 INFO - 'tpaint', 18:36:21 INFO - 'sessionrestore', 18:36:21 INFO - 'sessionrestore_many_windows', 18:36:21 INFO - 'sessionrestore_no_auto_restore', 18:36:21 INFO - 'tabpaint']}, 18:36:21 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 18:36:21 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 18:36:21 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:36:21 INFO - 'tests': ['perf_reftest_singletons']}, 18:36:21 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:36:21 INFO - 'tests': ['bloom_basic']}, 18:36:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:36:21 INFO - 'tsvgr_opacity', 18:36:21 INFO - 'tart', 18:36:21 INFO - 'tscrollx', 18:36:21 INFO - 'tsvg_static']}, 18:36:21 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 18:36:21 INFO - 'tests': ['tsvgx', 18:36:21 INFO - 'tsvgr_opacity', 18:36:21 INFO - 'tart', 18:36:21 INFO - 'tscrollx', 18:36:21 INFO - 'tsvg_static']}, 18:36:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:36:21 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:36:21 INFO - 'talos_options': ['--disable-stylo'], 18:36:21 INFO - 'tests': ['tp5o']}, 18:36:21 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:36:21 INFO - 'talos_options': ['--mitmproxy', 18:36:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:36:21 INFO - '--firstNonBlankPaint'], 18:36:21 INFO - 'tests': ['tp6_google', 18:36:21 INFO - 'tp6_youtube', 18:36:21 INFO - 'tp6_amazon', 18:36:21 INFO - 'tp6_facebook']}, 18:36:21 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:36:21 INFO - 'talos_options': ['--disable-stylo', 18:36:21 INFO - '--mitmproxy', 18:36:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:36:21 INFO - '--firstNonBlankPaint'], 18:36:21 INFO - 'tests': ['tp6_google', 18:36:21 INFO - 'tp6_youtube', 18:36:21 INFO - 'tp6_amazon', 18:36:21 INFO - 'tp6_facebook']}, 18:36:21 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:36:21 INFO - 'talos_options': ['--stylo-threads=1', 18:36:21 INFO - '--mitmproxy', 18:36:21 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:36:21 INFO - '--firstNonBlankPaint'], 18:36:21 INFO - 'tests': ['tp6_google', 18:36:21 INFO - 'tp6_youtube', 18:36:21 INFO - 'tp6_amazon', 18:36:21 INFO - 'tp6_facebook']}, 18:36:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:36:21 INFO - 'talos_options': ['--xperf_path', 18:36:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:36:21 INFO - 'tests': ['tp5n']}, 18:36:21 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 18:36:21 INFO - 'talos_options': ['--disable-stylo', 18:36:21 INFO - '--xperf_path', 18:36:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:36:21 INFO - 'tests': ['tp5n']}}} 18:36:21 INFO - Running post-action listener: _resource_record_post_action 18:36:21 INFO - [mozharness: 2017-09-21 01:36:21.530340Z] Finished populate-webroot step (success) 18:36:21 INFO - [mozharness: 2017-09-21 01:36:21.530430Z] Running create-virtualenv step. 18:36:21 INFO - Running pre-action listener: _resource_record_pre_action 18:36:21 INFO - Running main action method: create_virtualenv 18:36:21 INFO - Creating virtualenv /builds/slave/test/build/venv 18:36:21 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:36:21 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:36:21 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:36:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:36:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:36:22 INFO - Installing distribute.............................................................................................................................................................................................done. 18:36:22 INFO - Installing pip...............done. 18:36:22 INFO - Return code: 0 18:36:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:36:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:36:22 INFO - Reading from file tmpfile_stdout 18:36:22 INFO - Output received: 18:36:22 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:36:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:36:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a47a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a4b090>, '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 0x2a4bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a45f90>, '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': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', '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 18:36:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:36:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:36:22 INFO - Using env: {'DISPLAY': ':0', 18:36:22 INFO - 'HOME': '/home/cltbld', 18:36:22 INFO - 'LANG': 'en_US.UTF-8', 18:36:22 INFO - 'LANGUAGE': 'en_US:en', 18:36:22 INFO - 'LOGNAME': 'cltbld', 18:36:22 INFO - 'MAIL': '/var/mail/cltbld', 18:36:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:22 INFO - 'MOZ_NO_REMOTE': '1', 18:36:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:22 INFO - 'NO_EM_RESTART': '1', 18:36:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:22 INFO - 'PWD': '/builds/slave/test', 18:36:22 INFO - 'SHELL': '/bin/bash', 18:36:22 INFO - 'SHLVL': '1', 18:36:22 INFO - 'TERM': 'linux', 18:36:22 INFO - 'TMOUT': '86400', 18:36:22 INFO - 'USER': 'cltbld', 18:36:22 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', 18:36:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:22 INFO - '_': '/tools/buildbot/bin/python'} 18:36:24 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:36:24 INFO - Downloading/unpacking pip>=1.5 18:36:24 INFO - Running setup.py egg_info for package pip 18:36:24 INFO - warning: no files found matching 'pip/cacert.pem' 18:36:24 INFO - warning: no files found matching '*.html' under directory 'docs' 18:36:24 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:36:24 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:36:24 INFO - Installing collected packages: pip 18:36:24 INFO - Found existing installation: pip 1.1 18:36:24 INFO - Uninstalling pip: 18:36:24 INFO - Successfully uninstalled pip 18:36:24 INFO - Running setup.py install for pip 18:36:24 INFO - warning: no files found matching 'pip/cacert.pem' 18:36:24 INFO - warning: no files found matching '*.html' under directory 'docs' 18:36:24 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:36:24 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:36:24 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:36:24 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:36:24 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:36:24 INFO - Successfully installed pip 18:36:24 INFO - Cleaning up... 18:36:24 INFO - Return code: 0 18:36:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:36:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a47a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a4b090>, '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 0x2a4bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a45f90>, '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': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', '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 18:36:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:36:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:36:24 INFO - Using env: {'DISPLAY': ':0', 18:36:24 INFO - 'HOME': '/home/cltbld', 18:36:24 INFO - 'LANG': 'en_US.UTF-8', 18:36:24 INFO - 'LANGUAGE': 'en_US:en', 18:36:24 INFO - 'LOGNAME': 'cltbld', 18:36:24 INFO - 'MAIL': '/var/mail/cltbld', 18:36:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:24 INFO - 'MOZ_NO_REMOTE': '1', 18:36:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:24 INFO - 'NO_EM_RESTART': '1', 18:36:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:24 INFO - 'PWD': '/builds/slave/test', 18:36:24 INFO - 'SHELL': '/bin/bash', 18:36:24 INFO - 'SHLVL': '1', 18:36:24 INFO - 'TERM': 'linux', 18:36:24 INFO - 'TMOUT': '86400', 18:36:24 INFO - 'USER': 'cltbld', 18:36:24 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', 18:36:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:24 INFO - '_': '/tools/buildbot/bin/python'} 18:36:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:24 INFO - Downloading/unpacking psutil>=3.1.1 18:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:36:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:36:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:36:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:36:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:36:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:36:26 INFO - Installing collected packages: psutil 18:36:26 INFO - Running setup.py install for psutil 18:36:26 INFO - building 'psutil._psutil_linux' extension 18:36:26 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 18:36:26 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 18:36:26 INFO - building 'psutil._psutil_posix' extension 18:36:26 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 18:36:26 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 18:36:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:36:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:36:26 INFO - Successfully installed psutil 18:36:26 INFO - Cleaning up... 18:36:26 INFO - Return code: 0 18:36:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:36:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:26 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 0x26db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a47a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a4b090>, '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 0x2a4bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a45f90>, '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': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', '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 18:36:26 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 18:36:26 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 18:36:26 INFO - Using env: {'DISPLAY': ':0', 18:36:26 INFO - 'HOME': '/home/cltbld', 18:36:26 INFO - 'LANG': 'en_US.UTF-8', 18:36:26 INFO - 'LANGUAGE': 'en_US:en', 18:36:26 INFO - 'LOGNAME': 'cltbld', 18:36:26 INFO - 'MAIL': '/var/mail/cltbld', 18:36:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:26 INFO - 'MOZ_NO_REMOTE': '1', 18:36:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:26 INFO - 'NO_EM_RESTART': '1', 18:36:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:26 INFO - 'PWD': '/builds/slave/test', 18:36:26 INFO - 'SHELL': '/bin/bash', 18:36:26 INFO - 'SHLVL': '1', 18:36:26 INFO - 'TERM': 'linux', 18:36:26 INFO - 'TMOUT': '86400', 18:36:26 INFO - 'USER': 'cltbld', 18:36:26 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', 18:36:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:26 INFO - '_': '/tools/buildbot/bin/python'} 18:36:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:36:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:36:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:36:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:36:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:36:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:36:28 INFO - Installing collected packages: mozsystemmonitor 18:36:28 INFO - Running setup.py install for mozsystemmonitor 18:36:28 INFO - Successfully installed mozsystemmonitor 18:36:28 INFO - Cleaning up... 18:36:28 INFO - Return code: 0 18:36:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:36:28 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')]} 18:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:28 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')]} 18:36:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:28 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 0x26db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a47a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a4b090>, '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 0x2a4bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a45f90>, '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': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', '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 18:36:28 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 18:36:28 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 18:36:28 INFO - Using env: {'DISPLAY': ':0', 18:36:28 INFO - 'HOME': '/home/cltbld', 18:36:28 INFO - 'LANG': 'en_US.UTF-8', 18:36:28 INFO - 'LANGUAGE': 'en_US:en', 18:36:28 INFO - 'LOGNAME': 'cltbld', 18:36:28 INFO - 'MAIL': '/var/mail/cltbld', 18:36:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:28 INFO - 'MOZ_NO_REMOTE': '1', 18:36:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:28 INFO - 'NO_EM_RESTART': '1', 18:36:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:28 INFO - 'PWD': '/builds/slave/test', 18:36:28 INFO - 'SHELL': '/bin/bash', 18:36:28 INFO - 'SHLVL': '1', 18:36:28 INFO - 'TERM': 'linux', 18:36:28 INFO - 'TMOUT': '86400', 18:36:28 INFO - 'USER': 'cltbld', 18:36:28 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', 18:36:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:28 INFO - '_': '/tools/buildbot/bin/python'} 18:36:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:29 INFO - Downloading/unpacking jsonschema==2.5.1 18:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:36:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:36:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:36:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:36:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:36:30 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 18:36:30 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 18:36:30 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 18:36:30 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 18:36:30 INFO - Downloading functools32-3.2.3-2.tar.gz 18:36:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:36:30 INFO - warning: no files found matching '*.txt' 18:36:30 INFO - no previously-included directories found matching 'build' 18:36:30 INFO - no previously-included directories found matching 'dist' 18:36:30 INFO - no previously-included directories found matching '.git*' 18:36:30 INFO - Installing collected packages: jsonschema, functools32 18:36:30 INFO - Running setup.py install for functools32 18:36:30 INFO - warning: no files found matching '*.txt' 18:36:30 INFO - no previously-included directories found matching 'build' 18:36:30 INFO - no previously-included directories found matching 'dist' 18:36:30 INFO - no previously-included directories found matching '.git*' 18:36:30 INFO - Successfully installed jsonschema functools32 18:36:30 INFO - Cleaning up... 18:36:30 INFO - Return code: 0 18:36:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:36:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a47a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a4b090>, '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 0x2a4bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a45f90>, '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': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', '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 18:36:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:36:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:36:30 INFO - Using env: {'DISPLAY': ':0', 18:36:30 INFO - 'HOME': '/home/cltbld', 18:36:30 INFO - 'LANG': 'en_US.UTF-8', 18:36:30 INFO - 'LANGUAGE': 'en_US:en', 18:36:30 INFO - 'LOGNAME': 'cltbld', 18:36:30 INFO - 'MAIL': '/var/mail/cltbld', 18:36:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:30 INFO - 'MOZ_NO_REMOTE': '1', 18:36:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:30 INFO - 'NO_EM_RESTART': '1', 18:36:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:30 INFO - 'PWD': '/builds/slave/test', 18:36:30 INFO - 'SHELL': '/bin/bash', 18:36:30 INFO - 'SHLVL': '1', 18:36:30 INFO - 'TERM': 'linux', 18:36:30 INFO - 'TMOUT': '86400', 18:36:30 INFO - 'USER': 'cltbld', 18:36:30 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', 18:36:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:30 INFO - '_': '/tools/buildbot/bin/python'} 18:36:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:36:31 INFO - Cleaning up... 18:36:31 INFO - Return code: 0 18:36:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:36:31 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')]} 18:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:31 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')]} 18:36:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:31 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 0x26db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a47a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a4b090>, '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 0x2a4bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a45f90>, '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': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', '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 18:36:31 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 18:36:31 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 18:36:31 INFO - Using env: {'DISPLAY': ':0', 18:36:31 INFO - 'HOME': '/home/cltbld', 18:36:31 INFO - 'LANG': 'en_US.UTF-8', 18:36:31 INFO - 'LANGUAGE': 'en_US:en', 18:36:31 INFO - 'LOGNAME': 'cltbld', 18:36:31 INFO - 'MAIL': '/var/mail/cltbld', 18:36:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:31 INFO - 'MOZ_NO_REMOTE': '1', 18:36:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:31 INFO - 'NO_EM_RESTART': '1', 18:36:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:31 INFO - 'PWD': '/builds/slave/test', 18:36:31 INFO - 'SHELL': '/bin/bash', 18:36:31 INFO - 'SHLVL': '1', 18:36:31 INFO - 'TERM': 'linux', 18:36:31 INFO - 'TMOUT': '86400', 18:36:31 INFO - 'USER': 'cltbld', 18:36:31 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', 18:36:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:31 INFO - '_': '/tools/buildbot/bin/python'} 18:36:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:31 INFO - Downloading/unpacking blobuploader==1.2.4 18:36:31 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 18:36:31 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 18:36:31 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 18:36:31 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 18:36:32 INFO - Downloading blobuploader-1.2.4.tar.gz 18:36:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:36:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:36:32 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 18:36:32 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 18:36:32 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 18:36:32 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 18:36:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:36:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:36:32 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 18:36:32 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 18:36:32 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 18:36:32 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 18:36:33 INFO - Downloading docopt-0.6.1.tar.gz 18:36:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:36:33 INFO - Installing collected packages: blobuploader, requests, docopt 18:36:33 INFO - Running setup.py install for blobuploader 18:36:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:36:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:36:33 INFO - Running setup.py install for requests 18:36:33 INFO - Running setup.py install for docopt 18:36:33 INFO - Successfully installed blobuploader requests docopt 18:36:33 INFO - Cleaning up... 18:36:33 INFO - Return code: 0 18:36:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:33 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 0x26db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a47a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a4b090>, '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 0x2a4bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a45f90>, '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': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', '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 18:36:33 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 18:36:33 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 18:36:33 INFO - Using env: {'DISPLAY': ':0', 18:36:33 INFO - 'HOME': '/home/cltbld', 18:36:33 INFO - 'LANG': 'en_US.UTF-8', 18:36:33 INFO - 'LANGUAGE': 'en_US:en', 18:36:33 INFO - 'LOGNAME': 'cltbld', 18:36:33 INFO - 'MAIL': '/var/mail/cltbld', 18:36:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:33 INFO - 'MOZ_NO_REMOTE': '1', 18:36:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:33 INFO - 'NO_EM_RESTART': '1', 18:36:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:33 INFO - 'PWD': '/builds/slave/test', 18:36:33 INFO - 'SHELL': '/bin/bash', 18:36:33 INFO - 'SHLVL': '1', 18:36:33 INFO - 'TERM': 'linux', 18:36:33 INFO - 'TMOUT': '86400', 18:36:33 INFO - 'USER': 'cltbld', 18:36:33 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', 18:36:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:33 INFO - '_': '/tools/buildbot/bin/python'} 18:36:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:36:33 INFO - Running setup.py (path:/tmp/pip-KJZdOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:36:34 INFO - Running setup.py (path:/tmp/pip-WRJvEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:36:34 INFO - Running setup.py (path:/tmp/pip-SUSvqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:36:34 INFO - Running setup.py (path:/tmp/pip-Q8INY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:36:34 INFO - Running setup.py (path:/tmp/pip-O5nmJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:36:34 INFO - Running setup.py (path:/tmp/pip-ir7_WX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:36:34 INFO - Running setup.py (path:/tmp/pip-8zgtTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:36:34 INFO - Running setup.py (path:/tmp/pip-sKgtIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:36:34 INFO - Running setup.py (path:/tmp/pip-3Rk_pj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:36:34 INFO - Running setup.py (path:/tmp/pip-yjx76b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:36:34 INFO - Running setup.py (path:/tmp/pip-E5t0I3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:36:34 INFO - Running setup.py (path:/tmp/pip-b0MCnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:36:34 INFO - Running setup.py (path:/tmp/pip-wDARXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:36:34 INFO - Running setup.py (path:/tmp/pip-eBMCxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:36:34 INFO - Running setup.py (path:/tmp/pip-WE60dZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:36:35 INFO - Running setup.py (path:/tmp/pip-WfVBmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:36:35 INFO - Running setup.py (path:/tmp/pip-DPB_IB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:36:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:36:35 INFO - Running setup.py install for manifestparser 18:36:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:36:35 INFO - Running setup.py install for mozcrash 18:36:35 INFO - Running setup.py install for mozdebug 18:36:35 INFO - Running setup.py install for mozdevice 18:36:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:36:35 INFO - Running setup.py install for mozfile 18:36:35 INFO - Running setup.py install for mozhttpd 18:36:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:36:35 INFO - Running setup.py install for mozinfo 18:36:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:36:35 INFO - Running setup.py install for mozInstall 18:36:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:36:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:36:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:36:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:36:35 INFO - Running setup.py install for mozleak 18:36:36 INFO - Running setup.py install for mozlog 18:36:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:36:36 INFO - Running setup.py install for moznetwork 18:36:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:36:36 INFO - Running setup.py install for mozprocess 18:36:36 INFO - Running setup.py install for mozprofile 18:36:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:36:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:36:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:36:36 INFO - Running setup.py install for mozrunner 18:36:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:36:36 INFO - Running setup.py install for mozscreenshot 18:36:36 INFO - Running setup.py install for moztest 18:36:36 INFO - Running setup.py install for mozversion 18:36:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:36:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:36:36 INFO - Cleaning up... 18:36:36 INFO - Return code: 0 18:36:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:36:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:36 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 0x26db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a47a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a4b090>, '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 0x2a4bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a45f90>, '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': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', '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 18:36:36 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 18:36:36 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 18:36:36 INFO - Using env: {'DISPLAY': ':0', 18:36:36 INFO - 'HOME': '/home/cltbld', 18:36:36 INFO - 'LANG': 'en_US.UTF-8', 18:36:36 INFO - 'LANGUAGE': 'en_US:en', 18:36:36 INFO - 'LOGNAME': 'cltbld', 18:36:36 INFO - 'MAIL': '/var/mail/cltbld', 18:36:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:36 INFO - 'MOZ_NO_REMOTE': '1', 18:36:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:36 INFO - 'NO_EM_RESTART': '1', 18:36:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:36 INFO - 'PWD': '/builds/slave/test', 18:36:36 INFO - 'SHELL': '/bin/bash', 18:36:36 INFO - 'SHLVL': '1', 18:36:36 INFO - 'TERM': 'linux', 18:36:36 INFO - 'TMOUT': '86400', 18:36:36 INFO - 'USER': 'cltbld', 18:36:36 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', 18:36:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:36 INFO - '_': '/tools/buildbot/bin/python'} 18:36:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:36:37 INFO - Running setup.py (path:/tmp/pip-lnK2s0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:36:37 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)) 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:36:37 INFO - Running setup.py (path:/tmp/pip-HddCAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:36:37 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)) 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:36:37 INFO - Running setup.py (path:/tmp/pip-lURf0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:36:37 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)) 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:36:37 INFO - Running setup.py (path:/tmp/pip-Eccl3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:36:37 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)) 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:36:37 INFO - Running setup.py (path:/tmp/pip-Gbu7Es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:36:37 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)) 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:36:37 INFO - Running setup.py (path:/tmp/pip-_cdMw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:36:37 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)) 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:36:37 INFO - Running setup.py (path:/tmp/pip-tCQSMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:36:37 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)) 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:36:37 INFO - Running setup.py (path:/tmp/pip-0p3BIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:36:37 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)) 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:36:37 INFO - Running setup.py (path:/tmp/pip-CKOM15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:36:37 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)) 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:36:37 INFO - Running setup.py (path:/tmp/pip-8YQrNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:36:37 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)) 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:36:37 INFO - Running setup.py (path:/tmp/pip-PF8NO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:36:37 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)) 18:36:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:36:37 INFO - Running setup.py (path:/tmp/pip-TIi3oA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:36:38 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)) 18:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:36:38 INFO - Running setup.py (path:/tmp/pip-7QpaqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:36:38 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)) 18:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:36:38 INFO - Running setup.py (path:/tmp/pip-btWCIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:36:38 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)) 18:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:36:38 INFO - Running setup.py (path:/tmp/pip-VwuiBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:36:38 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)) 18:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:36:38 INFO - Running setup.py (path:/tmp/pip-NWaOHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:36:38 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)) 18:36:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:36:38 INFO - Running setup.py (path:/tmp/pip-OuHs8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:36:38 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)) 18:36:38 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)) 18:36:38 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)) 18:36:38 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)) 18:36:38 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)) 18:36:38 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)) 18:36:38 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)) 18:36:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:36:38 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 18:36:38 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 18:36:38 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 18:36:38 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 18:36:39 INFO - Downloading blessings-1.6.tar.gz 18:36:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:36:39 INFO - Installing collected packages: blessings 18:36:39 INFO - Running setup.py install for blessings 18:36:39 INFO - Successfully installed blessings 18:36:39 INFO - Cleaning up... 18:36:39 INFO - Return code: 0 18:36:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:36:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:36:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:36:39 INFO - Reading from file tmpfile_stdout 18:36:39 INFO - Current package versions: 18:36:39 INFO - argparse == 1.2.1 18:36:39 INFO - blessings == 1.6 18:36:39 INFO - blobuploader == 1.2.4 18:36:39 INFO - docopt == 0.6.1 18:36:39 INFO - functools32 == 3.2.3-2 18:36:39 INFO - jsonschema == 2.5.1 18:36:39 INFO - manifestparser == 1.1 18:36:39 INFO - mozInstall == 1.13 18:36:39 INFO - mozcrash == 1.0 18:36:39 INFO - mozdebug == 0.1 18:36:39 INFO - mozdevice == 0.51 18:36:39 INFO - mozfile == 1.2 18:36:39 INFO - mozhttpd == 0.7 18:36:39 INFO - mozinfo == 0.10 18:36:39 INFO - mozleak == 0.1 18:36:39 INFO - mozlog == 3.5 18:36:39 INFO - moznetwork == 0.27 18:36:39 INFO - mozprocess == 0.25 18:36:39 INFO - mozprofile == 0.28 18:36:39 INFO - mozrunner == 6.13 18:36:39 INFO - mozscreenshot == 0.1 18:36:39 INFO - mozsystemmonitor == 0.3 18:36:39 INFO - moztest == 0.8 18:36:39 INFO - mozversion == 1.4 18:36:39 INFO - psutil == 3.1.1 18:36:39 INFO - requests == 1.2.3 18:36:39 INFO - wsgiref == 0.1.2 18:36:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:36:39 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')]} 18:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:39 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')]} 18:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:39 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 0x26db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a47a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a4b090>, '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 0x2a4bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a45f90>, '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': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', '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 18:36:39 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 18:36:39 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 18:36:39 INFO - Using env: {'DISPLAY': ':0', 18:36:39 INFO - 'HOME': '/home/cltbld', 18:36:39 INFO - 'LANG': 'en_US.UTF-8', 18:36:39 INFO - 'LANGUAGE': 'en_US:en', 18:36:39 INFO - 'LOGNAME': 'cltbld', 18:36:39 INFO - 'MAIL': '/var/mail/cltbld', 18:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:39 INFO - 'MOZ_NO_REMOTE': '1', 18:36:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:39 INFO - 'NO_EM_RESTART': '1', 18:36:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:39 INFO - 'PWD': '/builds/slave/test', 18:36:39 INFO - 'SHELL': '/bin/bash', 18:36:39 INFO - 'SHLVL': '1', 18:36:39 INFO - 'TERM': 'linux', 18:36:39 INFO - 'TMOUT': '86400', 18:36:39 INFO - 'USER': 'cltbld', 18:36:39 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', 18:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:39 INFO - '_': '/tools/buildbot/bin/python'} 18:36:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:40 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)) 18:36:40 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)) 18:36:40 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)) 18:36:40 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)) 18:36:40 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)) 18:36:40 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)) 18:36:40 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)) 18:36:40 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)) 18:36:40 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)) 18:36:40 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:36:40 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 18:36:40 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 18:36:40 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 18:36:40 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 18:36:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:36:41 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)) 18:36:41 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)) 18:36:41 INFO - Installing collected packages: simplejson 18:36:41 INFO - Running setup.py install for simplejson 18:36:41 INFO - building 'simplejson._speedups' extension 18:36:41 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 18:36:42 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 18:36:42 INFO - Successfully installed simplejson 18:36:42 INFO - Cleaning up... 18:36:42 INFO - Return code: 0 18:36:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:36:42 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')]} 18:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:42 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')]} 18:36:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:42 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 0x26db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a47a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a4b090>, '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 0x2a4bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a45f90>, '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': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', '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 18:36:42 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 18:36:42 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 18:36:42 INFO - Using env: {'DISPLAY': ':0', 18:36:42 INFO - 'HOME': '/home/cltbld', 18:36:42 INFO - 'LANG': 'en_US.UTF-8', 18:36:42 INFO - 'LANGUAGE': 'en_US:en', 18:36:42 INFO - 'LOGNAME': 'cltbld', 18:36:42 INFO - 'MAIL': '/var/mail/cltbld', 18:36:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:42 INFO - 'MOZ_NO_REMOTE': '1', 18:36:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:36:42 INFO - 'NO_EM_RESTART': '1', 18:36:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:36:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:42 INFO - 'PWD': '/builds/slave/test', 18:36:42 INFO - 'SHELL': '/bin/bash', 18:36:42 INFO - 'SHLVL': '1', 18:36:42 INFO - 'TERM': 'linux', 18:36:42 INFO - 'TMOUT': '86400', 18:36:42 INFO - 'USER': 'cltbld', 18:36:42 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', 18:36:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:42 INFO - '_': '/tools/buildbot/bin/python'} 18:36:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:36:42 INFO - Cleaning up... 18:36:42 INFO - Return code: 0 18:36:42 INFO - Running post-action listener: _resource_record_post_action 18:36:42 INFO - Running post-action listener: _start_resource_monitoring 18:36:42 INFO - Starting resource monitoring. 18:36:42 INFO - [mozharness: 2017-09-21 01:36:42.696696Z] Finished create-virtualenv step (success) 18:36:42 INFO - [mozharness: 2017-09-21 01:36:42.697177Z] Running install step. 18:36:42 INFO - Running pre-action listener: _resource_record_pre_action 18:36:42 INFO - Running main action method: install 18:36:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:36:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:36:42 INFO - Reading from file tmpfile_stdout 18:36:42 INFO - Detecting whether we're running mozinstall >=1.0... 18:36:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:36:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:36:43 INFO - Reading from file tmpfile_stdout 18:36:43 INFO - Output received: 18:36:43 INFO - Usage: mozinstall [options] installer 18:36:43 INFO - Options: 18:36:43 INFO - -h, --help show this help message and exit 18:36:43 INFO - -d DEST, --destination=DEST 18:36:43 INFO - Directory to install application into. [default: 18:36:43 INFO - "/builds/slave/test"] 18:36:43 INFO - --app=APP Application being installed. [default: firefox] 18:36:43 INFO - mkdir: /builds/slave/test/build/application 18:36:43 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'] 18:36:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:37:00 INFO - Reading from file tmpfile_stdout 18:37:00 INFO - Output received: 18:37:00 INFO - /builds/slave/test/build/application/firefox/firefox 18:37:00 INFO - Running post-action listener: _resource_record_post_action 18:37:00 INFO - [mozharness: 2017-09-21 01:37:00.654520Z] Finished install step (success) 18:37:00 INFO - [mozharness: 2017-09-21 01:37:00.654774Z] Running setup-mitmproxy step. 18:37:00 INFO - Running pre-action listener: _resource_record_pre_action 18:37:00 INFO - Running main action method: setup_mitmproxy 18:37:00 INFO - Skipping: mitmproxy is not required 18:37:00 INFO - Running post-action listener: _resource_record_post_action 18:37:00 INFO - [mozharness: 2017-09-21 01:37:00.655896Z] Finished setup-mitmproxy step (success) 18:37:00 INFO - [mozharness: 2017-09-21 01:37:00.656116Z] Running run-tests step. 18:37:00 INFO - Running pre-action listener: _resource_record_pre_action 18:37:00 INFO - Running pre-action listener: _set_gcov_prefix 18:37:00 INFO - Running main action method: run_tests 18:37:00 WARNING - Try message not found. 18:37:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:37:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:37:00 INFO - Python 2.7.3 18:37:00 INFO - Return code: 0 18:37:00 INFO - grabbing minidump binary from tooltool 18:37: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')]} 18:37:00 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 0x2a4b090>, '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 0x2a4bd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a45f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:37:00 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 18:37:00 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 18:37:00 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 18:37:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:37:00 INFO - Return code: 0 18:37:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:37:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:37:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:37:00 INFO - ENV: RUST_BACKTRACE is now full 18:37:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:37:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:37:00 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/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-086', '--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 18:37:00 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/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-086 --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 18:37:00 INFO - Using env: {'DISPLAY': ':0', 18:37:00 INFO - 'HOME': '/home/cltbld', 18:37:00 INFO - 'LANG': 'en_US.UTF-8', 18:37:00 INFO - 'LANGUAGE': 'en_US:en', 18:37:00 INFO - 'LOGNAME': 'cltbld', 18:37:00 INFO - 'MAIL': '/var/mail/cltbld', 18:37:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:37:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:37:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:00 INFO - 'MOZ_NO_REMOTE': '1', 18:37:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:37:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:00 INFO - 'NO_EM_RESTART': '1', 18:37:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:00 INFO - 'PWD': '/builds/slave/test', 18:37:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:37:00 INFO - 'RUST_BACKTRACE': 'full', 18:37:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:37:00 INFO - 'SHELL': '/bin/bash', 18:37:00 INFO - 'SHLVL': '1', 18:37:00 INFO - 'TERM': 'linux', 18:37:00 INFO - 'TMOUT': '86400', 18:37:00 INFO - 'USER': 'cltbld', 18:37:00 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', 18:37:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:00 INFO - '_': '/tools/buildbot/bin/python'} 18:37:00 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/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-086', '--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 18:37:01 INFO - mozversion application_buildid: 20170921001837 18:37:01 INFO - mozversion application_changeset: 61340c7debf6dccec3b863d1bc00426feac42fe7 18:37:01 INFO - mozversion application_display_name: Nightly 18:37:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:37:01 INFO - mozversion application_name: Firefox 18:37:01 INFO - mozversion application_remotingname: firefox 18:37:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:37:01 INFO - mozversion application_vendor: Mozilla 18:37:01 INFO - mozversion application_version: 57.0a1 18:37:01 INFO - mozversion platform_buildid: 20170921001837 18:37:01 INFO - mozversion platform_changeset: 61340c7debf6dccec3b863d1bc00426feac42fe7 18:37:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:37:01 INFO - mozversion platform_version: 57.0a1 18:37:01 INFO - using testdate: 1505957821 18:37:01 INFO - actual date: 1505957821 18:37:01 INFO - starting webserver on 'localhost:52154' 18:37:01 INFO - SUITE-START | Running 2 tests 18:37:01 INFO - TEST-START | dromaeo_css 18:37:01 INFO - Initialising browser for dromaeo_css test... 18:37:01 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52154/getInfo.html -profile /tmp/tmpeUl27T/profile 18:37:01 INFO - TEST-INFO | started process 18586 (/builds/slave/test/build/application/firefox/firefox http://localhost:52154/getInfo.html) 18:37:06 INFO - TEST-INFO | 18586: exit 0 18:37:06 INFO - Browser initialized. 18:37:06 INFO - Running cycle 1/1 for dromaeo_css test... 18:37:06 INFO - Using env: {'DISPLAY': ':0', 18:37:06 INFO - 'HOME': '/home/cltbld', 18:37:06 INFO - 'JSGC_DISABLE_POISONING': '1', 18:37:06 INFO - 'LANG': 'en_US.UTF-8', 18:37:06 INFO - 'LANGUAGE': 'en_US:en', 18:37:06 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:37:06 INFO - 'LOGNAME': 'cltbld', 18:37:06 INFO - 'MAIL': '/var/mail/cltbld', 18:37:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:37:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:37:06 INFO - 'MOZ_CRASHREPORTER': '1', 18:37:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:06 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:37:06 INFO - 'MOZ_NO_REMOTE': '1', 18:37:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:37:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:37:06 INFO - 'NO_EM_RESTART': '1', 18:37:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:37:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:06 INFO - 'PWD': '/builds/slave/test', 18:37:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:37:06 INFO - 'RUST_BACKTRACE': 'full', 18:37:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:37:06 INFO - 'SHELL': '/bin/bash', 18:37:06 INFO - 'SHLVL': '1', 18:37:06 INFO - 'TERM': 'linux', 18:37:06 INFO - 'TMOUT': '86400', 18:37:06 INFO - 'USER': 'cltbld', 18:37:06 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', 18:37:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:06 INFO - '_': '/tools/buildbot/bin/python'} 18:37:06 INFO - TEST-INFO | started process 18822 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeUl27T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:37:06 INFO - PID 18822 | 18:37:06 INFO - PID 18822 | (/builds/slave/test/build/application/firefox/firefox:18872): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:37:06 INFO - PID 18822 | 18:37:17 INFO - PID 18822 | RSS: Main: 143474688 18:37:17 INFO - PID 18822 | 18:40:14 INFO - PID 18822 | Cycle 1(1): loaded http://localhost:52154/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52154/tests/dromaeo/cssquery-ext.html) 18:40:15 INFO - PID 18822 | RSS: Main: 147599360 18:40:15 INFO - PID 18822 | 18:43:16 INFO - PID 18822 | Cycle 1(1): loaded http://localhost:52154/tests/dromaeo/cssquery-ext.html (next: http://localhost:52154/tests/dromaeo/cssquery-jquery.html) 18:43:17 INFO - PID 18822 | RSS: Main: 147955712 18:43:17 INFO - PID 18822 | 18:46:19 INFO - PID 18822 | Cycle 1(1): loaded http://localhost:52154/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52154/tests/dromaeo/cssquery-mootools.html) 18:46:19 INFO - PID 18822 | RSS: Main: 148426752 18:46:19 INFO - PID 18822 | 18:49:21 INFO - PID 18822 | Cycle 1(1): loaded http://localhost:52154/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52154/tests/dromaeo/cssquery-prototype.html) 18:49:21 INFO - PID 18822 | RSS: Main: 148389888 18:49:21 INFO - PID 18822 | 18:52:25 INFO - PID 18822 | Cycle 1(1): loaded http://localhost:52154/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52154/tests/dromaeo/cssquery-yui.html) 18:52:25 INFO - PID 18822 | RSS: Main: 149495808 18:52:25 INFO - PID 18822 | 18:55:27 INFO - PID 18822 | Cycle 1(1): loaded http://localhost:52154/tests/dromaeo/cssquery-yui.html (next: http://localhost:52154/tests/dromaeo/cssquery-dojo.html) 18:55:27 INFO - PID 18822 | RSS: Main: 152072192 18:55:27 INFO - PID 18822 | 18:55:27 INFO - PID 18822 | __start_tp_report 18:55:27 INFO - PID 18822 | _x_x_mozilla_page_load 18:55:27 INFO - PID 18822 | _x_x_mozilla_page_load_details 18:55:27 INFO - PID 18822 | |i|pagename|runs| 18:55:27 INFO - PID 18822 | |0;dojo.html;3813.580506144324;3816.1413681921554;3834.022324307301;3835.82738776755;3847.576766555678;6192.473639740628;6291.1192433059405;6306;6319.115323854664;6327.620342779436;6344.904826427607;6378.712957916893;6388.744450221997;6395;6404.167458230423;5620.213170156181;5632.211490391328;5683.175939488782;5689.431056894319;5735.11105778605;5699.2306038684665;5738.942610573903;5751.309842818868;5789.884202315951;5798.536117110642;5668.829935101953;5695.829125126232;5779;5791.82624521265;5804.245448091742;330.7253624859808;5574.916376254359;5578.888422231573;5626;5696.259486266758;5604.663720176803;5621.7751289948355;5676.233708449347;5685.289338832646;5701.087825947829;6181.938180618181;6278.183836101323;6317.336679648617;6330.683465826736;6387.744490220432;6778.322167783232;6824.146981627297;6899.586024838526;6902.206246281641;6917.481188910801;6824.3858052774995;6830.931690683129;6863.10779598654;6925.792226233221;6948.200956889972;6196.070589411579;6298.527610429191;6298.811035668938;6373.075903993895;6380.744770209232;6935.4104901082965;7032.12098487689;7033.964830175919;7060;7064.32888875556;6689.966550167218;6700.396964273239;6784.932150678529;6791.3548212919695;6792.388685018372;6811.727530898807;6863.347981941708;6897.689603967833;6933.410660093848;6934.549254298455;6659.933400666028;6780.830479238059;6782.423494002966;6792.117024786746;6794.218664853605;6715.026321183401;6745.460363170934;6783.253842077367;6872.312768723088;6876.277990810893;4968.826091086867;4980.203167493256;4981.601471882241;4985.90028199434;4986.351774269322;4424.535423780521;4465.799039043251;4486.7756612169915;4491.865244042684;4551.135284295973;6222.066689996446;6265.467435268053;6277.96861015692;6278.905816412757;6299.0866324382705;6832.145981752281;6834;6849.726010959505;6917.377436030782;6945.687444065029;6070.484008859296;6086.6348019119;6124.142620033199;6147.354527774519;6161.229846269216;5428.212909128154;5429.56563474921;5431.2667789847865;5431.728413579305;5442.482964118402;6920.619365934821;6930.203026652;6992.650367481606;6995.79012629622;6998.440124790106;6757.594544327258;6766.898496522655;6768.763093291711;6779.220389655253;6830.7609233676585;6714;6879.071325371011;6883.655817209019;6989.161300643752;6994.33553812387;6618.6690665467495;6685.665716714242;6685.76599819004;6694.430973367318;6713.362230588086;6898.655067246517;6903.6203008835;6924.6537673117155;6947.062146610143;6956.234814170336;6604.207495100619;6620.569662971891;6635.8009259722285;6715.93284067153;6730.4952128589575;6467;6575.473962083118;6589.110470086477;6590.340965903371;6694.196696396463;6432.903506447314;6534.346565343428;6558.508111891722;6561.967190164019;6564.245111812203;6599.703013364314;6626.674665066909;6708.597484150967;6721.529492935643;6765.492588056014;6458.095866578777;6463.062855885964;6482.513811464253;6548.3124271952665;6548.770793022221 18:55:27 INFO - PID 18822 | |1;ext.html;946.8153710026547;972.9513524323784;1002.0530598584336;1004.4324956399638;1012.2307046644551;41702.791486042566;42082.57917420825;42499.78750106245;42507.36238956418;42524.14951700964;42655.786721066426;43116.78441607796;43255.134897302036;43519.34720979188;43547;8470.28002619778;8537.445066070686;8646.351523635722;8661.437006594582;8665.69670061548;8488;8626.568671566396;8630;8634.481931084156;8638.049814520398;8552.230299273064;8600.182982616672;8612.052674205832;8643.0924752901;8651.221390074892;8510.276626486695;8546.572671366437;8549.95725021374;8574.69988550404;8590.269827064707;8393.076761556224;8409.20112589303;8432.241098301121;8445.324374050062;8457.619407126695;38560.61439385598;38891;39173.804130979166;39207.21585568301;39228.80385598083;3764.1154328732728;3778.9811050944845;3806.5241844769403;3807.904802379963;3815.771053736785;32338.353232935446;32349.191270218198;32354.676453235636;32394.19014524632;32435.837820810983;5340.973295133538;5464.754086066137;5466.753996070147;5509.696966666832;5613.607047506676;204866;206453;208275;210599;219210;51670.48329516657;51710.48289517132;52171.73914130405;52211.73894130581;52306.738466307426;53812.46187538153;53891;53952.190717139274;53996.19005714917;54025;16364.509064728078;16487.34050637985;16510;16524.25640846165;16681.416150434678;13189.274589897455;13249.072564920363;13253.602391928258;13309.201447913156;13311.334433278298;13893;13902.721945561032;14014.929925350309;14034.43862245519;14145.080569762933;5643.097104463266;5657.462541058593;5663.263775709213;5827;5829.7376618052285;1078.4769386847372;1088.9237753357347;1102.4763237462141;1111.149970272742;1114.8885111488787;24497.26508204721;24585.508289834102;24683.25950221496;24706.629400558646;24820.5035899281;1175.312442221293;1196.276252867003;1213.4114954247184;1219.9024078073733;1223.406647775828;12916;13181.53864614753;13218.60344189676;13247.867521324855;13280.601581952556;265465;265513;266613;267730;267769;53405.46594534005;53430;53680.194797078075;53910.191347129825;53962.46037539653;1451.8330392004843;1457.6501639606417;1506.5781581157294;1510.9848901511064;1538.169388530181;23951.76048239565;24177.395565111012;24199.879000604884;24459;24675.876620616782;39280.80359598184;39313.21373572455;39555.80222098871;39748;39757.40363894486;213096;213913;214702;215589;215654;34627.826860865534;36804.631953680124;38755.22489550137;39069.218615628095;39096.80451597724;48284.75857620689;48946.26580601365;49497.50502495073;49537.25694114519;49595.008099838524;36828.44757328696;36837.81581092077;37036.25927481381;37310.440343395414;37330.25339493141;35946.82026589955;36297.45553816642;36529.81735091307;36553.81723091367;36690;40919.59080409277;41322.38016429816;41360.1727965445;41906.58093419027;41962;34909;34966.47550286697;35286.647133528335;35498.11254718652;35555.28889422249;26860.865695671397;27238.182854514398;27700.307492312855;27884.88460461559;27922.860385697943 18:55:27 INFO - PID 18822 | |2;jquery.html;616.836538317346;622.3309941812553;625.4621025917708;630.1178350309566;643.4305639509034;22394.776052239515;23036.424089397773;24153.516929661397;24282.27153185405;24291.392715182108;24484.510309793794;25761;25772.613410798855;25777.097801576947;25797.355066123357;6307.747690092391;6472.352764723537;6480.4491618212505;6484.1894763154605;6488.448481879046;6538.934610653904;6918.965405172967;6919.619420931846;6924.4806639501985;6933.757318493854;6851.623160726158;6857.245702972669;6859.622720750357;6873.896891546606;6874.27820078892;6450.903236451481;6698.49761267902;6739.427148692367;6832.385085342318;6843.623600701984;6299.5905266158015;6302.086197501382;6311.52663550231;6313.494920406357;6318.052292156168;30441.847790760905;30848.8457557713;30867.228319292197;31117.688823111705;31133.688663113306;2278.5784629843533;2309.6881920940796;2323.5585238804574;2324.1865347128537;2325.5348930213854;19633.214671413127;20783.68824467634;20959.580808383747;20963.5807283855;20971.475713107142;2530.316814460104;2558.7057488388887;2627.04112998756;2628.5531459651716;2631.1974847671454;138462;144662;144676.2766186173;154350.22824885917;161744.99999999884;15774.447894323874;15890.60273493172;16307.510774676779;16329.18354082291;16359.263833127538;26145.084922027636;26220.8688956554;26251.868740656173;26355.341116472242;26420.33949151248;10067.597296108073;10234.669492966015;10320.793584128276;10436.42599657011;10456.477176141163;9242.075792420705;9327.253819694408;9427.387219830689;9472.431654100776;9520.143187113055;9336.299777516713;9540.952295238618;9554.713358599252;9668.468234247042;9895;2709.4851978124093;2912.461194678991;2932.6627437844922;2954.615899933038;2974.2488198416377;1047.8166320893813;1050.1021626509378;1055.4511653939908;1058.7829494953517;1069.8769641491328;20506.282280120126;20614.896925515575;20800.791992079885;20976.685349719766;21161.25935592247;1122.7810576937418;1126.6957921361186;1136.3920302638214;1143.3082984794246;1144.2390810111183;11464.426778661034;11469.369184694755;11488.368139752227;11541.480633371519;11574.768504629861;566601;577052;577646;579033;596615;132561;136278;136385.318073409;138285;138506.307468462;1208.1361826294242;1249.7188132670258;1256.9874301257053;1260.4832018872403;1263.260992319485;18395.988220647992;18511.166997484877;18615.255389784255;18798.624027519647;18953.526161846064;63198;63837.361626385;65841;66507;66855.66572167107;76922.61538692271;79090;81456;82426;82721.58639206765;14973.176475293963;15384.615384615474;15434.536963891102;15533.223338832788;15706.2932168055;22675.319740407813;22687.88656056709;22753.658695119917;23725.525489490457;23872.28383148508;18848.811511884705;19047.57143214237;19096.331628392938;19288.517787055436;19480.512987175432;16753.329866805678;17020.063896485317;17253.82746172522;17266.22301996388;17545.385911493035;18001;19006.809931900505;19380.612387752448;19526.414207573795;19710.21159153618;15035.849641503444;15168.24158792078;15202.391904324146;15242.085474871099;15250;18789.342373017425;19061.142248598568;19090.809091908905;19139.425817225507;19228.71156932675 18:55:27 INFO - PID 18822 | |3;mootools.html;1444.4799872046062;1468.2878803780172;1477.3425825507647;1515.909045457272;1525.3136088760061;16620.25208865601;17333.913330433363;17344.913275433606;17350.73973890391;17355.47933561995;16788.74816877745;16805.4118105866;16961;16969.406070787525;17010.914945425255;2783.498970185366;2805.747482726554;2810.985945070272;2822.463731890934;2824.943501129976;2724.3870129220963;2779.9305017374627;2780.06867699321;2785.7353551412684;2786.9966811947684;2683.047518131065;2732;2737.986310068447;2739.1782465260444;2740.630014947986;2778.3748656552216;2789.693133755275;2799.272189230794;2801.383695586978;2802.9159125226274;2659.9069032583766;2675.5184066867955;2686.597010448429;2691.8923243070253;2692.6903406108354;16358.345666173338;16632.667346652997;16915.06967116808;17031.57421064471;17094.145292735437;7957.801054973614;8261.917380826175;8262.297704695107;8263.00843898736;8271.1315311892;5435.864103397446;5652.686474713756;5725.541956643458;5776.220210271602;5782.566307526952;2139.1657253671083;2169.0998235732154;2173.7500187478486;2197.121151539381;2219.7336319641745;30263.092107236596;30398;30503.08490745259;30608.387832243232;30646.54030189549;6912.412444942136;7033;7217.819554511074;7317.012203352586;7357.448191385667;19818.90090549538;19863.60272794536;19870.60258794816;19879.403617891487;19901.104450299772;8224.218699223564;8360.414770966096;8369.916300837036;8378.622961966727;8378.706745263886;7099.219085900547;7389.7413590524075;7440.255974402625;7583.9620801895635;7587.393008559302;5490.121580547093;5708.628939118944;5708.686022268732;5722.856928576819;5765.423457654285;1651.7522371644109;1654.59462431705;1667.5330907345963;1677.4464426739146;1692.9153542322836;897.6364572348209;917.1149840403909;917.4311926605548;928.0533855467386;936.784539555906;7810.921890781133;7947.880781788278;7956.2043795619975;7982.640781164861;8026.157253488416;785.83890302489;796.3191471291962;800.9519428834288;803.4135081390617;803.7669075967904;5736.5123964462655;5804.4195580442465;5962.791302304486;6118.541109416722;6223.96888015566;24289.39281498553;25532.744672553035;25536;25697.48605027889;25911.481770364866;24405.511889762107;24758.628620570707;24997;25097.24708258755;25308.873455632973;11773;11876.821847672292;12160;12390.752184956431;12566.183198092094;9285.86071208946;9379.296552758708;9492.098250666451;9499.145076943109;9696.660616878375;21124.577508449438;21190.576188476454;21209.15163393447;21400.143994240058;21520.569588608454;22129.446763831034;22316.330510084725;22505.212317568807;22671.65992510081;22815.543689125792;12237.938810305892;12306.507739690313;12336.62990110298;12339.321337326533;12344.197627154206;15060.472883449027;15250.771238431655;15335.923320383774;15456.768148477557;15460.381584736935;11965.521379144737;12174.513019479122;12282.87717122853;12392.442340094898;12536.874631253935;11692.123090768397;11736.119791015879;12029.218100823418;12198.207116537396;12370.938145309216;10972.286801357886;11279.379634120201;11283.097352211968;11284.379359135326;11285.492152853305;8074.555899425588;8154.796130096795;8165.959170204111;8256.504609723435;8274.255317021498;8397.286230670461;8441.409101362846;8473.406861519634;8501.19238672325;8506 18:55:27 INFO - PID 18822 | |4;prototype.html;529.9788008479661;572.6936089192282;584.4710536964047;586.2378907420352;586.7418335932192;6237.5009999200065;6290.427393151712;6290.465310448606;6290.798220622785;6387.297397286301;6155;6179;6187.133801267815;6248.37516248375;6251.843703907404;5059.215821547657;5078.365204349457;5106.361704786902;5114.0110713643235;5138.460461651529;4807.35100761398;4975.208924787074;4985.725785081819;5021.598272138239;5096.541311281984;4978.402591689002;4989.625778066641;4993.525615066581;5011.498850114996;5017.799288028475;4881.755912204376;4918.508149185071;4926.876828079291;4957.752112394366;4968.279599458058;4448.043670610834;4509.7970591323465;4558.566936141061;4579.244424669959;4580.587747102777;6151.507879369639;6188.47397971173;6191.2570491540855;6237.719302631393;6286.685665716742;5804.535637149018;5871;5889.793857214977;5902.527797776168;5905.261842269716;6555.115059467006;6594;6627.7680281190405;6694.933050669529;6699.799006029827;5067;5087.847364579068;5088.084144853925;5114.411842638107;5118;6536.836579085513;6778.186617605869;6827.269092363045;6844.589324640538;6920.757773478005;5195.142801437796;5244.292020577173;5247.134222853187;5256.658317209369;5278.181881808326;5213.218017297436;5249.448807875195;5272.604554658416;5281.9207711884355;5342.465753424626;5797.536197104221;5841.386654401312;5851.795187168429;5904.380040095814;5918.141869428909;6599.76900808479;6728.562643428161;6731.427828634621;6736.562123461959;6781.254062053269;1800.2438975630005;1826.712167921612;1844.97232541512;1849.5653521422455;1850.4448665400325;1250.6498180509473;1298.1821452484874;1298.4416700818738;1307.6861553227156;1315.5066849931275;6085.2393450818645;6113.266408030976;6154.292256390572;6163.106349579286;6175.413335733098;6034.969825150934;6593.86812263752;6729.2934241903895;6747.245716113747;6765.2896445872475;5957.212836149224;5988.161657367972;6059.939400606026;6067.817965460955;6101.908471372933;3526.4357702767948;3531.5938667053106;3554.537910071726;3556.199855032597;3590.263995880838;6869.690863911136;6886.758963436256;6926.965365173242;6928.826779330456;6950.513464057467;6913.170419549686;6957.339052789976;7011.158660960616;7037.437005039585;7038.6480675965995;6804.387605115563;6806.285340039224;6869.278725733726;6872.896906546406;6965.547239429421;6605.834854128493;6642.202935647753;6674.399304062657;6738.932610674026;6775.49183811226;6642.804295226712;6665.933340666726;6744;6772.492063095386;6776.2207346153855;6805.863882722417;6840.144981877265;6849.554778939353;6859.519833611599;6861.759838405632;5170.689758614345;5181.870453238699;5186.974065129651;5214.817481388133;5223.347081614798;6508.902366464602;6762.289959554176;6779.8135326316715;6781.42357899584;6808.829779255559;6340.714667839866;6429.453496452854;6526.608403495806;6546.869062618816;6590.8352291193105;6486.772962946275;6486.870262594816;6497.18785151856;6537.803865884031;6561.114249576437;6318.494520438262;6385.8722825544155;6394.90407643895;6395.360463953567;6536.967315163393;6578.473722102125;6687.832804179934;6690.498212633975;6706.731730730911;6713.825080610971;6451;6466.288708242184;6513.837154071186;6553.5084868634085;6561.1470508833545 18:55:27 INFO - PID 18822 | |5;yui.html;954.475038728699;984.8522721591761;992.3995982430633;1006.5973610555777;1014.2149816323268;20398.89800550997;20912.68630970533;20931.686024709605;21109;21263.57472850542;19059.42821715351;19309.806901930977;19604.117814698337;20487.38537843867;20681.68977465339;1039.4387031003268;1058.185197398003;1059.4384975962753;1059.7456610413522;1062.75556621977;1049.8057859296016;1081.5133190064503;1099.444780385904;1100.1748688483638;1100.9944950275237;1151.4875880233299;1151.9136064795132;1153.423288355822;1153.5904753812401;1154.2958795134962;1157.7915975124474;1158.0619698044611;1158.362900404774;1159.1248607301475;1161.181367136167;797.4816369359904;824.8556502612018;826.7147833997261;839.1063517353998;855.0167307596314;18962.71555926662;19005.904970475196;19037.42887713371;19068;19137.13882875274;22493.775062249497;23060.53878922429;23404.882975585013;23528.411789705224;23573.410664733517;18424.078796060145;18555.350562730375;18793.53016174593;19020.42938711841;19048.85706857579;3902.5316961964463;3982.82077306519;4005.379166229239;4007.939880901789;4009.959900401017;160260;161884;163473;165436;167197;15023.323950422257;15325.157116358485;15344.079355238722;15390;15509.379624814881;32084.358312833614;32289.51565726516;33447.16382090467;33448;33472.66527334744;9103.135202155785;9266.166045055978;9297.256219502558;9316.02181770918;9344;4643.907121857612;4758.738269395148;4760.904781904343;4778.116048531033;4781.2111001685025;5726.484616384545;5815.941840581625;5854.5901786875365;5904.439078287556;5930.614510056833;1178.1222988873344;1178.2753606531921;1189.7679952409371;1191.4102519253024;1199.4662375243108;676.1210426445601;725.4631572636316;736.5102207032263;742.1835980421602;745.3589912675095;13080.934595326962;13350.799738003938;13465.461381544825;13872.514461993984;14041.55429028061;690.2510775808304;711.0969069281983;731.6049333360032;733.1898252431093;743.18992298395;5761.854646841993;5812.418758124239;5870.148828419855;6037.516998640098;6074.331823499411;145053;145256;149910;152387;157598;125567.3721631386;128847.35576322058;130445.34777326243;131597.3420132893;131897;115796;116195;116211.41894290474;117317.85004643051;122242.3887880555;12936.611901642966;13314.533991310258;13350.399232034388;13383.933080334536;13507.324633768469;56160;56532.71733641306;56762.14856777069;57226.7138664304;59694;70921.6453917727;73547.63226183834;73990;75841.6207918957;76698.2330176691;19932.50168745793;20177;20225.089870955548;20343.389698309074;20387.388378348674;21039;22121.889390552944;22219.22232721847;22226.888865555567;22235.555288893807;20064.291320671524;20247.4938126548;20346.08442620056;20406.59186816285;20837.37487875366;20712.757234565976;20976.895115524323;21109.155633774477;21200.68198976986;21442.463938401666;19229.13468893931;19404.805951940863;19446.124924378153;19639.509012274808;19796.406107816787;15501.767473488133;15547.144907030222;15611.843881561039;15783.68432631364;16061.277242524347;15626.765598515804;15679.294431750826;15846.762298565762;15864;15915.761263580824 18:55:27 INFO - PID 18822 | __end_tp_report 18:55:27 INFO - PID 18822 | __start_cc_report 18:55:27 INFO - PID 18822 | _x_x_mozilla_cycle_collect,288 18:55:27 INFO - PID 18822 | __end_cc_report 18:55:27 INFO - PID 18822 | __startTimestamp1505958927448__endTimestamp 18:55:27 INFO - PID 18822 | 18:55:27 INFO - PID 18822 | ------- Summary: start ------- 18:55:27 INFO - PID 18822 | Number of tests: 6 18:55:27 INFO - PID 18822 | 18:55:27 INFO - PID 18822 | [#0] dojo.html Cycles:165 Average:6237.45 Median:6548.77 stddev:878.69 (13.4%) stddev-sans-first:860.57 18:55:27 INFO - PID 18822 | Values: 3813.6 3816.1 3834.0 3835.8 3847.6 6192.5 6291.1 6306.0 6319.1 6327.6 6344.9 6378.7 6388.7 6395.0 6404.2 5620.2 5632.2 5683.2 5689.4 5735.1 5699.2 5738.9 5751.3 5789.9 5798.5 5668.8 5695.8 5779.0 5791.8 5804.2 330.7 5574.9 5578.9 5626.0 5696.3 5604.7 5621.8 5676.2 5685.3 5701.1 6181.9 6278.2 6317.3 6330.7 6387.7 6778.3 6824.1 6899.6 6902.2 6917.5 6824.4 6830.9 6863.1 6925.8 6948.2 6196.1 6298.5 6298.8 6373.1 6380.7 6935.4 7032.1 7034.0 7060.0 7064.3 6690.0 6700.4 6784.9 6791.4 6792.4 6811.7 6863.3 6897.7 6933.4 6934.5 6659.9 6780.8 6782.4 6792.1 6794.2 6715.0 6745.5 6783.3 6872.3 6876.3 4968.8 4980.2 4981.6 4985.9 4986.4 4424.5 4465.8 4486.8 4491.9 4551.1 6222.1 6265.5 6278.0 6278.9 6299.1 6832.1 6834.0 6849.7 6917.4 6945.7 6070.5 6086.6 6124.1 6147.4 6161.2 5428.2 5429.6 5431.3 5431.7 5442.5 6920.6 6930.2 6992.7 6995.8 6998.4 6757.6 6766.9 6768.8 6779.2 6830.8 6714.0 6879.1 6883.7 6989.2 6994.3 6618.7 6685.7 6685.8 6694.4 6713.4 6898.7 6903.6 6924.7 6947.1 6956.2 6604.2 6620.6 6635.8 6715.9 6730.5 6467.0 6575.5 6589.1 6590.3 6694.2 6432.9 6534.3 6558.5 6562.0 6564.2 6599.7 6626.7 6708.6 6721.5 6765.5 6458.1 6463.1 6482.5 6548.3 6548.8 18:55:27 INFO - PID 18822 | 18:55:27 INFO - PID 18822 | [#1] ext.html Cycles:180 Average:41115.60 Median:3771.55 stddev:60112.54 (1593.8%) stddev-sans-first:60205.51 18:55:27 INFO - PID 18822 | Values: 946.8 973.0 1002.1 1004.4 1012.2 41702.8 42082.6 42499.8 42507.4 42524.1 42655.8 43116.8 43255.1 43519.3 43547.0 8470.3 8537.4 8646.4 8661.4 8665.7 8488.0 8626.6 8630.0 8634.5 8638.0 8552.2 8600.2 8612.1 8643.1 8651.2 8510.3 8546.6 8550.0 8574.7 8590.3 8393.1 8409.2 8432.2 8445.3 8457.6 38560.6 38891.0 39173.8 39207.2 39228.8 3764.1 3779.0 3806.5 3807.9 3815.8 32338.4 32349.2 32354.7 32394.2 32435.8 5341.0 5464.8 5466.8 5509.7 5613.6 204866.0 206453.0 208275.0 210599.0 219210.0 51670.5 51710.5 52171.7 52211.7 52306.7 53812.5 53891.0 53952.2 53996.2 54025.0 16364.5 16487.3 16510.0 16524.3 16681.4 13189.3 13249.1 13253.6 13309.2 13311.3 13893.0 13902.7 14014.9 14034.4 14145.1 5643.1 5657.5 5663.3 5827.0 5829.7 1078.5 1088.9 1102.5 1111.1 1114.9 24497.3 24585.5 24683.3 24706.6 24820.5 1175.3 1196.3 1213.4 1219.9 1223.4 12916.0 13181.5 13218.6 13247.9 13280.6 265465.0 265513.0 266613.0 267730.0 267769.0 53405.5 53430.0 53680.2 53910.2 53962.5 1451.8 1457.7 1506.6 1511.0 1538.2 23951.8 24177.4 24199.9 24459.0 24675.9 39280.8 39313.2 39555.8 39748.0 39757.4 213096.0 213913.0 214702.0 215589.0 215654.0 34627.8 36804.6 38755.2 39069.2 39096.8 48284.8 48946.3 49497.5 49537.3 49595.0 36828.4 36837.8 37036.3 37310.4 37330.3 35946.8 36297.5 36529.8 36553.8 36690.0 40919.6 41322.4 41360.2 41906.6 41962.0 34909.0 34966.5 35286.6 35498.1 35555.3 26860.9 27238.2 27700.3 27884.9 27922.9 18:55:27 INFO - PID 18822 | 18:55:27 INFO - PID 18822 | [#2] jquery.html Cycles:180 Average:39083.56 Median:12673.06 stddev:97416.29 (768.7%) stddev-sans-first:97646.75 18:55:27 INFO - PID 18822 | Values: 616.8 622.3 625.5 630.1 643.4 22394.8 23036.4 24153.5 24282.3 24291.4 24484.5 25761.0 25772.6 25777.1 25797.4 6307.7 6472.4 6480.4 6484.2 6488.4 6538.9 6919.0 6919.6 6924.5 6933.8 6851.6 6857.2 6859.6 6873.9 6874.3 6450.9 6698.5 6739.4 6832.4 6843.6 6299.6 6302.1 6311.5 6313.5 6318.1 30441.8 30848.8 30867.2 31117.7 31133.7 2278.6 2309.7 2323.6 2324.2 2325.5 19633.2 20783.7 20959.6 20963.6 20971.5 2530.3 2558.7 2627.0 2628.6 2631.2 138462.0 144662.0 144676.3 154350.2 161745.0 15774.4 15890.6 16307.5 16329.2 16359.3 26145.1 26220.9 26251.9 26355.3 26420.3 10067.6 10234.7 10320.8 10436.4 10456.5 9242.1 9327.3 9427.4 9472.4 9520.1 9336.3 9541.0 9554.7 9668.5 9895.0 2709.5 2912.5 2932.7 2954.6 2974.2 1047.8 1050.1 1055.5 1058.8 1069.9 20506.3 20614.9 20800.8 20976.7 21161.3 1122.8 1126.7 1136.4 1143.3 1144.2 11464.4 11469.4 11488.4 11541.5 11574.8 566601.0 577052.0 577646.0 579033.0 596615.0 132561.0 136278.0 136385.3 138285.0 138506.3 1208.1 1249.7 1257.0 1260.5 1263.3 18396.0 18511.2 18615.3 18798.6 18953.5 63198.0 63837.4 65841.0 66507.0 66855.7 76922.6 79090.0 81456.0 82426.0 82721.6 14973.2 15384.6 15434.5 15533.2 15706.3 22675.3 22687.9 22753.7 23725.5 23872.3 18848.8 19047.6 19096.3 19288.5 19480.5 16753.3 17020.1 17253.8 17266.2 17545.4 18001.0 19006.8 19380.6 19526.4 19710.2 15035.8 15168.2 15202.4 15242.1 15250.0 18789.3 19061.1 19090.8 19139.4 19228.7 18:55:27 INFO - PID 18822 | 18:55:27 INFO - PID 18822 | [#3] mootools.html Cycles:180 Average:10388.35 Median:2708.54 stddev:7780.31 (287.3%) stddev-sans-first:7773.12 18:55:27 INFO - PID 18822 | Values: 1444.5 1468.3 1477.3 1515.9 1525.3 16620.3 17333.9 17344.9 17350.7 17355.5 16788.7 16805.4 16961.0 16969.4 17010.9 2783.5 2805.7 2811.0 2822.5 2824.9 2724.4 2779.9 2780.1 2785.7 2787.0 2683.0 2732.0 2738.0 2739.2 2740.6 2778.4 2789.7 2799.3 2801.4 2802.9 2659.9 2675.5 2686.6 2691.9 2692.7 16358.3 16632.7 16915.1 17031.6 17094.1 7957.8 8261.9 8262.3 8263.0 8271.1 5435.9 5652.7 5725.5 5776.2 5782.6 2139.2 2169.1 2173.8 2197.1 2219.7 30263.1 30398.0 30503.1 30608.4 30646.5 6912.4 7033.0 7217.8 7317.0 7357.4 19818.9 19863.6 19870.6 19879.4 19901.1 8224.2 8360.4 8369.9 8378.6 8378.7 7099.2 7389.7 7440.3 7584.0 7587.4 5490.1 5708.6 5708.7 5722.9 5765.4 1651.8 1654.6 1667.5 1677.4 1692.9 897.6 917.1 917.4 928.1 936.8 7810.9 7947.9 7956.2 7982.6 8026.2 785.8 796.3 801.0 803.4 803.8 5736.5 5804.4 5962.8 6118.5 6224.0 24289.4 25532.7 25536.0 25697.5 25911.5 24405.5 24758.6 24997.0 25097.2 25308.9 11773.0 11876.8 12160.0 12390.8 12566.2 9285.9 9379.3 9492.1 9499.1 9696.7 21124.6 21190.6 21209.2 21400.1 21520.6 22129.4 22316.3 22505.2 22671.7 22815.5 12237.9 12306.5 12336.6 12339.3 12344.2 15060.5 15250.8 15335.9 15456.8 15460.4 11965.5 12174.5 12282.9 12392.4 12536.9 11692.1 11736.1 12029.2 12198.2 12370.9 10972.3 11279.4 11283.1 11284.4 11285.5 8074.6 8154.8 8166.0 8256.5 8274.3 8397.3 8441.4 8473.4 8501.2 8506.0 18:55:27 INFO - PID 18822 | 18:55:27 INFO - PID 18822 | [#4] prototype.html Cycles:180 Average:5618.59 Median:6189.87 stddev:1557.77 (25.2%) stddev-sans-first:1514.60 18:55:27 INFO - PID 18822 | Values: 530.0 572.7 584.5 586.2 586.7 6237.5 6290.4 6290.5 6290.8 6387.3 6155.0 6179.0 6187.1 6248.4 6251.8 5059.2 5078.4 5106.4 5114.0 5138.5 4807.4 4975.2 4985.7 5021.6 5096.5 4978.4 4989.6 4993.5 5011.5 5017.8 4881.8 4918.5 4926.9 4957.8 4968.3 4448.0 4509.8 4558.6 4579.2 4580.6 6151.5 6188.5 6191.3 6237.7 6286.7 5804.5 5871.0 5889.8 5902.5 5905.3 6555.1 6594.0 6627.8 6694.9 6699.8 5067.0 5087.8 5088.1 5114.4 5118.0 6536.8 6778.2 6827.3 6844.6 6920.8 5195.1 5244.3 5247.1 5256.7 5278.2 5213.2 5249.4 5272.6 5281.9 5342.5 5797.5 5841.4 5851.8 5904.4 5918.1 6599.8 6728.6 6731.4 6736.6 6781.3 1800.2 1826.7 1845.0 1849.6 1850.4 1250.6 1298.2 1298.4 1307.7 1315.5 6085.2 6113.3 6154.3 6163.1 6175.4 6035.0 6593.9 6729.3 6747.2 6765.3 5957.2 5988.2 6059.9 6067.8 6101.9 3526.4 3531.6 3554.5 3556.2 3590.3 6869.7 6886.8 6927.0 6928.8 6950.5 6913.2 6957.3 7011.2 7037.4 7038.6 6804.4 6806.3 6869.3 6872.9 6965.5 6605.8 6642.2 6674.4 6738.9 6775.5 6642.8 6665.9 6744.0 6772.5 6776.2 6805.9 6840.1 6849.6 6859.5 6861.8 5170.7 5181.9 5187.0 5214.8 5223.3 6508.9 6762.3 6779.8 6781.4 6808.8 6340.7 6429.5 6526.6 6546.9 6590.8 6486.8 6486.9 6497.2 6537.8 6561.1 6318.5 6385.9 6394.9 6395.4 6537.0 6578.5 6687.8 6690.5 6706.7 6713.8 6451.0 6466.3 6513.8 6553.5 6561.1 18:55:27 INFO - PID 18822 | 18:55:27 INFO - PID 18822 | [#5] yui.html Cycles:180 Average:28960.82 Median:19998.40 stddev:42662.98 (213.3%) stddev-sans-first:42730.83 18:55:27 INFO - PID 18822 | Values: 954.5 984.9 992.4 1006.6 1014.2 20398.9 20912.7 20931.7 21109.0 21263.6 19059.4 19309.8 19604.1 20487.4 20681.7 1039.4 1058.2 1059.4 1059.7 1062.8 1049.8 1081.5 1099.4 1100.2 1101.0 1151.5 1151.9 1153.4 1153.6 1154.3 1157.8 1158.1 1158.4 1159.1 1161.2 797.5 824.9 826.7 839.1 855.0 18962.7 19005.9 19037.4 19068.0 19137.1 22493.8 23060.5 23404.9 23528.4 23573.4 18424.1 18555.4 18793.5 19020.4 19048.9 3902.5 3982.8 4005.4 4007.9 4010.0 160260.0 161884.0 163473.0 165436.0 167197.0 15023.3 15325.2 15344.1 15390.0 15509.4 32084.4 32289.5 33447.2 33448.0 33472.7 9103.1 9266.2 9297.3 9316.0 9344.0 4643.9 4758.7 4760.9 4778.1 4781.2 5726.5 5815.9 5854.6 5904.4 5930.6 1178.1 1178.3 1189.8 1191.4 1199.5 676.1 725.5 736.5 742.2 745.4 13080.9 13350.8 13465.5 13872.5 14041.6 690.3 711.1 731.6 733.2 743.2 5761.9 5812.4 5870.1 6037.5 6074.3 145053.0 145256.0 149910.0 152387.0 157598.0 125567.4 128847.4 130445.3 131597.3 131897.0 115796.0 116195.0 116211.4 117317.9 122242.4 12936.6 13314.5 13350.4 13383.9 13507.3 56160.0 56532.7 56762.1 57226.7 59694.0 70921.6 73547.6 73990.0 75841.6 76698.2 19932.5 20177.0 20225.1 20343.4 20387.4 21039.0 22121.9 22219.2 22226.9 22235.6 20064.3 20247.5 20346.1 20406.6 20837.4 20712.8 20976.9 21109.2 21200.7 21442.5 19229.1 19404.8 19446.1 19639.5 19796.4 15501.8 15547.1 15611.8 15783.7 16061.3 15626.8 15679.3 15846.8 15864.0 15915.8 18:55:27 INFO - PID 18822 | -------- Summary: end -------- 18:55:27 INFO - PID 18822 | 18:55:28 INFO - TEST-INFO | 18822: exit 0 18:55:28 INFO - TEST-OK | dromaeo_css | took 1107020ms 18:55:28 INFO - TEST-START | kraken 18:55:28 INFO - Initialising browser for kraken test... 18:55:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52154/getInfo.html -profile /tmp/tmpK2jIq8/profile 18:55:28 INFO - TEST-INFO | started process 19198 (/builds/slave/test/build/application/firefox/firefox http://localhost:52154/getInfo.html) 18:55:32 INFO - TEST-INFO | 19198: exit 0 18:55:32 INFO - Browser initialized. 18:55:32 INFO - Running cycle 1/1 for kraken test... 18:55:32 INFO - Using env: {'DISPLAY': ':0', 18:55:32 INFO - 'HOME': '/home/cltbld', 18:55:32 INFO - 'JSGC_DISABLE_POISONING': '1', 18:55:32 INFO - 'LANG': 'en_US.UTF-8', 18:55:32 INFO - 'LANGUAGE': 'en_US:en', 18:55:32 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:55:32 INFO - 'LOGNAME': 'cltbld', 18:55:32 INFO - 'MAIL': '/var/mail/cltbld', 18:55:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:55:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:55:32 INFO - 'MOZ_CRASHREPORTER': '1', 18:55:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:32 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:55:32 INFO - 'MOZ_NO_REMOTE': '1', 18:55:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:55:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:55:32 INFO - 'NO_EM_RESTART': '1', 18:55:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:55:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:32 INFO - 'PWD': '/builds/slave/test', 18:55:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:55:32 INFO - 'RUST_BACKTRACE': 'full', 18:55:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:55:32 INFO - 'SHELL': '/bin/bash', 18:55:32 INFO - 'SHLVL': '1', 18:55:32 INFO - 'TERM': 'linux', 18:55:32 INFO - 'TMOUT': '86400', 18:55:32 INFO - 'USER': 'cltbld', 18:55:32 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222', 18:55:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:32 INFO - '_': '/tools/buildbot/bin/python'} 18:55:32 INFO - TEST-INFO | started process 19439 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK2jIq8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:55:33 INFO - PID 19439 | 18:55:33 INFO - PID 19439 | (/builds/slave/test/build/application/firefox/firefox:19490): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:55:33 INFO - PID 19439 | 18:55:44 INFO - PID 19439 | RSS: Main: 147456000 18:55:44 INFO - PID 19439 | 18:55:46 INFO - PID 19439 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52154/tests/kraken/driver.html?testName=audio-beat-detection) 18:55:47 INFO - PID 19439 | RSS: Main: 152567808 18:55:47 INFO - PID 19439 | 18:55:50 INFO - PID 19439 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52154/tests/kraken/driver.html?testName=audio-dft) 18:55:50 INFO - PID 19439 | RSS: Main: 152825856 18:55:50 INFO - PID 19439 | 18:55:53 INFO - PID 19439 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52154/tests/kraken/driver.html?testName=audio-fft) 18:55:54 INFO - PID 19439 | RSS: Main: 149549056 18:55:54 INFO - PID 19439 | 18:55:56 INFO - PID 19439 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52154/tests/kraken/driver.html?testName=audio-oscillator) 18:55:56 INFO - PID 19439 | RSS: Main: 150421504 18:55:56 INFO - PID 19439 | 18:55:58 INFO - PID 19439 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52154/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:55:59 INFO - PID 19439 | RSS: Main: 150548480 18:55:59 INFO - PID 19439 | 18:56:02 INFO - PID 19439 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52154/tests/kraken/driver.html?testName=imaging-darkroom) 18:56:03 INFO - PID 19439 | RSS: Main: 150482944 18:56:03 INFO - PID 19439 | 18:56:06 INFO - PID 19439 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52154/tests/kraken/driver.html?testName=imaging-desaturate) 18:56:07 INFO - PID 19439 | RSS: Main: 153391104 18:56:07 INFO - PID 19439 | 18:56:10 INFO - PID 19439 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52154/tests/kraken/driver.html?testName=json-parse-financial) 18:56:10 INFO - PID 19439 | RSS: Main: 153235456 18:56:10 INFO - PID 19439 | 18:56:12 INFO - PID 19439 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52154/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:56:13 INFO - PID 19439 | RSS: Main: 152928256 18:56:13 INFO - PID 19439 | 18:56:14 INFO - PID 19439 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:56:15 INFO - PID 19439 | RSS: Main: 153088000 18:56:15 INFO - PID 19439 | 18:56:17 INFO - PID 19439 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:56:17 INFO - PID 19439 | RSS: Main: 150106112 18:56:17 INFO - PID 19439 | 18:56:20 INFO - PID 19439 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:56:20 INFO - PID 19439 | RSS: Main: 149987328 18:56:20 INFO - PID 19439 | 18:56:23 INFO - PID 19439 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:56:24 INFO - PID 19439 | RSS: Main: 150695936 18:56:24 INFO - PID 19439 | 18:56:25 INFO - PID 19439 | Cycle 1(1): loaded http://localhost:52154/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52154/tests/kraken/driver.html?testName=ai-astar) 18:56:26 INFO - PID 19439 | RSS: Main: 152309760 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | __start_tp_report 18:56:26 INFO - PID 19439 | _x_x_mozilla_page_load 18:56:26 INFO - PID 19439 | _x_x_mozilla_page_load_details 18:56:26 INFO - PID 19439 | |i|pagename|runs| 18:56:26 INFO - PID 19439 | |0;ai-astar;108;106;108;106;105;107;107;104;106;106 18:56:26 INFO - PID 19439 | |1;audio-beat-detection;111;117;115;114;112;115;115;119;121;120 18:56:26 INFO - PID 19439 | |2;audio-dft;164;162;165;164;163;163;162;163;169;167 18:56:26 INFO - PID 19439 | |3;audio-fft;74;76;75;73;73;74;72;75;74;73 18:56:26 INFO - PID 19439 | |4;audio-oscillator;78;77;78;77;77;78;79;77;81;82 18:56:26 INFO - PID 19439 | |5;imaging-gaussian-blur;110;115;110;114;115;114;109;113;114;115 18:56:26 INFO - PID 19439 | |6;imaging-darkroom;150;149;150;148;150;149;150;150;150;153 18:56:26 INFO - PID 19439 | |7;imaging-desaturate;100;94;95;93;94;94;94;94;95;95 18:56:26 INFO - PID 19439 | |8;json-parse-financial;44;44;44;48;45;43;43;44;43;47 18:56:26 INFO - PID 19439 | |9;json-stringify-tinderbox;42;37;38;38;37;38;38;38;38;38 18:56:26 INFO - PID 19439 | |10;stanford-crypto-aes;69;71;71;71;72;71;71;71;76;72 18:56:26 INFO - PID 19439 | |11;stanford-crypto-ccm;104;103;104;106;105;111;111;106;113;110 18:56:26 INFO - PID 19439 | |12;stanford-crypto-pbkdf2;158;149;165;162;156;152;150;149;153;151 18:56:26 INFO - PID 19439 | |13;stanford-crypto-sha256-iterative;64;64;58;60;60;61;59;59;58;64 18:56:26 INFO - PID 19439 | __end_tp_report 18:56:26 INFO - PID 19439 | __start_cc_report 18:56:26 INFO - PID 19439 | _x_x_mozilla_cycle_collect,680 18:56:26 INFO - PID 19439 | __end_cc_report 18:56:26 INFO - PID 19439 | __startTimestamp1505958986190__endTimestamp 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | ------- Summary: start ------- 18:56:26 INFO - PID 19439 | Number of tests: 14 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | [#0] ai-astar Cycles:10 Average:106.30 Median:106.50 stddev:1.25 (1.2%) stddev-sans-first:1.17 18:56:26 INFO - PID 19439 | Values: 108.0 106.0 108.0 106.0 105.0 107.0 107.0 104.0 106.0 106.0 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | [#1] audio-beat-detection Cycles:10 Average:115.90 Median:116.00 stddev:3.31 (2.9%) stddev-sans-first:3.00 18:56:26 INFO - PID 19439 | Values: 111.0 117.0 115.0 114.0 112.0 115.0 115.0 119.0 121.0 120.0 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | [#2] audio-dft Cycles:10 Average:164.20 Median:164.00 stddev:2.25 (1.4%) stddev-sans-first:2.39 18:56:26 INFO - PID 19439 | Values: 164.0 162.0 165.0 164.0 163.0 163.0 162.0 163.0 169.0 167.0 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | [#3] audio-fft Cycles:10 Average:73.90 Median:74.00 stddev:1.20 (1.6%) stddev-sans-first:1.27 18:56:26 INFO - PID 19439 | Values: 74.0 76.0 75.0 73.0 73.0 74.0 72.0 75.0 74.0 73.0 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | [#4] audio-oscillator Cycles:10 Average:78.40 Median:78.00 stddev:1.78 (2.3%) stddev-sans-first:1.88 18:56:26 INFO - PID 19439 | Values: 78.0 77.0 78.0 77.0 77.0 78.0 79.0 77.0 81.0 82.0 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:114.00 stddev:2.33 (2.0%) stddev-sans-first:2.22 18:56:26 INFO - PID 19439 | Values: 110.0 115.0 110.0 114.0 115.0 114.0 109.0 113.0 114.0 115.0 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | [#6] imaging-darkroom Cycles:10 Average:149.90 Median:150.00 stddev:1.29 (0.9%) stddev-sans-first:1.36 18:56:26 INFO - PID 19439 | Values: 150.0 149.0 150.0 148.0 150.0 149.0 150.0 150.0 150.0 153.0 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:94.00 stddev:1.93 (2.1%) stddev-sans-first:0.67 18:56:26 INFO - PID 19439 | Values: 100.0 94.0 95.0 93.0 94.0 94.0 94.0 94.0 95.0 95.0 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | [#8] json-parse-financial Cycles:10 Average:44.50 Median:44.00 stddev:1.72 (3.9%) stddev-sans-first:1.81 18:56:26 INFO - PID 19439 | Values: 44.0 44.0 44.0 48.0 45.0 43.0 43.0 44.0 43.0 47.0 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:1.40 (3.7%) stddev-sans-first:0.44 18:56:26 INFO - PID 19439 | Values: 42.0 37.0 38.0 38.0 37.0 38.0 38.0 38.0 38.0 38.0 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | [#10] stanford-crypto-aes Cycles:10 Average:71.50 Median:71.00 stddev:1.78 (2.5%) stddev-sans-first:1.64 18:56:26 INFO - PID 19439 | Values: 69.0 71.0 71.0 71.0 72.0 71.0 71.0 71.0 76.0 72.0 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | [#11] stanford-crypto-ccm Cycles:10 Average:107.30 Median:108.00 stddev:3.59 (3.3%) stddev-sans-first:3.61 18:56:26 INFO - PID 19439 | Values: 104.0 103.0 104.0 106.0 105.0 111.0 111.0 106.0 113.0 110.0 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.50 Median:154.50 stddev:5.60 (3.6%) stddev-sans-first:5.80 18:56:26 INFO - PID 19439 | Values: 158.0 149.0 165.0 162.0 156.0 152.0 150.0 149.0 153.0 151.0 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.70 Median:60.50 stddev:2.45 (4.1%) stddev-sans-first:2.29 18:56:26 INFO - PID 19439 | Values: 64.0 64.0 58.0 60.0 60.0 61.0 59.0 59.0 58.0 64.0 18:56:26 INFO - PID 19439 | -------- Summary: end -------- 18:56:26 INFO - PID 19439 | 18:56:26 INFO - PID 19439 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:56:26 INFO - TEST-INFO | 19439: exit 0 18:56:26 INFO - TEST-OK | kraken | took 58800ms 18:56:26 INFO - SUITE-END | took 1165s 18:56:26 INFO - Completed test suite (00:19:25) 18:56:26 INFO - javascript benchmark 18:56:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9065.45309857242, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3813.580506144324, 3816.1413681921554, 3834.022324307301, 3835.82738776755, 3847.576766555678, 6192.473639740628, 6291.1192433059405, 6306.0, 6319.115323854664, 6327.620342779436, 6344.904826427607, 6378.712957916893, 6388.744450221997, 6395.0, 6404.167458230423, 5620.213170156181, 5632.211490391328, 5683.175939488782, 5689.431056894319, 5735.11105778605, 5699.2306038684665, 5738.942610573903, 5751.309842818868, 5789.884202315951, 5798.536117110642, 5668.829935101953, 5695.829125126232, 5779.0, 5791.82624521265, 5804.245448091742, 330.7253624859808, 5574.916376254359, 5578.888422231573, 5626.0, 5696.259486266758, 5604.663720176803, 5621.7751289948355, 5676.233708449347, 5685.289338832646, 5701.087825947829, 6181.938180618181, 6278.183836101323, 6317.336679648617, 6330.683465826736, 6387.744490220432, 6778.322167783232, 6824.146981627297, 6899.586024838526, 6902.206246281641, 6917.481188910801, 6824.3858052774995, 6830.931690683129, 6863.10779598654, 6925.792226233221, 6948.200956889972, 6196.070589411579, 6298.527610429191, 6298.811035668938, 6373.075903993895, 6380.744770209232, 6935.4104901082965, 7032.12098487689, 7033.964830175919, 7060.0, 7064.32888875556, 6689.966550167218, 6700.396964273239, 6784.932150678529, 6791.3548212919695, 6792.388685018372, 6811.727530898807, 6863.347981941708, 6897.689603967833, 6933.410660093848, 6934.549254298455, 6659.933400666028, 6780.830479238059, 6782.423494002966, 6792.117024786746, 6794.218664853605, 6715.026321183401, 6745.460363170934, 6783.253842077367, 6872.312768723088, 6876.277990810893, 4968.826091086867, 4980.203167493256, 4981.601471882241, 4985.90028199434, 4986.351774269322, 4424.535423780521, 4465.799039043251, 4486.7756612169915, 4491.865244042684, 4551.135284295973, 6222.066689996446, 6265.467435268053, 6277.96861015692, 6278.905816412757, 6299.0866324382705, 6832.145981752281, 6834.0, 6849.726010959505, 6917.377436030782, 6945.687444065029, 6070.484008859296, 6086.6348019119, 6124.142620033199, 6147.354527774519, 6161.229846269216, 5428.212909128154, 5429.56563474921, 5431.2667789847865, 5431.728413579305, 5442.482964118402, 6920.619365934821, 6930.203026652, 6992.650367481606, 6995.79012629622, 6998.440124790106, 6757.594544327258, 6766.898496522655, 6768.763093291711, 6779.220389655253, 6830.7609233676585, 6714.0, 6879.071325371011, 6883.655817209019, 6989.161300643752, 6994.33553812387, 6618.6690665467495, 6685.665716714242, 6685.76599819004, 6694.430973367318, 6713.362230588086, 6898.655067246517, 6903.6203008835, 6924.6537673117155, 6947.062146610143, 6956.234814170336, 6604.207495100619, 6620.569662971891, 6635.8009259722285, 6715.93284067153, 6730.4952128589575, 6467.0, 6575.473962083118, 6589.110470086477, 6590.340965903371, 6694.196696396463, 6432.903506447314, 6534.346565343428, 6558.508111891722, 6561.967190164019, 6564.245111812203, 6599.703013364314, 6626.674665066909, 6708.597484150967, 6721.529492935643, 6765.492588056014, 6458.095866578777, 6463.062855885964, 6482.513811464253, 6548.3124271952665, 6548.770793022221], "value": 6178.714088406125, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [946.8153710026547, 972.9513524323784, 1002.0530598584336, 1004.4324956399638, 1012.2307046644551, 41702.791486042566, 42082.57917420825, 42499.78750106245, 42507.36238956418, 42524.14951700964, 42655.786721066426, 43116.78441607796, 43255.134897302036, 43519.34720979188, 43547.0, 8470.28002619778, 8537.445066070686, 8646.351523635722, 8661.437006594582, 8665.69670061548, 8488.0, 8626.568671566396, 8630.0, 8634.481931084156, 8638.049814520398, 8552.230299273064, 8600.182982616672, 8612.052674205832, 8643.0924752901, 8651.221390074892, 8510.276626486695, 8546.572671366437, 8549.95725021374, 8574.69988550404, 8590.269827064707, 8393.076761556224, 8409.20112589303, 8432.241098301121, 8445.324374050062, 8457.619407126695, 38560.61439385598, 38891.0, 39173.804130979166, 39207.21585568301, 39228.80385598083, 3764.1154328732728, 3778.9811050944845, 3806.5241844769403, 3807.904802379963, 3815.771053736785, 32338.353232935446, 32349.191270218198, 32354.676453235636, 32394.19014524632, 32435.837820810983, 5340.973295133538, 5464.754086066137, 5466.753996070147, 5509.696966666832, 5613.607047506676, 204866.0, 206453.0, 208275.0, 210599.0, 219210.0, 51670.48329516657, 51710.48289517132, 52171.73914130405, 52211.73894130581, 52306.738466307426, 53812.46187538153, 53891.0, 53952.190717139274, 53996.19005714917, 54025.0, 16364.509064728078, 16487.34050637985, 16510.0, 16524.25640846165, 16681.416150434678, 13189.274589897455, 13249.072564920363, 13253.602391928258, 13309.201447913156, 13311.334433278298, 13893.0, 13902.721945561032, 14014.929925350309, 14034.43862245519, 14145.080569762933, 5643.097104463266, 5657.462541058593, 5663.263775709213, 5827.0, 5829.7376618052285, 1078.4769386847372, 1088.9237753357347, 1102.4763237462141, 1111.149970272742, 1114.8885111488787, 24497.26508204721, 24585.508289834102, 24683.25950221496, 24706.629400558646, 24820.5035899281, 1175.312442221293, 1196.276252867003, 1213.4114954247184, 1219.9024078073733, 1223.406647775828, 12916.0, 13181.53864614753, 13218.60344189676, 13247.867521324855, 13280.601581952556, 265465.0, 265513.0, 266613.0, 267730.0, 267769.0, 53405.46594534005, 53430.0, 53680.194797078075, 53910.191347129825, 53962.46037539653, 1451.8330392004843, 1457.6501639606417, 1506.5781581157294, 1510.9848901511064, 1538.169388530181, 23951.76048239565, 24177.395565111012, 24199.879000604884, 24459.0, 24675.876620616782, 39280.80359598184, 39313.21373572455, 39555.80222098871, 39748.0, 39757.40363894486, 213096.0, 213913.0, 214702.0, 215589.0, 215654.0, 34627.826860865534, 36804.631953680124, 38755.22489550137, 39069.218615628095, 39096.80451597724, 48284.75857620689, 48946.26580601365, 49497.50502495073, 49537.25694114519, 49595.008099838524, 36828.44757328696, 36837.81581092077, 37036.25927481381, 37310.440343395414, 37330.25339493141, 35946.82026589955, 36297.45553816642, 36529.81735091307, 36553.81723091367, 36690.0, 40919.59080409277, 41322.38016429816, 41360.1727965445, 41906.58093419027, 41962.0, 34909.0, 34966.47550286697, 35286.647133528335, 35498.11254718652, 35555.28889422249, 26860.865695671397, 27238.182854514398, 27700.307492312855, 27884.88460461559, 27922.860385697943], "value": 18518.027283676074, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.836538317346, 622.3309941812553, 625.4621025917708, 630.1178350309566, 643.4305639509034, 22394.776052239515, 23036.424089397773, 24153.516929661397, 24282.27153185405, 24291.392715182108, 24484.510309793794, 25761.0, 25772.613410798855, 25777.097801576947, 25797.355066123357, 6307.747690092391, 6472.352764723537, 6480.4491618212505, 6484.1894763154605, 6488.448481879046, 6538.934610653904, 6918.965405172967, 6919.619420931846, 6924.4806639501985, 6933.757318493854, 6851.623160726158, 6857.245702972669, 6859.622720750357, 6873.896891546606, 6874.27820078892, 6450.903236451481, 6698.49761267902, 6739.427148692367, 6832.385085342318, 6843.623600701984, 6299.5905266158015, 6302.086197501382, 6311.52663550231, 6313.494920406357, 6318.052292156168, 30441.847790760905, 30848.8457557713, 30867.228319292197, 31117.688823111705, 31133.688663113306, 2278.5784629843533, 2309.6881920940796, 2323.5585238804574, 2324.1865347128537, 2325.5348930213854, 19633.214671413127, 20783.68824467634, 20959.580808383747, 20963.5807283855, 20971.475713107142, 2530.316814460104, 2558.7057488388887, 2627.04112998756, 2628.5531459651716, 2631.1974847671454, 138462.0, 144662.0, 144676.2766186173, 154350.22824885917, 161744.99999999884, 15774.447894323874, 15890.60273493172, 16307.510774676779, 16329.18354082291, 16359.263833127538, 26145.084922027636, 26220.8688956554, 26251.868740656173, 26355.341116472242, 26420.33949151248, 10067.597296108073, 10234.669492966015, 10320.793584128276, 10436.42599657011, 10456.477176141163, 9242.075792420705, 9327.253819694408, 9427.387219830689, 9472.431654100776, 9520.143187113055, 9336.299777516713, 9540.952295238618, 9554.713358599252, 9668.468234247042, 9895.0, 2709.4851978124093, 2912.461194678991, 2932.6627437844922, 2954.615899933038, 2974.2488198416377, 1047.8166320893813, 1050.1021626509378, 1055.4511653939908, 1058.7829494953517, 1069.8769641491328, 20506.282280120126, 20614.896925515575, 20800.791992079885, 20976.685349719766, 21161.25935592247, 1122.7810576937418, 1126.6957921361186, 1136.3920302638214, 1143.3082984794246, 1144.2390810111183, 11464.426778661034, 11469.369184694755, 11488.368139752227, 11541.480633371519, 11574.768504629861, 566601.0, 577052.0, 577646.0, 579033.0, 596615.0, 132561.0, 136278.0, 136385.318073409, 138285.0, 138506.307468462, 1208.1361826294242, 1249.7188132670258, 1256.9874301257053, 1260.4832018872403, 1263.260992319485, 18395.988220647992, 18511.166997484877, 18615.255389784255, 18798.624027519647, 18953.526161846064, 63198.0, 63837.361626385, 65841.0, 66507.0, 66855.66572167107, 76922.61538692271, 79090.0, 81456.0, 82426.0, 82721.58639206765, 14973.176475293963, 15384.615384615474, 15434.536963891102, 15533.223338832788, 15706.2932168055, 22675.319740407813, 22687.88656056709, 22753.658695119917, 23725.525489490457, 23872.28383148508, 18848.811511884705, 19047.57143214237, 19096.331628392938, 19288.517787055436, 19480.512987175432, 16753.329866805678, 17020.063896485317, 17253.82746172522, 17266.22301996388, 17545.385911493035, 18001.0, 19006.809931900505, 19380.612387752448, 19526.414207573795, 19710.21159153618, 15035.849641503444, 15168.24158792078, 15202.391904324146, 15242.085474871099, 15250.0, 18789.342373017425, 19061.142248598568, 19090.809091908905, 19139.425817225507, 19228.71156932675], "value": 12715.993156114959, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1444.4799872046062, 1468.2878803780172, 1477.3425825507647, 1515.909045457272, 1525.3136088760061, 16620.25208865601, 17333.913330433363, 17344.913275433606, 17350.73973890391, 17355.47933561995, 16788.74816877745, 16805.4118105866, 16961.0, 16969.406070787525, 17010.914945425255, 2783.498970185366, 2805.747482726554, 2810.985945070272, 2822.463731890934, 2824.943501129976, 2724.3870129220963, 2779.9305017374627, 2780.06867699321, 2785.7353551412684, 2786.9966811947684, 2683.047518131065, 2732.0, 2737.986310068447, 2739.1782465260444, 2740.630014947986, 2778.3748656552216, 2789.693133755275, 2799.272189230794, 2801.383695586978, 2802.9159125226274, 2659.9069032583766, 2675.5184066867955, 2686.597010448429, 2691.8923243070253, 2692.6903406108354, 16358.345666173338, 16632.667346652997, 16915.06967116808, 17031.57421064471, 17094.145292735437, 7957.801054973614, 8261.917380826175, 8262.297704695107, 8263.00843898736, 8271.1315311892, 5435.864103397446, 5652.686474713756, 5725.541956643458, 5776.220210271602, 5782.566307526952, 2139.1657253671083, 2169.0998235732154, 2173.7500187478486, 2197.121151539381, 2219.7336319641745, 30263.092107236596, 30398.0, 30503.08490745259, 30608.387832243232, 30646.54030189549, 6912.412444942136, 7033.0, 7217.819554511074, 7317.012203352586, 7357.448191385667, 19818.90090549538, 19863.60272794536, 19870.60258794816, 19879.403617891487, 19901.104450299772, 8224.218699223564, 8360.414770966096, 8369.916300837036, 8378.622961966727, 8378.706745263886, 7099.219085900547, 7389.7413590524075, 7440.255974402625, 7583.9620801895635, 7587.393008559302, 5490.121580547093, 5708.628939118944, 5708.686022268732, 5722.856928576819, 5765.423457654285, 1651.7522371644109, 1654.59462431705, 1667.5330907345963, 1677.4464426739146, 1692.9153542322836, 897.6364572348209, 917.1149840403909, 917.4311926605548, 928.0533855467386, 936.784539555906, 7810.921890781133, 7947.880781788278, 7956.2043795619975, 7982.640781164861, 8026.157253488416, 785.83890302489, 796.3191471291962, 800.9519428834288, 803.4135081390617, 803.7669075967904, 5736.5123964462655, 5804.4195580442465, 5962.791302304486, 6118.541109416722, 6223.96888015566, 24289.39281498553, 25532.744672553035, 25536.0, 25697.48605027889, 25911.481770364866, 24405.511889762107, 24758.628620570707, 24997.0, 25097.24708258755, 25308.873455632973, 11773.0, 11876.821847672292, 12160.0, 12390.752184956431, 12566.183198092094, 9285.86071208946, 9379.296552758708, 9492.098250666451, 9499.145076943109, 9696.660616878375, 21124.577508449438, 21190.576188476454, 21209.15163393447, 21400.143994240058, 21520.569588608454, 22129.446763831034, 22316.330510084725, 22505.212317568807, 22671.65992510081, 22815.543689125792, 12237.938810305892, 12306.507739690313, 12336.62990110298, 12339.321337326533, 12344.197627154206, 15060.472883449027, 15250.771238431655, 15335.923320383774, 15456.768148477557, 15460.381584736935, 11965.521379144737, 12174.513019479122, 12282.87717122853, 12392.442340094898, 12536.874631253935, 11692.123090768397, 11736.119791015879, 12029.218100823418, 12198.207116537396, 12370.938145309216, 10972.286801357886, 11279.379634120201, 11283.097352211968, 11284.379359135326, 11285.492152853305, 8074.555899425588, 8154.796130096795, 8165.959170204111, 8256.504609723435, 8274.255317021498, 8397.286230670461, 8441.409101362846, 8473.406861519634, 8501.19238672325, 8506.0], "value": 7214.145910211452, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [529.9788008479661, 572.6936089192282, 584.4710536964047, 586.2378907420352, 586.7418335932192, 6237.5009999200065, 6290.427393151712, 6290.465310448606, 6290.798220622785, 6387.297397286301, 6155.0, 6179.0, 6187.133801267815, 6248.37516248375, 6251.843703907404, 5059.215821547657, 5078.365204349457, 5106.361704786902, 5114.0110713643235, 5138.460461651529, 4807.35100761398, 4975.208924787074, 4985.725785081819, 5021.598272138239, 5096.541311281984, 4978.402591689002, 4989.625778066641, 4993.525615066581, 5011.498850114996, 5017.799288028475, 4881.755912204376, 4918.508149185071, 4926.876828079291, 4957.752112394366, 4968.279599458058, 4448.043670610834, 4509.7970591323465, 4558.566936141061, 4579.244424669959, 4580.587747102777, 6151.507879369639, 6188.47397971173, 6191.2570491540855, 6237.719302631393, 6286.685665716742, 5804.535637149018, 5871.0, 5889.793857214977, 5902.527797776168, 5905.261842269716, 6555.115059467006, 6594.0, 6627.7680281190405, 6694.933050669529, 6699.799006029827, 5067.0, 5087.847364579068, 5088.084144853925, 5114.411842638107, 5118.0, 6536.836579085513, 6778.186617605869, 6827.269092363045, 6844.589324640538, 6920.757773478005, 5195.142801437796, 5244.292020577173, 5247.134222853187, 5256.658317209369, 5278.181881808326, 5213.218017297436, 5249.448807875195, 5272.604554658416, 5281.9207711884355, 5342.465753424626, 5797.536197104221, 5841.386654401312, 5851.795187168429, 5904.380040095814, 5918.141869428909, 6599.76900808479, 6728.562643428161, 6731.427828634621, 6736.562123461959, 6781.254062053269, 1800.2438975630005, 1826.712167921612, 1844.97232541512, 1849.5653521422455, 1850.4448665400325, 1250.6498180509473, 1298.1821452484874, 1298.4416700818738, 1307.6861553227156, 1315.5066849931275, 6085.2393450818645, 6113.266408030976, 6154.292256390572, 6163.106349579286, 6175.413335733098, 6034.969825150934, 6593.86812263752, 6729.2934241903895, 6747.245716113747, 6765.2896445872475, 5957.212836149224, 5988.161657367972, 6059.939400606026, 6067.817965460955, 6101.908471372933, 3526.4357702767948, 3531.5938667053106, 3554.537910071726, 3556.199855032597, 3590.263995880838, 6869.690863911136, 6886.758963436256, 6926.965365173242, 6928.826779330456, 6950.513464057467, 6913.170419549686, 6957.339052789976, 7011.158660960616, 7037.437005039585, 7038.6480675965995, 6804.387605115563, 6806.285340039224, 6869.278725733726, 6872.896906546406, 6965.547239429421, 6605.834854128493, 6642.202935647753, 6674.399304062657, 6738.932610674026, 6775.49183811226, 6642.804295226712, 6665.933340666726, 6744.0, 6772.492063095386, 6776.2207346153855, 6805.863882722417, 6840.144981877265, 6849.554778939353, 6859.519833611599, 6861.759838405632, 5170.689758614345, 5181.870453238699, 5186.974065129651, 5214.817481388133, 5223.347081614798, 6508.902366464602, 6762.289959554176, 6779.8135326316715, 6781.42357899584, 6808.829779255559, 6340.714667839866, 6429.453496452854, 6526.608403495806, 6546.869062618816, 6590.8352291193105, 6486.772962946275, 6486.870262594816, 6497.18785151856, 6537.803865884031, 6561.114249576437, 6318.494520438262, 6385.8722825544155, 6394.90407643895, 6395.360463953567, 6536.967315163393, 6578.473722102125, 6687.832804179934, 6690.498212633975, 6706.731730730911, 6713.825080610971, 6451.0, 6466.288708242184, 6513.837154071186, 6553.5084868634085, 6561.1470508833545], "value": 5173.882047975214, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [954.475038728699, 984.8522721591761, 992.3995982430633, 1006.5973610555777, 1014.2149816323268, 20398.89800550997, 20912.68630970533, 20931.686024709605, 21109.0, 21263.57472850542, 19059.42821715351, 19309.806901930977, 19604.117814698337, 20487.38537843867, 20681.68977465339, 1039.4387031003268, 1058.185197398003, 1059.4384975962753, 1059.7456610413522, 1062.75556621977, 1049.8057859296016, 1081.5133190064503, 1099.444780385904, 1100.1748688483638, 1100.9944950275237, 1151.4875880233299, 1151.9136064795132, 1153.423288355822, 1153.5904753812401, 1154.2958795134962, 1157.7915975124474, 1158.0619698044611, 1158.362900404774, 1159.1248607301475, 1161.181367136167, 797.4816369359904, 824.8556502612018, 826.7147833997261, 839.1063517353998, 855.0167307596314, 18962.71555926662, 19005.904970475196, 19037.42887713371, 19068.0, 19137.13882875274, 22493.775062249497, 23060.53878922429, 23404.882975585013, 23528.411789705224, 23573.410664733517, 18424.078796060145, 18555.350562730375, 18793.53016174593, 19020.42938711841, 19048.85706857579, 3902.5316961964463, 3982.82077306519, 4005.379166229239, 4007.939880901789, 4009.959900401017, 160260.0, 161884.0, 163473.0, 165436.0, 167197.0, 15023.323950422257, 15325.157116358485, 15344.079355238722, 15390.0, 15509.379624814881, 32084.358312833614, 32289.51565726516, 33447.16382090467, 33448.0, 33472.66527334744, 9103.135202155785, 9266.166045055978, 9297.256219502558, 9316.02181770918, 9344.0, 4643.907121857612, 4758.738269395148, 4760.904781904343, 4778.116048531033, 4781.2111001685025, 5726.484616384545, 5815.941840581625, 5854.5901786875365, 5904.439078287556, 5930.614510056833, 1178.1222988873344, 1178.2753606531921, 1189.7679952409371, 1191.4102519253024, 1199.4662375243108, 676.1210426445601, 725.4631572636316, 736.5102207032263, 742.1835980421602, 745.3589912675095, 13080.934595326962, 13350.799738003938, 13465.461381544825, 13872.514461993984, 14041.55429028061, 690.2510775808304, 711.0969069281983, 731.6049333360032, 733.1898252431093, 743.18992298395, 5761.854646841993, 5812.418758124239, 5870.148828419855, 6037.516998640098, 6074.331823499411, 145053.0, 145256.0, 149910.0, 152387.0, 157598.0, 125567.3721631386, 128847.35576322058, 130445.34777326243, 131597.3420132893, 131897.0, 115796.0, 116195.0, 116211.41894290474, 117317.85004643051, 122242.3887880555, 12936.611901642966, 13314.533991310258, 13350.399232034388, 13383.933080334536, 13507.324633768469, 56160.0, 56532.71733641306, 56762.14856777069, 57226.7138664304, 59694.0, 70921.6453917727, 73547.63226183834, 73990.0, 75841.6207918957, 76698.2330176691, 19932.50168745793, 20177.0, 20225.089870955548, 20343.389698309074, 20387.388378348674, 21039.0, 22121.889390552944, 22219.22232721847, 22226.888865555567, 22235.555288893807, 20064.291320671524, 20247.4938126548, 20346.08442620056, 20406.59186816285, 20837.37487875366, 20712.757234565976, 20976.895115524323, 21109.155633774477, 21200.68198976986, 21442.463938401666, 19229.13468893931, 19404.805951940863, 19446.124924378153, 19639.509012274808, 19796.406107816787, 15501.767473488133, 15547.144907030222, 15611.843881561039, 15783.68432631364, 16061.277242524347, 15626.765598515804, 15679.294431750826, 15846.762298565762, 15864.0, 15915.761263580824], "value": 10220.342725289622, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1373.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 106.0, 108.0, 106.0, 105.0, 107.0, 107.0, 104.0, 106.0, 106.0], "value": 106.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 117.0, 115.0, 114.0, 112.0, 115.0, 115.0, 119.0, 121.0, 120.0], "value": 115.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 165.0, 164.0, 163.0, 163.0, 162.0, 163.0, 169.0, 167.0], "value": 164.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 73.0, 73.0, 74.0, 72.0, 75.0, 74.0, 73.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 78.0, 77.0, 77.0, 78.0, 79.0, 77.0, 81.0, 82.0], "value": 78.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 115.0, 110.0, 114.0, 115.0, 114.0, 109.0, 113.0, 114.0, 115.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 150.0, 148.0, 150.0, 149.0, 150.0, 150.0, 150.0, 153.0], "value": 149.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 94.0, 95.0, 93.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 44.0, 44.0, 48.0, 45.0, 43.0, 43.0, 44.0, 43.0, 47.0], "value": 44.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 37.0, 38.0, 38.0, 37.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 71.0, 71.0, 72.0, 71.0, 71.0, 71.0, 76.0, 72.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 103.0, 104.0, 106.0, 105.0, 111.0, 111.0, 106.0, 113.0, 110.0], "value": 107.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 149.0, 165.0, 162.0, 156.0, 152.0, 150.0, 149.0, 153.0, 151.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 58.0, 60.0, 60.0, 61.0, 59.0, 59.0, 58.0, 64.0], "value": 60.7, "unit": "score"}]}]} 18:56:26 INFO - Return code: 0 18:56:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:56:26 INFO - # TBPL SUCCESS # 18:56:26 INFO - Running post-action listener: _package_coverage_data 18:56:26 INFO - Running post-action listener: _resource_record_post_action 18:56:26 INFO - [mozharness: 2017-09-21 01:56:26.992697Z] Finished run-tests step (success) 18:56:26 INFO - Running post-run listener: _resource_record_post_run 18:56:27 INFO - Total resource usage - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 329150464; Read time: 24; Write time: 171544 18:56:27 INFO - TinderboxPrint: CPU usage
13.1% 18:56:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 24 18:56:27 INFO - TinderboxPrint: I/O write bytes / time
329,150,464 / 171,544 18:56:27 INFO - TinderboxPrint: CPU idle
8,206.2 (86.9%) 18:56:27 INFO - TinderboxPrint: CPU user
1,185.8 (12.6%) 18:56:27 INFO - TinderboxPrint: Swap in / out
0 / 0 18:56:27 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 9895936; Read time: 0; Write time: 732 18:56:27 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:56:27 INFO - run-tests - Wall time: 1166s; CPU: 13.0%; Read bytes: 4096; Write bytes: 319254528; Read time: 24; Write time: 170812 18:56:27 INFO - Running post-run listener: _upload_blobber_files 18:56:27 INFO - Blob upload gear active. 18:56:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:56:27 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 18:56:27 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'] 18:56:27 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 18:56:28 INFO - (blobuploader) - INFO - Open directory for files ... 18:56:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:56:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:56:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:56:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:56:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:56:40 INFO - (blobuploader) - INFO - Done attempting. 18:56:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:56:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:56:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:56:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:56:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:56:41 INFO - (blobuploader) - INFO - Done attempting. 18:56:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:56:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:56:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:56:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:56:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:56:42 INFO - (blobuploader) - INFO - Done attempting. 18:56:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:56:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:56:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:56:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:56:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:56:43 INFO - (blobuploader) - INFO - Done attempting. 18:56:43 INFO - (blobuploader) - INFO - Iteration through files over. 18:56:43 INFO - Return code: 0 18:56:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:56:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:56:43 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9040c1ea85967e1805cf311b9c9ffc6398bc1a5e269ad3dfbd5f65893b04f49de13b73b6c86beb13e09964aa57de05e2bd6d28f1632123e7c17c1793f26c8ffb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/972787a2790224232f090089750cc1a19929a53d694b01e42e47fb48e174e8a9fa57b2a0be31e483f59a87ce4de86e78b5fc66775402abe65af42be8c428dd2e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a7d896726f43dd1be531344f7133448bddaff0e58357ba584b9a95e27a875a81ea2a2fa44f8650933125813196a9c666285621cfd6b0cb6ff39d6c54203733e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bc874d32f0657312993d698c8a8db1d4921c0f7b17e45f320550a0ff9285e60b29a2e8b31172c123fa455fe667a3404f9ff9c0f0084b2c6b4f1f17436bd9d96"} 18:56:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:56:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:56:43 INFO - Contents: 18:56:43 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9040c1ea85967e1805cf311b9c9ffc6398bc1a5e269ad3dfbd5f65893b04f49de13b73b6c86beb13e09964aa57de05e2bd6d28f1632123e7c17c1793f26c8ffb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/972787a2790224232f090089750cc1a19929a53d694b01e42e47fb48e174e8a9fa57b2a0be31e483f59a87ce4de86e78b5fc66775402abe65af42be8c428dd2e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a7d896726f43dd1be531344f7133448bddaff0e58357ba584b9a95e27a875a81ea2a2fa44f8650933125813196a9c666285621cfd6b0cb6ff39d6c54203733e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bc874d32f0657312993d698c8a8db1d4921c0f7b17e45f320550a0ff9285e60b29a2e8b31172c123fa455fe667a3404f9ff9c0f0084b2c6b4f1f17436bd9d96"} 18:56:43 INFO - Running post-run listener: copy_logs_to_upload_dir 18:56:43 INFO - Copying logs to upload dir... 18:56:43 INFO - mkdir: /builds/slave/test/build/upload/logs 18:56:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.810435 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-20 18:56:43.361583) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 18:56:43.366138) ========= 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=438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9040c1ea85967e1805cf311b9c9ffc6398bc1a5e269ad3dfbd5f65893b04f49de13b73b6c86beb13e09964aa57de05e2bd6d28f1632123e7c17c1793f26c8ffb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/972787a2790224232f090089750cc1a19929a53d694b01e42e47fb48e174e8a9fa57b2a0be31e483f59a87ce4de86e78b5fc66775402abe65af42be8c428dd2e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a7d896726f43dd1be531344f7133448bddaff0e58357ba584b9a95e27a875a81ea2a2fa44f8650933125813196a9c666285621cfd6b0cb6ff39d6c54203733e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bc874d32f0657312993d698c8a8db1d4921c0f7b17e45f320550a0ff9285e60b29a2e8b31172c123fa455fe667a3404f9ff9c0f0084b2c6b4f1f17436bd9d96"} build_url:https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011348 build_url: 'https://queue.taskcluster.net/v1/task/SUgHeWVwSNKoEh42Vq7Wvw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9040c1ea85967e1805cf311b9c9ffc6398bc1a5e269ad3dfbd5f65893b04f49de13b73b6c86beb13e09964aa57de05e2bd6d28f1632123e7c17c1793f26c8ffb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/972787a2790224232f090089750cc1a19929a53d694b01e42e47fb48e174e8a9fa57b2a0be31e483f59a87ce4de86e78b5fc66775402abe65af42be8c428dd2e", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a7d896726f43dd1be531344f7133448bddaff0e58357ba584b9a95e27a875a81ea2a2fa44f8650933125813196a9c666285621cfd6b0cb6ff39d6c54203733e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bc874d32f0657312993d698c8a8db1d4921c0f7b17e45f320550a0ff9285e60b29a2e8b31172c123fa455fe667a3404f9ff9c0f0084b2c6b4f1f17436bd9d96"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-20 18:56:43.397682) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:56:43.398119) ========= 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=438111f3d31ce799eff2119d000001bc-1505957731.14255-1552165222 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004557 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-20 18:56:43.450907) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 18:56:43.451274) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-20 18:56:43.451686) ========= ========= Total master_lag: 0.26 =========