Oracle 11.2.0.4 the time when RAC logs are recorded is inconsistent with the operating system time.

Oracle 11.2.0.4 the time when RAC logs are recorded is inconsistent with the operating system time.

After Oracle 11.2.0.4 RAC is set up and the time zone is changed at the system level, it is found that the RAC log records are inconsistent with the operating system time.

Including

Time Point generated by AWR

Database alert Log

CRS logs

And so on.

For example:

Kaycsqdb001r:/u01/app/11.2.0/grid/log/kaycsqdb001r $ date
Fri Oct 17 11:06:37 CST 2014 <this time point, another window is opened to execute the cluster startup command
Kaycsqdb001r:/u01/app/11.2.0/grid/log/kaycsqdb001r $ tail-f al * 1r. log <monitoring log output
2014-10-16 22:06:24. 941:
[/U01/app/11.2.0/grid/bin/orarootagent. bin (3146170)] CRS-2302: Cannot get GPnP profile. Error CLSGPNP_NO_DAEMON (GPNPD daemon is not running ).
2014-10-16 22:06:30. 145:
[Ohasd (5242990)] CRS-2302: Cannot get GPnP profile. Error CLSGPNP_NO_DAEMON (GPNPD daemon is not running ).
2014-10-16 22:06:30. 162:
[Gpnpd (5767206)] CRS-2328: GPNPD started on node kaycsqdb001r.
2014-10-16 22:06:33. 415:
[Cssd (3277140)] CRS-1713: CSSD daemon is started in clustered mode
2014-10-16 22:06:34. 856:
[Ohasd (5242990)] CRS-2767: Resource state recovery not attempted for 'ora. diskmon' as its target state is OFFLINE
2014-10-16 22:06:44. 482:
[Cssd (3277140)] CRS-1707: Lease acquisition for node kaycsqdb001r number 1 completed
2014-10-16 22:06:45. 853:
[Cssd (3277140)] CRS-1605: CSSD voting file is online:/dev/rhdisk2; details in/u01/app/11.2.0/grid/log kay/ csqdb001r/cssd/ocssd. log.
22:06:50. 018:
[Cssd (3277140)] CRS-1601: CSSD Reconfiguration complete. Active nodes are kaycsqdb001r kaycsqdb002r.
2014-10-16 22:06:52. 856:
[Ctssd (3998150)] CRS-2407: The new Cluster Time Synchronization Service reference node is host kaycsqdb002r.
2014-10-16 22:06:52. 858:
[Ctssd (3998150)] CRS-2401: The Cluster Time Synchronization Service started on host kaycsqdb001r.
2014-10-16 22:06:54. 468:
[Ohasd (5242990)] CRS-2767: Resource state recovery not attempted for 'ora. diskmon' as its target state is OFFLINE
2014-10-16 22:06:58. 959:
[Ctssd (3998150)] CRS-2408: The clock on host kaycsqdb001r has been updated by the Cluster Time Synchronization Service to be synchronous with the mean cluster time.
22:07:04. 036:
[/U01/app/11.2.0/grid/bin/orarootagent. bin (6684836)] CRS-5018 :(: CLSN00037 :) Removed unused HAIP route: 169.254.255.255/255.255.255.0.0/169.254.152.93/en1
[Client (3604840)] CRS-10001: 16--14 22:ACFS-9391: Checking for existing ADVM/ACFS installation.
[Client (3604842)] CRS-10001: 16--14 -14 ACFS-9392: Validating ADVM/ACFS installation files for operating system.
[Client (3604844)] CRS-10001: 16--14 -14 ACFS-9393: Verifying ASM Administrator setup.
[Client (3604846)] CRS-10001: 16--14 22:ACFS-9308: Loading installed ADVM/ACFS drivers.
[Client (3604852)] CRS-10001: 16--14 22:ACFS-9154: Loading 'oracleadvm. ext 'driver.
[Client (3670458)] CRS-10001: 16--14 22:ACFS-9154: Loading 'oracleacfs. ext 'driver.
[Client (7208994)] CRS-10001: 16--14 22:ACFS-9327: Verifying ADVM/ACFS devices.
[Client (3670464)] CRS-10001: 16--14 -14 ACFS-9156: Detecting control device '/dev/asm/. asm_ctl_spec '.
[Client (3670468)] CRS-10001: 16--14 -14 ACFS-9156: Detecting control device '/dev/ofsctl '.
[Client (3670472)] CRS-10001: 16--14 14 ACFS-9322: completed
2014-10-16 22:07:19. 261:
[Crsd (5111996)] CRS-1012: The OCR service started on node kaycsqdb001r.
2014-10-16 22:07:19. 303:
[Evmd (4456814)] CRS-1401: EVMD started on node kaycsqdb001r.
22:07:21. 026:
[Crsd (5111996)] CRS-1201: CRSD started on node kaycsqdb001r.

As you can see, the log write time is nearly 13 hours later than the operating system time.

This is due to a change in the new version, and the GRID also has its own time zone settings. During installation, the default value is the same as the TZ environment variable of the operating system.

Microdb01:/u01/app/11.2.0/grid/crs/install $ cat s_crsconfig_microdb01_env.txt
TZ = BEIST-8 <GRID time zone settings
NLS_LANG = AMERICAN_AMERICA.WE8ISO8859P1
RT_GRQ = ON
EXTSHM = OFF
TNS_ADMIN =
ORACLE_BASE =

Microdb01:/u01/app/11.2.0/grid/crs/install $ grep TZ/etc/environment
TZ = Asia/Shanghai <System Time Zone settings
Microdb01:/u01/app/11.2.0/grid/crs/install $

The Time Zone of the GRID is different from the system environment variable TZ.

Solution:

Edit s_crsconfig_microdb01_env.txt and set TZ = Asia/Shanghai. Restart the cluster service to restore it.

Oracle 11g RAC local time and listener connection time are different