Udn replication error

Hi!

Something is wrong with the notifier on your DC master. It seems to die directly after starting it.
Can you please send some more last lines of the notifier.log?

Sönke

here it is:

Summary
2019-02-27 14:39:11,604:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:39:22,945:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:39:34,567:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:39:45,990:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:39:55,441:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:40:06,970:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:40:18,253:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:40:28,884:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:40:40,446:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:40:51,647:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:41:02,961:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:41:14,101:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:41:25,747:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:41:37,029:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:41:48,602:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:42:00,114:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack
2019-02-27 14:42:11,112:CRITICAL:ldap_search(reqSession=13907,cn=translog): No such object
Traceback (most recent call last):
  File "/usr/share/univention-directory-notifier/univention-translog", line 713, in <module>
    exit(main())
  File "/usr/share/univention-directory-notifier/univention-translog", line 374, in main
    return opt.func(opt) or 0
  File "/usr/share/univention-directory-notifier/univention-translog", line 458, in import_all
    for rec in chain(lead, translog):
  File "/usr/share/univention-directory-notifier/univention-translog", line 306, in __iter__
    rec = self.parse_line(line)
  File "/usr/share/univention-directory-notifier/univention-translog", line 234, in parse_line
    dn, command = rest.rsplit(' ', 1)
ValueError: need more than 1 value to unpack

thx
bernhard

Hi!

I suspect, that your transaction file /var/lib/univention-ldap/notify/transaction is somewhere broken around transaction id 13907. The file consists of 3 columns: transaction id, DN, change type (a, m or d). Empty lines are not allowed and all columns have to exist.
Please have a look at it and correct it if possible. If the error is fixed, try to restart the UDN.

Best regards,

Sönke

Good Morning Sönke,

again I ran the python script for finding enumeration mistakes, but it’s ok (no output).
But I can see some lines with blanks wich are not used as column-separator but are due to container names with a blank in it. For UCS also seems to use cns incuding blanks, this should not cause my probs. Beside this I can’t see anything wrong.

Hi!

I’m pretty sure, that your transaction file is broken. There is one line, that contains only 1 space character.
You can send me your transaction file via direct message if you want.

Sönke

Hi!

Thanks for your transaction file. The last column is missing for transaction IDs 11709 and 11710.
Please add a m to both lines. Then, the traceback should be gone.

Best regards,

Sönke

Hi,
thank you very much.
I added the missing m now and restarted the service
but the warning “problem with udn-replication” regrettably still persists.

Edit: No, wait, I have to set the counts of listener / notifer to be equal

Okay, univention-directory-listener-ctrl status
now returns

Current Notifier ID on "hipsctrl-1.hipsy.intranet"
 14365

Last Notifier ID processed by local Listener:
 13205

Last transaction processed:
 14365 zoneName=hipsy.intranet,cn=dns,dc=hipsy,dc=intranet m

/var/lib/univention-ldap/last_id is set to the last number.
So, how can the listener be persuaded to process the last thousand lines?

Best regards

Bernhard

Hi!

Simply restart the listener and check the listerner.log.

Sönke

Hm, service univention-directory-listener restart
doesn’t solve the discrepancy:

v# univention-directory-listener-ctrl status
Listener status:
 finish: univention-directory-listener: (pid 14785) 6s, normally down

Current Notifier ID on "hipsctrl-1.hipsy.intranet"
 14365

Last Notifier ID processed by local Listener:
 13205

Last transaction processed:
 14365 zoneName=hipsy.intranet,cn=dns,dc=hipsy,dc=intranet m

Your UDN seems to run. Have a look at /var/log/univention/listener.log why the UDL is not catching up the missing transactions.

Hi,
that is what i see:

UNIVENTION_DEBUG_BEGIN  : uldap.__open host=hipsctrl-1.hipsy.intranet port=7389 base=dc=hipsy,dc=intranet
01.03.19 12:30:14.196  LDAP        ( INFO    ) : establishing new connection with retry_max=11
01.03.19 12:30:14.203  LDAP        ( INFO    ) : bind binddn=cn=admin,dc=hipsy,dc=intranet
UNIVENTION_DEBUG_END    : uldap.__open host=hipsctrl-1.hipsy.intranet port=7389 base=dc=hipsy,dc=intranet
01.03.19 12:30:14.204  LISTENER    ( INFO    ) : Last Notifier ID: 13205
01.03.19 12:30:14.204  LISTENER    ( INFO    ) : notifier returned = id:14365	dn:<LDAP>	cmd:*
01.03.19 12:30:14.205  LISTENER    ( ERROR   ) : notifier.c:129:notifier_wait_id_result LDAP failed No such object (32): id:13206
01.03.19 12:30:14.205  LISTENER    ( ERROR   ) : listener: 32
01.03.19 12:30:14.205  LDAP        ( INFO    ) : closing connection

:pouting_man:

Hi,
things are getting more confusing (for me).
The transaction file now shows a jump over some hundred lines (13907 followed 14364), while the notifier-id sticks with 13205. I don’t know how to handle that.

Hi!

The thread (Transaction file checking) you mentioned in your first post, contains a section Discontinuity in the numbering can be refilled by editing the transaction file manually, that may help you.

Best regards,

Sönke

Hello,
I know that article. I had these problems 8 weeks ago and solved them following the methods explained there. But now - as described above - it does not work this way this time and I don’t wish to do that every couple of weeks. Control over the system is lost and I decided to rebuild the domain from scratch.

Nevertheless I’m particularly interested in the question how to avoid a udn-error in the future for it causing me serious trouble.

Regards

Bernhard

Mastodon