search

oracle / docker-images

Official source of container configurations, images, and examples for Oracle products and projects
https://developer.oracle.com/use-cases/#containers
Universal Permissive License v1.0
6.57k stars 5.43k forks source link

Database Setup Unsuccessful - Oracle Database 12.2.0.1 #822

Closed forestoden6 closed 6 years ago

forestoden6 commented 6 years ago

Running into an issue trying to run the Oracle Database Docker container built using the Oracle SOA Suite instructions. The output and log files do not show any errors that I see, so hoping I can get pointed in the right direction.

Output from docker run:

foden@ubuntu:~/oracle-docker/docker-images/OracleDatabase/SingleInstance/dockerfiles$ docker run --name soadb  --network=SOANet -p 1521:1521 -p 5500:5500 -v /scratch/DockerVolume/SOAVolume/DB:/opt/oracle/oradata --env-file ./db.env.list  oracle/database:12.2.0.1-ee
ORACLE PASSWORD FOR SYS, SYSTEM AND PDBADMIN: Welcome1

LSNRCTL for Linux: Version 12.2.0.1.0 - Production on 03-APR-2018 19:46:02

Copyright (c) 1991, 2016, Oracle.  All rights reserved.

Starting /opt/oracle/product/12.2.0.1/dbhome_1/bin/tnslsnr: please wait...

TNSLSNR for Linux: Version 12.2.0.1.0 - Production
System parameter file is /opt/oracle/product/12.2.0.1/dbhome_1/network/admin/listener.ora
Log messages written to /opt/oracle/diag/tnslsnr/9de7b7b0a4f4/listener/alert/log.xml
Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=ipc)(KEY=EXTPROC1)))
Listening on: (DESCRIPTION=(ADDRESS=(PROTOCOL=tcp)(HOST=0.0.0.0)(PORT=1521)))

Connecting to (DESCRIPTION=(ADDRESS=(PROTOCOL=IPC)(KEY=EXTPROC1)))
STATUS of the LISTENER
------------------------
Alias                     LISTENER
Version                   TNSLSNR for Linux: Version 12.2.0.1.0 - Production
Start Date                03-APR-2018 19:46:03
Uptime                    0 days 0 hr. 0 min. 1 sec
Trace Level               off
Security                  ON: Local OS Authentication
SNMP                      OFF
Listener Parameter File   /opt/oracle/product/12.2.0.1/dbhome_1/network/admin/listener.ora
Listener Log File         /opt/oracle/diag/tnslsnr/9de7b7b0a4f4/listener/alert/log.xml
Listening Endpoints Summary...
  (DESCRIPTION=(ADDRESS=(PROTOCOL=ipc)(KEY=EXTPROC1)))
  (DESCRIPTION=(ADDRESS=(PROTOCOL=tcp)(HOST=0.0.0.0)(PORT=1521)))
The listener supports no services
The command completed successfully
[WARNING] [DBT-10102] The listener configuration is not selected for the database. EM DB Express URL will not be accessible.
   CAUSE: The database should be registered with a listener in order to access the EM DB Express URL.
   ACTION: Select a listener to be registered or created with the database.
Copying database files
1% complete
13% complete
25% complete
Creating and starting Oracle instance
26% complete
30% complete
31% complete
35% complete
38% complete
39% complete
41% complete
Completing Database Creation
42% complete
43% complete
44% complete
46% complete
47% complete
50% complete
Creating Pluggable Databases
55% complete
75% complete
Executing Post Configuration Actions
100% complete
Look at the log file "/opt/oracle/cfgtoollogs/dbca/soadb/soadb.log" for further details.

SQL*Plus: Release 12.2.0.1.0 Production on Tue Apr 3 19:54:18 2018

Copyright (c) 1982, 2016, Oracle.  All rights reserved.

Connected to:
Oracle Database 12c Enterprise Edition Release 12.2.0.1.0 - 64bit Production

SQL> 
System altered.

SQL> 
Pluggable database altered.

SQL> Disconnected from Oracle Database 12c Enterprise Edition Release 12.2.0.1.0 - 64bit Production
The Oracle base remains unchanged with value /opt/oracle
#####################################
########### E R R O R ###############
DATABASE SETUP WAS NOT SUCCESSFUL!
Please check output for further info!
########### E R R O R ###############
#####################################
The following output is now a tail of the alert.log:
Completed: alter pluggable database soapdb open
2018-04-03T19:54:17.050915+00:00
SOAPDB(3):CREATE SMALLFILE TABLESPACE "USERS" LOGGING  DATAFILE  '/opt/oracle/oradata/soadb/soapdb/users01.dbf' SIZE 5M REUSE AUTOEXTEND ON NEXT  1280K MAXSIZE UNLIMITED  EXTENT MANAGEMENT LOCAL  SEGMENT SPACE MANAGEMENT  AUTO
SOAPDB(3):Completed: CREATE SMALLFILE TABLESPACE "USERS" LOGGING  DATAFILE  '/opt/oracle/oradata/soadb/soapdb/users01.dbf' SIZE 5M REUSE AUTOEXTEND ON NEXT  1280K MAXSIZE UNLIMITED  EXTENT MANAGEMENT LOCAL  SEGMENT SPACE MANAGEMENT  AUTO
SOAPDB(3):ALTER DATABASE DEFAULT TABLESPACE "USERS"
SOAPDB(3):Completed: ALTER DATABASE DEFAULT TABLESPACE "USERS"
2018-04-03T19:54:18.152611+00:00
ALTER SYSTEM SET control_files='/opt/oracle/oradata/soadb/control01.ctl' SCOPE=SPFILE;
   ALTER PLUGGABLE DATABASE soapdb SAVE STATE
Completed:    ALTER PLUGGABLE DATABASE soapdb SAVE STATE

/opt/oracle/cfgtoollogs/dbca/soadb/soadb.log:

[oracle@9de7b7b0a4f4 soadb]$ cat soadb.log 
[ 2018-04-03 19:46:17.922 UTC ] Copying database files
DBCA_PROGRESS : 1%
DBCA_PROGRESS : 13%
DBCA_PROGRESS : 25%
[ 2018-04-03 19:48:00.075 UTC ] Creating and starting Oracle instance
DBCA_PROGRESS : 26%
DBCA_PROGRESS : 30%
DBCA_PROGRESS : 31%
DBCA_PROGRESS : 35%
DBCA_PROGRESS : 38%
DBCA_PROGRESS : 39%
DBCA_PROGRESS : 41%
[ 2018-04-03 19:51:17.402 UTC ] Completing Database Creation
DBCA_PROGRESS : 42%
DBCA_PROGRESS : 43%
DBCA_PROGRESS : 44%
DBCA_PROGRESS : 46%
DBCA_PROGRESS : 47%
DBCA_PROGRESS : 50%
[ 2018-04-03 19:53:58.191 UTC ] Creating Pluggable Databases
DBCA_PROGRESS : 55%
DBCA_PROGRESS : 75%
[ 2018-04-03 19:54:17.547 UTC ] Executing Post Configuration Actions
DBCA_PROGRESS : 100%
[ 2018-04-03 19:54:17.548 UTC ] Database creation complete. For details check the logfiles at:
 /opt/oracle/cfgtoollogs/dbca/soadb.
Database Information:
Global Database Name:soadb
System Identifier(SID):soadb

Thanks

forestoden6 commented 6 years ago

I tried the 12.1.0.2 version as well and run into the same issue. I found another log file that shows error messages but I'm unsure what the cause/fix is. The errors seem to begin at Executing Step : PLUG_DATABASE

[Thread-16] [ 2018-04-04 20:57:41.237 UTC ] [PostDBCreationStep.executeImpl:831]  cleaning up AWR data
[Thread-16] [ 2018-04-04 20:57:42.271 UTC ] [PostDBCreationStep.executeImpl:834]  AWR cleanup is done
[Thread-16] [ 2018-04-04 20:58:04.370 UTC ] [PostDBCreationStep.executeImpl:843]  PostDBCreationStep: bShared=false
[Thread-16] [ 2018-04-04 20:58:04.370 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /opt/oracle/product/12.1.0.2/dbhome_1
[Thread-16] [ 2018-04-04 20:58:04.370 UTC ] [CommonAttributes.replaceVariables:1402]  var2 soadb
[Thread-16] [ 2018-04-04 20:58:04.371 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /opt/oracle/product/12.1.0.2/dbhome_1
[Thread-16] [ 2018-04-04 20:58:04.371 UTC ] [CommonAttributes.replaceVariables:1402]  var2 soadb
[Thread-16] [ 2018-04-04 20:58:04.379 UTC ] [CommonAttributes.replaceVariables:1402]  var2 /opt/oracle/product/12.1.0.2/dbhome_1
[Thread-16] [ 2018-04-04 20:58:04.379 UTC ] [CommonAttributes.replaceVariables:1402]  var2 soadb
[Thread-16] [ 2018-04-04 20:58:05.433 UTC ] [Host.startupDBWithApprInitFile:8864]  startDBWithApp: startup clause=
[Thread-16] [ 2018-04-04 20:58:13.903 UTC ] [HADatabaseUtils.isGridMgmtDB:2842]  Mgmt DBName from srvm _mgmtdb
[Thread-16] [ 2018-04-04 20:58:13.903 UTC ] [PostDBCreationStep.executeImpl:1180]  Calling EMConfigStep: Central -false; EMExpress - true
[Thread-16] [ 2018-04-04 20:58:13.908 UTC ] [BasicStep.execute:250]  Executing Step : EM_CONFIG
[Thread-16] [ 2018-04-04 20:58:13.908 UTC ] [BasicStep.configureSettings:353]  messageHandler being set=null
[Thread-16] [ 2018-04-04 20:58:15.815 UTC ] [EMConfigStep.executeImpl:184]  DB Express port configuration succeeded. Port configured=5500
[Thread-16] [ 2018-04-04 20:58:15.817 UTC ] [EMConfigStep.executeImpl:186]  Host Name =00cee7b5513e
[Thread-16] [ 2018-04-04 20:58:15.817 UTC ] [EMConfigStep.executeImpl:214]  URL=https://00cee7b5513e:5500/em
[Thread-16] [ 2018-04-04 20:58:15.817 UTC ] [SQLEngine.done:2278]  Done called
[Thread-16] [ 2018-04-04 20:58:15.820 UTC ] [SQLEngine.spoolOff:2124]  Setting spool off = /opt/oracle/cfgtoollogs/dbca/soadb/postDBCreation.log
[Thread-16] [ 2018-04-04 20:58:15.824 UTC ] [BasicStep.configureSettings:353]  messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@1b78efd8
[Thread-16] [ 2018-04-04 20:58:15.825 UTC ] [BasicStep.configureSettings:353]  messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@1b78efd8
[Thread-16] [ 2018-04-04 20:58:15.825 UTC ] [StepContext$ModeRunner.run:2873]  ---- Progress Needed:=true
[Thread-16] [ 2018-04-04 20:58:15.826 UTC ] [BasicStep.execute:250]  Executing Step : POST_CDB_PDB_CREATION
[Thread-16] [ 2018-04-04 20:58:15.826 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-00439
[Thread-16] [ 2018-04-04 20:58:15.826 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-00904
[Thread-16] [ 2018-04-04 20:58:15.826 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-00942
[Thread-16] [ 2018-04-04 20:58:15.827 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-00955
[Thread-16] [ 2018-04-04 20:58:15.827 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01418
[Thread-16] [ 2018-04-04 20:58:15.827 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01432
[Thread-16] [ 2018-04-04 20:58:15.827 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01434
[Thread-16] [ 2018-04-04 20:58:15.827 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01917
[Thread-16] [ 2018-04-04 20:58:15.827 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01918
[Thread-16] [ 2018-04-04 20:58:15.827 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01919
[Thread-16] [ 2018-04-04 20:58:15.827 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01920
[Thread-16] [ 2018-04-04 20:58:15.827 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01921
[Thread-16] [ 2018-04-04 20:58:15.828 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-02260
[Thread-16] [ 2018-04-04 20:58:15.828 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-02289
[Thread-16] [ 2018-04-04 20:58:15.828 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-02303
[Thread-16] [ 2018-04-04 20:58:15.828 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-04043
[Thread-16] [ 2018-04-04 20:58:15.828 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-04080
[Thread-16] [ 2018-04-04 20:58:15.829 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-06550
[Thread-16] [ 2018-04-04 20:58:15.829 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29807
[Thread-16] [ 2018-04-04 20:58:15.829 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29809
[Thread-16] [ 2018-04-04 20:58:15.829 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29833
[Thread-16] [ 2018-04-04 20:58:15.829 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01430
[Thread-16] [ 2018-04-04 20:58:15.829 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29844
[Thread-16] [ 2018-04-04 20:58:15.829 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29830
[Thread-16] [ 2018-04-04 20:58:15.829 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-04042
[Thread-16] [ 2018-04-04 20:58:15.829 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29816
[Thread-16] [ 2018-04-04 20:58:15.829 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: PLS-00905
[Thread-16] [ 2018-04-04 20:58:15.829 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-06512
[Thread-16] [ 2018-04-04 20:58:15.829 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29515
[Thread-16] [ 2018-04-04 20:58:15.829 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-22303
[Thread-16] [ 2018-04-04 20:58:15.829 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: PLS-00201
[Thread-16] [ 2018-04-04 20:58:15.829 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: PL/SQL
[Thread-16] [ 2018-04-04 20:58:15.830 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29931
[Thread-16] [ 2018-04-04 20:58:15.830 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-02024
[Thread-16] [ 2018-04-04 20:58:15.830 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01927
[Thread-16] [ 2018-04-04 20:58:15.830 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-65040
[Thread-16] [ 2018-04-04 20:58:15.830 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-65087
[Thread-16] [ 2018-04-04 20:58:15.830 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01952
[Thread-16] [ 2018-04-04 20:58:15.830 UTC ] [BasicStep.configureSettings:353]  messageHandler being set=null
[Thread-16] [ 2018-04-04 20:58:15.830 UTC ] [SQLEngine.reInitialize:785]  Reinitializing SQLEngine...
[Thread-16] [ 2018-04-04 20:58:15.830 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-16] [ 2018-04-04 20:58:15.830 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-16] [ 2018-04-04 20:58:15.830 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-16] [ 2018-04-04 20:58:15.830 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-16] [ 2018-04-04 20:58:15.831 UTC ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0
[Thread-16] [ 2018-04-04 20:58:15.831 UTC ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0
[Thread-16] [ 2018-04-04 20:58:15.831 UTC ] [SQLPlusEngine.getCmmdParams:225]  m_home 12.1.0.2.0
[Thread-16] [ 2018-04-04 20:58:15.831 UTC ] [SQLPlusEngine.getCmmdParams:226]  version > 112 true
[Thread-16] [ 2018-04-04 20:58:15.831 UTC ] [SQLEngine.getEnvParams:602]  Default NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-16] [ 2018-04-04 20:58:15.831 UTC ] [SQLEngine.getEnvParams:612]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8
[Thread-16] [ 2018-04-04 20:58:15.845 UTC ] [SQLEngine.initialize:358]  Execing SQLPLUS/SVRMGR process...
[Thread-16] [ 2018-04-04 20:58:15.854 UTC ] [SQLEngine.initialize:395]  m_bReaderStarted: false
[Thread-16] [ 2018-04-04 20:58:15.854 UTC ] [SQLEngine.initialize:399]  Starting Reader Thread... 
[Thread-16] [ 2018-04-04 20:58:15.880 UTC ] [SQLEngine.initialize:448]  Waiting for m_bReaderStarted to be true 
[Thread-16] [ 2018-04-04 20:58:15.978 UTC ] [SQLEngine.setSpool:2056]  old Spool  = null
[Thread-16] [ 2018-04-04 20:58:15.979 UTC ] [SQLEngine.setSpool:2057]  Setting Spool  = /opt/oracle/cfgtoollogs/dbca/soadb/PDBCreation.log
[Thread-16] [ 2018-04-04 20:58:15.979 UTC ] [SQLEngine.setSpool:2058]  Is spool appendable? --> true
[Thread-16] [ 2018-04-04 20:58:15.981 UTC ] [CreatePDBAfterCDBCreationStep.executeImpl:304]  File /opt/oracle/product/12.1.0.2/dbhome_1/assistants/dbca/templates//sampleschema.dfb isExists : true
[Thread-16] [ 2018-04-04 20:58:15.981 UTC ] [CreatePDBAfterCDBCreationStep.executeImpl:305]  need SampleSchem false
[Thread-16] [ 2018-04-04 20:58:15.981 UTC ] [CreatePDBAfterCDBCreationStep.executeImpl:306]  is cloneDB true
[Thread-16] [ 2018-04-04 20:58:15.981 UTC ] [CreatePDBAfterCDBCreationStep.executeImpl:307]  local Instance Name soadb
[Thread-16] [ 2018-04-04 20:58:15.982 UTC ] [CreatePDBAfterCDBCreationStep.executeImpl:383]  Creating PDB after CDB creation.
[Thread-16] [ 2018-04-04 20:58:15.982 UTC ] [CreatePDBAfterCDBCreationStep.executeImpl:398]  isOMF false
[Thread-16] [ 2018-04-04 20:58:15.983 UTC ] [CreatePDBAfterCDBCreationStep.executeImpl:443]  Creating PDB=soapdb
[Thread-16] [ 2018-04-04 20:58:15.983 UTC ] [BasicStep.execute:250]  Executing Step : PLUG_DATABASE
[Thread-16] [ 2018-04-04 20:58:15.983 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-65107
[Thread-16] [ 2018-04-04 20:58:15.983 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-15173
[Thread-16] [ 2018-04-04 20:58:15.983 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-19505
[Thread-16] [ 2018-04-04 20:58:15.983 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-65104
[Thread-16] [ 2018-04-04 20:58:15.983 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-01119
[Thread-16] [ 2018-04-04 20:58:15.983 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-65011
[Thread-16] [ 2018-04-04 20:58:15.984 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-65012
[Thread-16] [ 2018-04-04 20:58:15.984 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-65027
[Thread-16] [ 2018-04-04 20:58:15.984 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-00604
[Thread-16] [ 2018-04-04 20:58:15.989 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-65026
[Thread-16] [ 2018-04-04 20:58:15.989 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-65005
[Thread-16] [ 2018-04-04 20:58:15.989 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-01034
[Thread-16] [ 2018-04-04 20:58:15.989 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-03114
[Thread-16] [ 2018-04-04 20:58:15.989 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-12560
[Thread-16] [ 2018-04-04 20:58:15.989 UTC ] [BasicStep.configureSettings:353]  messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@1b78efd8
[Thread-16] [ 2018-04-04 20:58:16.033 UTC ] [SQLEngine.spoolOff:2124]  Setting spool off = /opt/oracle/cfgtoollogs/dbca/soadb/PDBCreation.log
[Thread-16] [ 2018-04-04 20:58:16.033 UTC ] [SQLEngine.setSpool:2056]  old Spool  = null
[Thread-16] [ 2018-04-04 20:58:16.034 UTC ] [SQLEngine.setSpool:2057]  Setting Spool  = /opt/oracle/cfgtoollogs/dbca/soadb/plugDatabase.log
[Thread-16] [ 2018-04-04 20:58:16.034 UTC ] [SQLEngine.setSpool:2058]  Is spool appendable? --> true
[Thread-16] [ 2018-04-04 20:58:16.038 UTC ] [SQLEngine.spoolOff:2124]  Setting spool off = /opt/oracle/cfgtoollogs/dbca/soadb/plugDatabase.log
[Thread-16] [ 2018-04-04 20:58:16.038 UTC ] [SQLEngine.setSpool:2056]  old Spool  = null
[Thread-16] [ 2018-04-04 20:58:16.039 UTC ] [SQLEngine.setSpool:2057]  Setting Spool  = /opt/oracle/cfgtoollogs/dbca/soadb/plugDatabase.log
[Thread-16] [ 2018-04-04 20:58:16.039 UTC ] [SQLEngine.setSpool:2058]  Is spool appendable? --> true
[Thread-16] [ 2018-04-04 20:58:16.039 UTC ] [RestorationFactory.getRestorer:58]  Performing pdb seed restore
[Thread-16] [ 2018-04-04 20:58:16.040 UTC ] [PDBRestorer.restoreFiles:49]  Creating folder for user tablespace=/opt/oracle/oradata/soadb/{PDB_NAME}
[Thread-16] [ 2018-04-04 20:58:16.040 UTC ] [CommonAttributes.replaceVariables:1402]  var2 soapdb
[Thread-16] [ 2018-04-04 20:58:16.040 UTC ] [FileRestorer.createDestFolder:90]  Creating Destination file directory=/opt/oracle/oradata/soadb/soapdb
[Thread-16] [ 2018-04-04 20:58:16.044 UTC ] [FileRestorer.createDestFolder:92]  Destination folder /opt/oracle/oradata/soadb/soapdb creation status = true
[Thread-16] [ 2018-04-04 20:58:16.044 UTC ] [PDBSeedRestorer.restoreFiles:90]  PDB Data folder = /opt/oracle/oradata/soadb/{PDB_NAME}
[Thread-16] [ 2018-04-04 20:58:16.044 UTC ] [CommonAttributes.replaceVariables:1402]  var2 soapdb
[Thread-16] [ 2018-04-04 20:58:16.067 UTC ] [CommonAttributes.replaceVariables:1402]  var2 soapdb
[Thread-16] [ 2018-04-04 20:58:16.068 UTC ] [CommonAttributes.replaceVariables:1402]  var2 soapdb
[Thread-16] [ 2018-04-04 20:58:16.068 UTC ] [CommonAttributes.replaceVariables:1402]  var2 soapdb
[Thread-16] [ 2018-04-04 20:58:16.068 UTC ] [PluggableDatabaseSQLGenerator.getDefinitionText:118]  convertClause= file_name_convert=('/opt/oracle/oradata/soadb/pdbseed/pdbseed_temp012018-04-04_08-52-27-PM.dbf','/opt/oracle/oradata/soadb/soapdb/temp012018-04-04_08-52-27-PM.dbf',
'/opt/oracle/oradata/soadb/pdbseed/sysaux01.dbf','/opt/oracle/oradata/soadb/soapdb/sysaux01.dbf',
'/opt/oracle/oradata/soadb/pdbseed/system01.dbf','/opt/oracle/oradata/soadb/soapdb/system01.dbf') 
[Thread-16] [ 2018-04-04 20:58:29.872 UTC ] [PlugDatabaseStep.executeImpl:202]  Done with Create PDB
[Thread-16] [ 2018-04-04 20:58:31.917 UTC ] [BasicStep.configureSettings:353]  messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@1b78efd8
[Thread-16] [ 2018-04-04 20:58:31.928 UTC ] [BasicStep.execute:250]  Executing Step : POST_PDB_CREATION
[Thread-16] [ 2018-04-04 20:58:31.928 UTC ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-03113
[Thread-16] [ 2018-04-04 20:58:31.928 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-00439
[Thread-16] [ 2018-04-04 20:58:31.928 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-00904
[Thread-16] [ 2018-04-04 20:58:31.928 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-00942
[Thread-16] [ 2018-04-04 20:58:31.928 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-00955
[Thread-16] [ 2018-04-04 20:58:31.928 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01418
[Thread-16] [ 2018-04-04 20:58:31.928 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01432
[Thread-16] [ 2018-04-04 20:58:31.929 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01434
[Thread-16] [ 2018-04-04 20:58:31.929 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01917
[Thread-16] [ 2018-04-04 20:58:31.929 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01918
[Thread-16] [ 2018-04-04 20:58:31.929 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01919
[Thread-16] [ 2018-04-04 20:58:31.929 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01920
[Thread-16] [ 2018-04-04 20:58:31.929 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01921
[Thread-16] [ 2018-04-04 20:58:31.929 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-02260
[Thread-16] [ 2018-04-04 20:58:31.929 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-02289
[Thread-16] [ 2018-04-04 20:58:31.929 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-02303
[Thread-16] [ 2018-04-04 20:58:31.929 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-04043
[Thread-16] [ 2018-04-04 20:58:31.929 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-04080
[Thread-16] [ 2018-04-04 20:58:31.929 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-06550
[Thread-16] [ 2018-04-04 20:58:31.929 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29807
[Thread-16] [ 2018-04-04 20:58:31.929 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29809
[Thread-16] [ 2018-04-04 20:58:31.930 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29833
[Thread-16] [ 2018-04-04 20:58:31.930 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01430
[Thread-16] [ 2018-04-04 20:58:31.930 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29844
[Thread-16] [ 2018-04-04 20:58:31.930 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29830
[Thread-16] [ 2018-04-04 20:58:31.930 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-04042
[Thread-16] [ 2018-04-04 20:58:31.930 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29816
[Thread-16] [ 2018-04-04 20:58:31.930 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: PLS-00905
[Thread-16] [ 2018-04-04 20:58:31.930 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-06512
[Thread-16] [ 2018-04-04 20:58:31.930 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29515
[Thread-16] [ 2018-04-04 20:58:31.930 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-22303
[Thread-16] [ 2018-04-04 20:58:31.930 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: PLS-00201
[Thread-16] [ 2018-04-04 20:58:31.930 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: PL/SQL
[Thread-16] [ 2018-04-04 20:58:31.930 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-29931
[Thread-16] [ 2018-04-04 20:58:31.931 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-02024
[Thread-16] [ 2018-04-04 20:58:31.931 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01927
[Thread-16] [ 2018-04-04 20:58:31.931 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-65040
[Thread-16] [ 2018-04-04 20:58:31.931 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-65087
[Thread-16] [ 2018-04-04 20:58:31.931 UTC ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01952
[Thread-16] [ 2018-04-04 20:58:31.931 UTC ] [BasicStep.configureSettings:353]  messageHandler being set=null
[Thread-16] [ 2018-04-04 20:58:31.943 UTC ] [PostPDBCreationStep.executeImpl:193]  is DSRegistration selected : false
[Thread-16] [ 2018-04-04 20:58:31.943 UTC ] [PostPDBCreationStep.executeImpl:212]  location: null file: 
[Thread-16] [ 2018-04-04 20:58:31.992 UTC ] [SQLEngine.spoolOff:2124]  Setting spool off = /opt/oracle/cfgtoollogs/dbca/soadb/plugDatabase.log
[Thread-16] [ 2018-04-04 20:58:31.992 UTC ] [SQLEngine.setSpool:2056]  old Spool  = null
[Thread-16] [ 2018-04-04 20:58:31.992 UTC ] [SQLEngine.setSpool:2057]  Setting Spool  = /opt/oracle/cfgtoollogs/dbca/soadb/postPDBCreation.log
[Thread-16] [ 2018-04-04 20:58:31.992 UTC ] [SQLEngine.setSpool:2058]  Is spool appendable? --> true
[Thread-16] [ 2018-04-04 20:58:31.993 UTC ] [TablespaceSQLGenerator.filespecsClause:49]  Number of datafiles: 1
[Thread-16] [ 2018-04-04 20:58:31.994 UTC ] [CommonAttributes.replaceVariables:1402]  var2 soapdb
[Thread-16] [ 2018-04-04 20:58:31.994 UTC ] [CommonAttributes.replaceVariables:1402]  var2 soapdb
[Thread-16] [ 2018-04-04 20:58:31.994 UTC ] [PostPDBCreationStep.createUserTableSpace:760]  Creating user tableSpace : CREATE SMALLFILE TABLESPACE "USERS" LOGGING  DATAFILE  '/opt/oracle/oradata/soadb/soapdb/soapdb_users01.dbf' SIZE 5M REUSE AUTOEXTEND ON NEXT  1280K MAXSIZE UNLIMITED  EXTENT MANAGEMENT LOCAL  SEGMENT SPACE MANAGEMENT  AUTO 
[Thread-16] [ 2018-04-04 20:58:32.123 UTC ] [PostPDBCreationStep.configureDV:373]  ConfigureDV not selected
[Thread-16] [ 2018-04-04 20:58:32.148 UTC ] [SQLEngine.spoolOff:2124]  Setting spool off = /opt/oracle/cfgtoollogs/dbca/soadb/postPDBCreation.log
[Thread-16] [ 2018-04-04 20:58:32.148 UTC ] [SQLEngine.setSpool:2056]  old Spool  = null
[Thread-16] [ 2018-04-04 20:58:32.148 UTC ] [SQLEngine.setSpool:2057]  Setting Spool  = /opt/oracle/cfgtoollogs/dbca/soadb/postPDBCreation.log
[Thread-16] [ 2018-04-04 20:58:32.148 UTC ] [SQLEngine.setSpool:2058]  Is spool appendable? --> true
[Thread-16] [ 2018-04-04 20:58:32.333 UTC ] [PostPDBCreationStep.recompilePDBObjects:791]  Invalid objets 0
[Thread-16] [ 2018-04-04 20:58:32.333 UTC ] [BasicStep.configureSettings:353]  messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@1b78efd8
[Thread-16] [ 2018-04-04 20:58:32.334 UTC ] [SQLEngine.done:2278]  Done called
[Thread-16] [ 2018-04-04 20:58:32.335 UTC ] [SQLEngine.spoolOff:2124]  Setting spool off = /opt/oracle/cfgtoollogs/dbca/soadb/postPDBCreation.log
[Thread-16] [ 2018-04-04 20:58:32.338 UTC ] [BasicStep.configureSettings:353]  messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@1b78efd8
[main] [ 2018-04-04 20:58:32.339 UTC ] [Host.cleanup:3928]  Dbca exit status is: 0
[main] [ 2018-04-04 20:58:32.339 UTC ] [Host.cleanup:3931]  check point context oracle.sysman.assistants.util.CheckpointContext@4ab34646
[main] [ 2018-04-04 20:58:32.342 UTC ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS
[main] [ 2018-04-04 20:58:32.342 UTC ] [InventoryUtil.getHomeName:113]  homeName = OraDB12Home1
[main] [ 2018-04-04 20:58:32.342 UTC ] [Host.cleanup:3957]  check point  oracle.sysman.oic.oics.OicsCheckPoint@79b0d33c
[main] [ 2018-04-04 20:58:32.342 UTC ] [Host.cleanup:3969]  adding checkpoint to session
[main] [ 2018-04-04 20:58:32.403 UTC ] [OsUtilsBase.copyFile:1505]  OsUtilsBase.copyFile: 
[main] [ 2018-04-04 20:58:32.404 UTC ] [OsUtilsBase.copyFile:1553]  **write of file at destination complete...
[main] [ 2018-04-04 20:58:32.404 UTC ] [OsUtilsBase.copyFile:1588]  **file copy status:= true
[main] [ 2018-04-04 20:58:32.404 UTC ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /opt/oracle/cfgtoollogs/dbca/silent.log_2018-04-04_08-48-46-PM
[Thread-3] [ 2018-04-04 20:58:32.416 UTC ] [DbcaCleanupHook.run:44]  Cleanup started
[Thread-3] [ 2018-04-04 20:58:32.417 UTC ] [OracleHome.cleanupDBOptionsIntance:1671]  DB Options dummy instance sid=null
[Thread-3] [ 2018-04-04 20:58:32.417 UTC ] [DbcaCleanupHook.run:49]  Cleanup ended
gvenzl commented 6 years ago

I believe that this is a false positive here from the check script and a duplicate of #819. The PDB is open and you should be able to connect to it without any issues based on this line in the log: Completed: alter pluggable database soapdb open

I believe it's just to do that the check script doesn't expect a lowercase PDB name.

Michi2018 commented 6 years ago

IMHO simplest way is to change the line in checkDBStatus.sh: ORACLE_PDB="ls -dl $ORACLE_BASE/oradata/$ORACLE_SID/*/ | grep -v pdbseed | awk '{print $9}' | cut -d/ -f6" to: ORACLE_PDB="ls -dl $ORACLE_BASE/oradata/$ORACLE_SID/*/ | grep -v pdbseed | awk '{print toupper($9)}' | cut -d/ -f6"

forestoden6 commented 6 years ago

Thank you both for the help! I was able to get the DB running and communicating SOA containers