Creating of SSO token may fail for many reasons.
For startup try the following:
* Check whether you have assigned IM & Presence to the domain/org & to users.
* IM was configured to use AM Policy & SSO?
If the above options doesn't work then please provide more details like:
* IM version?
* Am version etc & some more debug logs.
I have the IM 7.1 from the 2005q4 suite of jes with the patches installed.
I have turned on the debug to the fatal level in the iim.conf but just get the same error message all the time. I have checked and the users and they all have the presence services and the im service in their profiles what other logs can I check to see what is happening.
Carinus
I have posted it to your gmail account but I posting here as well just in case I got a crash on my mail client when I sent it. I got a error message for the log4j when I made the change in the conf file.
log4j:WARN No appenders could be found for logger (nbcollab.nio).
log4j:WARN Please initialize the log4j system properly.
xmppd.log
[19 Jul 2006 10:52:17,285] INFO xmppd [main] Starting XMPP Server: Version 7.1
[19 Jul 2006 10:52:20,964] INFO xmppd [main] RealmClassName com.iplanet.im.server.IdentityRealm
[19 Jul 2006 10:52:20,964] DEBUG xmppd [main] Creating realm for first time com.iplanet.im.server.IdentityRealm
[19 Jul 2006 10:52:24,293] DEBUG xmppd [main] [LDAPPool] binding as cn=dsameuser,ou=DSAME Users,o=msp
[19 Jul 2006 10:52:24,294] DEBUG xmppd [main] [LDAPPool] creating a new context
[19 Jul 2006 10:52:28,944] DEBUG xmppd [main] [FileGroupChatStorage] root dir = /var/opt/sun/im/default/db/pubsub
[19 Jul 2006 10:52:28,944] DEBUG xmppd [main] [FileGroupChatStorage] loaded /var/opt/sun/im/default/db/pubsub
[19 Jul 2006 10:52:37,322] DEBUG xmppd [main] [Identity] open called, getting user attributes names.
[19 Jul 2006 10:52:37,466] DEBUG xmppd [main] [Identity] sunIM user service attributes: [sunIMUserProperties, sunIMUserRoster, sunIMUserConferenceRoster, sunIMUserNewsRoster, sunIMUserPrivateSettings]
[19 Jul 2006 10:52:37,467] DEBUG xmppd [main] [Identity] sunIM dynamic service attributes: [sunIMProperties, sunIMPrivateSettings, sunIMNewsRoster, sunIMRoster, sunIMConferenceRoster]
[19 Jul 2006 10:52:37,467] DEBUG xmppd [main] [Identity] sunIM policy attributes: [sunIMAllowPollingAccess, sunIMAllowContactListManage, sunIMAllowAlertsAccess, sunIMAllowPollingSend, sunIMAllowChatInvite, sunIMAllowFileTransfer, sunIMAllowChat, sunIMAllowForumManage, sunIMAllowNewsManage, sunIMAllowNewsAccess, sunIMAllowForumAccess, sunIMAllowForumModerate, sunIMAllowUserSettings, sunIMAllowAlertsSend]
[19 Jul 2006 10:52:37,681] DEBUG xmppd [main] [Identity] sunPresence user service attributes: [sunPresenceEntityAccessDenied, sunPresenceEntityDevices, sunPresenceEntityDefaultAccess, sunPresenceEntityAccessPermitted, sunPresenceUserPrivacy]
[19 Jul 2006 10:52:37,682] DEBUG xmppd [main] [Identity] sunPresence dynamic service attributes: [sunPresenceAccessDenied, sunPresenceDefaultAccess, sunPresenceDevices, sunPresenceAccessPermitted, sunPresencePrivacy]
[19 Jul 2006 10:52:37,682] DEBUG xmppd [main] [Identity] sunPresence policy attributes: [sunPresenceAllowPublish, sunPresenceAllowManage, sunPresenceAllowAccess]
[19 Jul 2006 10:52:37,691] DEBUG xmppd [main] [Identity] getServiceVersion Exception: Service does not exist : SunIMService
[19 Jul 2006 10:52:37,692] INFO xmppd [main] [Identity] no old sunIMService attributes: Service does not exist : SunIMService
[19 Jul 2006 10:52:41,961] DEBUG xmppd [main] [Identity] admin role DNs: [cn=IM Administrator,o=msp]
[19 Jul 2006 10:52:41,961] DEBUG xmppd [main] [Identity] admin role DN: cn=IM Administrator,o=msp
[19 Jul 2006 10:52:41,985] DEBUG xmppd [main] [Identity] admin user DNs: []
[19 Jul 2006 10:52:42,003] DEBUG xmppd [main] [Identity] topics role DN: cn=IM News Administrator,o=msp
[19 Jul 2006 10:52:42,042] DEBUG xmppd [main] [Identity] rooms role DN: cn=IM Conference Rooms Administrator,o=msp
[19 Jul 2006 10:52:42,060] DEBUG xmppd [main] [Identity] send alerts role DN: cn=IM Allow Send Alerts Role,o=msp
[19 Jul 2006 10:52:42,078] DEBUG xmppd [main] [Identity] watch role DN: cn=IM Allow Watch Changes Role,o=msp
[19 Jul 2006 10:52:42,097] DEBUG xmppd [main] [Identity] save user settings role DN: cn=IM Allow User Settings Role,o=msp
[19 Jul 2006 10:52:43,508] DEBUG xmppd [main] [Identity] _topicsDefault: deny
[19 Jul 2006 10:52:43,509] DEBUG xmppd [main] [Identity] _roomsDefault: deny
[19 Jul 2006 10:52:43,509] DEBUG xmppd [main] [Identity] _sendAlertsDefault: allow
[19 Jul 2006 10:52:43,509] DEBUG xmppd [main] [Identity] _saveUserSettingsDefault: allow
[19 Jul 2006 10:52:43,509] DEBUG xmppd [main] [Identity] _watchDefault: allow
[19 Jul 2006 10:52:43,509] INFO xmppd [main] Loaded realm: com.iplanet.im.server.IdentityRealm
[19 Jul 2006 10:52:43,619] INFO xmppd [main] Monitoring is disabled
[19 Jul 2006 10:52:43,625] INFO xmppd [main] XMPP Server, version 7.1 Starting
[19 Jul 2006 10:52:43,670] DEBUG xmppd [main] [Archive] not loaded yet
[19 Jul 2006 10:52:43,670] DEBUG xmppd [main] [Archive] initializing archive providers
[19 Jul 2006 10:52:43,710] INFO xmppd [main] [Archive] loaded archive provider: com.iplanet.im.server.EmailIMArchive
[19 Jul 2006 10:52:43,710] INFO xmppd [main] [Archive] autoarchive=false
[19 Jul 2006 10:52:43,710] DEBUG xmppd [main] [Archive] finished loading
[19 Jul 2006 10:52:43,745] DEBUG xmppd [Timer-1] [EmailIMArchive] submit called:
[19 Jul 2006 10:52:43,973] DEBUG xmppd [main] [Disco] new key: muc new size=1
[19 Jul 2006 10:52:43,974] DEBUG xmppd [main] [Disco] new key: pubsub new size=2
[19 Jul 2006 10:52:43,974] DEBUG xmppd [main] [Disco] new key: jud new size=3
[19 Jul 2006 10:52:43,978] DEBUG xmppd [main] ServerSocketChannel on: null Port: 5269
[19 Jul 2006 10:52:44,009] WARN xmppd [main] [S2SSession] server ID = null
[19 Jul 2006 10:52:44,012] DEBUG xmppd [main] [PeerServer] connecting to peers
[19 Jul 2006 10:52:44,014] INFO xmppd [main] [Multiplex] starting on 0.0.0.0:45222
[19 Jul 2006 10:52:44,015] INFO xmppd [main] Server start completed
[19 Jul 2006 10:52:44,015] INFO xmppd [Thread-14] [NioSelectAcceptor][0.0.0.0:5269] Acceptor starting
[19 Jul 2006 10:52:44,578] INFO xmppd [Thread-15] New Multiplexor Connection started from /127.0.0.1
[19 Jul 2006 10:52:44,585] INFO xmppd [Thread-15] New Multiplexor Connection started from /127.0.0.1
[19 Jul 2006 10:52:44,586] DEBUG xmppd [Thread-16] MultiplexManager#33185374 buffered bytes: 0
[19 Jul 2006 10:52:44,588] ERROR xmppd [Thread-16] MultiplexManager#33185374 read error java.io.IOException: Connection reset by peer
[19 Jul 2006 10:52:44,589] ERROR xmppd [Thread-16] MultiplexManager#33185374 error sending BYE
[19 Jul 2006 10:52:44,589] INFO xmppd [Thread-16] MultiplexManager#33185374 exiting
[19 Jul 2006 10:52:44,590] DEBUG xmppd [Thread-17] MultiplexManager#24211360 buffered bytes: 0
[19 Jul 2006 10:52:44,596] INFO xmppd [Thread-17] MultiplexManager#24211360 HELLO received version=4.1 maxchannels=1002
[19 Jul 2006 10:52:44,597] DEBUG xmppd [Thread-17] MultiplexManager#24211360 buffered bytes: 0
[19 Jul 2006 10:52:44,606] INFO xmppd [Thread-15] New Multiplexor Connection started from /127.0.0.1
[19 Jul 2006 10:52:44,606] DEBUG xmppd [Thread-18] MultiplexManager#31447311 buffered bytes: 0
[19 Jul 2006 10:52:44,607] INFO xmppd [Thread-18] MultiplexManager#31447311 HELLO received version=4.1 maxchannels=1002
[19 Jul 2006 10:52:44,607] DEBUG xmppd [Thread-18] MultiplexManager#31447311 buffered bytes: 0
[19 Jul 2006 10:53:17,416] DEBUG xmppd [Thread-18] MultiplexManager#31447311 OPEN Payload len=8
[19 Jul 2006 10:53:17,452] DEBUG xmppd [ThreadPoolManager:default] Starting activity check; inactivity timeout=900000 now=1153299197450
[19 Jul 2006 10:53:17,453] DEBUG xmppd [ThreadPoolManager:default] Completed activity check for 0 channels.
[19 Jul 2006 10:53:17,500] DEBUG xmppd [Thread-18] useTLS : false
[19 Jul 2006 10:53:17,500] DEBUG xmppd [Thread-18] useTLS1 : false
[19 Jul 2006 10:53:17,500] DEBUG xmppd [Thread-18] useSASL : true
[19 Jul 2006 10:53:17,513] DEBUG xmppd [Thread-18] FSN : <features xmlns='http://etherx.jabber.org/streams'><mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>PLAIN</mechanis m></mechanisms><auth xmlns='http://jabber.org/features/iq-auth'/></features>
[19 Jul 2006 10:53:17,513] DEBUG xmppd [Thread-18] tlsFN : null
[19 Jul 2006 10:53:17,606] DEBUG xmppd [Thread-18] Session[null] outbound status changed from disconnected to connected
[19 Jul 2006 10:53:17,607] DEBUG xmppd [Thread-18] Session[null] inbound status changed from disconnected to connected
[19 Jul 2006 10:53:17,607] DEBUG xmppd [Thread-18] mxchnl[1] created, addr=/172.18.2.43
[19 Jul 2006 10:53:17,607] DEBUG xmppd [Thread-18] MultiplexManager#31447311 New channel 1 client address:/172.18.2.43
[19 Jul 2006 10:53:17,608] DEBUG xmppd [Thread-18] MultiplexManager#31447311 buffered bytes: 0
[19 Jul 2006 10:53:17,608] DEBUG xmppd [Thread-18] MultiplexManager#31447311 data received for channel 1 com.iplanet.im.server.MultiplexChannel@dc1f04
[19 Jul 2006 10:53:17,610] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 0] ClientSession started process()
[19 Jul 2006 10:53:17,611] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 0] Session[null] processing input
[19 Jul 2006 10:53:17,625] DEBUG xmppd [Thread-18] MultiplexManager#31447311 addRunnable: 1
[19 Jul 2006 10:53:17,625] DEBUG xmppd [Thread-18] MultiplexManager#31447311 buffered bytes: 0
[19 Jul 2006 10:53:17,728] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 0] Session[null] inbound status changed from connected to opened
[19 Jul 2006 10:53:17,728] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 0] [Session] expecting SASL support from client
[19 Jul 2006 10:53:17,728] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 0] [Session] opened.
[19 Jul 2006 10:53:17,730] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 0] [Session] Opening client session to proxy1.mrc.ac.za
[19 Jul 2006 10:53:17,732] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 0] [Session] opening outbound
[19 Jul 2006 10:53:17,737] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 0] Session[null] outbound status changed from connected to opened
[19 Jul 2006 10:53:17,737] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 0] [Session] opened outbound
[19 Jul 2006 10:53:17,744] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 0] [Session] opened.
[19 Jul 2006 10:53:17,744] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 0] Session[null] processing input
[19 Jul 2006 10:53:17,745] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 0] Session[null] processed input
[19 Jul 2006 10:53:17,745] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] ClientSession started process()
[19 Jul 2006 10:53:17,746] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] Session[null] processing input
[19 Jul 2006 10:53:17,746] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] Session[null] processed input
[19 Jul 2006 10:53:17,746] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] ClientSession finished process()
[19 Jul 2006 10:53:17,747] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 0] Session[null] processed input
[19 Jul 2006 10:53:17,747] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 0] ClientSession finished process()
[19 Jul 2006 10:53:18,074] DEBUG xmppd [Thread-18] MultiplexManager#31447311 data received for channel 1 com.iplanet.im.server.MultiplexChannel@dc1f04
[19 Jul 2006 10:53:18,074] DEBUG xmppd [Thread-18] MultiplexManager#31447311 addRunnable: 1
[19 Jul 2006 10:53:18,074] DEBUG xmppd [Thread-18] MultiplexManager#31447311 buffered bytes: 96
[19 Jul 2006 10:53:18,075] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] ClientSession started process()
[19 Jul 2006 10:53:18,075] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] Session[null] processing input
[19 Jul 2006 10:53:18,085] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] Session[null] processing request
[19 Jul 2006 10:53:18,085] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [ClientPacketDispatcher] SASLPacket detected
[19 Jul 2006 10:53:18,085] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] new packet
[19 Jul 2006 10:53:18,086] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] IS SASLAuthPacket
[19 Jul 2006 10:53:18,086] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] mechanism PLAIN
[19 Jul 2006 10:53:18,091] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] saslProvider : com.iplanet.im.server.sasl.PlainSASLProvider@135da43
[19 Jul 2006 10:53:18,092] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] requestFailed : false
[19 Jul 2006 10:53:18,093] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] saslProvider.isRaw() : false
[19 Jul 2006 10:53:18,100] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] request status : {urn:ietf:params:xml:ns:xmpp-sasl}auth
[19 Jul 2006 10:53:18,100] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] PLAIN mechanism requested
[19 Jul 2006 10:53:18,101] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Identity] Authenticating user jisolder
[19 Jul 2006 10:53:18,101] INFO xmppd [org.netbeans.lib.collab.util.Worker 2] Authenticating using Identity Server SSO
[19 Jul 2006 10:53:18,111] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] Unable to create SSOToken - the specified token might be a password.
[19 Jul 2006 10:53:18,112] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Identity] domain = mrc.ac.za
[19 Jul 2006 10:53:18,283] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Identity] getSearchBase(mrc.ac.za)=o=mrc.ac.za,o=msp
[19 Jul 2006 10:53:18,290] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Identity] domain=mrc.ac.za OrgDN=o=mrc.ac.za,o=msp
[19 Jul 2006 10:53:18,640] INFO xmppd [org.netbeans.lib.collab.util.Worker 2] [Identity] Failed to create SSO token for jisolder
[19 Jul 2006 10:53:18,641] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] request status : 0
[19 Jul 2006 10:53:18,641] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] response status : 3
[19 Jul 2006 10:53:18,646] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] Session[class com.iplanet.im.server.ClientSession null] authFailed - null
[19 Jul 2006 10:53:18,646] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] Session[null] processed input
[19 Jul 2006 10:53:18,646] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] ClientSession finished process()
[19 Jul 2006 10:53:18,676] DEBUG xmppd [Thread-18] MultiplexManager#31447311 data received for channel 1 com.iplanet.im.server.MultiplexChannel@dc1f04
[19 Jul 2006 10:53:18,676] DEBUG xmppd [Thread-18] MultiplexManager#31447311 addRunnable: 1
[19 Jul 2006 10:53:18,676] DEBUG xmppd [Thread-18] MultiplexManager#31447311 buffered bytes: 16
[19 Jul 2006 10:53:18,677] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] ClientSession started process()
[19 Jul 2006 10:53:18,677] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] Session[null] processing input
[19 Jul 2006 10:53:18,677] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] Session[null] inbound status changed from opened to closed
[19 Jul 2006 10:53:18,678] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] Session[null] outbound status changed from opened to closed
[19 Jul 2006 10:53:18,678] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] mxchnl[1] closing
[19 Jul 2006 10:53:18,679] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] MultiplexManager#31447311 channel 1 terminated.
[19 Jul 2006 10:53:18,679] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] Session[null] outbound status changed from closed to disconnected
[19 Jul 2006 10:53:18,679] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] Session[null] inbound status changed from closed to disconnected
[19 Jul 2006 10:53:18,679] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] Session[null] inbound status reset from disconnected to disconnected
[19 Jul 2006 10:53:18,680] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] Session[null] processed input
[19 Jul 2006 10:53:18,680] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] ClientSession finished process()
[19 Jul 2006 10:53:44,595] DEBUG xmppd [Timer-0] MultiplexManager#11442116 Starting activity check at 1153299224595
[19 Jul 2006 10:53:44,596] DEBUG xmppd [Timer-0] MultiplexManager#11442116 Completed activity check
[19 Jul 2006 10:53:44,625] DEBUG xmppd [Timer-0] MultiplexManager#7202313 Starting activity check at 1153299224625
[19 Jul 2006 10:53:44,626] DEBUG xmppd [Timer-0] MultiplexManager#7202313 Completed activity check
[19 Jul 2006 10:54:17,425] DEBUG xmppd [ThreadPoolManager:default] Starting activity check; inactivity timeout=900000 now=1153299256925
[19 Jul 2006 10:54:17,426] DEBUG xmppd [ThreadPoolManager:default] Completed activity check for 0 channels.
[19 Jul 2006 10:54:30,110] DEBUG xmppd [Thread-18] MultiplexManager#31447311 OPEN Payload len=8
[19 Jul 2006 10:54:30,111] DEBUG xmppd [Thread-18] useTLS : false
[19 Jul 2006 10:54:30,111] DEBUG xmppd [Thread-18] useTLS1 : false
[19 Jul 2006 10:54:30,111] DEBUG xmppd [Thread-18] useSASL : true
[19 Jul 2006 10:54:30,112] DEBUG xmppd [Thread-18] FSN : <features xmlns='http://etherx.jabber.org/streams'><mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>PLAIN</mechanis m></mechanisms><auth xmlns='http://jabber.org/features/iq-auth'/></features>
[19 Jul 2006 10:54:30,112] DEBUG xmppd [Thread-18] tlsFN : null
[19 Jul 2006 10:54:30,113] DEBUG xmppd [Thread-18] Session[null] outbound status changed from disconnected to connected
[19 Jul 2006 10:54:30,113] DEBUG xmppd [Thread-18] Session[null] inbound status changed from disconnected to connected
[19 Jul 2006 10:54:30,113] DEBUG xmppd [Thread-18] mxchnl[2] created, addr=/172.18.2.43
[19 Jul 2006 10:54:30,114] DEBUG xmppd [Thread-18] MultiplexManager#31447311 New channel 2 client address:/172.18.2.43
[19 Jul 2006 10:54:30,114] DEBUG xmppd [Thread-18] MultiplexManager#31447311 buffered bytes: 0
[19 Jul 2006 10:54:30,145] DEBUG xmppd [Thread-18] MultiplexManager#31447311 data received for channel 2 com.iplanet.im.server.MultiplexChannel@1195c2b
[19 Jul 2006 10:54:30,146] DEBUG xmppd [Thread-18] MultiplexManager#31447311 addRunnable: 1
[19 Jul 2006 10:54:30,146] DEBUG xmppd [Thread-18] MultiplexManager#31447311 buffered bytes: 121
[19 Jul 2006 10:54:30,146] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] ClientSession started process()
[19 Jul 2006 10:54:30,146] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] Session[null] processing input
[19 Jul 2006 10:54:30,147] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] Session[null] inbound status changed from connected to opened
[19 Jul 2006 10:54:30,147] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] [Session] expecting SASL support from client
[19 Jul 2006 10:54:30,148] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] [Session] opened.
[19 Jul 2006 10:54:30,149] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] [Session] Opening client session to proxy1.mrc.ac.za
[19 Jul 2006 10:54:30,149] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] [Session] opening outbound
[19 Jul 2006 10:54:30,150] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] Session[null] outbound status changed from connected to opened
[19 Jul 2006 10:54:30,150] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] [Session] opened outbound
[19 Jul 2006 10:54:30,151] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] [Session] opened.
[19 Jul 2006 10:54:30,152] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] Session[null] processing input
[19 Jul 2006 10:54:30,152] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] Session[null] processed input
[19 Jul 2006 10:54:30,152] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] Session[null] processed input
[19 Jul 2006 10:54:30,152] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] ClientSession finished process()
[19 Jul 2006 10:54:30,152] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] ClientSession started process()
[19 Jul 2006 10:54:30,152] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] Session[null] processing input
[19 Jul 2006 10:54:30,153] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] Session[null] processed input
[19 Jul 2006 10:54:30,153] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 1] ClientSession finished process()
[19 Jul 2006 10:54:30,163] DEBUG xmppd [Thread-18] MultiplexManager#31447311 data received for channel 2 com.iplanet.im.server.MultiplexChannel@1195c2b
[19 Jul 2006 10:54:30,164] DEBUG xmppd [Thread-18] MultiplexManager#31447311 addRunnable: 1
[19 Jul 2006 10:54:30,164] DEBUG xmppd [Thread-18] MultiplexManager#31447311 buffered bytes: 96
[19 Jul 2006 10:54:30,164] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] ClientSession started process()
[19 Jul 2006 10:54:30,164] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] Session[null] processing input
[19 Jul 2006 10:54:30,165] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] Session[null] processing request
[19 Jul 2006 10:54:30,166] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [ClientPacketDispatcher] SASLPacket detected
[19 Jul 2006 10:54:30,166] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] new packet
[19 Jul 2006 10:54:30,166] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] IS SASLAuthPacket
[19 Jul 2006 10:54:30,166] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] mechanism PLAIN
[19 Jul 2006 10:54:30,166] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] saslProvider : com.iplanet.im.server.sasl.PlainSASLProvider@cd83d8
[19 Jul 2006 10:54:30,166] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] requestFailed : false
[19 Jul 2006 10:54:30,166] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] saslProvider.isRaw() : false
[19 Jul 2006 10:54:30,167] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] request status : {urn:ietf:params:xml:ns:xmpp-sasl}auth
[19 Jul 2006 10:54:30,167] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] PLAIN mechanism requested
[19 Jul 2006 10:54:30,167] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Identity] Authenticating user ccarelse
[19 Jul 2006 10:54:30,167] INFO xmppd [org.netbeans.lib.collab.util.Worker 2] Authenticating using Identity Server SSO
[19 Jul 2006 10:54:30,168] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] Unable to create SSOToken - the specified token might be a password.
[19 Jul 2006 10:54:30,169] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Identity] domain = mrc.ac.za
[19 Jul 2006 10:54:30,169] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Identity] getSearchBase(mrc.ac.za)=o=mrc.ac.za,o=msp
[19 Jul 2006 10:54:30,169] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Identity] domain=mrc.ac.za OrgDN=o=mrc.ac.za,o=msp
[19 Jul 2006 10:54:30,239] INFO xmppd [org.netbeans.lib.collab.util.Worker 2] [Identity] Failed to create SSO token for ccarelse
[19 Jul 2006 10:54:30,239] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] request status : 0
[19 Jul 2006 10:54:30,239] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] [Sasl] response status : 3
[19 Jul 2006 10:54:30,243] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] Session[class com.iplanet.im.server.ClientSession null] authFailed - null
[19 Jul 2006 10:54:30,243] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] Session[null] processed input
[19 Jul 2006 10:54:30,244] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 2] ClientSession finished process()
[19 Jul 2006 10:54:30,259] DEBUG xmppd [Thread-18] MultiplexManager#31447311 data received for channel 2 com.iplanet.im.server.MultiplexChannel@1195c2b
[19 Jul 2006 10:54:30,259] DEBUG xmppd [Thread-18] MultiplexManager#31447311 addRunnable: 1
[19 Jul 2006 10:54:30,259] DEBUG xmppd [Thread-18] MultiplexManager#31447311 buffered bytes: 16
[19 Jul 2006 10:54:30,259] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] ClientSession started process()
[19 Jul 2006 10:54:30,260] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] Session[null] processing input
[19 Jul 2006 10:54:30,260] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] Session[null] inbound status changed from opened to closed
[19 Jul 2006 10:54:30,260] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] Session[null] outbound status changed from opened to closed
[19 Jul 2006 10:54:30,261] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] mxchnl[2] closing
[19 Jul 2006 10:54:30,261] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] MultiplexManager#31447311 channel 2 terminated.
[19 Jul 2006 10:54:30,261] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] Session[null] outbound status changed from closed to disconnected
[19 Jul 2006 10:54:30,261] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] Session[null] inbound status changed from closed to disconnected
[19 Jul 2006 10:54:30,261] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] Session[null] inbound status reset from disconnected to disconnected
[19 Jul 2006 10:54:30,261] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] Session[null] processed input
[19 Jul 2006 10:54:30,262] DEBUG xmppd [org.netbeans.lib.collab.util.Worker 3] ClientSession finished process()
[19 Jul 2006 10:54:43,755] DEBUG xmppd [Timer-1] [EmailIMArchive] submit called:
[19 Jul 2006 10:54:44,605] DEBUG xmppd [Timer-0] MultiplexManager#11442116 Starting activity check at 1153299284605
[19 Jul 2006 10:54:44,606] DEBUG xmppd [Timer-0] MultiplexManager#11442116 Completed activity check
[19 Jul 2006 10:54:44,635] DEBUG xmppd [Timer-0] MultiplexManager#7202313 Starting activity check at 1153299284635
[19 Jul 2006 10:54:44,636] DEBUG xmppd [Timer-0] MultiplexManager#7202313 Completed activity check
[19 Jul 2006 10:55:17,435] DEBUG xmppd [ThreadPoolManager:default] Starting activity check; inactivity timeout=900000 now=1153299316515
[19 Jul 2006 10:55:17,436] DEBUG xmppd [ThreadPoolManager:default] Completed activity check for 0 channels.