Skip to Main Content

Database Software

Announcement

For appeals, questions and feedback about Oracle Forums, please email oracle-forums-moderators_us@oracle.com. Technical questions should be asked in the appropriate category. Thank you!

11.2.0.3 boot process requires more than half an hour, gpnp, mdns, gipc issue?

anbobDec 26 2016 — edited Dec 29 2016

This problem plagued for a long time, like the same set of RAC environment, only one node will start this situation, the need to start more than half an hour, from the log that is in the GPNP start-up phase, but has not confirmed Gipc or mdns start the anomaly, opened the SR because the current version of 11.2.0.3 did not find a solution in the known case.

# gpnp log

2016-11-24 00:15:13.238: [ default][1]gpnpd START pid=12527 Oracle Grid Plug-and-Play Daemon

2016-11-24 00:15:13.238: [    GPNP][1]clsgpnp_Init: [at clsgpnp0.c:585] '/oracle/app/11.2.0.3/grid' in effect as GPnP home base.

2016-11-24 00:15:13.238: [    GPNP][1]clsgpnp_Init: [at clsgpnp0.c:619] GPnP pid=12527, GPNP comp tracelevel=1, depcomp tracelevel=0, tlsrc:ORA_DAEMON_LOGGING_LEVELS, apitl:0, complog:1, tstenv:0, devenv:0, envopt:0, flags=3

2016-11-24 00:15:13.259: [    GPNP][1]clsgpnpkwf_initwfloc: [at clsgpnpkwf.c:399] Using FS Wallet Location : /oracle/app/11.2.0.3/grid/gpnp/anbob1/wallets/peer/

[   CLWAL][1]clsw_Initialize: OLR initlevel [70000]

[  clsdmt][4]Listening to (ADDRESS=(PROTOCOL=ipc)(KEY=anbob1DBG_GPNPD))

2016-11-24 00:15:13.320: [  clsdmt][4]PID for the Process [12527], connkey 10

2016-11-24 00:15:13.320: [  clsdmt][4]Creating PID [12527] file for home /oracle/app/11.2.0.3/grid host anbob1 bin gpnp to /oracle/app/11.2.0.3/grid/gpnp/init/

2016-11-24 00:15:13.321: [  clsdmt][4]Writing PID [12527] to the file [/oracle/app/11.2.0.3/grid/gpnp/init/anbob1.pid]

2016-11-24 00:15:14.155: [    GPNP][1]clsgpnpd_validateProfile: [at clsgpnpd.c:2919] GPnPD taken cluster name 'anbob-cluster'

2016-11-24 00:15:14.155: [    GPNP][1]clsgpnpd_openLocalProfile: [at clsgpnpd.c:3477] Got local profile from file cache provider (LCP-FS).

2016-11-24 00:15:14.160: [    GPNP][1]clsgpnpd_openLocalProfile: [at clsgpnpd.c:3532] Got local profile from OLR cache provider (LCP-OLR).

2016-11-24 00:15:14.161: [    GPNP][1]clsgpnpd_openLocalProfile: [at clsgpnpd.c:3477] Got local profile from file cache provider (LCP-FS).

2016-11-24 00:15:14.163: [    GPNP][1]clsgpnpd_openLocalProfile: [at clsgpnpd.c:3532] Got local profile from OLR cache provider (LCP-OLR).

2016-11-24 00:15:14.167: [    GPNP][1]clsgpnpd_lOpen: [at clsgpnpd.c:1734] Listening on ipc://GPNPD_anbob1

2016-11-24 00:15:14.168: [ default][1]GPNPD started on node anbob1.

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: [at clsgpnpd.c:4836] --- Local best profile:

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: <?xml version="1.0" encoding="UTF-8"?><gpnp:GPnP-Profile Version[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: ="1.0" xmlns="http://www.grid-pnp.org/2005/11/gpnp-profile" xmln[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: s:gpnp="http://www.grid-pnp.org/2005/11/gpnp-profile" xmlns:orcl[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: ="http://www.oracle.com/gpnp/2005/11/gpnp-profile" xmlns:xsi="ht[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: tp://www.w3.org/2001/XMLSchema-instance" xsi:schemaLocation="htt[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: p://www.grid-pnp.org/2005/11/gpnp-profile gpnp-profile.xsd" Prof[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: ileSequence="4" ClusterUId="706e9ddf75936f72ff9e67f78aebc1dd" Cl[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: usterName="anbob-cluster" PALocation=""><gpnp:Network-Profile><g[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: pnp:HostNetwork id="gen" HostName="*"><gpnp:Network id="net1" IP[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: ="192.168.43.0" Adapter="lan901" Use="cluster_interconnect"/><gp[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: np:Network id="net2" IP="133.96.43.0" Adapter="lan900" Use="publ[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: ic"/></gpnp:HostNetwork></gpnp:Network-Profile><orcl:CSS-Profile[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork:  id="css" DiscoveryString="+asm" LeaseDuration="400"/><orcl:ASM-[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: Profile id="asm" DiscoveryString="/dev/yya_crsvg/r*" SPFile="+CR[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: SDG/anbob-cluster/asmparameterfile/registry.253.921245169"/><ds:[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: Signature xmlns:ds="http://www.w3.org/2000/09/xmldsig#"><ds:Sign[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: edInfo><ds:CanonicalizationMethod Algorithm="http://www.w3.org/2[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: 001/10/xml-exc-c14n#"/><ds:SignatureMethod Algorithm="http://www[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: .w3.org/2000/09/xmldsig#rsa-sha1"/><ds:Reference URI=""><ds:Tran[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: sforms><ds:Transform Algorithm="http://www.w3.org/2000/09/xmldsi[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: g#enveloped-signature"/><ds:Transform Algorithm="http://www.w3.o[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: rg/2001/10/xml-exc-c14n#"> <InclusiveNamespaces xmlns="http://ww[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: w.w3.org/2001/10/xml-exc-c14n#" PrefixList="gpnp orcl xsi"/></ds[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: :Transform></ds:Transforms><ds:DigestMethod Algorithm="http://ww[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: w.w3.org/2000/09/xmldsig#sha1"/><ds:DigestValue>j3cEdgu2gg6FY+SY[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: cwEBr/D4Eng=</ds:DigestValue></ds:Reference></ds:SignedInfo><ds:[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: SignatureValue>V7J7izjZYEfUIpxx8V/SQP9iEnjLq8Zd/nWH/JK98U0ZieShY[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: i3NQ9647NitR9lx3PwTlHLdPwvDUMjdXFjLg02Tq0lU7yukNsjrvVOyM10aitow1[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: GY4+9d6l4fbI3sLUk6tMkF447f039ymhYbKKDA/7qTYNX3XcBTdLEtSfic=</ds:[cont]

2016-11-24 00:15:14.169: [    GPNP][1]clsgpnpd_MainWork: SignatureValue></ds:Signature></gpnp:GPnP-Profile>

2016-11-24 00:15:14.170: [    GPNP][1]clsgpnpd_lOpen: [at clsgpnpd.c:1734] Listening on tcp://0.0.0.0:47010

2016-11-24 00:15:20.391: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:15:34.530: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:15:55.741: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:16:24.021: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:16:59.371: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:17:41.792: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:18:31.281: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:19:27.842: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:20:31.472: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:21:42.173: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:22:59.943: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:24:24.784: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:25:55.684: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:27:26.585: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:28:57.486: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:30:28.386: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:31:59.287: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:32:46.225: [    GPNP][1]clsgpnpdRCB: [at clsgpnpd.c:3933] GPnPD endpoint url "mdns:gpnp._tcp://anbob1:47010/agent=gpnpd,cname=anbob-cluster,host=anbob1,pid=12527/gpnpd h:anbob1 c:anbob-cluster" successfully advertised with RD

2016-11-24 00:33:30.187: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:35:01.088: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:36:31.988: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:38:02.889: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:39:33.789: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:41:04.690: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:42:35.591: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:44:06.491: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:45:37.392: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:47:08.292: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:48:39.193: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:50:10.093: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:51:40.994: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:52:46.325: [    GPNP][7]clsgpnp_profileCallUrlInt: [at clsgpnp.c:2104] put-profile call to url "tcp://anbob2:54247" disco "mdns:service:gpnp._tcp.local.://anbob2:54247/agent=gpnpd,cname=anbob-cluster,host=anbob2,pid=15219/gpnpd h:anbob2 c:anbob-cluster" [f=0 claimed- host:anbob1 cname:anbob-cluster seq:4 auth:CN=GPnP_peer]

2016-11-24 00:52:46.403: [    GPNP][7]clsgpnp_profileCallUrlInt: [at clsgpnp.c:2234] Result: (25) CLSGPNP_DUPLICATE. Successful put-profile CALL to remote "tcp://anbob2:54247" disco "mdns:service:gpnp._tcp.local.://anbob2:54247/agent=gpnpd,cname=anbob-cluster,host=anbob2,pid=15219/gpnpd h:anbob2 c:anbob-cluster"

2016-11-24 00:52:46.403: [    GPNP][7]clsgpnpd_pushThread: [at clsgpnpd.c:4770] START gpnpd start serving clients after profile updates

2016-11-24 00:53:11.894: [  OCRMSG][3]GIPC error [29] msg [gipcretConnectionRefused]

2016-11-24 00:54:42.804: [    GPNP][3]clsgpnp_ocrDetectThread: [at clsgpnp0.c:4508] OCR client init SUCCEEDED. OCR shared cache is now available

someone know that?  Where the problem? finally the node can be activated, but the time for a long time, another node nerver like this in same cluster.

thanks.

Comments
Locked Post
New comments cannot be posted to this locked post.
Post Details
Locked on Jan 26 2017
Added on Dec 26 2016
5 comments
618 views