Skip to main content
Delphix

How to resolve: "Failed to change archivelog mode"

Issue

When provisioning a virtual database (VDB), the job fails with the following error:

Failed to set noarchivelog mode on virtual database "DEVBASE". Review the Oracle alert log for more details. 

Troubleshooting

Review of the alert log, starting at the last instance of "Starting ORACLE instance (normal), will contain the necessary information to understand this failure.  Excerpt from alert log: 

Starting ORACLE instance (normal)
************************ Large Pages Information *******************
Per process system memlock (soft) limit = 64 KB
Total Shared Global Region in Large Pages = 0 KB (0%)
Large Pages used by this instance: 0 (0 KB)
Large Pages unused system wide = 0 (0 KB)
Large Pages configured system wide = 0 (0 KB)
Large Page size = 2048 KB
RECOMMENDATION: 
  Total System Global Area size is 6146 MB. For optimal performance,
  prior to the next instance restart:
  1. Increase the number of unused large pages by 
 at least 3073 (page size 2048 KB, total size 6146 MB) system wide to
  get 100% of the System Global Area allocated with large pages
  2. Large pages are automatically locked into physical memory.
 Increase the per process memlock (soft) limit to at least 6154 MB to lock
 100% System Global Area's large pages into physical memory
********************************************************************
LICENSE_MAX_SESSION = 0
LICENSE_SESSIONS_WARNING = 0 
Initial number of CPU is 4
Number of processor cores in the system is 4
Number of processor sockets in the system is 1
Picked latch-free SCN scheme 3
Autotune of undo retention is turned on. 
IMODE=BR
ILAT =415
LICENSE_MAX_USERS = 0
SYS auditing is disabled
Starting up:
Oracle Database 11g Enterprise Edition Release 11.2.0.4.0 - 64bit Production
With the Partitioning option.
ORACLE_HOME = /u01/app/oracle/product/11.2.0/dbhome_1
System name: Linux
Node name: dev-target.delphix.com
Release: 2.6.32-642.el6.x86_64
Version: #1 SMP Wed Apr 13 00:51:26 EDT 2016
Machine: x86_64
VM name: VMWare Version: 6
Using parameter settings in server-side pfile /u01/app/oracle/product/11.2.0/dbhome_1/dbs/initDEVBASE.ora
System parameters with non-default values:
  processes                = 2500
  sessions                 = 3776
  sga_max_size             = 6G
  spfile                   = "/home/svc.delphix/delphix/provision/DEVBASE/datafile/spfile.ora"
  _high_priority_processes = "LMS*|LGWR|VKTM"
  nls_length_semantics     = "CHAR"
  filesystemio_options     = "SETALL"
  _omf                     = "ENABLED"
  sga_target               = 6G
  control_files            = "/home/svc.delphix/delphix/provision/DEVBASE/datafile/+REDO01/econnprd/controlfile/current.256.844616717"
  db_block_size            = 8192
  compatible               = "11.2.0.4.0"
  log_archive_dest_1       = "location=/home/svc.delphix/delphix/provision/DEVBASE/archive/ MANDATORY"
  log_archive_trace        = 0
  log_archive_max_processes= 4
  archive_lag_target       = 0
  db_create_file_dest      = "/home/svc.delphix/delphix/provision/DEVBASE/datafile"
  db_lost_write_protect    = "TYPICAL"
  _bct_public_dba_buffer_size= 1048576000
  _bct_buffer_allocation_size= 20971520
  _bct_buffer_allocation_max= 1048576000
  undo_retention           = 14400
  instance_number          = 1
  db_block_checking        = "FULL"
  db_securefile            = "ALWAYS"
  os_roles                 = FALSE
  remote_os_authent        = FALSE 
  remote_os_roles          = FALSE
  O7_DICTIONARY_ACCESSIBILITY= FALSE
  remote_login_passwordfile= "EXCLUSIVE"
  audit_sys_operations     = FALSE
  service_names            = "SYS$SYS.SCHEDULER$_EVENT_QUEUE.ECONNPRD"
  service_names            = "ECONNPRD_HAS" 
  local_listener           = "(ADDRESS_LIST=(ADDRESS=(PROTOCOL=TCP)(HOST=10.138.6.81)(PORT=1522)))"
  utl_file_dir             = ""
  audit_trail              = "NONE"
  db_name                  = "DEVBASE"
  db_unique_name           = "DEVBASE"
  open_cursors             = 3000
  sql92_security           = FALSE
  _b_tree_bitmap_plans     = FALSE
  _unnest_subquery         = FALSE
  parallel_degree_policy   = "AUTO"
  query_rewrite_integrity  = "TRUSTED"
  pga_aggregate_target     = 1G
  optimizer_dynamic_sampling= 2
  parallel_force_local     = TRUE
  sec_protocol_error_trace_action= "LOG"
  sec_protocol_error_further_action= "(delay,3)"
  sec_max_failed_login_attempts= 10
  sec_return_server_release_banner= FALSE
  _trace_files_public      = FALSE
Deprecated system parameters with specified values:
  remote_os_authent        
End of deprecated system parameter listing
Thu Jul 21 11:01:18 2016
PMON started with pid=2, OS id=5997 
Thu Jul 21 11:01:18 2016
PSP0 started with pid=3, OS id=6001  
Thu Jul 21 11:01:19 2016
VKTM started with pid=4, OS id=6005 at elevated priority
VKTM running at (1)millisec precision with DBRM quantum (100)ms
Thu Jul 21 11:01:19 2016
GEN0 started with pid=5, OS id=6011 
Thu Jul 21 11:01:19 2016
DIAG started with pid=6, OS id=6015 
Thu Jul 21 11:01:19 2016
DBRM started with pid=7, OS id=6019 
Thu Jul 21 11:01:19 2016
DIA0 started with pid=8, OS id=6023 
Thu Jul 21 11:01:19 2016
MMAN started with pid=9, OS id=6027 
Thu Jul 21 11:01:19 2016
DBW0 started with pid=10, OS id=6031 
Thu Jul 21 11:01:19 2016
LGWR started with pid=11, OS id=6035 at elevated priority
Thu Jul 21 11:01:19 2016
CKPT started with pid=12, OS id=6041 
Thu Jul 21 11:01:19 2016
SMON started with pid=13, OS id=6045 
Thu Jul 21 11:01:19 2016
RECO started with pid=14, OS id=6049 
Thu Jul 21 11:01:19 2016
MMON started with pid=15, OS id=6053 
Thu Jul 21 11:01:19 2016
MMNL started with pid=16, OS id=6057 
ORACLE_BASE from environment = /u01/app/oracle
Thu Jul 21 11:01:19 2016
ALTER DATABASE   MOUNT
Successful mount of redo thread 1, with mount id 1128754239
Database mounted in Exclusive Mode
Lost write protection mode set to "typical"
Completed: ALTER DATABASE   MOUNT
Thu Jul 21 11:01:23 2016
alter database NOARCHIVELOG
Completed: alter database NOARCHIVELOG
Thu Jul 21 11:01:23 2016
alter database open
Thread 1 opened at log sequence 4
  Current log# 4 seq# 4 mem# 0: /home/svc.delphix/delphix/provision/DEVBASE/datafile/DEVBASE/onlinelog/o1_mf_4_cs1zx4fj_.log
Successful open of redo thread 1
MTTR advisory is disabled because FAST_START_MTTR_TARGET is not set
SMON: enabling cache recovery
[6080] Successfully onlined Undo Tablespace 2.
Undo initialization finished serial:0 start:3251039384 end:3251039464 diff:80 (0 seconds)
Verifying file header compatibility for 11g tablespace encryption..
Verifying 11g file header compatibility for tablespace encryption completed
SMON: enabling tx recovery
Database Characterset is AL32UTF8
No Resource Manager plan active
replication_dependency_tracking turned off (no async multimaster replication found)
Starting background process QMNC
Thu Jul 21 11:01:24 2016
QMNC started with pid=18, OS id=6088 
Completed: alter database open
Thu Jul 21 11:01:25 2016
Shutting down instance (abort)
License high water mark = 1
USER (ospid: 6134): terminating the instance
Instance terminated by USER, pid = 6134
Thu Jul 21 11:01:26 2016
Instance shutdown complete

 

Review of the alert log does indicate archive mode was successfully set.  Excerpt from alert log:

alter database NOARCHIVELOG
Completed: alter database NOARCHIVELOG

Resolution

Search alert log for "Deprecated system parameters with specified values:".  If Deprecated parameter is listed, remove parameter noted from "VDB Configuration Templates" and retry the provision of the virtual database.  Example of Deprecated system parameter found in alert log:

Deprecated system parameters with specified values:
  remote_os_authent        
End of deprecated system parameter listing

Additional Information

Delphix Development has been alerted of this condition.