Attachment 10419 Details for Bug 51647 – connector.log

connector.log

connect_log_4.log (text/x-log), 16.98 KB, created by stefan.bauer on 2020-07-08 14:42:36 CEST

(hide)

Description:

Filename:

MIME Type:

Creator: stefan.bauer

Created: 2020-07-08 14:42:36 CEST

Size: 16.98 KB

patch

obsolete

>08.07.2020 14:19:06.167 LDAP        (INFO   ): The following attributes have been changed: ['mail', 'whenChanged', 'uSNChanged']
>08.07.2020 14:19:06.167 LDAP        (INFO   ): _ignore_object: Do not ignore uid=mustermann,cn=users,dc=CUSTOMER,dc=local (key: user)
>08.07.2020 14:19:06.168 LDAP        (INFO   ): get_ucs_object: object found: uid=mustermann,cn=users,dc=CUSTOMER,dc=local
>08.07.2020 14:19:06.168 LDAP        (PROCESS): sync to ucs:   [          user] [    modify] uid=mustermann,cn=users,dc=CUSTOMER,dc=local
>08.07.2020 14:19:06.168 LDAP        (INFO   ): sync_to_ucs: set position to cn=users,dc=CUSTOMER,dc=local
>08.07.2020 14:19:06.168 LDAP        (INFO   ): sync_to_ucs: using existing target object type: users/user
>08.07.2020 14:19:06.169 LDAP        (INFO   ): __set_values: object: {'dn': u'uid=mustermann,cn=users,dc=CUSTOMER,dc=local', 'attributes': {'primaryGroupID': [u'513'], 'logonCount': [u'0'], 'cn': [u'Max Mustermann'], 'countryCode': [u'0'], 'objectClass': [u'top', u'person', u'organizationalPerson', u'user'], 'userPrincipalName': [u'mustermann@CUSTOMER.local'], 'instanceType': [u'4'], 'uid': [u'mustermann'], 'distinguishedName': [u'CN=Max Mustermann,CN=Users,DC=CUSTOMER,DC=local'], 'dSCorePropagationData': [u'20200708081226.0Z', u'16010101000000.0Z'], 'objectSid': ['S-1-5-21-909422031-1467594303-3191779460-1604'], 'whenCreated': [u'20200708081226.0Z'], 'uSNCreated': [u'1627138'], 'mail': [u'TEST@customerdomain.de'], 'badPasswordTime': [u'0'], 'pwdLastSet': [u'132386695462176530'], 'sAMAccountName': [u'mustermann'], 'objectCategory': [u'CN=Person,CN=Schema,CN=Configuration,DC=CUSTOMER,DC=local'], 'objectGUID': ['\xd5\x16\x10e\x9c\xce\x02F\xad\x88\x84\xfcj\x84+\xdf'], 'whenChanged': [u'20200708121903.0Z'], 'badPwdCount': [u'0'], 'accountExpires': [u'9223372036854775807'], 'mailPrimaryAddress': [u'TEST@customerdomain.de'], 'sAMAccountType': [u'805306368'], 'displayName': [u'Max Mustermann'], 'name': [u'Max Mustermann'], 'codePage': [u'0'], 'userAccountControl': [u'66048'], 'lastLogon': [u'0'], 'uSNChanged': [u'1675250'], 'sn': [u'Musterman'], 'givenName': [u'Max'], 'lastLogoff': [u'0']}, 'changed_attributes': ['mail', 'whenChanged', 'uSNChanged'], 'modtype': 'modify'}
>08.07.2020 14:19:06.170 LDAP        (INFO   ): __set_values: Skip: givenName
>08.07.2020 14:19:06.170 LDAP        (INFO   ): __set_values: Skip: sAMAccountName
>08.07.2020 14:19:06.170 LDAP        (INFO   ): __set_values: Skip: sn
>08.07.2020 14:19:06.170 LDAP        (INFO   ): __set_values: mapping for attribute: <univention.connector.attribute instance at 0x7f75b909e368>
>08.07.2020 14:19:06.170 LDAP        (INFO   ): __set_values: Skip: telephoneNumber
>08.07.2020 14:19:06.170 LDAP        (INFO   ): __set_values: mapping for attribute: <univention.connector.attribute instance at 0x7f75b909e200>
>08.07.2020 14:19:06.170 LDAP        (INFO   ): __set_values: Skip: l
>08.07.2020 14:19:06.170 LDAP        (INFO   ): __set_values: mapping for attribute: <univention.connector.attribute instance at 0x7f75b909e1b8>
>08.07.2020 14:19:06.170 LDAP        (INFO   ): __set_values: Skip: streetAddress
>08.07.2020 14:19:06.171 LDAP        (INFO   ): __set_values: mapping for attribute: <univention.connector.attribute instance at 0x7f75b909bf38>
>08.07.2020 14:19:06.171 LDAP        (INFO   ): __set_values: Skip: company
>08.07.2020 14:19:06.171 LDAP        (INFO   ): __set_values: mapping for attribute: <univention.connector.attribute instance at 0x7f75b909e170>
>08.07.2020 14:19:06.171 LDAP        (INFO   ): __set_values: Skip: description
>08.07.2020 14:19:06.171 LDAP        (INFO   ): __set_values: mapping for attribute: <univention.connector.attribute instance at 0x7f75b909e3f8>
>08.07.2020 14:19:06.171 LDAP        (INFO   ): __set_values: Skip: mobile
>08.07.2020 14:19:06.171 LDAP        (INFO   ): __set_values: mapping for attribute: <univention.connector.attribute instance at 0x7f75b909e248>
>08.07.2020 14:19:06.171 LDAP        (INFO   ): __set_values: Skip: postalCode
>08.07.2020 14:19:06.171 LDAP        (INFO   ): __set_values: mapping for attribute: <univention.connector.attribute instance at 0x7f75b909e320>
>08.07.2020 14:19:06.171 LDAP        (INFO   ): __set_values: Skip: scriptPath
>08.07.2020 14:19:06.172 LDAP        (INFO   ): __set_values: mapping for attribute: <univention.connector.attribute instance at 0x7f75b909e290>
>08.07.2020 14:19:06.172 LDAP        (INFO   ): __set_values: Skip: userWorkstations
>08.07.2020 14:19:06.172 LDAP        (INFO   ): __set_values: mapping for attribute: <univention.connector.attribute instance at 0x7f75b909e2d8>
>08.07.2020 14:19:06.172 LDAP        (INFO   ): __set_values: Skip: profilePath
>08.07.2020 14:19:06.172 LDAP        (INFO   ): __set_values: mapping for attribute: <univention.connector.attribute instance at 0x7f75b909e440>
>08.07.2020 14:19:06.172 LDAP        (INFO   ): __set_values: Skip: pager
>08.07.2020 14:19:06.172 LDAP        (INFO   ): __set_values: mapping for attribute: <univention.connector.attribute instance at 0x7f75b909e488>
>08.07.2020 14:19:06.172 LDAP        (INFO   ): __set_values: Skip: displayName
>08.07.2020 14:19:06.172 LDAP        (INFO   ): __set_values: mapping for attribute: <univention.connector.attribute instance at 0x7f75b909e3b0>
>08.07.2020 14:19:06.173 LDAP        (INFO   ): __set_values: Skip: homePhone
>08.07.2020 14:19:06.173 LDAP        (INFO   ): __set_values: mapping for attribute: <univention.connector.attribute instance at 0x7f75b909e098>
>08.07.2020 14:19:06.173 LDAP        (INFO   ): __set_values: Skip: proxyAddresses
>08.07.2020 14:19:06.176 LDAP        (INFO   ): ADCache: Execute SQL command: 'SELECT id FROM GUIDS WHERE guid=?;', '('651016d5-ce9c-4602-ad88-84fc6a842bdf',)'
>08.07.2020 14:19:06.176 LDAP        (INFO   ): ADCache: Return SQL result: '[(9,)]'
>08.07.2020 14:19:06.176 LDAP        (INFO   ): ADCache: Execute SQL command: 'SELECT id FROM GUIDS WHERE guid=?;', '('651016d5-ce9c-4602-ad88-84fc6a842bdf',)'
>08.07.2020 14:19:06.176 LDAP        (INFO   ): ADCache: Return SQL result: '[(9,)]'
>08.07.2020 14:19:06.176 LDAP        (INFO   ): ADCache: Execute SQL command: 'SELECT id FROM GUIDS WHERE guid=?;', '('651016d5-ce9c-4602-ad88-84fc6a842bdf',)'
>08.07.2020 14:19:06.176 LDAP        (INFO   ): ADCache: Return SQL result: '[(9,)]'
>08.07.2020 14:19:06.176 LDAP        (INFO   ): ADCache: Execute SQL command: 'SELECT ATTRIBUTES.attribute,data.value from data 				inner join ATTRIBUTES ON data.attribute_id=attributes.id where guid_id = ?;', '('9',)'
>08.07.2020 14:19:06.177 LDAP        (INFO   ): ADCache: Return SQL result: '[(u'primaryGroupID', u'NTEz'), (u'logonCount', u'MA=='), (u'cn', u'TWF4IE11c3Rlcm1hbm4='), (u'countryCode', u'MA=='), (u'dSCorePropagationData', u'MjAyMDA3MDgwODEyMjYuMFo='), (u'dSCorePropagationData', u'MTYwMTAxMDEwMDAwMDAuMFo='), (u'objectClass', u'dG9w'), (u'objectClass', u'cGVyc29u'), (u'objectClass', u'b3JnYW5pemF0aW9uYWxQZXJzb24='), (u'objectClass', u'dXNlcg=='), (u'instanceType', u'NA=='), (u'distinguishedName', u'Q049TWF4IE11c3Rlcm1hbm4sQ049VXNlcnMsREM9QkVORURJS1QsREM9bG9jYWw='), (u'sAMAccountType', u'ODA1MzA2MzY4'), (u'objectSid', u'Uy0xLTUtMjEtOTA5NDIyMDMxLTE0Njc1OTQzMDMtMzE5MTc3OTQ2MC0xNjA0'), (u'whenCreated', u'MjAyMDA3MDgwODEyMjYuMFo='), (u'uSNCreated', u'MTYyNzEzOA=='), (u'badPasswordTime', u'MA=='), (u'pwdLastSet', u'MTMyMzg2Njk1NDYyMTc2NTMw'), (u'sAMAccountName', u'bXVzdGVybWFubg=='), (u'objectCategory', u'Q049UGVyc29uLENOPVNjaGVtYSxDTj1Db25maWd1cmF0aW9uLERDPUJFTkVESUtULERDPWxvY2Fs'), (u'objectGUID', u'1RYQZZzOAkatiIT8aoQr3w=='), (u'whenChanged', u'MjAyMDA3MDgxMjE2MzcuMFo='), (u'badPwdCount', u'MA=='), (u'accountExpires', u'OTIyMzM3MjAzNjg1NDc3NTgwNw=='), (u'name', u'TWF4IE11c3Rlcm1hbm4='), (u'codePage', u'MA=='), (u'userAccountControl', u'NjYwNDg='), (u'lastLogon', u'MA=='), (u'uSNChanged', u'MTY3NDc1Mw=='), (u'lastLogoff', u'MA=='), (u'userPrincipalName', u'bXVzdGVybWFubkBCRU5FRElLVC5sb2NhbA=='), (u'mail', u'base64-encoded-changed@customerdomain.de'), (u'displayName', u'TWF4IE11c3Rlcm1hbm4='), (u'sn', u'TXVzdGVybWFu'), (u'givenName', u'TWF4')]'
>08.07.2020 14:19:06.177 LDAP        (INFO   ): ADCache: Execute SQL command: 'SELECT id FROM ATTRIBUTES WHERE attribute=?;', '('mail',)'
>08.07.2020 14:19:06.177 LDAP        (INFO   ): ADCache: Return SQL result: '[(40,)]'
>08.07.2020 14:19:06.177 LDAP        (INFO   ): ADCache: Execute SQL command: 'SELECT id FROM ATTRIBUTES WHERE attribute=?;', '('uSNChanged',)'
>08.07.2020 14:19:06.177 LDAP        (INFO   ): ADCache: Return SQL result: '[(32,)]'
>08.07.2020 14:19:06.178 LDAP        (INFO   ): ADCache: Execute SQL command: 'SELECT id FROM ATTRIBUTES WHERE attribute=?;', '('whenChanged',)'
>08.07.2020 14:19:06.178 LDAP        (INFO   ): ADCache: Return SQL result: '[(23,)]'
>08.07.2020 14:19:06.178 LDAP        (INFO   ): ADCache: Execute SQL command: 'DELETE FROM data WHERE data.id IN (							SELECT data.id FROM DATA INNER JOIN ATTRIBUTES ON data.attribute_id=attributes.id 							where attributes.id=? and guid_id = ? and value = ? 						);', '('40', '9', 'base64-encoded-changed@customerdomain.de')'
>08.07.2020 14:19:06.178 LDAP        (INFO   ): ADCache: Execute SQL command: 'INSERT INTO DATA(guid_id,attribute_id,value) VALUES(?,?,?);', '('9', '40', 'base64-string-of-TEST@customerdomain.de')'
>08.07.2020 14:19:06.179 LDAP        (INFO   ): ADCache: Execute SQL command: 'DELETE FROM data WHERE data.id IN (							SELECT data.id FROM DATA INNER JOIN ATTRIBUTES ON data.attribute_id=attributes.id 							where attributes.id=? and guid_id = ? and value = ? 						);', '('32', '9', 'MTY3NDc1Mw==')'
>08.07.2020 14:19:06.179 LDAP        (INFO   ): ADCache: Execute SQL command: 'INSERT INTO DATA(guid_id,attribute_id,value) VALUES(?,?,?);', '('9', '32', 'MTY3NTI1MA==')'
>08.07.2020 14:19:06.179 LDAP        (INFO   ): ADCache: Execute SQL command: 'DELETE FROM data WHERE data.id IN (							SELECT data.id FROM DATA INNER JOIN ATTRIBUTES ON data.attribute_id=attributes.id 							where attributes.id=? and guid_id = ? and value = ? 						);', '('23', '9', 'MjAyMDA3MDgxMjE2MzcuMFo=')'
>08.07.2020 14:19:06.179 LDAP        (INFO   ): ADCache: Execute SQL command: 'INSERT INTO DATA(guid_id,attribute_id,value) VALUES(?,?,?);', '('9', '23', 'MjAyMDA3MDgxMjE5MDMuMFo=')'
>08.07.2020 14:19:06.180 LDAP        (INFO   ): Call post_ucs_modify_functions: <function password_sync_kinit at 0x7f75b90918c0>
>08.07.2020 14:19:06.180 LDAP        (INFO   ): _object_mapping: map with key user and type ucs
>08.07.2020 14:19:06.180 LDAP        (INFO   ): _dn_type ucs
>08.07.2020 14:19:06.181 LDAP        (INFO   ): samaccount_dn_mapping: check newdn for key dn:
>08.07.2020 14:19:06.181 LDAP        (INFO   ): get_object: got object: cn=max mustermann,cn=users,DC=CUSTOMER,DC=local
>08.07.2020 14:19:06.182 LDAP        (INFO   ): encode_ad_object: attrib objectGUID ignored during encoding
>08.07.2020 14:19:06.182 LDAP        (INFO   ): samaccount_dn_mapping: premapped AD object found
>08.07.2020 14:19:06.182 LDAP        (INFO   ): samaccount_dn_mapping: check newdn for key olddn:
>08.07.2020 14:19:06.182 LDAP        (INFO   ): Call post_ucs_modify_functions: <function password_sync_kinit at 0x7f75b90918c0> (done)
>08.07.2020 14:19:06.182 LDAP        (INFO   ): Call post_ucs_modify_functions: <function set_univentionObjectFlag_to_synced at 0x7f75d5d269b0>
>08.07.2020 14:19:06.182 LDAP        (INFO   ): _object_mapping: map with key user and type ucs
>08.07.2020 14:19:06.183 LDAP        (INFO   ): _dn_type ucs
>08.07.2020 14:19:06.183 LDAP        (INFO   ): samaccount_dn_mapping: check newdn for key dn:
>08.07.2020 14:19:06.184 LDAP        (INFO   ): get_object: got object: cn=max mustermann,cn=users,DC=CUSTOMER,DC=local
>08.07.2020 14:19:06.184 LDAP        (INFO   ): encode_ad_object: attrib objectGUID ignored during encoding
>08.07.2020 14:19:06.184 LDAP        (INFO   ): samaccount_dn_mapping: premapped AD object found
>08.07.2020 14:19:06.184 LDAP        (INFO   ): samaccount_dn_mapping: check newdn for key olddn:
>08.07.2020 14:19:06.184 LDAP        (INFO   ): Call post_ucs_modify_functions: <function set_univentionObjectFlag_to_synced at 0x7f75d5d269b0> (done)
>08.07.2020 14:19:06.184 LDAP        (INFO   ): Call post_ucs_modify_functions: <function primary_group_sync_to_ucs at 0x7f75d5d26c80>
>08.07.2020 14:19:06.185 LDAP        (INFO   ): _object_mapping: map with key user and type ucs
>08.07.2020 14:19:06.185 LDAP        (INFO   ): _dn_type ucs
>08.07.2020 14:19:06.185 LDAP        (INFO   ): samaccount_dn_mapping: check newdn for key dn:
>08.07.2020 14:19:06.186 LDAP        (INFO   ): get_object: got object: cn=max mustermann,cn=users,DC=CUSTOMER,DC=local
>08.07.2020 14:19:06.186 LDAP        (INFO   ): encode_ad_object: attrib objectGUID ignored during encoding
>08.07.2020 14:19:06.186 LDAP        (INFO   ): samaccount_dn_mapping: premapped AD object found
>08.07.2020 14:19:06.186 LDAP        (INFO   ): samaccount_dn_mapping: check newdn for key olddn:
>08.07.2020 14:19:06.187 LDAP        (INFO   ): get_object: got object: cn=max mustermann,cn=users,DC=CUSTOMER,DC=local
>08.07.2020 14:19:06.187 LDAP        (INFO   ): encode_ad_object: attrib objectGUID ignored during encoding
>08.07.2020 14:19:06.187 LDAP        (INFO   ): primary_group_sync_to_ucs: AD rid: 513
>08.07.2020 14:19:06.187 LDAP        (INFO   ): Search AD with filter: (objectSid=S-1-5-21-909422031-1467594303-3191779460-513)
>08.07.2020 14:19:06.188 LDAP        (INFO   ): encode_ad_object: attrib objectGUID ignored during encoding
>08.07.2020 14:19:06.188 LDAP        (INFO   ): _object_mapping: map with key group and type con
>08.07.2020 14:19:06.188 LDAP        (INFO   ): _dn_type con
>08.07.2020 14:19:06.188 LDAP        (INFO   ): samaccount_dn_mapping: check newdn for key dn:
>08.07.2020 14:19:06.188 LDAP        (INFO   ): samaccount_dn_mapping: premapped UCS object found
>08.07.2020 14:19:06.189 LDAP        (INFO   ): samaccount_dn_mapping: check newdn for key olddn:
>08.07.2020 14:19:06.189 LDAP        (INFO   ): primary_group_sync_to_ucs: ucs-group: cn=domÃ¤nen-benutzer,cn=groups,dc=CUSTOMER,dc=local
>08.07.2020 14:19:06.190 LDAP        (INFO   ): primary_group_sync_to_ucs: change of primary Group in ucs not needed
>08.07.2020 14:19:06.190 LDAP        (INFO   ): Call post_ucs_modify_functions: <function primary_group_sync_to_ucs at 0x7f75d5d26c80> (done)
>08.07.2020 14:19:06.190 LDAP        (INFO   ): Call post_ucs_modify_functions: <function object_memberships_sync_to_ucs at 0x7f75d5d26b90>
>08.07.2020 14:19:06.190 LDAP        (INFO   ): _object_mapping: map with key user and type con
>08.07.2020 14:19:06.190 LDAP        (INFO   ): _dn_type con
>08.07.2020 14:19:06.190 LDAP        (INFO   ): samaccount_dn_mapping: check newdn for key dn:
>08.07.2020 14:19:06.191 LDAP        (INFO   ): samaccount_dn_mapping: not premapped (in first instance)
>08.07.2020 14:19:06.191 LDAP        (INFO   ): samaccount_dn_mapping: got an AD-Object
>08.07.2020 14:19:06.191 LDAP        (INFO   ): samaccount_dn_mapping: samaccountname is:mustermann
>08.07.2020 14:19:06.191 LDAP        (INFO   ): samaccount_dn_mapping: newdn is ucsdn
>08.07.2020 14:19:06.191 LDAP        (INFO   ): samaccount_dn_mapping: newdn for key dn:
>08.07.2020 14:19:06.191 LDAP        (INFO   ): samaccount_dn_mapping: olddn: uid=mustermann,cn=users,dc=CUSTOMER,dc=local
>08.07.2020 14:19:06.191 LDAP        (INFO   ): samaccount_dn_mapping: newdn: uid=mustermann,cn=users,dc=CUSTOMER,dc=local
>08.07.2020 14:19:06.191 LDAP        (INFO   ): samaccount_dn_mapping: check newdn for key olddn:
>08.07.2020 14:19:06.192 LDAP        (INFO   ): Call post_ucs_modify_functions: <function object_memberships_sync_to_ucs at 0x7f75d5d26b90> (done)
>08.07.2020 14:19:06.192 LDAP        (INFO   ): Call post_ucs_modify_functions: <function disable_user_to_ucs at 0x7f75d5d26de8>
>08.07.2020 14:19:06.192 LDAP        (INFO   ): _object_mapping: map with key user and type ucs
>08.07.2020 14:19:06.192 LDAP        (INFO   ): _dn_type ucs
>08.07.2020 14:19:06.192 LDAP        (INFO   ): samaccount_dn_mapping: check newdn for key dn:
>08.07.2020 14:19:06.193 LDAP        (INFO   ): get_object: got object: cn=max mustermann,cn=users,DC=CUSTOMER,DC=local
>08.07.2020 14:19:06.193 LDAP        (INFO   ): encode_ad_object: attrib objectGUID ignored during encoding
>08.07.2020 14:19:06.193 LDAP        (INFO   ): samaccount_dn_mapping: premapped AD object found
>08.07.2020 14:19:06.193 LDAP        (INFO   ): samaccount_dn_mapping: check newdn for key olddn:
>08.07.2020 14:19:06.194 LDAP        (INFO   ): get_object: got object: cn=max mustermann,cn=users,DC=CUSTOMER,DC=local
>08.07.2020 14:19:06.194 LDAP        (INFO   ): encode_ad_object: attrib objectGUID ignored during encoding
>08.07.2020 14:19:06.195 LDAP        (INFO   ): Call post_ucs_modify_functions: <function disable_user_to_ucs at 0x7f75d5d26de8> (done)
>08.07.2020 14:19:06.196 LDAP        (INFO   ): Return  result for DN (uid=mustermann,cn=users,dc=CUSTOMER,dc=local)
>08.07.2020 14:19:06.197 LDAP        (INFO   ): _set_lastUSN: new lastUSN is: 1675250
>08.07.2020 14:19:07.199 LDAP        (INFO   ): Search AD with filter: (uSNCreated>=1675251)
>08.07.2020 14:19:07.200 LDAP        (INFO   ): Search AD with filter: (uSNChanged>=1675251)
>08.07.2020 14:19:12.206 LDAP        (INFO   ): Search AD with filter: (uSNCreated>=1675251)
>08.07.2020 14:19:12.206 LDAP        (INFO   ): Search AD with filter: (uSNChanged>=1675251)
>

Actions: View

Attachments on bug 51647: 10419 | 10427