"ORA-27300: OS system dependent operation:GetThreadTimes failed" Causes Instance Crash 10.2.0.2

From: Charles Hooper <hooperc2000_at_yahoo.com>
Date: 30 Oct 2006 13:51:45 -0800
Message-ID: <1162245105.514969.261430@h48g2000cwc.googlegroups.com>

Just curious to see if anyone has seen this problem before - I could not find anything on Metalink. One of my Oracle instances crashed at 34 seconds past midnight on Saturday - no known jobs run at that time. The server is running Windows 2003 x64, database is Oracle 10.2.0.2. The instance has been extremely stable since put in production in April 2006.

The alert log shows this:

---

Sat Oct 28 00:00:34 2006
Process startup failed, error stack:
Sat Oct 28 00:00:34 2006
Errors in file
d:\oracle\product\10.2.0\admin\DB\bdump\vmfg_psp0_2196.trc: ORA-27300: OS system dependent operation:GetThreadTimes failed with status: 6
ORA-27301: OS failure message: The handle is invalid. ORA-27302: failure occurred at: skgpspawn

Sat Oct 28 00:00:34 2006
Errors in file
d:\oracle\product\10.2.0\admin\DB\bdump\vmfg_psp0_2196.trc: ORA-00600: internal error code, arguments: [784], [0x1A0AB9EE0], [0x195106208], [0], [], [], [], []

Sat Oct 28 00:00:35 2006
Errors in file
d:\oracle\product\10.2.0\admin\DB\bdump\vmfg_psp0_2196.trc: ORA-00600: internal error code, arguments: [784], [0x1A0AB9EE0], [0x195106208], [0], [], [], [], []

PSP0: terminating instance due to error 490 Sat Oct 28 00:00:35 2006
Errors in file
d:\oracle\product\10.2.0\admin\DB\bdump\vmfg_lgwr_1296.trc: ORA-00490: PSP process terminated with error

Sat Oct 28 00:00:35 2006
Errors in file
d:\oracle\product\10.2.0\admin\DB\bdump\vmfg_ckpt_888.trc: ORA-00490: PSP process terminated with error

Sat Oct 28 00:00:35 2006
Errors in file
d:\oracle\product\10.2.0\admin\DB\bdump\vmfg_pmon_2176.trc: ORA-00490: PSP process terminated with error

Sat Oct 28 00:00:36 2006
Errors in file
d:\oracle\product\10.2.0\admin\DB\bdump\vmfg_mman_2220.trc: ORA-00490: PSP process terminated with error

Sat Oct 28 00:00:37 2006
Errors in file
d:\oracle\product\10.2.0\admin\DB\bdump\vmfg_dbw0_1928.trc: ORA-00490: PSP process terminated with error

Sat Oct 28 00:00:37 2006
Errors in file
d:\oracle\product\10.2.0\admin\DB\bdump\vmfg_j000_4600.trc: ORA-00490: PSP process terminated with error

Sat Oct 28 00:00:57 2006
Errors in file
d:\oracle\product\10.2.0\admin\DB\bdump\vmfg_q003_1108.trc: ORA-00490: PSP process terminated with error

Sat Oct 28 00:01:02 2006
Errors in file
d:\oracle\product\10.2.0\admin\DB\bdump\vmfg_reco_1932.trc: ORA-00490: PSP process terminated with error

Sat Oct 28 00:01:02 2006
Errors in file
d:\oracle\product\10.2.0\admin\DB\bdump\vmfg_smon_2044.trc: ORA-00490: PSP process terminated with error

Sat Oct 28 00:01:03 2006
Instance terminated by PSP0, pid = 2196

---

Metalink contains this information about the PSP0 process: New background processes introduced in Oracle 10.2... PSP stands for Process SPawner and this process has the job of creating and managing other Oracle processes. NOTE: There is no documentation currently in the Oracle Documentation set on this process.

Among a lot of other items in the
d:\oracle\product\10.2.0\admin\DB\bdump\vmfg_psp0_2196.trc file, the following appeared several times for all pso_serial#:: 047EA02B:55205486 27 0 10280 8 kst: process state object about to be deleted
047EA058:552054BE 27 0 10280 1 kst: process state object created on 10-27 23:38:59.483
047EA066:552054BF 27 0 10280 3 kst: process info: ospid=728 pso_num=27 pso_serial#=107
047EA066:552054C0 27 0 10420 1 kso: new process: pid=728
(legacy spawn)

047EA4A9:55205AF3 27 0 10280 8 kst: process state object about to be deleted
047EA4E4:55205B25 27 0 10280 1 kst: process state object created on 10-27 23:39:10.983
047EA4E4:55205B26 27 0 10280 3 kst: process info: ospid=3320 pso_num=27 pso_serial#=108
047EA4E4:55205B27 27 0 10420 1 kso: new process: pid=3320
(legacy spawn)

047EA63F:55205EFC 27 0 10280 8 kst: process state object about to be deleted
047EA67C:55205F0B 27 0 10280 1 kst: process state object created on 10-27 23:39:15.061
047EA67C:55205F0C 27 0 10280 3 kst: process info: ospid=3412 pso_num=27 pso_serial#=109
047EA67C:55205F0D 27 0 10420 1 kso: new process: pid=3412
(legacy spawn)

047EA7CC:552062E9 27 0 10280 8 kst: process state object about to be deleted
047EA7F8:55206303 27 0 10280 1 kst: process state object created on 10-27 23:39:19.014
047EA807:55206304 27 0 10280 3 kst: process info: ospid=1784 pso_num=27 pso_serial#=110
047EA807:55206305 27 0 10420 1 kso: new process: pid=1784
(legacy spawn)

047EA95C:5520672F 27 0 10280 8 kst: process state object about to be deleted
047EA997:55206761 27 0 10280 1 kst: process state object created on 10-27 23:39:23.014
047EA997:55206762 27 0 10280 3 kst: process info: ospid=2660 pso_num=27 pso_serial#=111
047EA997:55206763 27 0 10420 1 kso: new process: pid=2660
(legacy spawn)

047EAAD6:55206B43 27 0 10280 8 kst: process state object about to be deleted
047EAB10:55206B4F 27 0 10280 1 kst: process state object created on 10-27 23:39:26.780
047EAB10:55206B50 27 0 10280 3 kst: process info: ospid=3560 pso_num=27 pso_serial#=112
047EAB10:55206B51 27 0 10420 1 kso: new process: pid=3560
(legacy spawn)

047EAF51:55207127 27 0 10280 8 kst: process state object about to be deleted
047EAF7A:55207176 27 0 10280 1 kst: process state object created on 10-27 23:39:38.264
047EAF8C:55207177 27 0 10280 3 kst: process info: ospid=3416 pso_num=27 pso_serial#=113
047EAF8C:55207178 27 0 10420 1 kso: new process: pid=3416
(legacy spawn)

047EB0DF:5520753E 27 0 10280 8 kst: process state object about to be deleted
047EB113:5520754F 27 0 10280 1 kst: process state object created on 10-27 23:39:42.233
047EB119:55207550 27 0 10280 3 kst: process info: ospid=4964 pso_num=27 pso_serial#=114
047EB119:55207551 27 0 10420 1 kso: new process: pid=4964
(legacy spawn)

047EB272:55207920 27 0 10280 8 kst: process state object about to be deleted
047EB2AC:5520794E 27 0 10280 1 kst: process state object created on 10-27 23:39:46.264
047EB2AC:5520794F 27 0 10280 3 kst: process info: ospid=1376 pso_num=27 pso_serial#=115
047EB2AC:55207950 27 0 10420 1 kso: new process: pid=1376
(legacy spawn)

047EB407:55207D38 27 0 10280 8 kst: process state object about to be deleted
047EB42A:55207D91 27 0 10280 1 kst: process state object created on 10-27 23:39:50.311
047EB441:55207D92 27 0 10280 3 kst: process info: ospid=4728 pso_num=27 pso_serial#=116
047EB441:55207D93 27 0 10420 1 kso: new process: pid=4728
(legacy spawn)

It looks like Oracle 10.2.x may have a new feature.

Charles Hooper
PC Support Specialist
K&M Machine-Fabricating, Inc. Received on Mon Oct 30 2006 - 15:51:45 CST