688616 - Edit profile bio ends 500, INVALID_CREDENTIALS: {'desc': 'Invalid credentials'}

Status: VERIFIED WORKSFORME

(Participation Infrastructure :: Phonebook, defect)

Description

[Austin King [:ozten]]

Steps to reproduce
1) Login to stage
2) Edit Profile > change bio for user who already has a bio
3) Click Update

Expected:
Bio is updated

Actual:
https://mozillians.allizom.org/en-US/user/edit/cbac836cb6
generates
Traceback (most recent call last):

  File "/data/www/mozillians.allizom.org/mozillians/vendor/src/django/django/core/handlers/base.py", line 111, in get_response
    response = callback(request, *callback_args, **callback_kwargs)

  File "/data/www/mozillians.allizom.org/mozillians/apps/phonebook/views.py", line 102, in edit_profile
    return _edit_profile(request, unique_id, False)

  File "/data/www/mozillians.allizom.org/mozillians/apps/phonebook/views.py", line 123, in _edit_profile
    ldap.update_person(unique_id, form.cleaned_data)

  File "/data/www/mozillians.allizom.org/mozillians/apps/larper/__init__.py", line 245, in update_person
    conn = self._ensure_conn(WRITE)

  File "/data/www/mozillians.allizom.org/mozillians/apps/larper/__init__.py", line 117, in _ensure_conn
    conn.bind_s(dn, password)

  File "/usr/lib/python2.6/site-packages/ldap/ldapobject.py", line 221, in bind_s
    return self.result(msgid,all=1,timeout=self.timeout)

  File "/usr/lib/python2.6/site-packages/ldap/ldapobject.py", line 436, in result
    res_type,res_data,res_msgid = self.result2(msgid,all,timeout)

  File "/usr/lib/python2.6/site-packages/ldap/ldapobject.py", line 440, in result2
    res_type, res_data, res_msgid, srv_ctrls = self.result3(msgid,all,timeout)

  File "/usr/lib/python2.6/site-packages/ldap/ldapobject.py", line 446, in result3
    ldap_result = self._ldap_call(self._l.result3,msgid,all,timeout)

  File "/usr/lib/python2.6/site-packages/ldap/ldapobject.py", line 96, in _ldap_call
    result = func(*args,**kwargs)

INVALID_CREDENTIALS: {'desc': 'Invalid credentials'}

Comment 1

[Austin King [:ozten]]

This doesn't repro on dev
https://mozillians-dev.allizom.org/en-US/

Comment 2

[Austin King [:ozten]]

Per Bug#688204, stage is having replication issues. This might be causing of this issue.

Comment 3

[tofumatt [:tofumatt]]

This doesn't happen on local dev in vagrant either, for what it's worth. All signs point to something weird happening on stage.

Is there a reasonable way to deal with this kind of (seemingly long) replication lag?

Comment 4

[Austin King [:ozten]]

This no longer reproduces with stage config fixed in Bug#688204.

Comment 5

[Tobias (:Tobbi) Markus]

Nor can I reproduce the issue anymore. Verifying invalid.

Comment 6

[Matt Brandt [:mbrandt]]

Tracebacks are coming in again from prod. Reopening.

Affected accounts:
https://mozillians.org/en-US/u/29ab5ae13f
https://mozillians.org/en-US/u/515554e83b

Traceback (most recent call last):

 File "/data/www/mozillians.org/mozillians/vendor/src/django/django/core/handlers/base.py", line 111, in get_response
   response = callback(request, *callback_args, **callback_kwargs)

 File "/data/www/mozillians.org/mozillians/vendor/src/django/django/views/decorators/cache.py", line 88, in _wrapped_view_func
   response = view_func(request, *args, **kwargs)

 File "/data/www/mozillians.org/mozillians/vendor/src/django/django/contrib/auth/decorators.py", line 19, in _wrapped_view
   return view_func(request, *args, **kwargs)

 File "/data/www/mozillians.org/mozillians/apps/phonebook/views.py", line 58, in profile_uid
   person = ldap.get_by_unique_id(unique_id, needs_master)

 File "/data/www/mozillians.org/mozillians/apps/larper/__init__.py", line 191, in get_by_unique_id
   results = self._people_search(q, use_master)

 File "/data/www/mozillians.org/mozillians/apps/larper/__init__.py", line 347, in _people_search
   conn = self._ensure_conn(READ)

 File "/data/www/mozillians.org/mozillians/apps/larper/__init__.py", line 125, in _ensure_conn
   conn.bind_s(dn, password)

 File "/usr/lib/python2.6/site-packages/ldap/ldapobject.py", line 221, in bind_s
   return self.result(msgid,all=1,timeout=self.timeout)

 File "/usr/lib/python2.6/site-packages/ldap/ldapobject.py", line 436, in result
   res_type,res_data,res_msgid = self.result2(msgid,all,timeout)

 File "/usr/lib/python2.6/site-packages/ldap/ldapobject.py", line 440, in result2
   res_type, res_data, res_msgid, srv_ctrls = self.result3(msgid,all,timeout)

 File "/usr/lib/python2.6/site-packages/ldap/ldapobject.py", line 446, in result3
   ldap_result = self._ldap_call(self._l.result3,msgid,all,timeout)

 File "/usr/lib/python2.6/site-packages/ldap/ldapobject.py", line 96, in _ldap_call
   result = func(*args,**kwargs)

INVALID_CREDENTIALS: {'desc': 'Invalid credentials'}

Comment 8

[Austin King [:ozten]]

How many times did we try to repo and how many times did it fail?

I just tried to repo once and it WFM on mozillians-dev.

Comment 9

[Matt Brandt [:mbrandt]]

(In reply to Austin King [:ozten] from comment #8)
> How many times did we try to repo and how many times did it fail?
I'm not sure what has changed, this was at one time reproducible via the urls in comment 6 (100% of the time). What were the results of looking at the logs?

This WFM now as well.

Comment 10

[Matt Brandt [:mbrandt]]

Bumping to verified per comment 8, comment 9, and passage of time.