IDVault - Erstes anmelden schlägt fehl

[khing]

Hallo,

ich bin mal wieder am verzweifeln   und hoffe, dass ihr noch eine Idee habt. 

Seit einigen Monaten oder vielleicht auch schon seit der Umstellung auf Domino/Notes 9 (aktuell 9.01 FP4) werden die ID-Files von einem User beim Registrierungsprozess vom Notes auf den Client nicht mehr gezogen (muss man immer manuell angeben).
Irgendwie hab ich jedoch das Gefühl, dass das nur bei komplett neuen Usern ist. Bei bestehenden, die schon länger in der Vault sind, klappt es auf einem neuen PC den Notes-Client zu initialisieren, ohne die user.ID auf den Rechner ablegen zu müssen.

Auf jeden Fall war es heute wieder soweit, dass die ID über unseren Clusterserver 1 nicht gezogen werden konnte. Die IDVault selbst liegt auf dem Clusterserver 2. Der neue Notes-User wurde von der Kollegin bereits am Freitag angelegt. Das habe ich auch in der Vault-DB gesehen.
In den Logs finde ich immer wieder "Entry not found in index":
ServerLog:
25.07.2016 10:21:49   ID failed to authenticate in vault ''.  'XXXXX' (IP address xx.xx.xxx.xx:49820) made request.  Error: Entry not found in index
25.07.2016 10:23:29   ID failed to authenticate in vault ''.  'XXXXX' (IP address xx.xx.xxx.:49868) made request.  Error: Entry not found in index
25.07.2016 10:24:54   Unable to find ID for 'XXXXX' in vault ''.  Error: Entry not found in index

Bei Google und atnotes hab ich leider keinen Eintrag gefunden, der in diese Richtung passt. Die Richtlinien werden über unsere Organisation geregelt aber die hat er zum Zeitpunkt der Initialisierung des Notes-Clients natürlich noch nicht gehabt.

Woran könnte es liegen? Habt ihr eine Idee?

Vielen Dank

Gruß Kristian

[Tode]

Was hast Du denn schon geprüft?

IST die ID wirklich in der Vault?
Stimmt "XXXX" mit dem Namen überein, der in der Vault steht?
Was sagt die lokale "console.log" des Clients zu dem Vorgang?

[khing]

Ja, die ID XXXX ist seit dem 22.07.2016 14:00:32 Uhr in der Vault. Von dort habe ich sie auch schon erfolgreich extrahiert.

10:29:13 Uhr hatte ich die ID ins Filesystem auf den Client gelegt und von dort auslesen lassen. Dann hat alles normal funktioniert.

consol.log:

Code

[07F4:0002-0380] 25.07.2016 10:23:29   Neue Protokolldatei unter C:\Users\xxxx\AppData\Local\IBM\Notes\Data\log.nsf erstellt
[07F4:0002-0380] 25.07.2016 10:23:29   Server 'CL1/ORG' meldete folgende Probleme, die für das Fehlschlagen der Authentifizierung verantwortlich sind: Eintrag im Index nicht gefunden
[07F4:0002-0380] 25.07.2016 10:29:13,43 DeskClientOpenInt> Calling CreateProgramRCP pszRCPCmdLine[/authenticate ] bDeskProvisioningRestart [0]
[07F4:0002-0380] 25.07.2016 10:29:13,46 DeskClientOpenInt> DESK_CHECKNOTES2ALIVE_TIMER started
[07F4:0002-0380] 25.07.2016 10:29:13,46 DeskClientOpenInt> Executed CreateProgramRCP
[0B9C:0002-08C0] 25.07.2016 10:29:15   Dynamische Client-Konfiguration gestartet
[0B9C:0002-08C0] 25.07.2016 10:29:15   Dynamische Client-Konfiguration wird initialisiert
[0B9C:0002-08C0] 25.07.2016 10:29:15   Dynamische Client-Konfiguration aktualisiert Richtlinieninformationen
[0B9C:0002-08C0] 25.07.2016 10:29:16   Dynamische Client-Konfiguration: Richtlinienlesezeichen werden angewandt
[0B9C:0002-08C0] 25.07.2016 10:29:16   Dynamische Client-Konfiguration aktualisiert Informationen zu Arbeitsumgebung
[0B9C:0002-08C0] 25.07.2016 10:29:16   Dynamische Client-Konfiguration aktualisiert Informationen zu Arbeitsumgebung unter Verwendung von Richtlinie
[0B9C:0002-08C0] 25.07.2016 10:29:16,29 DynConfig> Found $DPLocked field: $DPLocked on policy note.  Copying to location doc
[0B9C:0002-08C0] 25.07.2016 10:29:16,29 DynConfig> Found $DPLocked field: $DPLockedUnstripped on policy note.  Copying to location doc
[0B9C:0002-08C0] 25.07.2016 10:29:16   Dynamische Client-Konfiguration aktualisiert Informationen zu Arbeitsumgebung unter Verwendung von Richtlinie
[0B9C:0002-08C0] 25.07.2016 10:29:16,29 DynConfig> Found $DPLocked field: $DPLocked on policy note.  Copying to location doc
[0B9C:0002-08C0] 25.07.2016 10:29:16,29 DynConfig> Found $DPLocked field: $DPLockedUnstripped on policy note.  Copying to location doc
[0B9C:0002-08C0] 25.07.2016 10:29:16   Dynamische Client-Konfiguration aktualisiert Informationen zu Arbeitsumgebung unter Verwendung von Richtlinie
[0B9C:0002-08C0] 25.07.2016 10:29:16,29 DynConfig> Found $DPLocked field: $DPLocked on policy note.  Copying to location doc
[0B9C:0002-08C0] 25.07.2016 10:29:16,29 DynConfig> Found $DPLocked field: $DPLockedUnstripped on policy note.  Copying to location doc
[0B9C:0002-08C0] 25.07.2016 10:29:16   Dynamische Client-Konfiguration heruntergefahren
[07F4:0002-0380] 25.07.2016 10:29:58,70 DeskClientOpenInt> Calling CreateProgramRCP pszRCPCmdLine[/authenticate  -RPARAMS  -data C:\Users\xxxx\AppData\Local\IBM\Notes\Data\workspace -vmargs] bDeskProvisioningRestart [1]
[07F4:0002-0380] 25.07.2016 10:29:58,70 DeskClientOpenInt> DESK_CHECKNOTES2ALIVE_TIMER started
[07F4:0002-0380] 25.07.2016 10:29:58,70 DeskClientOpenInt> Executed CreateProgramRCP
[07A8:0002-0D44] 25.07.2016 10:30:05,92 InitGlobalProcessInfo> PID [1960]!= [0]  
[0D00:0002-0434] 25.07.2016 10:30:27   Dynamische Client-Konfiguration gestartet
[0D00:0002-0434] 25.07.2016 10:30:27   Dynamische Client-Konfiguration wird initialisiert
[0D00:0002-0434] 25.07.2016 10:30:27   Dynamische Client-Konfiguration aktualisiert Richtlinieninformationen
[0D00:0002-0434] 25.07.2016 10:30:27   Dynamische Client-Konfiguration: Richtlinienlesezeichen werden angewandt
[0D00:0002-0434] 25.07.2016 10:30:27   Dynamische Client-Konfiguration aktualisiert Informationen zu Arbeitsumgebung
[0D00:0002-0434] 25.07.2016 10:30:27   Dynamische Client-Konfiguration aktualisiert Informationen zu Arbeitsumgebung unter Verwendung von Richtlinie
[0D00:0002-0434] 25.07.2016 10:30:27,83 DynConfig> Found $DPLocked field: $DPLocked on policy note.  Copying to location doc
[0D00:0002-0434] 25.07.2016 10:30:27,83 DynConfig> Found $DPLocked field: $DPLockedUnstripped on policy note.  Copying to location doc
[0D00:0002-0434] 25.07.2016 10:30:27   Dynamische Client-Konfiguration aktualisiert Informationen zu Arbeitsumgebung unter Verwendung von Richtlinie
[0D00:0002-0434] 25.07.2016 10:30:27,83 DynConfig> Found $DPLocked field: $DPLocked on policy note.  Copying to location doc
[0D00:0002-0434] 25.07.2016 10:30:27,83 DynConfig> Found $DPLocked field: $DPLockedUnstripped on policy note.  Copying to location doc
[0D00:0002-0434] 25.07.2016 10:30:27   Dynamische Client-Konfiguration aktualisiert Informationen zu Arbeitsumgebung unter Verwendung von Richtlinie
[0D00:0002-0434] 25.07.2016 10:30:27,83 DynConfig> Found $DPLocked field: $DPLocked on policy note.  Copying to location doc
[0D00:0002-0434] 25.07.2016 10:30:27,83 DynConfig> Found $DPLocked field: $DPLockedUnstripped on policy note.  Copying to location doc
[0D00:0002-0434] 25.07.2016 10:30:27   Dynamische Client-Konfiguration heruntergefahren
[0984:0006-05C8:housekeeping] 25.07.2016 10:30:28   Housekeeping: 0 Lesezeichenprotokoll-Datensätze werden gelöscht.
[0738:0004-06E8] 25.07.2016 10:30:29   bookmark.nsf (Lesezeichen (9)) wird komprimiert,  -C -ODS -Quiet -Client -UpdateIndexes
[0738:0004-06E8] 25.07.2016 10:30:29   Fehler beim Komprimieren von bookmark.nsf,  -C -ODS -Quiet -Client -UpdateIndexes: Die Datenbank wird bereits von Ihnen oder einem anderen Benutzer verwendet
[0738:0004-06E8] 25.07.2016 10:30:29   headline.nsf (Schlagzeilen) wird komprimiert,  -C -ODS -Quiet -Client -UpdateIndexes
[0738:0004-06E8] 25.07.2016 10:30:29   Fehler beim Komprimieren von headline.nsf,  -C -ODS -Quiet -Client -UpdateIndexes: Die Datenbank wird bereits von Ihnen oder einem anderen Benutzer verwendet
[0738:0004-06E8] 25.07.2016 10:30:29   localfeedcontent.nsf (Feed Content) wird komprimiert,  -C -ODS -Quiet -Client -UpdateIndexes
[0738:0004-06E8] 25.07.2016 10:30:29   localfeedcontent.nsf komprimiert, vergrößert um 128 KB (50%),  -C -ODS -Quiet -Client -UpdateIndexes
[0738:0004-06E8] 25.07.2016 10:30:29   log.nsf (Protokoll von Johannes CORNILS) wird komprimiert,  -C -ODS -Quiet -Client -UpdateIndexes
[0738:0004-06E8] 25.07.2016 10:30:29   log.nsf komprimiert, vergrößert um 144 KB (13%),  -C -ODS -Quiet -Client -UpdateIndexes
[0738:0004-06E8] 25.07.2016 10:30:29   names.nsf (Kontakte von CORNILS) wird komprimiert,  -C -ODS -Quiet -Client -UpdateIndexes
[0738:0004-06E8] 25.07.2016 10:30:29   Fehler beim Komprimieren von names.nsf,  -C -ODS -Quiet -Client -UpdateIndexes: Die Datenbank wird bereits von Ihnen oder einem anderen Benutzer verwendet
[0738:0004-06E8] 25.07.2016 10:30:29   Cache.NDK (Cache.NDK) wird komprimiert,  -C -ODS -Quiet -Client -UpdateIndexes
[0738:0004-06E8] 25.07.2016 10:30:29   Fehler beim Komprimieren von Cache.NDK,  -C -ODS -Quiet -Client -UpdateIndexes: Die Datenbank wird bereits von Ihnen oder einem anderen Benutzer verwendet
[0738:0004-06E8] 25.07.2016 10:30:29   Fehler beim Öffnen von Mail.box: Datei nicht vorhanden
[0738:0004-06E8] 25.07.2016 10:30:29   Fehler beim Komprimieren von Mail.box,  -C -ODS -Quiet -Client -UpdateIndexes: Datei nicht vorhanden
[0738:0002-0580] 25.07.2016 10:30:31   Datenbank-Komprimierungsprozess wurde heruntergefahren 
[0738:0002-0580] 25.07.2016 10:30:31   6 databases had an older NSF ODS version.   2 of those databases were successfully upgraded to a later NSF ODS. 
[0564:0002-0DA4] 25.07.2016 10:32:27   Dynamische Client-Konfiguration gestartet
[0564:0002-0DA4] 25.07.2016 10:32:27   Dynamische Client-Konfiguration wird initialisiert
[0564:0002-0DA4] 25.07.2016 10:32:27   Dynamische Client-Konfiguration heruntergefahren

[Tode]

Dann setze doch mal bitte die Debugging- Parameter an Client und Server laut diesem Artikel... Vielleicht steht da ja was aufschlussreicheres drin...

[khing]

ahhh, super. Da leg ich mir morgen mal ein Testuser an und probier die Parameter aus. Melde mich wieder. Vielen Dank erstmal 

[khing]

Vorführeffekt... Mit einem Testuser hat es natürlich bestens geklappt 

Komisch ist eben nur, dass der erste Clusterserver die Fehlermeldung bei dem ausgegeben hat, wo es nicht funktionierte. Aktuell gibt das Logging der zweite Clusterserver aus, wo die IDVault liegt - wie es sein sollte.

Ich werde mal einen Langzeittest durchführen und die ini-Parameter aktiv lassen...

Gruß Kristian

[MacSpudik]

Mal in eine andere Richtung gedacht:
Ist der Homeserver vom ersten User (der nicht funktionierte) Clusterserver1 und dieser hat keine Replik (via Vaultoptionen eingerichtet) des Vault von Server 2?
Ist der Homeserver vom zweiten User (Testuser der funktionierte) Clusterserver2 (wo der Vault liegt).
Und nun der sprigende Punkt: Bekommt der PC bei der Einrichtung des Notesclients eine Verbindung zum Clusterserver2, sprich zum Vault?

Hintergrund: Wir hatten mal die unschöne Konstellation, dass aufgrund Firewall und Co. gewisse Außenstandorts PCs den Vaultserver nicht erreichen konnten. Der Effekt war der gleiche. Abhilfe schaffte zunächst eine Replik des Vault über die Vaulteinstellungen auf dem Homeserver und abschließend natürlich die Änderung an der Firewall.

Grüße von
Sebastian

[khing]

Der Homeserver ist für alle der Clusterserver 1. Das passt so m. E.

Mit dem Zugriff bin ich mir nicht ganz sicher. Wir haben den Clusterserver1 als Hauptserver und nur ein Failover auf den zweiten Server. In die Richtug der Erreichbarkeit habe ich auch schon gedacht. Bei der Einrichtung hatte ich den Clusterserver2 auch probiert aber das hatte nicht geklappt. Das habe ich dann nicht weiter verfolgt. Werde ich aber auch auf jeden Fall testen.

Der Nutzer der ging, der war in der AD über mich angemeldet. Ich sollte vielleicht einen Test mit einem anderen AD-User machen. Die Firewall sollte es "eigentlich" nicht sein, da wir uns in einem LAN befinden (ok, vielleicht andere vlans). Ganz ausschließen tue ich es aber nicht.

Ich werde dieser Idee auf jeden Fall auch nachgehen. Vielen Dank 

Gruß Kristian

[khing]

Und da ist die Lösung...

Die User die nicht funktionierten waren in einer anderen AD-Gruppe, die intern keine Rechte auf den Notes-Server haben. Das normale Notes-Protokoll funktioniert jedoch.

Habt alle vielen Dank 

Gruß Kristian

[MacSpudik]

Danke auch fürs Feedback.
Sowas hatte ich auch noch nicht