{"action":"created","issue":{"url":"https://api.github.com/repos/oravirt/ansible-oracle/issues/107","repository_url":"https://api.github.com/repos/oravirt/ansible-oracle","labels_url":"https://api.github.com/repos/oravirt/ansible-oracle/issues/107/labels{/name}","comments_url":"https://api.github.com/repos/oravirt/ansible-oracle/issues/107/comments","events_url":"https://api.github.com/repos/oravirt/ansible-oracle/issues/107/events","html_url":"https://github.com/oravirt/ansible-oracle/issues/107","id":367385424,"node_id":"MDU6SXNzdWUzNjczODU0MjQ=","number":107,"title":"create controlfile failed","user":{"login":"daverod24","id":981954,"node_id":"MDQ6VXNlcjk4MTk1NA==","avatar_url":"https://avatars3.githubusercontent.com/u/981954?v=4","gravatar_id":"","url":"https://api.github.com/users/daverod24","html_url":"https://github.com/daverod24","followers_url":"https://api.github.com/users/daverod24/followers","following_url":"https://api.github.com/users/daverod24/following{/other_user}","gists_url":"https://api.github.com/users/daverod24/gists{/gist_id}","starred_url":"https://api.github.com/users/daverod24/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/daverod24/subscriptions","organizations_url":"https://api.github.com/users/daverod24/orgs","repos_url":"https://api.github.com/users/daverod24/repos","events_url":"https://api.github.com/users/daverod24/events{/privacy}","received_events_url":"https://api.github.com/users/daverod24/received_events","type":"User","site_admin":false},"labels":[],"state":"open","locked":false,"assignee":null,"assignees":[],"milestone":null,"comments":3,"created_at":"2018-10-05T22:16:15Z","updated_at":"2018-10-09T19:10:17Z","closed_at":null,"author_association":"NONE","body":"Hello\r\n\r\n  I have this issue when I run the ansible playbook I get this error at the end\r\nI am installing oracle 12c 12.1.0.2 the binaries are:\r\nlinuxamd64_12102_database_1of2.zip,\r\nlinuxamd64_12102_database_2of2.zip\r\n\r\nhere I put the logs because I'm not creating the controlfile of the redo\r\n\r\nI hope you can help me, it would be excellent to document a little more that should be changed to make a cleaner installation with the ansible.\r\n\r\nthanks in advance, best regards\r\n\r\n```\r\n##ansible log verbose -vvvv\r\n fatal: [db-fs-1]: FAILED! => {\r\n       \"changed\": true,\r\n       \"cmd\": \"time /u01/app/oracle/12.1.0.2/db1-base/bin/dbca -createDatabase -responseFile \r\n/u01/stage/rsp/dbca_orcl.rsp -silent  -initParams db_name=orcl,db_create_file_dest=/u02/oradata,db_create_online_log_dest_1=/u02/fra\",\r\n       \"delta\": \"0:02:31.089884\",\r\n       \"end\": \"2018-10-05 23:57:55.614014\",\r\n       \"invocation\": {\r\n           \"module_args\": {\r\n               \"_raw_params\": \"time /u01/app/oracle/12.1.0.2/db1-base/bin/dbca -createDatabase -responseFile /u01/stage/rsp/dbca_orcl.rsp -silent  -initParams db_name=orcl,db_create_file_dest=/u02/oradata,db_create_online_log_dest_1=/u02/fra\",\r\n               \"_uses_shell\": true,\r\n               \"argv\": null,\r\n               \"chdir\": null,\r\n               \"creates\": null,\r\n               \"executable\": null,\r\n               \"removes\": null,\r\n               \"stdin\": null,\r\n               \"warn\": true\r\n           }\r\n       },\r\n       \"msg\": \"non-zero return code\",\r\n       \"rc\": 1,\r\n       \"start\": \"2018-10-05 23:55:24.524130\",\r\n       \"stderr\": \"\\nreal\\t2m31.042s\\nuser\\t0m2.716s\\nsys\\t0m0.887s\",\r\n       \"stderr_lines\": [\r\n           \"\",\r\n           \"real\\t2m31.042s\",\r\n           \"user\\t0m2.716s\",\r\n           \"sys\\t0m0.887s\"\r\n       ],\r\n       \"stdout\": \"Cleaning up failed steps\\n5% complete\\nCopying database files\\n7% complete\\n9% complete\\n16% complete\\n23% complete\\n30% complete\\n41% complete\\nCreating and starting Oracle instance\\nDBCA Operation failed.\\nLook at the log file \\\"/u01/app/oracle/cfgtoollogs/dbca/orcl/orcl1.log\\\" for further details.\",\r\n       \"stdout_lines\": [\r\n           \"Cleaning up failed steps\",\r\n           \"5% complete\",\r\n           \"Copying database files\",\r\n           \"7% complete\",\r\n           \"9% complete\",\r\n           \"16% complete\",\r\n           \"23% complete\",\r\n           \"30% complete\",\r\n           \"41% complete\",\r\n           \"Creating and starting Oracle instance\",\r\n           \"DBCA Operation failed.\",\r\n           \"Look at the log file \\\"/u01/app/oracle/cfgtoollogs/dbca/orcl/orcl1.log\\\" for further details.\"\r\n       ]\r\n   }\r\n           to retry, use: --limit @/home/david/Documentos/indevsecops-ibk/ubuntutest/testing/vagrant2/vagrant-vbox-si-fs/extra-provision/ansible-oracle/vbox-si-fs-install.retry\r\n   \r\n   PLAY RECAP **************************************************************************************************************************************************************************\r\n   db-fs-1                    : ok=60   changed=2    unreachable=0    failed=1\r\n```\r\n```\r\ntail: /u01/app/oracle/cfgtoollogs/dbca/orcl/trace.log: file truncated\r\n[main] [ 2018-10-05 23:56:04.141 CEST ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase \r\n/u01/app/oracle\r\n[main] [ 2018-10-05 23:56:04.141 CEST ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle\r\n[main] [ 2018-10-05 23:56:04.141 CEST ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle\r\n[main] [ 2018-10-05 23:56:04.141 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[main] [ 2018-10-05 23:56:04.141 CEST ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle\r\n[main] [ 2018-10-05 23:56:04.141 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[main] [ 2018-10-05 23:56:04.141 CEST ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[main] [ 2018-10-05 23:56:04.141 CEST ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[main] [ 2018-10-05 23:56:04.142 CEST ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/db1-base\r\n[main] [ 2018-10-05 23:56:04.144 CEST ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase \r\n/u01/app/oracle\r\n[main] [ 2018-10-05 23:56:04.144 CEST ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle\r\n[main] [ 2018-10-05 23:56:04.144 CEST ] [Host.getAuditNextGen:9054]  NextGen Audit /u01/app/oracle/audit\r\n[main] [ 2018-10-05 23:56:04.144 CEST ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle\r\n[main] [ 2018-10-05 23:56:04.144 CEST ] [Host.getFolders:10740]  adding path for DB_RECOVERY_FILE_DETS/u02/fra\r\n[main] [ 2018-10-05 23:56:04.145 CEST ] [Host.getFolders:10750]  RedoLogDestName db_create_online_log_dest_1\r\n[main] [ 2018-10-05 23:56:04.145 CEST ] [Host.getFolders:10750]  RedoLogDestName db_create_online_log_dest_2\r\n[main] [ 2018-10-05 23:56:04.145 CEST ] [Host.getFolders:10750]  RedoLogDestName db_create_online_log_dest_3\r\n[main] [ 2018-10-05 23:56:04.145 CEST ] [Host.getFolders:10750]  RedoLogDestName db_create_online_log_dest_4\r\n[main] [ 2018-10-05 23:56:04.145 CEST ] [Host.getFolders:10750]  RedoLogDestName db_create_online_log_dest_5\r\n[main] [ 2018-10-05 23:56:04.145 CEST ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle/12.1.0.2/db1-base\r\n[main] [ 2018-10-05 23:56:04.145 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[main] [ 2018-10-05 23:56:04.145 CEST ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle/12.1.0.2/db1-base\r\n[main] [ 2018-10-05 23:56:04.145 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[main] [ 2018-10-05 23:56:04.145 CEST ] [Host.getFolders:10786]  adding path for /u01/app/oracle/12.1.0.2/db1-base/dbs\r\n[main] [ 2018-10-05 23:56:04.146 CEST ] [Host.getFolders:10808]  adding path for /u02/oradata\r\n[main] [ 2018-10-05 23:56:04.147 CEST ] [Host.setUpForOperation:4253]  Folder[0]=/u01/app/oracle\r\n[main] [ 2018-10-05 23:56:04.147 CEST ] [Host.setUpForOperation:4253]  Folder[1]=/u01/app/oracle/12.1.0.2/db1-base/dbs\r\n[main] [ 2018-10-05 23:56:04.147 CEST ] [Host.setUpForOperation:4253]  Folder[2]=/u01/app/oracle/admin/orcl/adump\r\n[main] [ 2018-10-05 23:56:04.154 CEST ] [Host.setUpForOperation:4253]  Folder[3]=/u01/app/oracle/admin/orcl/dpdump\r\n[main] [ 2018-10-05 23:56:04.155 CEST ] [Host.setUpForOperation:4253]  Folder[4]=/u01/app/oracle/admin/orcl/pfile\r\n[main] [ 2018-10-05 23:56:04.155 CEST ] [Host.setUpForOperation:4253]  Folder[5]=/u01/app/oracle/audit\r\n[main] [ 2018-10-05 23:56:04.155 CEST ] [Host.setUpForOperation:4253]  Folder[6]=/u01/app/oracle/cfgtoollogs/dbca/orcl\r\n[main] [ 2018-10-05 23:56:04.155 CEST ] [Host.setUpForOperation:4253]  Folder[7]=/u02/fra\r\n[main] [ 2018-10-05 23:56:04.155 CEST ] [Host.setUpForOperation:4253]  Folder[8]=/u02/oradata\r\n[main] [ 2018-10-05 23:56:04.155 CEST ] [Host.executeSteps:6091]  setupForOperation returned: true\r\n[main] [ 2018-10-05 23:56:04.156 CEST ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[main] [ 2018-10-05 23:56:04.156 CEST ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[main] [ 2018-10-05 23:56:04.156 CEST ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/db1-base\r\n[main] [ 2018-10-05 23:56:04.163 CEST ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase \r\n/u01/app/oracle\r\n[main] [ 2018-10-05 23:56:04.164 CEST ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle\r\n[main] [ 2018-10-05 23:56:04.164 CEST ] [Host.createStepSQLInterface:8605]  sid =orcl\r\n[main] [ 2018-10-05 23:56:04.164 CEST ] [Host.createStepSQLInterface:8606]  sidOrConnectString orcl\r\n[main] [ 2018-10-05 23:56:04.164 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: null\r\n[main] [ 2018-10-05 23:56:04.165 CEST ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS\r\n[main] [ 2018-10-05 23:56:04.165 CEST ] [OracleHome.getVersion:1010]  Homeinfo /u01/app/oracle/12.1.0.2/db1-base,1\r\n[main] [ 2018-10-05 23:56:04.273 CEST ] [OracleHome.getVersion:1038]  OracleHome.server.getVersion Version: 12.1.0.2.0\r\n[main] [ 2018-10-05 23:56:04.273 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[main] [ 2018-10-05 23:56:04.274 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[main] [ 2018-10-05 23:56:04.274 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[main] [ 2018-10-05 23:56:04.274 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[main] [ 2018-10-05 23:56:04.274 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[main] [ 2018-10-05 23:56:04.274 CEST ] [SQLPlusEngine.getCmmdParams:225]  m_home 12.1.0.2.0\r\n[main] [ 2018-10-05 23:56:04.274 CEST ] [SQLPlusEngine.getCmmdParams:226]  version > 112 true\r\n[main] [ 2018-10-05 23:56:04.274 CEST ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[main] [ 2018-10-05 23:56:04.274 CEST ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[main] [ 2018-10-05 23:56:04.274 CEST ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/db1-base\r\n[main] [ 2018-10-05 23:56:04.278 CEST ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase \r\n/u01/app/oracle\r\n[main] [ 2018-10-05 23:56:04.278 CEST ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle\r\n[main] [ 2018-10-05 23:56:04.279 CEST ] [SQLEngine.getEnvParams:602]  Default NLS_LANG: AMERICAN_AMERICA.AL32UTF8\r\n[main] [ 2018-10-05 23:56:04.279 CEST ] [SQLEngine.getEnvParams:612]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8\r\n[main] [ 2018-10-05 23:56:04.279 CEST ] [SQLEngine.initialize:358]  Execing SQLPLUS/SVRMGR process...\r\n[main] [ 2018-10-05 23:56:04.285 CEST ] [SQLEngine.initialize:395]  m_bReaderStarted: false\r\n[main] [ 2018-10-05 23:56:04.286 CEST ] [SQLEngine.initialize:399]  Starting Reader Thread... \r\n[Thread-48] [ 2018-10-05 23:56:04.288 CEST ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:56:04.288 CEST ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:56:04.289 CEST ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/db1-base\r\n[Thread-48] [ 2018-10-05 23:56:04.294 CEST ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase \r\n/u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:04.294 CEST ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:04.296 CEST ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS\r\n[Thread-48] [ 2018-10-05 23:56:04.297 CEST ] [InventoryUtil.getHomeName:113]  homeName = OraDB12Home1\r\n[Thread-48] [ 2018-10-05 23:56:04.298 CEST ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:56:04.298 CEST ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:56:04.298 CEST ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/db1-base\r\n[Thread-48] [ 2018-10-05 23:56:04.300 CEST ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase \r\n/u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:04.300 CEST ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:04.301 CEST ] [StepManager.getSteps:275]  logging folder set: /u01/app/oracle/cfgtoollogs/dbca/orcl\r\n[Thread-48] [ 2018-10-05 23:56:04.301 CEST ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:56:04.301 CEST ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:56:04.301 CEST ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/db1-base\r\n[Thread-48] [ 2018-10-05 23:56:04.303 CEST ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase \r\n/u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:04.303 CEST ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:04.304 CEST ] [StepManager.getSteps:295]  ckFileName /u01/app/oracle/cfgtoollogs/dbca/orcl/OraDB12Home1_orcl_creation_checkpoint.xml ckContext oracle.sysman.assistants.util.CheckpointContext@32b95f72\r\n[Thread-48] [ 2018-10-05 23:56:04.322 CEST ] [CheckpointContext.isCleanupNeeded:140]   failedChkFiles {/u01/app/oracle/cfgtoollogs/dbca/orcl/OraDB12Home1_orcl_creation_checkpoint.xml=null}\r\n[Thread-48] [ 2018-10-05 23:56:04.322 CEST ] [CheckpointContext.isCleanupNeeded:149]  Failed check files iterator java.util.HashMap$KeyIterator@629ca1fb\r\n[Thread-48] [ 2018-10-05 23:56:04.322 CEST ] [CheckpointContext.isCleanupNeeded:155]  failed chjeckpoint file /u01/app/oracle/cfgtoollogs/dbca/orcl/OraDB12Home1_orcl_creation_checkpoint.xml\r\n[Thread-48] [ 2018-10-05 23:56:04.322 CEST ] [CheckpointContext.isCleanupNeeded:160]  getting all check point list\r\n[Thread-48] [ 2018-10-05 23:56:04.323 CEST ] [CheckpointContext.getAllCheckPoints:224]  getting all check point list\r\n[Thread-48] [ 2018-10-05 23:56:04.323 CEST ] [CheckpointContext.getAllCheckPoints:226]  returning all check point list\r\n[Thread-48] [ 2018-10-05 23:56:04.323 CEST ] [CheckpointContext.isCleanupNeeded:163]   all check point list size 1\r\n[Thread-48] [ 2018-10-05 23:56:04.323 CEST ] [CheckpointContext.isCleanupNeeded:189]  adding cleanup step since it is a retry operation.\r\n[Thread-48] [ 2018-10-05 23:56:04.323 CEST ] [CheckpointContext.isCleanupNeeded:196]  old checkpoint file deleted: true\r\n[Thread-48] [ 2018-10-05 23:56:04.324 CEST ] [CheckpointContext.retrieveCheckPoint:338]  check point db_orcl retrieved from file oracle.sysman.oic.oics.OicsCheckPoint@6ce931d9\r\n[Thread-48] [ 2018-10-05 23:56:04.324 CEST ] [StepManager.getSteps:326]  checkpoint from file oracle.sysman.oic.oics.OicsCheckPoint@6ce931d9\r\n[Thread-48] [ 2018-10-05 23:56:04.324 CEST ] [StepManager.getSteps:332]  adding checkpoint to session\r\n[Thread-48] [ 2018-10-05 23:56:04.324 CEST ] [StepManager.getSteps:334]  saved new checkpoint session for DB creation\r\n[Thread-48] [ 2018-10-05 23:56:04.324 CEST ] [StepManager.getSteps:430]  StepManager isHARegistrationRequired : false\r\n[Thread-48] [ 2018-10-05 23:56:04.333 CEST ] [StepContext$ModeRunner.run:2873]  ---- Progress Needed:=true\r\n[Thread-48] [ 2018-10-05 23:56:04.333 CEST ] [BasicStep.execute:250]  Executing Step : CLEANUP\r\n[Thread-48] [ 2018-10-05 23:56:04.334 CEST ] [BasicStep.configureSettings:353]  messageHandler being set=null\r\n[Thread-48] [ 2018-10-05 23:56:04.334 CEST ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:56:04.334 CEST ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:56:04.334 CEST ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/db1-base\r\n[Thread-48] [ 2018-10-05 23:56:04.337 CEST ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase \r\n/u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:04.338 CEST ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:04.339 CEST ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS\r\n[Thread-48] [ 2018-10-05 23:56:04.339 CEST ] [InventoryUtil.getHomeName:113]  homeName = OraDB12Home1\r\n[Thread-48] [ 2018-10-05 23:56:04.339 CEST ] [BasicStep.configureSettings:353]  messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@4f2d26d2\r\n[Thread-48] [ 2018-10-05 23:56:04.340 CEST ] [StepContext$ModeRunner.run:2873]  ---- Progress Needed:=true\r\n[Thread-48] [ 2018-10-05 23:56:04.340 CEST ] [BasicStep.execute:250]  Executing Step : CLONE_DB_CREATION_RMAN_RESTORE\r\n[Thread-48] [ 2018-10-05 23:56:04.340 CEST ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-01092\r\n[Thread-48] [ 2018-10-05 23:56:04.340 CEST ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-01034\r\n[Thread-48] [ 2018-10-05 23:56:04.340 CEST ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-03114\r\n[Thread-48] [ 2018-10-05 23:56:04.340 CEST ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-12560\r\n[Thread-48] [ 2018-10-05 23:56:04.340 CEST ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01109\r\n[Thread-48] [ 2018-10-05 23:56:04.340 CEST ] [BasicStep.configureSettings:353]  messageHandler being set=null\r\n[Thread-48] [ 2018-10-05 23:56:04.342 CEST ] [Host.getRMANConnectionInfo:1171]  Connect to Instance orcl\r\n[Thread-48] [ 2018-10-05 23:56:04.346 CEST ] [BasicStep.execute:250]  Executing Step : INSTANCE_CREATION\r\n[Thread-48] [ 2018-10-05 23:56:04.346 CEST ] [BasicStep.configureSettings:353]  messageHandler being set=null\r\n[Thread-48] [ 2018-10-05 23:56:04.348 CEST ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:04.348 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:56:04.349 CEST ] [InitParamAttributes.sortParams:3916]  m_sortOn:-1 sortOn:4\r\n[Thread-48] [ 2018-10-05 23:56:04.360 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param db_block_size\r\n[Thread-48] [ 2018-10-05 23:56:04.360 CEST ] [InitParamAttributes.createInitFile:1816]  The value is 8192\r\n[Thread-48] [ 2018-10-05 23:56:04.360 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param open_cursors\r\n[Thread-48] [ 2018-10-05 23:56:04.361 CEST ] [InitParamAttributes.createInitFile:1816]  The value is 300\r\n[Thread-48] [ 2018-10-05 23:56:04.361 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param db_domain\r\n[Thread-48] [ 2018-10-05 23:56:04.361 CEST ] [InitParamAttributes.createInitFile:1816]  The value is \"\"\r\n[Thread-48] [ 2018-10-05 23:56:04.361 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param db_name\r\n[Thread-48] [ 2018-10-05 23:56:04.361 CEST ] [InitParamAttributes.createInitFile:1816]  The value is \"orcl\"\r\n[Thread-48] [ 2018-10-05 23:56:04.361 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param control_files\r\n[Thread-48] [ 2018-10-05 23:56:04.361 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:56:04.361 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:56:04.361 CEST ] [InitParamAttributes.createInitFile:1816]  The value is (\"/u02/oradata/orcl/control01.ctl\", \"/u02/fra/orcl/control02.ctl\")\r\n[Thread-48] [ 2018-10-05 23:56:04.361 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param db_create_file_dest\r\n[Thread-48] [ 2018-10-05 23:56:04.362 CEST ] [InitParamAttributes.createInitFile:1816]  The value is \"/u02/oradata\"\r\n[Thread-48] [ 2018-10-05 23:56:04.362 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param db_create_online_log_dest_1\r\n[Thread-48] [ 2018-10-05 23:56:04.362 CEST ] [InitParamAttributes.createInitFile:1816]  The value is /u02/fra\r\n[Thread-48] [ 2018-10-05 23:56:04.362 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param db_recovery_file_dest\r\n[Thread-48] [ 2018-10-05 23:56:04.362 CEST ] [InitParamAttributes.createInitFile:1816]  The value is \"/u02/fra\"\r\n[Thread-48] [ 2018-10-05 23:56:04.362 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param db_recovery_file_dest_size\r\n[Thread-48] [ 2018-10-05 23:56:04.362 CEST ] [InitParamAttributes.createInitFile:1816]  The value is 4560m\r\n[Thread-48] [ 2018-10-05 23:56:04.362 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param compatible\r\n[Thread-48] [ 2018-10-05 23:56:04.362 CEST ] [InitParamAttributes.createInitFile:1816]  The value is 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.362 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param diagnostic_dest\r\n[Thread-48] [ 2018-10-05 23:56:04.362 CEST ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:04.363 CEST ] [InitParamAttributes.createInitFile:1816]  The value is /u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:04.363 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param memory_target\r\n[Thread-48] [ 2018-10-05 23:56:04.363 CEST ] [InitParamAttributes.createInitFile:1816]  The value is 1024m\r\n[Thread-48] [ 2018-10-05 23:56:04.363 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param processes\r\n[Thread-48] [ 2018-10-05 23:56:04.363 CEST ] [InitParamAttributes.createInitFile:1816]  The value is 300\r\n[Thread-48] [ 2018-10-05 23:56:04.363 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param audit_file_dest\r\n[Thread-48] [ 2018-10-05 23:56:04.363 CEST ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:04.363 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:56:04.363 CEST ] [InitParamAttributes.createInitFile:1816]  The value is \"/u01/app/oracle/admin/orcl/adump\"\r\n[Thread-48] [ 2018-10-05 23:56:04.363 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param audit_trail\r\n[Thread-48] [ 2018-10-05 23:56:04.363 CEST ] [InitParamAttributes.createInitFile:1816]  The value is db\r\n[Thread-48] [ 2018-10-05 23:56:04.364 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param remote_login_passwordfile\r\n[Thread-48] [ 2018-10-05 23:56:04.364 CEST ] [InitParamAttributes.createInitFile:1816]  The value is EXCLUSIVE\r\n[Thread-48] [ 2018-10-05 23:56:04.364 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param dispatchers\r\n[Thread-48] [ 2018-10-05 23:56:04.364 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:56:04.364 CEST ] [InitParamAttributes.createInitFile:1816]  The value is \"(PROTOCOL=TCP) (SERVICE=orclXDB)\"\r\n[Thread-48] [ 2018-10-05 23:56:04.364 CEST ] [InitParamAttributes.createInitFile:1811]  Processing init param undo_tablespace\r\n[Thread-48] [ 2018-10-05 23:56:04.364 CEST ] [InitParamAttributes.createInitFile:1816]  The value is UNDOTBS1\r\n[Thread-48] [ 2018-10-05 23:56:04.365 CEST ] [HADatabaseUtils.isGridMgmtDB:2842]  Mgmt DBName from srvm _mgmtdb\r\n[Thread-48] [ 2018-10-05 23:56:04.366 CEST ] [InstanceStep.executeImpl:280]  Checking Register with HA Listener\r\n[Thread-48] [ 2018-10-05 23:56:04.366 CEST ] [Host.noEntryinOratab:7194]  Check made for oratab arg passed............\r\n[Thread-48] [ 2018-10-05 23:56:04.366 CEST ] [Oratab.getTempFile:805]  Temp oratab: /tmp/oratab\r\n[Thread-48] [ 2018-10-05 23:56:04.368 CEST ] [InstanceStep.executeImpl:331]  Password file location prior to parsing /u01/app/oracle/12.1.0.2/db1-base/dbs/orapworcl\r\n[Thread-48] [ 2018-10-05 23:56:04.368 CEST ] [InstanceStep.executeImpl:333]  Password file location prior to parsing /u01/app/oracle/12.1.0.2/db1-base/dbs/orapworcl\r\n[Thread-48] [ 2018-10-05 23:56:04.368 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.368 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.368 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.368 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.369 CEST ] [CommonUtils.createPasswordFile:1243]  calling new orapwd for 11.1 or higher\r\n[Thread-48] [ 2018-10-05 23:56:04.369 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.369 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.369 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.369 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.369 CEST ] [CommonUtils.getPasswordFileCreateCmd:1182]  for new orapwd for 11.1 or higher\r\n[Thread-48] [ 2018-10-05 23:56:04.370 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.370 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.371 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.371 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.371 CEST ] [CommonUtils.getPasswordFileCreateCmd:1213]  /u01/app/oracle/12.1.0.2/db1-base/bin/orapwd\r\n[Thread-48] [ 2018-10-05 23:56:04.371 CEST ] [CommonUtils.getPasswordFileCreateCmd:1213]  file=/u01/app/oracle/12.1.0.2/db1-base/dbs/orapworcl\r\n[Thread-48] [ 2018-10-05 23:56:04.371 CEST ] [CommonUtils.getPasswordFileCreateCmd:1213]  force=y\r\n[Thread-48] [ 2018-10-05 23:56:04.371 CEST ] [CommonUtils.getPasswordFileCreateCmd:1213]  format=12\r\n[Thread-48] [ 2018-10-05 23:56:04.371 CEST ] [OsUtilsBase.execProg:2123]  beginning execProg with input array.\r\n[Thread-48] [ 2018-10-05 23:56:04.385 CEST ] [OsUtilsBase.execProg:2160]  finished execProg with input array. Status:0\r\n[Thread-48] [ 2018-10-05 23:56:04.386 CEST ] [InstanceStep.setASMAdminGID:373]  isWindows: false Storage Type: 0\r\n[Thread-48] [ 2018-10-05 23:56:04.386 CEST ] [BasicStep.configureSettings:353]  messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@4f2d26d2\r\n[Thread-48] [ 2018-10-05 23:56:04.386 CEST ] [CloneRmanRestoreStep.executeImpl:270]  Instance Creation went fine..........\r\n[Thread-48] [ 2018-10-05 23:56:04.386 CEST ] [CloneRmanRestoreStep.executeImpl:277]  db_recovery_file_dest=\"/u02/fra\"\r\n[Thread-48] [ 2018-10-05 23:56:04.386 CEST ] [CloneRmanRestoreStep.executeImpl:280]  db_recovery_file_dest_size=4560m\r\n[Thread-48] [ 2018-10-05 23:56:04.386 CEST ] [InitParameterFile.getLongValue:687]  May have unit. Stripping unit and trying with 4560\r\n[Thread-48] [ 2018-10-05 23:56:04.386 CEST ] [CloneRmanRestoreStep.executeImpl:297]  Setting NLS_LANG to AMERICA.AL32UTF8\r\n[Thread-48] [ 2018-10-05 23:56:04.387 CEST ] [SQLEngine.done:2278]  Done called\r\n[Thread-48] [ 2018-10-05 23:56:04.388 CEST ] [SQLEngine.reInitialize:785]  Reinitializing SQLEngine...\r\n[Thread-48] [ 2018-10-05 23:56:04.388 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.388 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.389 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.389 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.389 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.389 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.389 CEST ] [SQLPlusEngine.getCmmdParams:225]  m_home 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:56:04.389 CEST ] [SQLPlusEngine.getCmmdParams:226]  version > 112 true\r\n[Thread-48] [ 2018-10-05 23:56:04.389 CEST ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:56:04.389 CEST ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:56:04.389 CEST ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/db1-base\r\n[Thread-48] [ 2018-10-05 23:56:04.392 CEST ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase \r\n/u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:04.392 CEST ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:04.392 CEST ] [SQLEngine.getEnvParams:612]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8\r\n[Thread-48] [ 2018-10-05 23:56:04.392 CEST ] [SQLEngine.initialize:358]  Execing SQLPLUS/SVRMGR process...\r\n[Thread-48] [ 2018-10-05 23:56:04.407 CEST ] [SQLEngine.initialize:395]  m_bReaderStarted: false\r\n[Thread-48] [ 2018-10-05 23:56:04.408 CEST ] [SQLEngine.initialize:399]  Starting Reader Thread... \r\n[Thread-48] [ 2018-10-05 23:56:04.410 CEST ] [SQLEngine.initialize:448]  Waiting for m_bReaderStarted to be true \r\n[Thread-48] [ 2018-10-05 23:56:05.455 CEST ] [SQLEngine.setSpool:2056]  old Spool  = null\r\n[Thread-48] [ 2018-10-05 23:56:05.456 CEST ] [SQLEngine.setSpool:2057]  Setting Spool  = /u01/app/oracle/cfgtoollogs/dbca/orcl/CloneRmanRestore.log\r\n[Thread-48] [ 2018-10-05 23:56:05.457 CEST ] [SQLEngine.setSpool:2058]  Is spool appendable? --> true\r\n[Thread-48] [ 2018-10-05 23:56:05.458 CEST ] [CloneRmanRestoreStep.executeImpl:323]  _diag_hm_rc_enabled to false\r\n[Thread-48] [ 2018-10-05 23:56:05.458 CEST ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle/12.1.0.2/db1-base\r\n[Thread-48] [ 2018-10-05 23:56:05.459 CEST ] [CloneRmanRestoreStep.executeImpl:358]  Using control file: /u01/app/oracle/12.1.0.2/db1-base/assistants/dbca/templates/Seed_Database.ctl; sourceDBName: seeddata\r\n[Thread-48] [ 2018-10-05 23:56:05.460 CEST ] [OsUtilsBase.copyFile:1505]  OsUtilsBase.copyFile: \r\n[Thread-48] [ 2018-10-05 23:56:05.523 CEST ] [OsUtilsBase.copyFile:1553]  **write of file at destination complete...\r\n[Thread-48] [ 2018-10-05 23:56:05.526 CEST ] [OsUtilsBase.copyFile:1588]  **file copy status:= true\r\n[Thread-48] [ 2018-10-05 23:56:05.527 CEST ] [CloneRmanRestoreStep.executeImpl:383]  set _enable_pluggable_database to true\r\n[Thread-48] [ 2018-10-05 23:56:24.283 CEST ] [CloneRmanRestoreStep.executeImpl:399]  update db alert log File\r\n[Thread-48] [ 2018-10-05 23:56:24.316 CEST ] [CloneRmanRestoreStep.executeImpl:402]  Clearing v$datafile_copy entries\r\n[Thread-48] [ 2018-10-05 23:56:24.412 CEST ] [CloneRmanRestoreStep.executeImpl:404]  Clearing all backup entries\r\n[Thread-48] [ 2018-10-05 23:56:24.473 CEST ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:24.672 CEST ] [CloneRmanRestoreStep.executeImpl:457]  Getting the map of data file ids and names for restore\r\n[Thread-63] [ 2018-10-05 23:56:24.674 CEST ] [CloneRmanRestoreStep$ProgressTracker.run:860]  numBytesRead: 32768\r\n[Thread-63] [ 2018-10-05 23:56:24.676 CEST ] [CloneRmanRestoreStep$ProgressTracker.run:860]  numBytesRead: -1\r\n[Thread-48] [ 2018-10-05 23:56:24.682 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:56:24.685 CEST ] [CloneRmanRestoreStep.getDatafileIdNameMapping:586]  newname for datafile is  null for OMF mode\r\n[Thread-48] [ 2018-10-05 23:56:24.686 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:56:24.686 CEST ] [CloneRmanRestoreStep.getDatafileIdNameMapping:586]  newname for datafile is  null for OMF mode\r\n[Thread-48] [ 2018-10-05 23:56:24.687 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:56:24.688 CEST ] [CloneRmanRestoreStep.getDatafileIdNameMapping:586]  newname for datafile is  null for OMF mode\r\n[Thread-48] [ 2018-10-05 23:56:24.691 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:56:24.692 CEST ] [CloneRmanRestoreStep.getDatafileIdNameMapping:586]  newname for datafile is  null for OMF mode\r\n[Thread-48] [ 2018-10-05 23:56:24.776 CEST ] [RMANUtil.getRMANUtil:147]   creating new util instance\r\n[Thread-48] [ 2018-10-05 23:56:24.776 CEST ] [RMANUtil.getRMANUtil:148]   target sid orcl\r\n[Thread-48] [ 2018-10-05 23:56:24.776 CEST ] [CommonAttributes.replaceVariables:1402]  var2 /u01/app/oracle/12.1.0.2/db1-base\r\n[Thread-48] [ 2018-10-05 23:56:24.800 CEST ] [RMANEngine.getCmmdParams:343]   using rman from  /u01/app/oracle/12.1.0.2/db1-base/bin/rman\r\n[Thread-48] [ 2018-10-05 23:56:24.809 CEST ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:56:24.813 CEST ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:56:24.814 CEST ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/db1-base\r\n[Thread-48] [ 2018-10-05 23:56:24.872 CEST ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase \r\n/u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:24.872 CEST ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:56:24.876 CEST ] [RMANEngine.getEnvParams:436]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8\r\n[Thread-48] [ 2018-10-05 23:56:24.882 CEST ] [RMANEngine.initialize:195]  Execing RMAN process from initialize...\r\n[Thread-48] [ 2018-10-05 23:56:24.902 CEST ] [RMANEngine.initialize:221]  Created RMAN process from initialize...\r\n[Thread-48] [ 2018-10-05 23:56:24.908 CEST ] [RMANEngine.initialize:235]  Starting Reader Thread... \r\n[Thread-48] [ 2018-10-05 23:56:24.910 CEST ] [RMANEngine.initialize:239]  Started reader thread for RMAN process in initialize...\r\n[Thread-66] [ 2018-10-05 23:56:24.944 CEST ] [RMANEngine.run:803]  Started reader thread for RMAN process from initialize...\r\n[Thread-66] [ 2018-10-05 23:56:24.945 CEST ] [RMANEngine.run:807]  Set m_bReaderStarted  to true  in initialize...\r\n[Thread-48] [ 2018-10-05 23:56:24.959 CEST ] [RMANEngine.initialize:247]  Started error thread for RMAN process from initialize...\r\n[Thread-48] [ 2018-10-05 23:56:24.965 CEST ] [RMANEngine.initialize:252]  Created output writer for RMAN process from initialize...\r\n[Thread-48] [ 2018-10-05 23:56:24.975 CEST ] [RMANEngine.initialize:290]  Created Log writer for RMAN process from initialize...\r\n[Thread-48] [ 2018-10-05 23:56:24.975 CEST ] [RMANEngine.initialize:295]  Value of m_bReaderStarted in initialize=true\r\n[Thread-48] [ 2018-10-05 23:56:24.976 CEST ] [RMANEngine.initialize:310]  Made sure reader has started for RMAN process from initialize...\r\n[Thread-48] [ 2018-10-05 23:56:24.980 CEST ] [RMANEngine.connect:775]  NOT using OS auth to connect\r\n[Thread-48] [ 2018-10-05 23:56:24.984 CEST ] [RMANEngine.executeImpl:1186]  m_bExecQuery=false\r\n[Thread-48] [ 2018-10-05 23:56:24.987 CEST ] [RMANEngine.executeImpl:1194]  Command being written to rman process=connect target SYS/******\r\n[Thread-48] [ 2018-10-05 23:56:24.996 CEST ] [RMANEngine.executeImpl:1222]  Notify reader to start reading\r\n[Thread-66] [ 2018-10-05 23:56:25.010 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:25.010 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Recovery Manager: Release 12.1.0.2.0 - Production on Fri Oct 5 23:56:25 2018\r\n[Thread-66] [ 2018-10-05 23:56:25.011 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:25.011 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Copyright (c) 1982, 2014, Oracle and/or its affiliates.  All rights reserved.\r\n[Thread-66] [ 2018-10-05 23:56:25.079 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:25.083 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=RMAN> \r\n[Thread-66] [ 2018-10-05 23:56:25.737 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=connected to target database: SEEDDATA (DBID=4180948263, not open)\r\n[Thread-66] [ 2018-10-05 23:56:25.746 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:25.753 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=RMAN> \r\n[Thread-66] [ 2018-10-05 23:56:25.755 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=echo set on\r\n[Thread-66] [ 2018-10-05 23:56:25.768 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=set echo off;\r\n[Thread-66] [ 2018-10-05 23:56:25.770 CEST ] [RMANEngine.readSqlOutput:1033]  Notifying writer to proceed because m_bInterrupted=falsebNotify=truem_bReaderDone=falsem_bFatalErrorOccured=false\r\n[Thread-66] [ 2018-10-05 23:56:25.771 CEST ] [RMANEngine.run:817]  Return Value from readSqlOutput=set echo off;\r\n[Thread-48] [ 2018-10-05 23:56:25.771 CEST ] [RMANEngine.executeImpl:1234]  Completed wait from reader\r\n[Thread-48] [ 2018-10-05 23:56:25.772 CEST ] [RMANEngine.executeImpl:1281]  DONE EXECUTING GIVEN COMMAND=connect target SYS/******\r\n[Thread-48] [ 2018-10-05 23:56:25.784 CEST ] [RMANUtil.getRMANEngine:197]  RMANUtil spooling to=/u01/app/oracle/cfgtoollogs/dbca/orcl/rmanUtil\r\n[Thread-48] [ 2018-10-05 23:56:25.785 CEST ] [RMANUtil.catalogBackup:471]   catalog command CATALOG START WITH   '/u01/app/oracle/12.1.0.2/db1-base/assistants/dbca/templates//Seed_Database.dfb'  NOPROMPT  \r\n[Thread-48] [ 2018-10-05 23:56:25.786 CEST ] [RMANEngine.executeImpl:1186]  m_bExecQuery=false\r\n[Thread-48] [ 2018-10-05 23:56:25.786 CEST ] [RMANEngine.executeImpl:1194]  Command being written to rman process=CATALOG START WITH   '/u01/app/oracle/12.1.0.2/db1-base/assistants/dbca/templates//Seed_Database.dfb'  NOPROMPT;\r\n[Thread-48] [ 2018-10-05 23:56:25.786 CEST ] [RMANEngine.executeImpl:1222]  Notify reader to start reading\r\n[Thread-66] [ 2018-10-05 23:56:25.788 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=echo set off\r\n[Thread-66] [ 2018-10-05 23:56:25.802 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:25.802 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=RMAN> \r\n[Thread-66] [ 2018-10-05 23:56:25.836 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Starting implicit crosscheck backup at 05-OCT-18\r\n[Thread-66] [ 2018-10-05 23:56:25.850 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=using target database control file instead of recovery catalog\r\n[Thread-66] [ 2018-10-05 23:56:26.083 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=allocated channel: ORA_DISK_1\r\n[Thread-66] [ 2018-10-05 23:56:26.086 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: SID=23 device type=DISK\r\n[Thread-66] [ 2018-10-05 23:56:26.280 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Finished implicit crosscheck backup at 05-OCT-18\r\n[Thread-66] [ 2018-10-05 23:56:26.281 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:26.282 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Starting implicit crosscheck copy at 05-OCT-18\r\n[Thread-66] [ 2018-10-05 23:56:26.283 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=using channel ORA_DISK_1\r\n[Thread-66] [ 2018-10-05 23:56:26.299 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Finished implicit crosscheck copy at 05-OCT-18\r\n[Thread-66] [ 2018-10-05 23:56:26.301 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:26.302 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=searching for all files in the recovery area\r\n[Thread-66] [ 2018-10-05 23:56:26.315 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=cataloging files...\r\n[Thread-66] [ 2018-10-05 23:56:26.523 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=no files cataloged\r\n[Thread-66] [ 2018-10-05 23:56:26.526 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:26.671 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=searching for all files that match the pattern /u01/app/oracle/12.1.0.2/db1-base/assistants/dbca/templates//Seed_Database.dfb\r\n[Thread-66] [ 2018-10-05 23:56:26.682 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:26.683 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=List of Files Unknown to the Database\r\n[Thread-66] [ 2018-10-05 23:56:26.685 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output======================================\r\n[Thread-66] [ 2018-10-05 23:56:26.686 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=File Name: /u01/app/oracle/12.1.0.2/db1-base/assistants/dbca/templates/Seed_Database.dfb\r\n[Thread-66] [ 2018-10-05 23:56:26.690 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=cataloging files...\r\n[Thread-66] [ 2018-10-05 23:56:26.776 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=cataloging done\r\n[Thread-66] [ 2018-10-05 23:56:26.779 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:26.781 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=List of Cataloged Files\r\n[Thread-66] [ 2018-10-05 23:56:26.786 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output========================\r\n[Thread-66] [ 2018-10-05 23:56:26.790 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=File Name: /u01/app/oracle/12.1.0.2/db1-base/assistants/dbca/templates/Seed_Database.dfb\r\n[Thread-66] [ 2018-10-05 23:56:26.861 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:26.866 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=RMAN> \r\n[Thread-66] [ 2018-10-05 23:56:26.867 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=echo set on\r\n[Thread-66] [ 2018-10-05 23:56:26.873 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=set echo off;\r\n[Thread-66] [ 2018-10-05 23:56:26.877 CEST ] [RMANEngine.readSqlOutput:1033]  Notifying writer to proceed because m_bInterrupted=falsebNotify=truem_bReaderDone=falsem_bFatalErrorOccured=false\r\n[Thread-48] [ 2018-10-05 23:56:26.878 CEST ] [RMANEngine.executeImpl:1234]  Completed wait from reader\r\n[Thread-48] [ 2018-10-05 23:56:26.879 CEST ] [RMANEngine.executeImpl:1281]  DONE EXECUTING GIVEN COMMAND=CATALOG START WITH   '/u01/app/oracle/12.1.0.2/db1-base/assistants/dbca/templates//Seed_Database.dfb'  NOPROMPT;\r\n[Thread-48] [ 2018-10-05 23:56:26.879 CEST ] [RMANUtil.catalogBackup:474]  done catalog\r\n[Thread-48] [ 2018-10-05 23:56:26.879 CEST ] [RMANUtil.getRestoreCommandForDataFiles:296]   generate set new name/restore command per datafile basis\r\n[Thread-66] [ 2018-10-05 23:56:26.878 CEST ] [RMANEngine.run:817]  Return Value from readSqlOutput=set echo off;\r\n[Thread-48] [ 2018-10-05 23:56:26.881 CEST ] [RMANUtil.restoreDataFilesFromBackup:343]   set new name command RUN {  \r\n\r\nset newname for datafile 3 to new; \r\n\r\nset newname for datafile 1 to new; \r\n\r\nset newname for datafile 6 to new; \r\n\r\nset newname for datafile 4 to new; \r\n\r\nrestore datafile 3; \r\n\r\nrestore datafile 1; \r\n\r\nrestore datafile 6; \r\n\r\nrestore datafile 4; }\r\n[Thread-48] [ 2018-10-05 23:56:26.884 CEST ] [RMANEngine.executeImpl:1186]  m_bExecQuery=false\r\n[Thread-48] [ 2018-10-05 23:56:26.885 CEST ] [RMANEngine.executeImpl:1194]  Command being written to rman process=RUN {  \r\n\r\nset newname for datafile 3 to new; \r\n\r\nset newname for datafile 1 to new; \r\n\r\nset newname for datafile 6 to new; \r\n\r\nset newname for datafile 4 to new; \r\n\r\nrestore datafile 3; \r\n\r\nrestore datafile 1; \r\n\r\nrestore datafile 6; \r\n\r\nrestore datafile 4; }\r\n[Thread-48] [ 2018-10-05 23:56:26.886 CEST ] [RMANEngine.executeImpl:1222]  Notify reader to start reading\r\n[Thread-66] [ 2018-10-05 23:56:26.887 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=echo set off\r\n[Thread-66] [ 2018-10-05 23:56:26.888 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:26.889 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=RMAN> 2> 3> 4> 5> 6> 7> 8> 9> 10> 11> 12> 13> 14> 15> 16> 17> \r\n[Thread-66] [ 2018-10-05 23:56:26.890 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=executing command: SET NEWNAME\r\n[Thread-66] [ 2018-10-05 23:56:26.908 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:26.909 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=executing command: SET NEWNAME\r\n[Thread-66] [ 2018-10-05 23:56:26.920 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:26.921 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=executing command: SET NEWNAME\r\n[Thread-66] [ 2018-10-05 23:56:26.938 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:26.939 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=executing command: SET NEWNAME\r\n[Thread-66] [ 2018-10-05 23:56:26.954 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:26.983 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Starting restore at 05-OCT-18\r\n[Thread-66] [ 2018-10-05 23:56:26.991 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=using channel ORA_DISK_1\r\n[Thread-66] [ 2018-10-05 23:56:27.016 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:27.122 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: starting datafile backup set restore\r\n[Thread-66] [ 2018-10-05 23:56:27.124 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: specifying datafile(s) to restore from backup set\r\n[Thread-66] [ 2018-10-05 23:56:27.126 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: restoring datafile 00003 to /u02/oradata/ORCL/datafile/o1_mf_sysaux_%u_.dbf\r\n[Thread-66] [ 2018-10-05 23:56:27.127 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: reading from backup piece /u01/app/oracle/12.1.0.2/db1-base/assistants/dbca/templates/Seed_Database.dfb\r\n[Thread-63] [ 2018-10-05 23:56:34.723 CEST ] [CloneRmanRestoreStep$ProgressTracker.run:860]  numBytesRead: -1\r\n[Thread-63] [ 2018-10-05 23:56:44.763 CEST ] [CloneRmanRestoreStep$ProgressTracker.run:860]  numBytesRead: -1\r\n[Thread-66] [ 2018-10-05 23:56:52.234 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: piece handle=/u01/app/oracle/12.1.0.2/db1-base/assistants/dbca/templates/Seed_Database.dfb tag=TAG20140707T070542\r\n[Thread-66] [ 2018-10-05 23:56:52.277 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: restored backup piece 1\r\n[Thread-66] [ 2018-10-05 23:56:52.326 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: restore complete, elapsed time: 00:00:25\r\n[Thread-66] [ 2018-10-05 23:56:52.368 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Finished restore at 05-OCT-18\r\n[Thread-66] [ 2018-10-05 23:56:52.468 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:52.509 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Starting restore at 05-OCT-18\r\n[Thread-66] [ 2018-10-05 23:56:52.531 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=using channel ORA_DISK_1\r\n[Thread-66] [ 2018-10-05 23:56:52.596 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:56:52.742 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: starting datafile backup set restore\r\n[Thread-66] [ 2018-10-05 23:56:52.756 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: specifying datafile(s) to restore from backup set\r\n[Thread-66] [ 2018-10-05 23:56:52.766 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: restoring datafile 00001 to /u02/oradata/ORCL/datafile/o1_mf_system_%u_.dbf\r\n[Thread-66] [ 2018-10-05 23:56:52.780 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: reading from backup piece /u01/app/oracle/12.1.0.2/db1-base/assistants/dbca/templates/Seed_Database.dfb\r\n[Thread-63] [ 2018-10-05 23:56:54.783 CEST ] [CloneRmanRestoreStep$ProgressTracker.run:860]  numBytesRead: 817\r\n[Thread-63] [ 2018-10-05 23:57:04.883 CEST ] [CloneRmanRestoreStep$ProgressTracker.run:860]  numBytesRead: -1\r\n[Thread-63] [ 2018-10-05 23:57:14.913 CEST ] [CloneRmanRestoreStep$ProgressTracker.run:860]  numBytesRead: -1\r\n[Thread-63] [ 2018-10-05 23:57:24.924 CEST ] [CloneRmanRestoreStep$ProgressTracker.run:860]  numBytesRead: 1068\r\n[Thread-66] [ 2018-10-05 23:57:27.804 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: piece handle=/u01/app/oracle/12.1.0.2/db1-base/assistants/dbca/templates/Seed_Database.dfb tag=TAG20140707T070542\r\n[Thread-66] [ 2018-10-05 23:57:27.809 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: restored backup piece 1\r\n[Thread-66] [ 2018-10-05 23:57:27.821 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: restore complete, elapsed time: 00:00:35\r\n[Thread-66] [ 2018-10-05 23:57:27.849 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Finished restore at 05-OCT-18\r\n[Thread-66] [ 2018-10-05 23:57:27.928 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:57:27.965 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Starting restore at 05-OCT-18\r\n[Thread-66] [ 2018-10-05 23:57:27.971 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=using channel ORA_DISK_1\r\n[Thread-66] [ 2018-10-05 23:57:27.999 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:57:28.117 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: starting datafile backup set restore\r\n[Thread-66] [ 2018-10-05 23:57:28.119 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: specifying datafile(s) to restore from backup set\r\n[Thread-66] [ 2018-10-05 23:57:28.120 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: restoring datafile 00006 to /u02/oradata/ORCL/datafile/o1_mf_users_%u_.dbf\r\n[Thread-66] [ 2018-10-05 23:57:28.126 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: reading from backup piece /u01/app/oracle/12.1.0.2/db1-base/assistants/dbca/templates/Seed_Database.dfb\r\n[Thread-66] [ 2018-10-05 23:57:29.126 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: piece handle=/u01/app/oracle/12.1.0.2/db1-base/assistants/dbca/templates/Seed_Database.dfb tag=TAG20140707T070542\r\n[Thread-66] [ 2018-10-05 23:57:29.154 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: restored backup piece 1\r\n[Thread-66] [ 2018-10-05 23:57:29.154 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: restore complete, elapsed time: 00:00:01\r\n[Thread-66] [ 2018-10-05 23:57:29.163 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Finished restore at 05-OCT-18\r\n[Thread-66] [ 2018-10-05 23:57:29.224 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:57:29.257 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Starting restore at 05-OCT-18\r\n[Thread-66] [ 2018-10-05 23:57:29.266 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=using channel ORA_DISK_1\r\n[Thread-66] [ 2018-10-05 23:57:29.277 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:57:29.378 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: starting datafile backup set restore\r\n[Thread-66] [ 2018-10-05 23:57:29.384 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: specifying datafile(s) to restore from backup set\r\n[Thread-66] [ 2018-10-05 23:57:29.385 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: restoring datafile 00004 to /u02/oradata/ORCL/datafile/o1_mf_undotbs1_%u_.dbf\r\n[Thread-66] [ 2018-10-05 23:57:29.386 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: reading from backup piece /u01/app/oracle/12.1.0.2/db1-base/assistants/dbca/templates/Seed_Database.dfb\r\n[Thread-66] [ 2018-10-05 23:57:30.397 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: piece handle=/u01/app/oracle/12.1.0.2/db1-base/assistants/dbca/templates/Seed_Database.dfb tag=TAG20140707T070542\r\n[Thread-66] [ 2018-10-05 23:57:30.399 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: restored backup piece 1\r\n[Thread-66] [ 2018-10-05 23:57:30.404 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=channel ORA_DISK_1: restore complete, elapsed time: 00:00:01\r\n[Thread-66] [ 2018-10-05 23:57:30.422 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=Finished restore at 05-OCT-18\r\n[Thread-66] [ 2018-10-05 23:57:30.521 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=\r\n[Thread-66] [ 2018-10-05 23:57:30.525 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=RMAN> \r\n[Thread-66] [ 2018-10-05 23:57:30.526 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=echo set on\r\n[Thread-66] [ 2018-10-05 23:57:30.531 CEST ] [RMANEngine.readSqlOutput:889]  Log RMAN Output=set echo off;\r\n[Thread-66] [ 2018-10-05 23:57:30.531 CEST ] [RMANEngine.readSqlOutput:1033]  Notifying writer to proceed because m_bInterrupted=falsebNotify=truem_bReaderDone=falsem_bFatalErrorOccured=false\r\n[Thread-66] [ 2018-10-05 23:57:30.534 CEST ] [RMANEngine.run:817]  Return Value from readSqlOutput=set echo off;\r\n[Thread-48] [ 2018-10-05 23:57:30.534 CEST ] [RMANEngine.executeImpl:1234]  Completed wait from reader\r\n[Thread-48] [ 2018-10-05 23:57:30.535 CEST ] [RMANEngine.executeImpl:1281]  DONE EXECUTING GIVEN COMMAND=RUN {  \r\n\r\nset newname for datafile 3 to new; \r\n\r\nset newname for datafile 1 to new; \r\n\r\nset newname for datafile 6 to new; \r\n\r\nset newname for datafile 4 to new; \r\n\r\nrestore datafile 3; \r\n\r\nrestore datafile 1; \r\n\r\nrestore datafile 6; \r\n\r\nrestore datafile 4; }\r\n[Thread-48] [ 2018-10-05 23:57:30.537 CEST ] [RMANUtil.restoreDataFilesFromBackup:346]  done with set new name\r\n[Thread-48] [ 2018-10-05 23:57:30.587 CEST ] [CloneRmanRestoreStep.setDataFileNames:638]  Updated datafile name:/u02/oradata/ORCL/datafile/o1_mf_sysaux_fvhqjc4g_.dbf\r\n[Thread-48] [ 2018-10-05 23:57:30.590 CEST ] [CloneRmanRestoreStep.setDataFileNames:638]  Updated datafile name:/u02/oradata/ORCL/datafile/o1_mf_system_fvhqk4qt_.dbf\r\n[Thread-48] [ 2018-10-05 23:57:30.592 CEST ] [CloneRmanRestoreStep.setDataFileNames:638]  Updated datafile name:/u02/oradata/ORCL/datafile/o1_mf_undotbs1_fvhql9dj_.dbf\r\n[Thread-48] [ 2018-10-05 23:57:30.596 CEST ] [CloneRmanRestoreStep.setDataFileNames:638]  Updated datafile name:/u02/oradata/ORCL/datafile/o1_mf_users_fvhql85w_.dbf\r\n[Thread-48] [ 2018-10-05 23:57:30.596 CEST ] [CloneRmanRestoreStep.executeImpl:508]  Restored datafiles\r\n[Thread-48] [ 2018-10-05 23:57:30.596 CEST ] [SQLEngine.spoolOff:2124]  Setting spool off = /u01/app/oracle/cfgtoollogs/dbca/orcl/CloneRmanRestore.log\r\n[Thread-48] [ 2018-10-05 23:57:30.597 CEST ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/app/oracle/cfgtoollogs/dbca/orcl/initorclTempOMF.ora\r\n[Thread-48] [ 2018-10-05 23:57:30.605 CEST ] [BasicStep.configureSettings:353]  messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@4f2d26d2\r\n[Thread-48] [ 2018-10-05 23:57:30.608 CEST ] [StepContext$ModeRunner.run:2873]  ---- Progress Needed:=true\r\n[Thread-48] [ 2018-10-05 23:57:30.610 CEST ] [BasicStep.execute:250]  Executing Step : CLONE_DB_CREATION\r\n[Thread-48] [ 2018-10-05 23:57:30.611 CEST ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-01503\r\n[Thread-48] [ 2018-10-05 23:57:30.611 CEST ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-01034\r\n[Thread-48] [ 2018-10-05 23:57:30.612 CEST ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-01092\r\n[Thread-48] [ 2018-10-05 23:57:30.612 CEST ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-03113\r\n[Thread-48] [ 2018-10-05 23:57:30.612 CEST ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-03114\r\n[Thread-48] [ 2018-10-05 23:57:30.612 CEST ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-19502\r\n[Thread-48] [ 2018-10-05 23:57:30.613 CEST ] [StepErrorHandler.setFatalErrors:323]  setting Fatal Error: ORA-01219\r\n[Thread-48] [ 2018-10-05 23:57:30.613 CEST ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01109\r\n[Thread-48] [ 2018-10-05 23:57:30.613 CEST ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-12712\r\n[Thread-48] [ 2018-10-05 23:57:30.614 CEST ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-00942\r\n[Thread-48] [ 2018-10-05 23:57:30.614 CEST ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-04043\r\n[Thread-48] [ 2018-10-05 23:57:30.614 CEST ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-44304\r\n[Thread-48] [ 2018-10-05 23:57:30.615 CEST ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-44305\r\n[Thread-48] [ 2018-10-05 23:57:30.615 CEST ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-06512\r\n[Thread-48] [ 2018-10-05 23:57:30.617 CEST ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-06510\r\n[Thread-48] [ 2018-10-05 23:57:30.618 CEST ] [StepErrorHandler.setIgnorableErrors:251]  setting Ignorable Error: ORA-01403\r\n[Thread-48] [ 2018-10-05 23:57:30.618 CEST ] [BasicStep.configureSettings:353]  messageHandler being set=null\r\n[Thread-48] [ 2018-10-05 23:57:30.691 CEST ] [SQLEngine.setSpool:2056]  old Spool  = null\r\n[Thread-48] [ 2018-10-05 23:57:30.691 CEST ] [SQLEngine.setSpool:2057]  Setting Spool  = /u01/app/oracle/cfgtoollogs/dbca/orcl/cloneDBCreation.log\r\n[Thread-48] [ 2018-10-05 23:57:30.692 CEST ] [SQLEngine.setSpool:2058]  Is spool appendable? --> true\r\n[Thread-48] [ 2018-10-05 23:57:30.694 CEST ] [CloneDBCreationStep.executeImpl:356]   size of datfiles in create db 4\r\n[Thread-48] [ 2018-10-05 23:57:30.695 CEST ] [CloneDBCreationStep.executeImpl:382]  a.createCtlFileSql = Create controlfile reuse set database \"orcl\"\r\nMAXINSTANCES 8\r\nMAXLOGHISTORY 1\r\nMAXLOGFILES 16\r\nMAXLOGMEMBERS 3\r\nMAXDATAFILES 100\r\nDatafile \r\n'/u02/oradata/ORCL/datafile/o1_mf_sysaux_fvhqjc4g_.dbf',\r\n'/u02/oradata/ORCL/datafile/o1_mf_system_fvhqk4qt_.dbf',\r\n[Thread-48] [ 2018-10-05 23:57:30.695 CEST ] [CloneDBCreationStep.executeImpl:386]  b.createCtlFileSql = Create controlfile reuse set database \"orcl\"\r\nMAXINSTANCES 8\r\nMAXLOGHISTORY 1\r\nMAXLOGFILES 16\r\nMAXLOGMEMBERS 3\r\nMAXDATAFILES 100\r\nDatafile \r\n'/u02/oradata/ORCL/datafile/o1_mf_sysaux_fvhqjc4g_.dbf',\r\n'/u02/oradata/ORCL/datafile/o1_mf_system_fvhqk4qt_.dbf',\r\n'/u02/oradata/ORCL/datafile/o1_mf_undotbs1_fvhql9dj_.dbf',\r\n[Thread-48] [ 2018-10-05 23:57:34.858 CEST ] [CloneDBCreationStep.executeImpl:400]  pfile for startup=/u01/app/oracle/admin/orcl/pfile/init.ora\r\n[Thread-48] [ 2018-10-05 23:57:48.409 CEST ] [CloneDBCreationStep.executeImpl:405]  createCtl=Create controlfile reuse set database \"orcl\"\r\nMAXINSTANCES 8\r\nMAXLOGHISTORY 1\r\nMAXLOGFILES 16\r\nMAXLOGMEMBERS 3\r\nMAXDATAFILES 100\r\nDatafile \r\n'/u02/oradata/ORCL/datafile/o1_mf_sysaux_fvhqjc4g_.dbf',\r\n'/u02/oradata/ORCL/datafile/o1_mf_system_fvhqk4qt_.dbf',\r\n'/u02/oradata/ORCL/datafile/o1_mf_undotbs1_fvhql9dj_.dbf',\r\n'/u02/oradata/ORCL/datafile/o1_mf_users_fvhql85w_.dbf',\r\n[Thread-48] [ 2018-10-05 23:57:48.469 CEST ] [CloneDBCreationStep.executeImpl:412]  crtCtl=Create controlfile reuse set database \"orcl\"\r\nMAXINSTANCES 8\r\nMAXLOGHISTORY 1\r\nMAXLOGFILES 16\r\nMAXLOGMEMBERS 3\r\nMAXDATAFILES 100\r\nDatafile \r\n'/u02/oradata/ORCL/datafile/o1_mf_sysaux_fvhqjc4g_.dbf',\r\n'/u02/oradata/ORCL/datafile/o1_mf_system_fvhqk4qt_.dbf',\r\n'/u02/oradata/ORCL/datafile/o1_mf_undotbs1_fvhql9dj_.dbf',\r\n'/u02/oradata/ORCL/datafile/o1_mf_users_fvhql85w_.dbf'\r\n[Thread-48] [ 2018-10-05 23:57:48.523 CEST ] [CloneDBCreationStep.executeImpl:428]  Length of OriginalRedoLogsGrNames=3\r\n[Thread-48] [ 2018-10-05 23:57:48.577 CEST ] [RedoLogGroupSQLGenerator.filespecsClause:68]  LOG FILE size in MB = 50\r\n[Thread-48] [ 2018-10-05 23:57:48.579 CEST ] [RedoLogGroupSQLGenerator.filespecsClause:74]  RedoFile space clause ('/u02/oradata/{DB_UNIQUE_NAME}/redo01.log') SIZE 50M\r\n[Thread-48] [ 2018-10-05 23:57:48.580 CEST ] [CloneDBCreationStep.executeImpl:437]  0th redoLogText = GROUP 1 ('/u02/oradata/{DB_UNIQUE_NAME}/redo01.log') SIZE 50M\r\n[Thread-48] [ 2018-10-05 23:57:48.582 CEST ] [RedoLogGroupSQLGenerator.filespecsClause:68]  LOG FILE size in MB = 50\r\n[Thread-48] [ 2018-10-05 23:57:48.583 CEST ] [RedoLogGroupSQLGenerator.filespecsClause:74]  RedoFile space clause ('/u02/oradata/{DB_UNIQUE_NAME}/redo02.log') SIZE 50M\r\n[Thread-48] [ 2018-10-05 23:57:48.584 CEST ] [CloneDBCreationStep.executeImpl:437]  1th redoLogText = GROUP 2 ('/u02/oradata/{DB_UNIQUE_NAME}/redo02.log') SIZE 50M\r\n[Thread-48] [ 2018-10-05 23:57:48.585 CEST ] [RedoLogGroupSQLGenerator.filespecsClause:68]  LOG FILE size in MB = 50\r\n[Thread-48] [ 2018-10-05 23:57:48.585 CEST ] [RedoLogGroupSQLGenerator.filespecsClause:74]  RedoFile space clause ('/u02/oradata/{DB_UNIQUE_NAME}/redo03.log') SIZE 50M\r\n[Thread-48] [ 2018-10-05 23:57:48.585 CEST ] [CloneDBCreationStep.executeImpl:437]  2th redoLogText = GROUP 3 ('/u02/oradata/{DB_UNIQUE_NAME}/redo03.log') SIZE 50M\r\n[Thread-48] [ 2018-10-05 23:57:48.590 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:57:48.597 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:57:48.599 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:57:48.601 CEST ] [CloneDBCreationStep.executeImpl:458]  createCTLSql=Create controlfile reuse set database \"orcl\"\r\nMAXINSTANCES 8\r\nMAXLOGHISTORY 1\r\nMAXLOGFILES 16\r\nMAXLOGMEMBERS 3\r\nMAXDATAFILES 100\r\nDatafile \r\n'/u02/oradata/ORCL/datafile/o1_mf_sysaux_fvhqjc4g_.dbf',\r\n'/u02/oradata/ORCL/datafile/o1_mf_system_fvhqk4qt_.dbf',\r\n'/u02/oradata/ORCL/datafile/o1_mf_undotbs1_fvhql9dj_.dbf',\r\n'/u02/oradata/ORCL/datafile/o1_mf_users_fvhql85w_.dbf'\r\nLOGFILE GROUP 1 ('/u02/oradata/orcl/redo01.log') SIZE 50M,\r\nGROUP 2 ('/u02/oradata/orcl/redo02.log') SIZE 50M,\r\nGROUP 3 ('/u02/oradata/orcl/redo03.log') SIZE 50M RESETLOGS;\r\n[Thread-48] [ 2018-10-05 23:57:48.906 CEST ] [SQLEngine.done:2278]  Done called\r\n[Thread-48] [ 2018-10-05 23:57:49.089 CEST ] [SQLEngine.spoolOff:2124]  Setting spool off = /u01/app/oracle/cfgtoollogs/dbca/orcl/cloneDBCreation.log\r\n[Thread-48] [ 2018-10-05 23:57:49.136 CEST ] [BasicStep.configureSettings:353]  messageHandler being set=oracle.sysman.assistants.util.SilentMessageHandler@4f2d26d2\r\n[Thread-48] [ 2018-10-05 23:57:49.138 CEST ] [StepContext$ModeRunner.run:2899]  ORA-01503: CREATE CONTROLFILE failed\r\nORA-00200: control file could not be created\r\nORA-00202: control file: '/u02/oradata/orcl/control01.ctl'\r\nORA-27040: file create error, unable to create file\r\n\r\noracle.sysman.assistants.dbca.backend.CloneDBCreationStep.executeImpl(CloneDBCreationStep.java:1134)\r\noracle.sysman.assistants.util.step.BasicStep.execute(BasicStep.java:259)\r\noracle.sysman.assistants.util.step.Step.execute(Step.java:135)\r\noracle.sysman.assistants.util.step.StepContext$ModeRunner.run(StepContext.java:2883)\r\njava.lang.Thread.run(Thread.java:682)\r\n[Thread-48] [ 2018-10-05 23:57:49.145 CEST ] [SQLEngine.done:2278]  Done called\r\n[Thread-48] [ 2018-10-05 23:57:49.153 CEST ] [Host.createStepSQLInterface:8605]  sid =orcl\r\n[Thread-48] [ 2018-10-05 23:57:49.154 CEST ] [Host.createStepSQLInterface:8606]  sidOrConnectString orcl\r\n[Thread-48] [ 2018-10-05 23:57:49.161 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: null\r\n[Thread-48] [ 2018-10-05 23:57:49.297 CEST ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS\r\n[Thread-48] [ 2018-10-05 23:57:49.299 CEST ] [OracleHome.getVersion:1010]  Homeinfo /u01/app/oracle/12.1.0.2/db1-base,1\r\n[Thread-48] [ 2018-10-05 23:57:49.777 CEST ] [OracleHome.getVersion:1038]  OracleHome.server.getVersion Version: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:57:49.780 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:57:49.780 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:57:49.780 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:57:49.781 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:57:49.782 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:57:49.782 CEST ] [SQLPlusEngine.getCmmdParams:225]  m_home 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:57:49.782 CEST ] [SQLPlusEngine.getCmmdParams:226]  version > 112 true\r\n[Thread-48] [ 2018-10-05 23:57:49.794 CEST ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:57:49.797 CEST ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:57:49.800 CEST ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/db1-base\r\n[Thread-48] [ 2018-10-05 23:57:49.875 CEST ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase \r\n/u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:57:49.875 CEST ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:57:49.875 CEST ] [SQLEngine.getEnvParams:612]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8\r\n[Thread-48] [ 2018-10-05 23:57:49.879 CEST ] [SQLEngine.initialize:358]  Execing SQLPLUS/SVRMGR process...\r\n[Thread-48] [ 2018-10-05 23:57:49.881 CEST ] [SQLEngine.initialize:395]  m_bReaderStarted: false\r\n[Thread-48] [ 2018-10-05 23:57:49.881 CEST ] [SQLEngine.initialize:399]  Starting Reader Thread... \r\n[Thread-48] [ 2018-10-05 23:57:50.056 CEST ] [CloneRmanRestoreStep.cancel:722]  Cleaning up partially extracted files failed\r\n[Thread-48] [ 2018-10-05 23:57:50.443 CEST ] [SQLEngine.done:2278]  Done called\r\n[Thread-48] [ 2018-10-05 23:57:50.458 CEST ] [CloneRmanRestoreStep.cancel:737]  RMAN cleanup failed\r\n[Thread-48] [ 2018-10-05 23:57:50.458 CEST ] [CloneRmanRestoreStep.cancel:738]  ORA-01507: database not mounted\r\n\r\noracle.sysman.assistants.util.sqlEngine.SQLEngine.executeImpl(SQLEngine.java:1739)\r\noracle.sysman.assistants.util.sqlEngine.SQLEngine.executeQuery(SQLEngine.java:878)\r\noracle.sysman.assistants.dbca.backend.CloneRmanRestoreStep.generateCancelScript(CloneRmanRestoreStep.java:772)\r\noracle.sysman.assistants.dbca.backend.CloneRmanRestoreStep.cancel(CloneRmanRestoreStep.java:732)\r\noracle.sysman.assistants.util.step.StepContext$ModeRunner.cancel(StepContext.java:2979)\r\noracle.sysman.assistants.util.step.StepContext$ModeRunner.run(StepContext.java:2940)\r\njava.lang.Thread.run(Thread.java:682)\r\n[Thread-48] [ 2018-10-05 23:57:50.461 CEST ] [CloneDBCreationStep.cancel:1178]  CloneDBCreationStep.cancel(): nodeList= null\r\n[Thread-48] [ 2018-10-05 23:57:50.462 CEST ] [SQLEngine.reInitialize:785]  Reinitializing SQLEngine...\r\n[Thread-48] [ 2018-10-05 23:57:50.462 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:57:50.462 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:57:50.462 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:57:50.462 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:57:50.463 CEST ] [OracleHome.getVersion:991]  OracleHome.getVersion called.  Current Version: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:57:50.463 CEST ] [OracleHome.getVersion:1059]  Current Version From Inventory: 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:57:50.463 CEST ] [SQLPlusEngine.getCmmdParams:225]  m_home 12.1.0.2.0\r\n[Thread-48] [ 2018-10-05 23:57:50.464 CEST ] [SQLPlusEngine.getCmmdParams:226]  version > 112 true\r\n[Thread-48] [ 2018-10-05 23:57:50.464 CEST ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:57:50.464 CEST ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[Thread-48] [ 2018-10-05 23:57:50.464 CEST ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/db1-base\r\n[Thread-48] [ 2018-10-05 23:57:50.475 CEST ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase \r\n/u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:57:50.475 CEST ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle\r\n[Thread-48] [ 2018-10-05 23:57:50.475 CEST ] [SQLEngine.getEnvParams:612]  NLS_LANG: AMERICAN_AMERICA.AL32UTF8\r\n[Thread-48] [ 2018-10-05 23:57:50.476 CEST ] [SQLEngine.initialize:358]  Execing SQLPLUS/SVRMGR process...\r\n[Thread-48] [ 2018-10-05 23:57:50.478 CEST ] [SQLEngine.initialize:395]  m_bReaderStarted: false\r\n[Thread-48] [ 2018-10-05 23:57:50.478 CEST ] [SQLEngine.initialize:399]  Starting Reader Thread... \r\n[Thread-48] [ 2018-10-05 23:57:50.481 CEST ] [SQLEngine.initialize:448]  Waiting for m_bReaderStarted to be true \r\n[Thread-48] [ 2018-10-05 23:57:54.882 CEST ] [Oratab.getTempFile:805]  Temp oratab: /tmp/oratab\r\n[Thread-48] [ 2018-10-05 23:57:54.890 CEST ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/app/oracle/12.1.0.2/db1-base/dbs/orapworcl\r\n[Thread-48] [ 2018-10-05 23:57:54.909 CEST ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/app/oracle/12.1.0.2/db1-base/dbs/initorcl.ora\r\n[Thread-48] [ 2018-10-05 23:57:54.909 CEST ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/app/oracle/admin/orcl/pfile/init.ora\r\n[Thread-48] [ 2018-10-05 23:57:54.914 CEST ] [CloneDBCreationStep.cancel:1225]  CloneDBCreationStep.cancel(): bRaw=false\r\n[Thread-48] [ 2018-10-05 23:57:54.916 CEST ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u02/oradata/ORCL/datafile/o1_mf_sysaux_fvhqjc4g_.dbf\r\n[Thread-48] [ 2018-10-05 23:57:54.918 CEST ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u02/oradata/ORCL/datafile/o1_mf_system_fvhqk4qt_.dbf\r\n[Thread-48] [ 2018-10-05 23:57:54.918 CEST ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u02/oradata/ORCL/datafile/o1_mf_undotbs1_fvhql9dj_.dbf\r\n[Thread-48] [ 2018-10-05 23:57:54.918 CEST ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u02/oradata/ORCL/datafile/o1_mf_users_fvhql85w_.dbf\r\n[Thread-48] [ 2018-10-05 23:57:54.918 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:57:54.918 CEST ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u02/oradata/orcl/redo01.log\r\n[Thread-48] [ 2018-10-05 23:57:54.919 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:57:54.919 CEST ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u02/oradata/orcl/redo02.log\r\n[Thread-48] [ 2018-10-05 23:57:54.919 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:57:54.919 CEST ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u02/oradata/orcl/redo03.log\r\n[Thread-48] [ 2018-10-05 23:57:54.919 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:57:54.919 CEST ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u02/oradata/orcl/control01.ctl\r\n[Thread-48] [ 2018-10-05 23:57:54.919 CEST ] [CommonAttributes.replaceVariables:1402]  var2 orcl\r\n[Thread-48] [ 2018-10-05 23:57:54.919 CEST ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u02/fra/orcl/control02.ctl\r\n[Thread-48] [ 2018-10-05 23:57:55.000 CEST ] [SQLEngine.done:2278]  Done called\r\n[main] [ 2018-10-05 23:57:55.014 CEST ] [Host.cleanup:3928]  Dbca exit status is: 1\r\n[main] [ 2018-10-05 23:57:55.014 CEST ] [Host.cleanup:3931]  check point context oracle.sysman.assistants.util.CheckpointContext@32b95f72\r\n[main] [ 2018-10-05 23:57:55.023 CEST ] [InventoryUtil.getOUIInvSession:349]  setting OUI READ level to ACCESSLEVEL_READ_LOCKLESS\r\n[main] [ 2018-10-05 23:57:55.023 CEST ] [InventoryUtil.getHomeName:113]  homeName = OraDB12Home1\r\n[main] [ 2018-10-05 23:57:55.023 CEST ] [OsUtilsBase.getBaseFromOrabase:659]  oraBaseUtility /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[main] [ 2018-10-05 23:57:55.025 CEST ] [OsUtilsBase.getBaseFromOrabase:668]  cmds: /u01/app/oracle/12.1.0.2/db1-base/bin/orabase\r\n[main] [ 2018-10-05 23:57:55.025 CEST ] [OsUtilsBase.getBaseFromOrabase:672]  envs:  ORACLE_HOME=/u01/app/oracle/12.1.0.2/db1-base\r\n[main] [ 2018-10-05 23:57:55.032 CEST ] [OsUtilsBase.getBaseFromOrabase:682]  baseLocation from orabase \r\n/u01/app/oracle\r\n[main] [ 2018-10-05 23:57:55.032 CEST ] [OsUtilsBase.getBaseFromOrabase:707]   orabaseLocation= /u01/app/oracle\r\n[main] [ 2018-10-05 23:57:55.034 CEST ] [Host.cleanup:3957]  check point  oracle.sysman.oic.oics.OicsCheckPoint@6ce931d9\r\n[main] [ 2018-10-05 23:57:55.143 CEST ] [OsUtilsBase.copyFile:1505]  OsUtilsBase.copyFile: \r\n[main] [ 2018-10-05 23:57:55.143 CEST ] [OsUtilsBase.copyFile:1553]  **write of file at destination complete...\r\n[main] [ 2018-10-05 23:57:55.143 CEST ] [OsUtilsBase.copyFile:1588]  **file copy status:= true\r\n[main] [ 2018-10-05 23:57:55.143 CEST ] [OsUtilsBase.deleteFile:1803]  OsUtilsBase.deleteFile: /u01/app/oracle/cfgtoollogs/dbca/silent.log_2018-10-05_11-55-26-PM\r\n[Thread-15] [ 2018-10-05 23:57:55.152 CEST ] [DbcaCleanupHook.run:44]  Cleanup started\r\n[Thread-15] [ 2018-10-05 23:57:55.152 CEST ] [OracleHome.cleanupDBOptionsIntance:1671]  DB Options dummy instance sid=null\r\n[Thread-15] [ 2018-10-05 23:57:55.152 CEST ] [DbcaCleanupHook.run:49]  Cleanup ended\r\n\r\n```\r\n\r\n```\r\nUnique database identifier check passed.\r\n\r\n/u02/ has enough space. Required space is 5990 MB , available space is 76726 MB.\r\nFile Validations Successful.\r\nCleaning up failed steps\r\nDBCA_PROGRESS : 5%\r\nCopying database files\r\nDBCA_PROGRESS : 7%\r\nDBCA_PROGRESS : 9%\r\nDBCA_PROGRESS : 16%\r\nDBCA_PROGRESS : 23%\r\nDBCA_PROGRESS : 30%\r\nDBCA_PROGRESS : 41%\r\nCreating and starting Oracle instance\r\nORA-01503: CREATE CONTROLFILE failed\r\nORA-00200: control file could not be created\r\nORA-00202: control file: '/u02/oradata/orcl/control01.ctl'\r\nORA-27040: file create error, unable to create file\r\n\r\nORA-01503: CREATE CONTROLFILE failed\r\nORA-00200: control file could not be created\r\nORA-00202: control file: '/u02/oradata/orcl/control01.ctl'\r\nORA-27040: file create error, unable to create file\r\n\r\nDBCA_PROGRESS : DBCA Operation failed.\r\n/u01/app/oracle/cfgtoollogs/dbca/orcl/orcl1.log (END)\r\n```"},"comment":{"url":"https://api.github.com/repos/oravirt/ansible-oracle/issues/comments/428314255","html_url":"https://github.com/oravirt/ansible-oracle/issues/107#issuecomment-428314255","issue_url":"https://api.github.com/repos/oravirt/ansible-oracle/issues/107","id":428314255,"node_id":"MDEyOklzc3VlQ29tbWVudDQyODMxNDI1NQ==","user":{"login":"oravirt","id":7466937,"node_id":"MDQ6VXNlcjc0NjY5Mzc=","avatar_url":"https://avatars2.githubusercontent.com/u/7466937?v=4","gravatar_id":"","url":"https://api.github.com/users/oravirt","html_url":"https://github.com/oravirt","followers_url":"https://api.github.com/users/oravirt/followers","following_url":"https://api.github.com/users/oravirt/following{/other_user}","gists_url":"https://api.github.com/users/oravirt/gists{/gist_id}","starred_url":"https://api.github.com/users/oravirt/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/oravirt/subscriptions","organizations_url":"https://api.github.com/users/oravirt/orgs","repos_url":"https://api.github.com/users/oravirt/repos","events_url":"https://api.github.com/users/oravirt/events{/privacy}","received_events_url":"https://api.github.com/users/oravirt/received_events","type":"User","site_admin":false},"created_at":"2018-10-09T19:10:17Z","updated_at":"2018-10-09T19:10:17Z","author_association":"OWNER","body":"Hi,\r\nThis should really be reported in the correct repo (http://github.com/oravirt/vagrant-vbox-si-fs,  as this doesn't really have anything to do with) the `ansible-oracle` repo.\r\n\r\nBut, the problem is that I haven't had time (yet) to externalize the `oracle_pdbs` dictionary in `config/oracle.rb` & `Vagrantfile`, so it falls back to the default name of `oraclepdb1` and most likely (haven't tested the theory yet) that's why you're getting the error (as the pdb `orclpdb1` doesn't exist)"}}
{"actor":{"display_login":"oravirt"}}