New issue
Advanced search Search tips
Note: Color blocks (like or ) mean that a user may not be available. Tooltip shows the reason.

Issue 721125 link

Starred by 1 user

Issue metadata

Status: Duplicate
Merged: issue 740839
Owner:
Last visit > 30 days ago
Closed: Jul 2017
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: ----
Pri: 1
Type: Bug



Sign in to add a comment

link-paladin login_MultipleSessions Unhandled DBusException

Project Member Reported by ayatane@chromium.org, May 10 2017

Issue description

Example builds:

https://luci-milo.appspot.com/buildbot/chromeos/link-paladin/28275
https://luci-milo.appspot.com/buildbot/chromeos/link-paladin/28269

05/10 13:49:52.905 DEBUG|              test:0379| starting test(run_once()), test details follow
()
05/10 13:49:52.914 DEBUG|        cryptohome:0476| Issued call <dbus.proxies._DeferredMethod instance at 0x7fdb826238c0> with async_id 19
05/10 13:49:52.915 DEBUG|        cros_disks:0133| Waiting for D-Bus signal "AsyncCallStatus"
05/10 13:49:52.916 DEBUG|        cros_disks:0138| Received D-Bus signal "AsyncCallStatus({'return_status': dbus.Boolean(True), 'async_id': dbus.Int32(19), 'return_code': dbus.Int32(0)})"
05/10 13:49:52.918 DEBUG|        cryptohome:0476| Issued call <dbus.proxies._ProxyMethod instance at 0x7fdb82601d40> with async_id 20
05/10 13:49:52.919 DEBUG|        cros_disks:0133| Waiting for D-Bus signal "AsyncCallStatus"
05/10 13:49:53.321 DEBUG|        cros_disks:0138| Received D-Bus signal "AsyncCallStatus({'return_status': dbus.Boolean(False), 'async_id': dbus.Int32(20), 'return_code': dbus.Int32(2)})"
05/10 13:49:53.325 DEBUG|              test:0389| Test failed due to org.chromium.SessionManagerInterface.NoUserNssDb: Can't create session.. Exception log follows the after_iteration_hooks.
05/10 13:49:53.326 DEBUG|              test:0392| starting after_iteration_hooks
05/10 13:49:53.327 DEBUG|             utils:0202| Running 'mkdir -p /usr/local/autotest/results/default/login_MultipleSessions/sysinfo/iteration.1/var/spool'
05/10 13:49:53.338 DEBUG|             utils:0202| Running 'rsync --no-perms --chmod=ugo+r -a --exclude=autoserv* --safe-links --exclude=*.core /var/spool/crash /usr/local/autotest/results/default/login_MultipleSessions/sysinfo/iteration.1/var/spool'
05/10 13:49:53.351 DEBUG|             utils:0202| Running 'rm -rf /var/spool/crash/*'
05/10 13:49:53.362 DEBUG|             utils:0202| Running 'logger "autotest finished iteration /usr/local/autotest/results/default/login_MultipleSessions/sysinfo/iteration.1"'
05/10 13:49:53.372 DEBUG|              test:0395| after_iteration_hooks completed
05/10 13:49:53.374 WARNI|              test:0615| The test failed with the following exception
Traceback (most recent call last):
  File "/usr/local/autotest/common_lib/test.py", line 609, in _exec
    _call_test_function(self.execute, *p_args, **p_dargs)
  File "/usr/local/autotest/common_lib/test.py", line 823, in _call_test_function
    raise error.UnhandledTestFail(e)
UnhandledTestFail: Unhandled DBusException: org.chromium.SessionManagerInterface.NoUserNssDb: Can't create session.
Traceback (most recent call last):
  File "/usr/local/autotest/common_lib/test.py", line 817, in _call_test_function
    return func(*args, **dargs)
  File "/usr/local/autotest/common_lib/test.py", line 470, in execute
    dargs)
  File "/usr/local/autotest/common_lib/test.py", line 347, in _call_run_once_with_retry
    postprocess_profiled_run, args, dargs)
  File "/usr/local/autotest/common_lib/test.py", line 380, in _call_run_once
    self.run_once(*args, **dargs)
  File "/usr/local/autotest/tests/login_MultipleSessions/login_MultipleSessions.py", line 42, in run_once
    self.__start_session_for(expected_owner)
  File "/usr/local/autotest/tests/login_MultipleSessions/login_MultipleSessions.py", line 81, in __start_session_for
    if not self._session_manager.StartSession(user, ''):
  File "/usr/local/lib64/python2.7/site-packages/dbus/proxies.py", line 145, in __call__
    **keywords)
  File "/usr/local/lib64/python2.7/site-packages/dbus/connection.py", line 651, in call_blocking
    message, timeout)
DBusException: org.chromium.SessionManagerInterface.NoUserNssDb: Can't create session.
 

Comment 1 by xixuan@chromium.org, May 11 2017

looks like a bad CL?
Project Member

Comment 2 by bugdroid1@chromium.org, May 11 2017

The following revision refers to this bug:
  https://chromium.googlesource.com/chromiumos/third_party/autotest/+/356d74b00224bdb2d2f52629f7e2b05c850b004e

commit 356d74b00224bdb2d2f52629f7e2b05c850b004e
Author: Allen Li <ayatane@chromium.org>
Date: Thu May 11 16:46:59 2017

Disable login_MultipleSessions

This test has been failing the CQ with:

DBusException: org.chromium.SessionManagerInterface.NoUserNssDb: Can't
create session

BUG= chromium:721125 
TEST=None

Change-Id: I2cd8974ca32c32e7583c9495c7c7ca6b1b535dac
Reviewed-on: https://chromium-review.googlesource.com/502348
Reviewed-by: Xixuan Wu <xixuan@chromium.org>
Reviewed-by: Ramya Bhagavatula <rbhagavatula@chromium.org>
Tested-by: Allen Li <ayatane@chromium.org>

[modify] https://crrev.com/356d74b00224bdb2d2f52629f7e2b05c850b004e/client/site_tests/login_MultipleSessions/control

I don't believe there's anything wrong with the test.  In particular,
the test isn't failing in the canaries:
    https://wmatrix.googleplex.com/unfiltered?hide_missing=True&releases=tot&tests=login_MultipleSessions

We should re-enable the test.

Comment 4 by aut...@google.com, May 23 2017

Status: Unconfirmed (was: Untriaged)
Project Member

Comment 5 by bugdroid1@chromium.org, Jun 2 2017

The following revision refers to this bug:
  https://chromium.googlesource.com/chromiumos/third_party/autotest/+/2260c566baf57061fb034054ad7da9f5021a1e71

commit 2260c566baf57061fb034054ad7da9f5021a1e71
Author: Richard Barnette <jrbarnette@chromium.org>
Date: Wed May 24 06:59:17 2017

Revert "Disable login_MultipleSessions"

This reverts commit 356d74b00224bdb2d2f52629f7e2b05c850b004e.

This test failed a few times in the CQ.  However, it's not failing
in the canaries at all.  There's no evidence that this test shouldn't
be back in bvt-inline.

BUG= chromium:721125 

Change-Id: Ib48799ee698ebe9c187df2249e2a18c0146297cb
Reviewed-on: https://chromium-review.googlesource.com/508953
Commit-Ready: Richard Barnette <jrbarnette@chromium.org>
Tested-by: Richard Barnette <jrbarnette@chromium.org>
Reviewed-by: Allen Li <ayatane@chromium.org>

[modify] https://crrev.com/2260c566baf57061fb034054ad7da9f5021a1e71/client/site_tests/login_MultipleSessions/control

Components: -Infra>Client>ChromeOS OS>Systems
Labels: -Pri-2 Pri-1
Once every week or two, this failure strikes the link paladin.
All evidence points to a bug in ToT.

Additional information:  The test is now showing regular retries.
   https://wmatrix.googleplex.com/retry_teststats/?days_back=30&tests=login_MultipleSessions

More boards are affected than link, although to date, in the
CQ, link is the only board to be affected.

Apparently this happened today?

Sounds like we should revert #5
> Sounds like we should revert #5

This event is very rare, and we may need to accumulate a
few errors in order to debug it.  I've escalated with the
sheriff, and I'm hoping someone can step in to make an
evaluation as to what's the best course of action.

Owner: sureshraj@chromium.org
Status: Assigned (was: Unconfirmed)
For reference, this is the most recent failure in the
link-paladin:
    https://luci-milo.appspot.com/buildbot/chromeos/link-paladin/28676#

The failure boils down to a D-Bus call to session_manager that
fails, so we need someone experienced with that code base to
look at this and evaluate it.

Cc: hidehiko@chromium.org
FYI: According to the log,

https://pantheon.corp.google.com/storage/browser/chromeos-autotest-results/116636605-chromeos-test/chromeos4-row5-rack13-host1/login_MultipleSessions/sysinfo/

DB is failed to be opened.

2017-05-10T02:29:52.829591+00:00 ERR session_manager[24862]: [ERROR:nss_util.cc(133)] Error opening persistent database (configDir='sql:/home/user/4744ca7a81f5f2b8212b94aeedd9b4d60c01b1cb/.pki/nssdb' tokenDescription='/home/user/4744ca7a81f5f2b8212b94aeedd9b4d60c01b1cb'): -8018
2017-05-10T02:29:52.829639+00:00 ERR session_manager[24862]: [ERROR:session_manager_impl.cc(1129)] Could not open the current user's NSS database.

I'm not very familiar with nss, but according to the error code,
https://mozilla.github.io/python-nss-docs/nss.error-module.html
-8018 is SEC_ERROR_UNKNOWN_PKCS11_ERROR.

Comment 13 by derat@chromium.org, Jul 23 2017

Mergedinto: 740839
Status: Duplicate (was: Assigned)

Sign in to add a comment