Today, the customer received their own test environment, resulting in a table from the source database to the target database, select * from tabname reported ORA-01435: user does not exist, select count (*) from tabname normal, I used to only understand fine-grained auditing, but I haven't really seen it in the environment, so it takes a little time to analyze the entire process.
The process is as follows:
- Scott @ EM10G> selectcount (*) from EMPLOYEES;
- COUNT (*)
- ----------
- 107
- Scott @ EM10G> select * from EMPLOYEES;
- Select * from EMPLOYEES
- *
- ERROR at line 1:
- ORA-01435: user does not exist
The following is a 10046 process.
- [Oracle @ RAC2 ~] $ Sqlplus/as sysdba
- SQL * Plus: Release 10.2.0.1.0-Production on Fri Mar 22 17:03:50 2013
- Copyright (c) 1982,200 5, Oracle. All rights reserved.
- Connected:
- Oracle Database 10g Enterprise Edition Release 10.2.0.1.0-Production
- With the Partitioning, OLAP and Data Mining options
- Sys @ EM10G> select spid from v $ process where addr = (select paddr from v $ session where username = 'Scott ');
- SPID
- ------------
- 30255
- Sys @ EM10G> oradebug setospid 30255
- Oracle pid: 22, Unix process pid: 30255, image: oracle @ RAC2 (TNS V1-V3)
- Sys @ EM10G> oradebug event 10046 trace name context forever, level 12;
- Statement processed.
- Sys @ EM10G> oradebug tracefile_name
- /U01/app/oracle/admin/em10g/udump/em10g_ora_301_trc
- Sys @ EM10G>! Vi/u01/app/oracle/admin/em10g/udump/em10g_ora_301_trc
- Dump file/u01/app/oracle/admin/em10g/udump/em10g_ora_301_trc
- Oracle Database 10g Enterprise Edition Release 10.2.0.1.0-Production
- With the Partitioning, OLAP and Data Mining options
- ORACLE_HOME =/u01/app/oracle
- System name: Linux
- Node name: RAC2
- Release: 2.6.9-89.0.0.0.1.ELsmp
- Version: #1 SMP Tue May 19 04:23:49 EDT 2009
- Machine: i686
- Instance name: em10g
- Redo thread mounted by this instance: 1
- Oracle process number: 22
- Unix process pid: 30255, image: oracle @ RAC2 (TNS V1-V3)
- * ** ACTIONNAME :() 17:02:24. 565
- * ** Module name :( SQL * Plus) 17:02:24. 565
- * ** Service name :( SYS $ USERS) 17:02:24. 565
- * ** Session id: (312.17658) 17:02:24. 565
- -----------------------------------
- Error during execution of handler in Fine Grained Auditing
- Audit handler: begin SEC. LOG_EMPS_SALARY (: sn,: on,: pl); end;
- Error Number 1: 1435
- Logon user: SCOTT
- Object Schema: SCOTT, Object Name: EMPLOYEES, Policy Name: AUDIT_EMPS_SALARY
- * ** 2013-03-22 17:04:36. 852
- Inclued ORADEBUG command 'event 10046 trace name context forever, level 12' from process Unix process pid: 30298, image:
- WAIT #0: nam = 'SQL * Net message from client' ela = 110477763 driver id = 1650815232 # bytes = 1 p3 = 0 obj #=-1 tim = 1331975666205121
- ==================================
- Parsing incursor #1 len = 23 dep = 0 uid = 27 oct = 3 lid = 27 tim = 1331975666206838 hv = 1609818433 ad = '58ce67f0'
- Select * from EMPLOYEES
- ENDOF STMT
- PARSE #1: c = 1000, e = 1593, p = 0, cr = 0, cu = 0, mis = 1, r = 0, dep = 0, og = 1, tim = 1331975666206830
- BINDS #1:
- ==================================
- Parsing incursor #2 len = 382 dep = 1 uid = 0 oct = 2 lid = 0 tim = 1331975666208989 hv = 1818756823 ad = '58f2cf18'
- Insertinto sys. fga_log $ (sessionid, ntimestamp #, dbuid, osuid, obj $ schema, obj $ name, policyname, scn, oshst, clientid, extid, lsqltext, proxy $ sid, user $ guid, instance #, process #, xid, statement, entryid, stmt_type, lsqlbind, auditid) values (: 1, SYS_EXTRACT_UTC (SYSTIMESTAMP),: 2,: 3,: 4,: 5,: 6,: 7,: 8,: 9,: 10,: 11,: 12,: 13,: 14,: 15,: 16,: 17,: 18 ,: 19,: 20,: 21)
- ENDOF STMT
- PARSE #2: c = 1000, e = 975, p = 0, cr = 0, cu = 0, mis = 1, r = 0, dep = 1, og = 4, tim = 1331975666208982
- BINDS #2:
- Kkscoacd
- Bind #0
- Oacdty = 02 mxl = 22 (22) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 00 fl2 = 0001 frm = 00 csi = 00 siz = 24 off = 0
- Kxsbbbfp = b72b8a94 bln = 22 avl = 04 flg = 05
- Value = 273276
- Bind #1
- Oacdty = 01 mxl = 32 (05) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 10 fl2 = 0001 frm = 01 csi = 01 siz = 32 off = 0
- Kxsbbbfp = bfffb9de bln = 32 avl = 05 flg = 09
- Value = "SCOTT"
- Bind #2
- Oacdty = 01 mxl = 32 (06) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 11 fl2 = 0001 frm = 01 csi = 01 siz = 32 off = 0
- Kxsbbbfp = bfffba02 bln = 32 avl = 06 flg = 09
- Value = "oracle"
- Bind #3
- Oacdty = 01 mxl = 32 (05) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 10 fl2 = 0001 frm = 01 csi = 01 siz = 32 off = 0
- Kxsbbbfp = bfffba22 bln = 32 avl = 05 flg = 09
- Value = "SCOTT"
- Bind #4
- Oacdty = 01 mxl = 32 (09) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 10 fl2 = 0001 frm = 01 csi = 01 siz = 32 off = 0
- Kxsbbbfp = bfffba42 bln = 32 avl = 09 flg = 09
- Value = "EMPLOYEES"
- Bind #5
- Oacdty = 01 mxl = 32 (17) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 10 fl2 = 0001 frm = 01 csi = 01 siz = 32 off = 0
- Kxsbbbfp = bfffba62 bln = 32 avl = 17 flg = 09
- Value = "AUDIT_EMPS_SALARY"
- Bind #6
- Oacdty = 02 mxl = 22 (05) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 10 fl2 = 0001 frm = 00 csi = 00 siz = 24 off = 0
- Kxsbbbfp = bfffba80 bln = 22 avl = 05 flg = 09
- Value = 76930924
- Bind #7
- Oacdty = 01 mxl = 32 (04) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 11 fl2 = 0001 frm = 01 csi = 01 siz = 32 off = 0
- Kxsbbbfp = bfffba9e bln = 32 avl = 04 flg = 09
- Value = "RAC2"
- Bind #8
- Oacdty = 01 mxl = 32 (00) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 11 fl2 = 0001 frm = 01 csi = 01 siz = 32 off = 0
- Kxsbbbfp = 00000000 bln = 32 avl = 00 flg = 09
- Bind #9
- Oacdty = 01 mxl = 32 (06) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 11 fl2 = 0001 frm = 01 csi = 01 siz = 32 off = 0
- Kxsbbbfp = bfffbb68 bln = 32 avl = 06 flg = 09
- Value = "oracle"
- Bind #10
- Oacdty = 112 mxl = 4000 (4000) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 01 fl2 = 0001 frm = 01 csi = 00 siz = 4000 off = 0
- Kxsbbbfp = b72b7700 bln = 4000 avl = 4000 flg = 05
- Value =
- Dump of memory from 0xB72B7700 to 0xB72B86A0
- B72B7700 01002600 03000802 00000100 013844FA [...]
- B72B7710 02000000 16000100 01000000 00000000 [......]
- B72B7720 D36EF96C 4EE43585 00000000 00000000 [l. n... 5. N ......]
- B72B7730 00000000 00000000 00000000 00000000 [......]
- Repeat 246 times
- Bind #11
- Oacdty = 02 mxl = 22 (22) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 01 fl2 = 0001 frm = 00 csi = 00 siz = 24 off = 0
- Kxsbbbfp = b72b8a70 bln = 22 avl = 00 flg = 05
- Bind #12
- Oacdty = 01 mxl = 32 (00) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 11 fl2 = 0001 frm = 01 csi = 01 siz = 32 off = 0
- Kxsbbbfp = 00000000 bln = 32 avl = 00 flg = 09
- Bind #13
- Oacdty = 02 mxl = 22 (22) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 00 fl2 = 0001 frm = 00 csi = 00 siz = 24 off = 0
- Kxsbbbfp = b72b8a4c bln = 22 avl = 01 flg = 05
- Value = 0
- Bind #14
- Oacdty = 01 mxl = 32 (05) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 11 fl2 = 0001 frm = 01 csi = 01 siz = 32 off = 0
- Kxsbbbfp = bfffbf94 bln = 32 avl = 05 flg = 09
- Value = "30255"
- Bind #15
- Oacdty = 23 mxl = 32 (00) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 11 fl2 = 0001 frm = 00 csi = 00 siz = 32 off = 0
- Kxsbbbfp = 00000000 bln = 32 avl = 00 flg = 09
- Bind #16
- Oacdty = 02 mxl = 22 (22) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 00 fl2 = 0001 frm = 00 csi = 00 siz = 72 off = 0
- Kxsbbbfp = b72b89f8 bln = 22 avl = 02 flg = 05
- Value = 9
- Bind #17
- Oacdty = 02 mxl = 22 (22) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 00 fl2 = 0001 frm = 00 csi = 00 siz = 0 off = 24
- Kxsbbbfp = b72b8a10 bln = 22 avl = 02 flg = 01
- Value = 2
- Bind #18
- Oacdty = 02 mxl = 22 (22) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 00 fl2 = 0001 frm = 00 csi = 00 siz = 0 off = 48
- Kxsbbbfp = b72b8a28 bln = 22 avl = 02 flg = 01
- Value = 1
- Bind #19
- Oacdty = 112 mxl = 4000 (4000) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 01 fl2 = 0001 frm = 01 csi = 00 siz = 4000 off = 0
- Kxsbbbfp = b72b6740 bln = 4000 avl = 00 flg = 05
- Bind #20
- Oacdty = 01 mxl = 32 (00) mxlc = 00 mal = 00 scl = 00 pre = 00
- Oacflg = 11 fl2 = 0001 frm = 01 csi = 01 siz = 32 off = 0
- Kxsbbbfp = 00000000 bln = 32 avl = 00 flg = 09
- Begin cleaning out block...
- Found allcommitted transactions
- Block cleanout record, scn: 0xffff. ffffffff ver: 0x01 opt: 0x02, entries follow...
- Itli: 1 flg: 2 scn: 0x0000. 0495 ddcb
- Itli: 2 flg: 2 scn: 0x0000. 0495df3e
- Block cleanout under the cache...
- Block cleanout record, scn: 0x0000. 0495df6c ver: 0x01 opt: 0x02, entries follow...
- Itli: 1 flg: 2 scn: 0x0000. 0495 ddcb
- Itli: 2 flg: 2 scn: 0x0000. 0495df3e
- ... Clean out dump complete.
- EXEC #2: c = 2000, e = 1974, p = 0, cr = 2, cu = 2, mis = 1, r = 1, dep = 1, og = 4, tim = 1331975666211048
- -----------------------------------
- Error during execution of handler in Fine Grained Auditing
- Audit handler: begin SEC. LOG_EMPS_SALARY (: sn,: on,: pl); end;
- Error Number 1: 1435
- Logon user: SCOTT
- Object Schema: SCOTT, Object Name: EMPLOYEES, Policy Name: AUDIT_EMPS_SALARY
- WAIT #1: nam = 'Log file sync' ela = 30974 buffer # = 1013 p2 = 0 p3 = 0 obj #=-1 tim = 1331975666243042
- WAIT #1: nam = 'SQL * Net break/reset to client' ela = 4 driver id = 1650815232 break? = 1 p3 = 0 obj #=-1 tim = 1331975666244102
- WAIT #1: nam = 'SQL * Net break/reset to client' ela = 85 driver id = 1650815232 break? = 0 p3 = 0 obj #=-1 tim = 1331975666244210
- WAIT #1: nam = 'SQL * Net message to client' ela = 2 driver id = 1650815232 # bytes = 1 p3 = 0 obj #=-1 tim = 1331975666244236
- WAIT #1: nam = 'SQL * Net message from client' ela = 0 driver id = 1650815232 # bytes = 1 p3 = 0 obj #=-1 tim = 1331975666244269
- STAT #1 id = 1 cnt = 0 pid = 0 pos = 1 obj = 21358 op = 'table access full employees (cr = 0 pr = 0 pw = 0 time = 784 us)'
- WAIT #0: nam = 'SQL * Net message to client' ela = 3 driver id = 1650815232 # bytes = 1 p3 = 0 obj #=-1 tim = 1331975666245227
- * ** 2013-03-22 17:04:48. 216
- Removed ed ORADEBUG command 'tracefile _ name' from process Unix process pid: 30298, image: