problem starting im server..

Hi,

after power failure we have i have a problem staring im processes...

it looks like it is started but the servers does not listen on port 5222 and 5269...

root@mail[msgsr]:log# /opt/SUNWiim/sbin/imadmin start

Starting Server[OK]

WARNING: The server will not not be able to perform authentication as it

does not have access to the local Access Manager configuration. Either

run server as root by changing iim.user and iim.group, or stop using

access manager for authentication or single-sign-on, by setting

iim.policy.modules to "ldap" and/or iim_server.usesso to "0".

Starting Multiplexor Exception in thread "main" java.lang.ExceptionInInitializerError

at com.sun.identity.security.AdminDNAction.run(AdminDNAction.java:86)

at java.security.AccessController.doPrivileged(Native Method)

at com.sun.im.identity.util.Auth.getAdminDN(Auth.java:134)

at com.iplanet.im.server.LDAPPool.getDefault(LDAPPool.java:66)

at com.iplanet.im.server.LDAPPool.<clinit>(LDAPPool.java:46)

at com.iplanet.im.server.LDAPRealm.<init>(LDAPRealm.java:250)

at com.iplanet.im.server.IdentityRealm.<init>(IdentityRealm.java:142)

at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)

at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessor Impl.java:39)

at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructor AccessorImpl.java:27)

at java.lang.reflect.Constructor.newInstance(Constructor.java:494)

at java.lang.Class.newInstance0(Class.java:350)

at java.lang.Class.newInstance(Class.java:303)

at com.iplanet.im.server.RealmManager.get(RealmManager.java:602)

at com.iplanet.im.server.RealmManager.init(RealmManager.java:572)

at com.iplanet.im.server.NMS.loadConfig(NMS.java:518)

at com.iplanet.im.server.NMS.init(NMS.java:386)

at com.iplanet.im.server.NMS.create(NMS.java:189)

at com.iplanet.im.server.NMS.main(NMS.java:955)

Caused by: java.lang.NullPointerException

at com.iplanet.am.util.AdminUtils.<clinit>(AdminUtils.java:84)

... 19 more

[OK]

Starting agent-calendar[OK]

root@mail[msgsr]:log# netstat -na | grep 522

root@mail[msgsr]:log# tail xmppd.log

[19 Jul 2007 16:51:17,445] DEBUG xmppd [Timer-1] [SSOTokenCacheCleanupTask] ended

[19 Jul 2007 16:51:54,472] INFO xmppd [main] Starting XMPP Server: Version 7.2

[19 Jul 2007 16:51:55,633] INFO xmppd [main] Server default domain : tempest.sk

[19 Jul 2007 16:51:55,633] ERROR xmppd [main] Compatability mode enabled - use iim_server.jid.encoding.compat to disable it.

[19 Jul 2007 16:51:55,634] ERROR xmppd [main] This is available only for backward compatibility. Newer features may have problems in this mode (s2s, etc).

[19 Jul 2007 16:51:55,634] ERROR xmppd [main] Support for this will be removed in future release.

[19 Jul 2007 16:51:55,679] INFO xmppd [main] RealmClassName com.iplanet.im.server.IdentityRealm

[19 Jul 2007 16:51:55,679] DEBUG xmppd [main] Creating realm for first time com.iplanet.im.server.IdentityRealm

[19 Jul 2007 16:52:54,498] DEBUG xmppd [ThreadPoolManager:default] Starting activity check; inactivity timeout=900000 now=1184856774495

[19 Jul 2007 16:52:54,498] DEBUG xmppd [ThreadPoolManager:default] Completed activity check for 0 channels.

root@mail[msgsr]:log# ps -ef | grep im

mailsrv 13601 77240Jul 13 ?0:04 /opt/SUNWmsgsr/lib/imsched

mailsrv 13520 77240Jul 13 ? 45:11 /opt/SUNWmsgsr/lib/imapd

mailsrv 13557 135200Jul 13 ? 48:34 /opt/SUNWmsgsr/lib/imapd -0 -d 0 -D 1

webservd 9923 77240 16:52:00 ?0:03 /usr/jdk/entsys-j2se/bin/java -Dlogdir=/var/opt/SUNWiim/default/log -Djava.awt.

webservd 9474 77240 16:51:52 ?0:10 /usr/jdk/entsys-j2se/bin/java -Dlogdir=/var/opt/SUNWiim/default/log -Djava.awt.

mailsrv 16635 136080 16:10:20 ?0:09 /opt/SUNWmsgsr/lib/ims_master

webservd 9670 77240 16:51:56 ?0:00 /opt/SUNWiim/sbin/../lib/multiplexor -c /opt/SUNWiim/sbin/../config/iim.conf

root 11351 279360 16:55:23 pts/70:00 grep im

root@mail[msgsr]:log# ps -ef | grep iim

webservd 9923 77240 16:52:00 ?0:03 /usr/jdk/entsys-j2se/bin/java -Dlogdir=/var/opt/SUNWiim/default/log -Djava.awt.

webservd 9474 77240 16:51:52 ?0:10 /usr/jdk/entsys-j2se/bin/java -Dlogdir=/var/opt/SUNWiim/default/log -Djava.awt.

root 11379 279360 16:55:26 pts/70:00 grep iim

webservd 9670 77240 16:51:56 ?0:00 /opt/SUNWiim/sbin/../lib/multiplexor -c /opt/SUNWiim/sbin/../config/iim.conf

root@mail[msgsr]:log# truss -p 9670

/2:lwp_park(0xFEBBFE48, 0) (sleeping...)

/1:pollsys(0x000448F4, 1, 0xFFBFF190, 0x00000000) (sleeping...)

/1:pollsys(0x000448F4, 1, 0xFFBFF190, 0x00000000) = 0

/1:lwp_unpark(2)= 0

/2:lwp_park(0xFEBBFE48, 0) = 0

/2:connect(5, 0x00046258, 16, SOV_DEFAULT) Err#146 ECONNREFUSED

/2:setsockopt(5, SOL_SOCKET, SO_LINGER, 0xFEBBFE34, 8, SOV_DEFAULT) Err#22 EINVAL

/2:close(5)= 0

/2:so_socket(PF_INET, SOCK_STREAM, IPPROTO_IP, "", SOV_DEFAULT) = 5

/2:ioctl(5, FIONBIO, 0xFEBBFDB8)= 0

/2:fcntl(5, F_GETFL)= 6

/2:fcntl(5, F_SETFL, FWRITE|FNDELAY|FNONBLOCK)= 0

/2:setsockopt(5, SOL_SOCKET, SO_OOBINLINE, 0xFEBBFDBC, 4, SOV_DEFAULT) = 0

/2:write(4, "\0", 1)= 1

/1:pollsys(0x000448F4, 1, 0xFFBFF190, 0x00000000) = 1

/2:time()= 1184856939

/1:read(3, "\0", 16384)= 1

/2:time()= 1184856939

/1:lwp_park(0x00000000, 0) = 0

/2:lwp_park(0xFEBBFE48, 1) (sleeping...)

/1:pollsys(0x000448F4, 1, 0xFFBFF190, 0x00000000) (sleeping...)

/1:pollsys(0x000448F4, 1, 0xFFBFF190, 0x00000000) = 0

/2:lwp_park(0xFEBBFE48, 1) = 0

/1:lwp_unpark(2)= 0

/1:lwp_unpark(2)= 0

/2:lwp_park(0x00000000, 0) = 0

/2:connect(5, 0x00046258, 16, SOV_DEFAULT) Err#150 EINPROGRESS

/2:write(4, "\0", 1)= 1

/1:pollsys(0x000448F4, 1, 0xFFBFF190, 0x00000000) = 1

/2:time()= 1184856941

/1:read(3, "\0", 16384)= 1

/2:time()= 1184856941

/1:lwp_park(0x00000000, 0) = 0

/1:pollsys(0x000448F4, 2, 0xFFBFF190, 0x00000000) = 1

/1:lwp_unpark(2)= 0

/2:lwp_park(0xFEBBFE48, 1) = 0

/2:connect(5, 0x00046258, 16, SOV_DEFAULT) Err#146 ECONNREFUSED

/2:setsockopt(5, SOL_SOCKET, SO_LINGER, 0xFEBBFE34, 8, SOV_DEFAULT) Err#22 EINVAL

/2:close(5)= 0

/2:so_socket(PF_INET, SOCK_STREAM, IPPROTO_IP, "", SOV_DEFAULT) = 5

/2:ioctl(5, FIONBIO, 0xFEBBFDB8)= 0

/2:fcntl(5, F_GETFL)= 6

/2:fcntl(5, F_SETFL, FWRITE|FNDELAY|FNONBLOCK)= 0

/2:setsockopt(5, SOL_SOCKET, SO_OOBINLINE, 0xFEBBFDBC, 4, SOV_DEFAULT) = 0

/1:pollsys(0x000448F4, 1, 0xFFBFF190, 0x00000000) = 1

/2:write(4, "\0", 1)= 1

/1:read(3, "\0", 16384)= 1

/2:time()= 1184856941

/2:time()= 1184856941

any ideas why ?

Thank for your help and time.

Stefan

[7052 byte] By [varga_steana] at [2007-11-27 11:05:27]
# 1

Hi Stefan,

If this system was working previously, can you check for AM SDK deployment sanity ? As in, are the files present, are the permissions to those file ok (in particular AMConfig.properties), are those files having same owner as the user im server is being run as ?

Essentially, im server is unable to talk to Access Manager server since the AM SDK seems to have some issues (typically this is a permission problem - but since you previously had it working, could be some file corruption, other errors).

Regards,

Mridul

MridulMa at 2007-7-29 13:08:47 > top of Java-index,E-Mail, Calendar, & Collaboration,Sun Java System Instant Messaging...
# 2

Hi,

amconfig.properties was owned by root:webservd and after changing to webservd:webservd it works again.

Thank you MdridulM.

Stefan

varga_steana at 2007-7-29 13:08:47 > top of Java-index,E-Mail, Calendar, & Collaboration,Sun Java System Instant Messaging...