SAP is not starting

[mathew_varughese]

After startsap command sap process dies dip trace shows following error

/>tail -f dev_disp

kill(692224,0) successful -> process alive

kill(671998,0) successful -> process alive

kill(544894,0) successful -> process alive

kill(765972,0) successful -> process alive

kill(561392,0) successful -> process alive

kill(811168,0) successful -> process alive

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 19000 ms

Fri Oct 3 00:49:09 2008

NiBufPoll: Postprocessing...

NiBufPoll: returning 1 clients

NiSelNext: returning hdl 1, r--, 112fc10f8, rest 0 entries (index 0)

NiIPeek: peek successful for hdl -1 / socket 6 (r)

NiIDgReceive - WAKEUP DGRAM received from:

host 127.0.0.1, port C0.DB/49371, fam 2 (low adr..high adr)

NiIPeek: peek for hdl -1 / socket 6 timed out (r; 0)

DpRqServiceQueue: 1 request(s) in NOWP queue ===>>

-IN-- sender_id WORK_PROCESS tid -1 wp_ca_blk 608 wp_id 6

-IN-- action SEND_TO_DP uid -1 appc_ca_blk -1 type NOWP

-IN-- new_stat WP_WAIT mode 0 len 8 rq_id 10

info for UPD: wait/run/hold/killed = 1/1/0/0 wps

DpRqNoWpHandle: new state of W6 is WP_WAIT

REL WP ca_blk 608

DpRqServiceQueue: 2 request(s) in DIA queue ===>>

all DIA wp's in use now ...

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

DISPATCHER has 1 WP and 0 APPC blks

dp_mytypes: 9f

dp_mytypes: 9f

kill(774364,0) -> ESRCH: process died

info for DIA: wait/run/hold/killed = 0/2/0/3 wps

• ERROR => W2 (pid 774364) died [dpxxdisp.c 13050]

force unlock of wp_adm mutex W2

dp_mytypes: 9f

kill(676070,0) successful -> process alive

kill(503920,0) successful -> process alive

kill(692224,0) successful -> process alive

kill(671998,0) successful -> process alive

kill(544894,0) successful -> process alive

kill(765972,0) successful -> process alive

kill(561392,0) successful -> process alive

kill(811168,0) successful -> process alive

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 11000 ms

Fri Oct 3 00:49:18 2008

NiBufPoll: Postprocessing...

NiBufPoll: returning 1 clients

NiSelNext: returning hdl 1, r--, 112fc10f8, rest 0 entries (index 0)

NiIPeek: peek successful for hdl -1 / socket 6 (r)

NiIDgReceive - WAKEUP DGRAM received from:

host 127.0.0.1, port C0.DD/49373, fam 2 (low adr..high adr)

NiIPeek: peek for hdl -1 / socket 6 timed out (r; 0)

DpRqServiceQueue: 1 request(s) in NOWP queue ===>>

-IN-- sender_id WORK_PROCESS tid -1 wp_ca_blk 380 wp_id 3

-IN-- action SEND_TO_DP uid -1 appc_ca_blk -1 type NOWP

-IN-- new_stat WP_WAIT mode 0 len 8 rq_id 11

info for DIA: wait/run/hold/killed = 1/1/0/3 wps

DpRqNoWpHandle: new state of W3 is WP_WAIT

REL WP ca_blk 380

DpRqServiceQueue: 2 request(s) in DIA queue ===>>

W3 is free

-IN-- sender_id DISPATCHER tid -1 wp_ca_blk -1 wp_id -1

-IN-- action SEND_TO_WP uid -1 appc_ca_blk -1 type DIA

-IN-- new_stat NO_CHANGE mode 255 len -1 rq_id 7

-IN-- req_info THDP_ADM SET_SYSTEM_USER

-IN-- thdp_adm_req_info

DpRqNormalHandle: set SYSTEM_USER T3 U4 M0

DpRqNormalHandle: DpRqCheck o.k.

info for DIA: wait/run/hold/killed = 0/2/0/3 wps

DpRqNormalHandle: new state of W3 is WP_RUN

DpRqNormalHandle: give request to W3 ...

all DIA wp's in use now ...

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

APPC blk 765 WORK_PROCESS 676070

DISPATCHER has 1 WP and 0 APPC blks

dp_mytypes: 9f

dp_mytypes: 9f

dp_mytypes: 9f

kill(676070,0) successful -> process alive

kill(503920,0) successful -> process alive

kill(692224,0) successful -> process alive

kill(671998,0) -> ESRCH: process died

info for UPD: wait/run/hold/killed = 0/1/0/1 wps

• ERROR => W6 (pid 671998) died [dpxxdisp.c 13050]

force unlock of wp_adm mutex W6

dp_mytypes: 9f

kill(544894,0) successful -> process alive

kill(765972,0) successful -> process alive

kill(561392,0) successful -> process alive

kill(811168,0) successful -> process alive

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 2000 ms

Fri Oct 3 00:49:20 2008

NiIPoll: TIMEOUT occured (2000 ms)

NiBufPoll: timeout

DpRqServiceQueue: 0 request(s) in NOWP queue ===>>

DpRqServiceQueue: 1 request(s) in DIA queue ===>>

all DIA wp's in use now ...

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

DISPATCHER has 1 WP and 0 APPC blks

AUTO_TH running in wp = 1

CALL_DELAYED running in wp = 3

CALL_DELAYED running in wp = 4

CALL_DELAYED running in wp = 5

CALL_DELAYED running in wp = 6

CALL_DELAYED running in wp = 7

CALL_DELAYED running in wp = 8

CALL_DELAYED running in wp = 9

CALL_DELAYED running in wp = 10

secs 1222994960, active req_info's 0xA0002010/0x2/

DpGetSchedule: found slot 4 (type/tid/mode DPT_ENVCHECK/-1/255)

DpSetNextSchedule: next schedule for slot 4: 1222994980

call DpEnvCheck

dp_mytypes: 9f

dp_mytypes: 9f

dp_mytypes: 9f

kill(676070,0) successful -> process alive

kill(503920,0) successful -> process alive

kill(692224,0) successful -> process alive

dp_mytypes: 9f

kill(544894,0) successful -> process alive

kill(765972,0) successful -> process alive

kill(561392,0) successful -> process alive

kill(811168,0) successful -> process alive

DpGwCheck: 0

kill(679970,0) successful -> process alive

DpGwCheck: ok

kill(602130,0) successful -> process alive

DpCheckTimeTab: check time table

DpGetSchedule: next schedule 1222994980

DpGetSchedule: no schedule found

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 20000 ms

Fri Oct 3 00:49:25 2008

NiBufPoll: Postprocessing...

NiBufPoll: returning 1 clients

NiSelNext: returning hdl 1, r--, 112fc10f8, rest 0 entries (index 0)

NiIPeek: peek successful for hdl -1 / socket 6 (r)

NiIDgReceive - WAKEUP DGRAM received from:

host 127.0.0.1, port C0.E1/49377, fam 2 (low adr..high adr)

NiIPeek: peek for hdl -1 / socket 6 timed out (r; 0)

DpRqServiceQueue: 1 request(s) in NOWP queue ===>>

-IN-- sender_id WORK_PROCESS tid -1 wp_ca_blk 532 wp_id 5

-IN-- action SEND_TO_DP uid -1 appc_ca_blk -1 type NOWP

-IN-- new_stat WP_WAIT mode 0 len 8 rq_id 12

info for UPD: wait/run/hold/killed = 1/0/0/1 wps

DpRqNoWpHandle: new state of W5 is WP_WAIT

REL WP ca_blk 532

DpRqServiceQueue: 1 request(s) in DIA queue ===>>

all DIA wp's in use now ...

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

DISPATCHER has 1 WP and 0 APPC blks

dp_mytypes: 9f

dp_mytypes: 9f

dp_mytypes: 9f

kill(676070,0) -> ESRCH: process died

info for DIA: wait/run/hold/killed = 0/1/0/4 wps

• ERROR => W3 (pid 676070) died [dpxxdisp.c 13050]

force unlock of wp_adm mutex W3

dp_mytypes: 9f

kill(503920,0) successful -> process alive

kill(692224,0) successful -> process alive

dp_mytypes: 9f

kill(544894,0) successful -> process alive

kill(765972,0) successful -> process alive

kill(561392,0) successful -> process alive

kill(811168,0) successful -> process alive

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 15000 ms

Fri Oct 3 00:49:32 2008

NiBufPoll: Postprocessing...

NiBufPoll: returning 1 clients

NiSelNext: returning hdl 1, r--, 112fc10f8, rest 0 entries (index 0)

NiIPeek: peek successful for hdl -1 / socket 6 (r)

NiIDgReceive - WAKEUP DGRAM received from:

host 127.0.0.1, port C0.E3/49379, fam 2 (low adr..high adr)

NiIPeek: peek for hdl -1 / socket 6 timed out (r; 0)

DpRqServiceQueue: 1 request(s) in NOWP queue ===>>

-IN-- sender_id WORK_PROCESS tid -1 wp_ca_blk 456 wp_id 4

-IN-- action SEND_TO_DP uid -1 appc_ca_blk -1 type NOWP

-IN-- new_stat WP_WAIT mode 0 len 8 rq_id 13

info for DIA: wait/run/hold/killed = 1/0/0/4 wps

DpRqNoWpHandle: new state of W4 is WP_WAIT

REL WP ca_blk 456

DpRqServiceQueue: 1 request(s) in DIA queue ===>>

W4 is free

-IN-- sender_id DISPATCHER tid -1 wp_ca_blk -1 wp_id -1

-IN-- action SEND_TO_WP uid -1 appc_ca_blk -1 type DIA

-IN-- new_stat NO_CHANGE mode 255 len -1 rq_id 8

-IN-- req_info SET_SYSTEM_USER DP_AUTO_ABAP

DpRqNormalHandle: set SYSTEM_USER T4 U5 M0

DpRqNormalHandle: DpRqCheck o.k.

info for DIA: wait/run/hold/killed = 0/1/0/4 wps

DpRqNormalHandle: new state of W4 is WP_RUN

DpRqNormalHandle: give request to W4 ...

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

APPC blk 918 WORK_PROCESS 503920

DISPATCHER has 1 WP and 0 APPC blks

dp_mytypes: 9f

dp_mytypes: 9f

dp_mytypes: 9f

dp_mytypes: 9f

kill(503920,0) successful -> process alive

kill(692224,0) -> ESRCH: process died

info for UPD: wait/run/hold/killed = 0/0/0/2 wps

• ERROR => W5 (pid 692224) died [dpxxdisp.c 13050]

force unlock of wp_adm mutex W5

dp_mytypes: 9d

my types changed after wp death/restart 0x9f --> 0x9d

NiBufSend starting

NiIWrite: write 110, 1 packs, MESG_IO, hdl 3, data complete

MsINiWrite: sent 110 bytes

MsIModTypes: change msgtypes from DIA UPD ENQ BTC SPO ICM to DIA ENQ BTC SPO ICM

dp_mytypes: 9d

kill(544894,0) successful -> process alive

kill(765972,0) successful -> process alive

kill(561392,0) successful -> process alive

kill(811168,0) successful -> process alive

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 8000 ms

NiBufPoll: Postprocessing...

NiIRead: read 229, 1 packs, MESG_IO, hdl 3, data complete

NiBufIn: NIBUF len=229

NiBufIn: Packet complete for hdl 3

NiBufPoll: returning 1 clients

NiSelNext: returning hdl 3, r--, 112fc1108, rest 0 entries (index 3)

NiBufReceive starting

MsINiRead: received 229 bytes

MSG received, len 110+119, flag 1, from MSG_SERVER, typ 0, key -

NiICheck2Cancel: reset from status 0

opcode : MS_SERVER_MOD

retcode : MSOP_OK

version : 2

charset : 1

DpMsInfo: request from MSG_SERVER, opcode MS_SERVER_MOD

MsConvMsxxlist: client_version=2, server_version=2

client name : de08u1743_TP1_00

client host : de08u1743

client address : 10.220.136.68

client services : tick-port

client port : 3200

message types : DIA ENQ BTC SPO ICM

status : STARTING

DpMBufMod: state MBUF_PREPARED -> return

DpRqServiceQueue: 0 request(s) in NOWP queue ===>>

DpRqServiceQueue: 0 request(s) in DIA queue ===>>

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

APPC blk 918 WORK_PROCESS 503920

DISPATCHER has 1 WP and 0 APPC blks

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 8000 ms

Fri Oct 3 00:49:39 2008

NiBufPoll: Postprocessing...

NiBufPoll: returning 1 clients

NiSelNext: returning hdl 1, r--, 112fc10f8, rest 0 entries (index 0)

NiIPeek: peek successful for hdl -1 / socket 6 (r)

NiIDgReceive - WAKEUP DGRAM received from:

host 127.0.0.1, port C0.E5/49381, fam 2 (low adr..high adr)

NiIPeek: peek for hdl -1 / socket 6 timed out (r; 0)

DpRqServiceQueue: 1 request(s) in NOWP queue ===>>

-IN-- sender_id APPC_SERVER tid -1 wp_ca_blk -1 wp_id -1

-IN-- action SEND_TO_DP uid -1 appc_ca_blk -1 type NOWP

-IN-- new_stat NO_CHANGE mode 255 len -1 rq_id 14

-IN-- req_info LOGIN

DpAsConnect: connect to local gateway

nihsl-getHostAddr: found hostname 'localhost' in cache

nihsi-getHostAddr: hostname 'localhost' = addr 127.0.0.1

nihsl-getServNo: found servicename 'sapgw00' in cache

nihsi-getServNo: servicename 'sapgw00' = port 0C.E4/3300

NiCreateHandle: state hdl 5 / socket -1 NI_INITIAL

nilh-localCheck: using local address list

nilh-localCheck: matched loopback address: 127.0.0.1

NiIBlockMode: switch off block-mode for hdl 5 / socket -1

NiLowLevCon: connect to: /tmp/.sapstream3300 fam 1

NiIInitSocket: set default settings for socket 11

NiISocket: hdl 5 got socket 11

NiPConnect: connect to partner failed (errno 79)

NiLowLevCon: connect to: host 127.0.0.1, port 0C.E4/3300, fam 2 (low adr..high adr)

NiISocket: Closing socket 11

NiIInitSocket: set default settings for socket 11

NiISocket: hdl 5 got socket 11

NiLowLevCon: took local port ED.C3/60867

NiSetStat: state hdl 5 NI_CONNECTED

NiBufHdlInit: BufInit for hdl 5

NiBufSetStat: bufstat of hdl 5 changed from OFF to OK

NiBufWrite starting

NiIWrite: write 64, 1 packs, MESG_IO, hdl 5, data complete

DpAsConnect: add gateway handle 5 to read mask

DpAddSocketInfo: add info for socket 5 (type/idx=5/0)

NiSelSet: hdl 5, r-, 112fc1118

set stat of gateway to AS_LOGGED_IN

DpRqServiceQueue: 0 request(s) in DIA queue ===>>

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

DISPATCHER has 1 WP and 0 APPC blks

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 1000 ms

Fri Oct 3 00:49:40 2008

NiBufPoll: Postprocessing...

NiBufPoll: returning 1 clients

NiSelNext: returning hdl 1, r--, 112fc10f8, rest 0 entries (index 0)

NiIPeek: peek successful for hdl -1 / socket 6 (r)

NiIDgReceive - WAKEUP DGRAM received from:

host 127.0.0.1, port C0.E7/49383, fam 2 (low adr..high adr)

NiIPeek: peek for hdl -1 / socket 6 timed out (r; 0)

DpRqServiceQueue: 1 request(s) in NOWP queue ===>>

-IN-- sender_id WORK_PROCESS tid -1 wp_ca_blk 684 wp_id 7

-IN-- action SEND_TO_DP uid -1 appc_ca_blk -1 type NOWP

-IN-- new_stat WP_WAIT mode 0 len 8 rq_id 15

info for ENQ: wait/run/hold/killed = 1/0/0/0 wps

DpRqNoWpHandle: new state of W7 is WP_WAIT

REL WP ca_blk 684

DpRqServiceQueue: 0 request(s) in DIA queue ===>>

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

DISPATCHER has 1 WP and 0 APPC blks

dp_mytypes: 9d

dp_mytypes: 9d

dp_mytypes: 9d

dp_mytypes: 9d

kill(503920,0) -> ESRCH: process died

info for DIA: wait/run/hold/killed = 0/0/0/5 wps

• ERROR => W4 (pid 503920) died [dpxxdisp.c 13050]

force unlock of wp_adm mutex W4

dp_mytypes: 9c

my types changed after wp death/restart 0x9d --> 0x9c

NiBufSend starting

NiIWrite: write 110, 1 packs, MESG_IO, hdl 3, data complete

MsINiWrite: sent 110 bytes

MsIModTypes: change msgtypes from DIA ENQ BTC SPO ICM to ENQ BTC SPO ICM

dp_mytypes: 9c

dp_mytypes: 9c

kill(544894,0) successful -> process alive

kill(765972,0) successful -> process alive

kill(561392,0) successful -> process alive

kill(811168,0) successful -> process alive

AUTO_TH running in wp = 1

CALL_DELAYED running in wp = 3

AUTO_ABAP running in wp = 4

CALL_DELAYED running in wp = 4

CALL_DELAYED running in wp = 5

CALL_DELAYED running in wp = 6

CALL_DELAYED running in wp = 7

CALL_DELAYED running in wp = 8

CALL_DELAYED running in wp = 9

CALL_DELAYED running in wp = 10

secs 1222994980, active req_info's 0xA000A010/0x2/

DpGetSchedule: found slot 6 (type/tid/mode DPT_AUTOTH/-1/255)

next 1222994980, 0 in queue, autothtime 60

DpSetNextSchedule: next schedule for slot 6: 1222995040

DpGetSchedule: found slot 4 (type/tid/mode DPT_ENVCHECK/-1/255)

DpSetNextSchedule: next schedule for slot 4: 1222995000

call DpEnvCheck

dp_mytypes: 9c

dp_mytypes: 9c

dp_mytypes: 9c

dp_mytypes: 9c

dp_mytypes: 9c

dp_mytypes: 9c

dp_mytypes: 9c

kill(544894,0) successful -> process alive

kill(765972,0) successful -> process alive

kill(561392,0) successful -> process alive

kill(811168,0) successful -> process alive

DpGwCheck: 0

kill(679970,0) successful -> process alive

DpGwCheck: ok

kill(602130,0) successful -> process alive

DpCheckTimeTab: check time table

DpGetSchedule: next schedule 1222995000

DpGetSchedule: no schedule found

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 20000 ms

NiBufPoll: Postprocessing...

NiIRead: read 229, 1 packs, MESG_IO, hdl 3, data complete

NiBufIn: NIBUF len=229

NiBufIn: Packet complete for hdl 3

NiBufPoll: returning 1 clients

NiSelNext: returning hdl 3, r--, 112fc1108, rest 0 entries (index 3)

NiBufReceive starting

MsINiRead: received 229 bytes

MSG received, len 110+119, flag 1, from MSG_SERVER, typ 0, key -

NiICheck2Cancel: reset from status 0

opcode : MS_SERVER_MOD

retcode : MSOP_OK

version : 2

charset : 1

DpMsInfo: request from MSG_SERVER, opcode MS_SERVER_MOD

MsConvMsxxlist: client_version=2, server_version=2

client name : de08u1743_TP1_00

client host : de08u1743

client address : 10.220.136.68

client services : tick-port

client port : 3200

message types : ENQ BTC SPO ICM

status : STARTING

DpMBufMod: state MBUF_PREPARED -> return

DpRqServiceQueue: 0 request(s) in NOWP queue ===>>

DpRqServiceQueue: 0 request(s) in DIA queue ===>>

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

DISPATCHER has 1 WP and 0 APPC blks

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 20000 ms

Fri Oct 3 00:49:47 2008

NiBufPoll: Postprocessing...

NiBufPoll: returning 1 clients

NiSelNext: returning hdl 1, r--, 112fc10f8, rest 0 entries (index 0)

NiIPeek: peek successful for hdl -1 / socket 6 (r)

NiIDgReceive - WAKEUP DGRAM received from:

host 127.0.0.1, port C0.EB/49387, fam 2 (low adr..high adr)

NiIPeek: peek for hdl -1 / socket 6 timed out (r; 0)

DpRqServiceQueue: 1 request(s) in NOWP queue ===>>

-IN-- sender_id WORK_PROCESS tid -1 wp_ca_blk 760 wp_id 8

-IN-- action SEND_TO_DP uid -1 appc_ca_blk -1 type NOWP

-IN-- new_stat WP_WAIT mode 0 len 8 rq_id 16

info for BTC: wait/run/hold/killed = 1/1/0/0 wps

DpRqNoWpHandle: new state of W8 is WP_WAIT

REL WP ca_blk 760

DpRqServiceQueue: 0 request(s) in DIA queue ===>>

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

APPC blk 1530 WORK_PROCESS 765972

DISPATCHER has 1 WP and 0 APPC blks

dp_mytypes: 9c

dp_mytypes: 9c

dp_mytypes: 9c

dp_mytypes: 9c

dp_mytypes: 9c

dp_mytypes: 9c

dp_mytypes: 9c

kill(544894,0) -> ESRCH: process died

info for ENQ: wait/run/hold/killed = 0/0/0/1 wps

• ERROR => W7 (pid 544894) died [dpxxdisp.c 13050]

force unlock of wp_adm mutex W7

dp_mytypes: 98

my types changed after wp death/restart 0x9c --> 0x98

NiBufSend starting

NiIWrite: write 110, 1 packs, MESG_IO, hdl 3, data complete

MsINiWrite: sent 110 bytes

MsIModTypes: change msgtypes from ENQ BTC SPO ICM to BTC SPO ICM

kill(765972,0) successful -> process alive

kill(561392,0) successful -> process alive

kill(811168,0) successful -> process alive

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 13000 ms

NiBufPoll: Postprocessing...

NiIRead: read 229, 1 packs, MESG_IO, hdl 3, data complete

NiBufIn: NIBUF len=229

NiBufIn: Packet complete for hdl 3

NiBufPoll: returning 1 clients

NiSelNext: returning hdl 3, r--, 112fc1108, rest 0 entries (index 3)

NiBufReceive starting

MsINiRead: received 229 bytes

MSG received, len 110+119, flag 1, from MSG_SERVER, typ 0, key -

NiICheck2Cancel: reset from status 0

opcode : MS_SERVER_MOD

retcode : MSOP_OK

version : 2

charset : 1

DpMsInfo: request from MSG_SERVER, opcode MS_SERVER_MOD

MsConvMsxxlist: client_version=2, server_version=2

client name : de08u1743_TP1_00

client host : de08u1743

client address : 10.220.136.68

client services : tick-port

client port : 3200

message types : BTC SPO ICM

status : STARTING

DpMBufMod: state MBUF_PREPARED -> return

DpRqServiceQueue: 0 request(s) in NOWP queue ===>>

DpRqServiceQueue: 0 request(s) in DIA queue ===>>

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

APPC blk 1530 WORK_PROCESS 765972

DISPATCHER has 1 WP and 0 APPC blks

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 13000 ms

Fri Oct 3 00:49:54 2008

NiBufPoll: Postprocessing...

NiBufPoll: returning 1 clients

NiSelNext: returning hdl 1, r--, 112fc10f8, rest 0 entries (index 0)

NiIPeek: peek successful for hdl -1 / socket 6 (r)

NiIDgReceive - WAKEUP DGRAM received from:

host 127.0.0.1, port C0.ED/49389, fam 2 (low adr..high adr)

NiIPeek: peek for hdl -1 / socket 6 timed out (r; 0)

DpRqServiceQueue: 1 request(s) in NOWP queue ===>>

-IN-- sender_id WORK_PROCESS tid -1 wp_ca_blk 836 wp_id 9

-IN-- action SEND_TO_DP uid -1 appc_ca_blk -1 type NOWP

-IN-- new_stat WP_WAIT mode 0 len 8 rq_id 17

info for BTC: wait/run/hold/killed = 2/0/0/0 wps

DpRqNoWpHandle: new state of W9 is WP_WAIT

REL WP ca_blk 836

DpRqServiceQueue: 0 request(s) in DIA queue ===>>

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

DISPATCHER has 1 WP and 0 APPC blks

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

kill(765972,0) -> ESRCH: process died

info for BTC: wait/run/hold/killed = 1/0/0/1 wps

• ERROR => W8 (pid 765972) died [dpxxdisp.c 13050]

force unlock of wp_adm mutex W8

dp_mytypes: 98

kill(561392,0) successful -> process alive

kill(811168,0) successful -> process alive

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 6000 ms

Fri Oct 3 00:50:00 2008

NiIPoll: TIMEOUT occured (6000 ms)

NiBufPoll: timeout

DpRqServiceQueue: 0 request(s) in NOWP queue ===>>

DpRqServiceQueue: 0 request(s) in DIA queue ===>>

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

DISPATCHER has 1 WP and 0 APPC blks

AUTO_TH running in wp = 1

CALL_DELAYED running in wp = 3

AUTO_ABAP running in wp = 4

CALL_DELAYED running in wp = 4

CALL_DELAYED running in wp = 5

CALL_DELAYED running in wp = 6

CALL_DELAYED running in wp = 7

CALL_DELAYED running in wp = 8

CALL_DELAYED running in wp = 9

CALL_DELAYED running in wp = 10

secs 1222995000, active req_info's 0xA000A010/0x2/

DpGetSchedule: found slot 7 (type/tid/mode DPT_CALL_DELAYED/-1/255)

next 1222995000, 0 in queue, call delayed freq 60

DpSetNextSchedule: next schedule for slot 7: 1222995060

DpGetSchedule: found slot 8 (type/tid/mode DPT_SPOOLALRM/-1/255)

next 1222995000, 0 in queue, spoolalrm 60

DpSetNextSchedule: next schedule for slot 8: 1222995060

activate spool alarm

DpRqPutIntoQueue: put request into queue (reqtype 5, prio LOW, rq_id 18)

nihsl-getHostAddr: found hostname 'localhost' in cache

nihsi-getHostAddr: hostname 'localhost' = addr 127.0.0.1

nihsl-getServNo: found servicename 'sapdp00' in cache

nihsi-getServNo: servicename 'sapdp00' = port 0C.80/3200

NiIDgsend: sending on connected datagram-handle

DpGetSchedule: found slot 2 (type/tid/mode DPT_BTCSCHED/-1/255)

next 1222995000, 0 in queue, btctime 60

DpSetNextSchedule: next schedule for slot 2: 1222995060

activate btc-scheduler

DpRqPutIntoQueue: put request into queue (reqtype 1, prio LOW, rq_id 19)

wake_evt_udp_now 1, no dgram needed

DpGetSchedule: found slot 4 (type/tid/mode DPT_ENVCHECK/-1/255)

DpSetNextSchedule: next schedule for slot 4: 1222995020

call DpEnvCheck

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

kill(561392,0) successful -> process alive

kill(811168,0) successful -> process alive

DpGwCheck: 0

kill(679970,0) successful -> process alive

DpGwCheck: ok

kill(602130,0) successful -> process alive

DpCheckTimeTab: check time table

DpGetSchedule: next schedule 1222995020

DpGetSchedule: no schedule found

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 20000 ms

NiBufPoll: Postprocessing...

NiBufPoll: returning 1 clients

NiSelNext: returning hdl 1, r--, 112fc10f8, rest 0 entries (index 0)

NiIPeek: peek successful for hdl -1 / socket 6 (r)

NiIDgReceive - WAKEUP DGRAM received from:

host 127.0.0.1, port C0.C9/49353, fam 2 (low adr..high adr)

NiIPeek: peek for hdl -1 / socket 6 timed out (r; 0)

DpRqServiceQueue: 0 request(s) in NOWP queue ===>>

DpRqServiceQueue: 1 request(s) in DIA queue ===>>

all DIA wp's in use now ...

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 1 request(s) in SPO queue ===>>

all SPO wp's in use now ...

DISPATCHER has 1 WP and 0 APPC blks

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 20000 ms

Fri Oct 3 00:50:01 2008

NiBufPoll: Postprocessing...

NiBufPoll: returning 1 clients

NiSelNext: returning hdl 1, r--, 112fc10f8, rest 0 entries (index 0)

NiIPeek: peek successful for hdl -1 / socket 6 (r)

NiIDgReceive - WAKEUP DGRAM received from:

host 127.0.0.1, port C0.EF/49391, fam 2 (low adr..high adr)

NiIPeek: peek for hdl -1 / socket 6 timed out (r; 0)

DpRqServiceQueue: 1 request(s) in NOWP queue ===>>

-IN-- sender_id WORK_PROCESS tid -1 wp_ca_blk 912 wp_id 10

-IN-- action SEND_TO_DP uid -1 appc_ca_blk -1 type NOWP

-IN-- new_stat WP_WAIT mode 0 len 8 rq_id 20

info for SPO: wait/run/hold/killed = 1/0/0/0 wps

DpRqNoWpHandle: new state of W10 is WP_WAIT

REL WP ca_blk 912

DpRqServiceQueue: 1 request(s) in DIA queue ===>>

all DIA wp's in use now ...

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 1 request(s) in SPO queue ===>>

W10 is free

-IN-- sender_id DISPATCHER tid -1 wp_ca_blk -1 wp_id -1

-IN-- action SEND_TO_WP uid -1 appc_ca_blk -1 type SPO

-IN-- new_stat NO_CHANGE mode 255 len -1 rq_id 18

-IN-- req_info SET_SYSTEM_USER DP_SPOOL_ALRM

DpRqNormalHandle: set SYSTEM_USER T10 U11 M0

DpRqNormalHandle: DpRqCheck o.k.

info for SPO: wait/run/hold/killed = 0/1/0/0 wps

DpRqNormalHandle: new state of W10 is WP_RUN

DpRqNormalHandle: give request to W10 ...

DpRqNormalHandle: set dp_spoolwp_with_token = W10

APPC blk 1836 WORK_PROCESS 811168

DISPATCHER has 1 WP and 0 APPC blks

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

dp_mytypes: 98

kill(561392,0) -> ESRCH: process died

info for BTC: wait/run/hold/killed = 0/0/0/2 wps

• ERROR => W9 (pid 561392) died [dpxxdisp.c 13050]

force unlock of wp_adm mutex W9

dp_mytypes: 90

my types changed after wp death/restart 0x98 --> 0x90

NiBufSend starting

NiIWrite: write 110, 1 packs, MESG_IO, hdl 3, data complete

MsINiWrite: sent 110 bytes

MsIModTypes: change msgtypes from BTC SPO ICM to SPO ICM

kill(811168,0) successful -> process alive

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 19000 ms

NiBufPoll: Postprocessing...

NiIRead: read 229, 1 packs, MESG_IO, hdl 3, data complete

NiBufIn: NIBUF len=229

NiBufIn: Packet complete for hdl 3

NiBufPoll: returning 1 clients

NiSelNext: returning hdl 3, r--, 112fc1108, rest 0 entries (index 3)

NiBufReceive starting

MsINiRead: received 229 bytes

MSG received, len 110+119, flag 1, from MSG_SERVER, typ 0, key -

NiICheck2Cancel: reset from status 0

opcode : MS_SERVER_MOD

retcode : MSOP_OK

version : 2

charset : 1

DpMsInfo: request from MSG_SERVER, opcode MS_SERVER_MOD

MsConvMsxxlist: client_version=2, server_version=2

client name : de08u1743_TP1_00

client host : de08u1743

client address : 10.220.136.68

client services : tick-port

client port : 3200

message types : SPO ICM

status : STARTING

DpMBufMod: state MBUF_PREPARED -> return

DpRqServiceQueue: 0 request(s) in NOWP queue ===>>

DpRqServiceQueue: 1 request(s) in DIA queue ===>>

all DIA wp's in use now ...

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

APPC blk 1836 WORK_PROCESS 811168

DISPATCHER has 1 WP and 0 APPC blks

• DP LOOP ***********

NiBufPoll: Preprocessing...

NiIPoll: timeout 19000 ms

Fri Oct 3 00:50:08 2008

NiBufPoll: Postprocessing...

NiBufPoll: returning 1 clients

NiSelNext: returning hdl 1, r--, 112fc10f8, rest 0 entries (index 0)

NiIPeek: peek successful for hdl -1 / socket 6 (r)

NiIDgReceive - WAKEUP DGRAM received from:

host 127.0.0.1, port C0.F0/49392, fam 2 (low adr..high adr)

NiIPeek: peek for hdl -1 / socket 6 timed out (r; 0)

DpRqServiceQueue: 1 request(s) in NOWP queue ===>>

-IN-- sender_id ICMAN tid -1 wp_ca_blk -1 wp_id -1

-IN-- action SEND_TO_DP uid -1 appc_ca_blk -1 type NOWP

-IN-- new_stat NO_CHANGE mode 0 len -1 rq_id 21

-IN-- req_info LOGIN DP_ICM_EVENT

DpIcmConnect: connect to ICM

DpIcmConnect: use port 65000, timeout=5000

nihsl-getHostAddr: found hostname 'localhost' in cache

nihsi-getHostAddr: hostname 'localhost' = addr 127.0.0.1

nihsi-getServNo: servicename '65000' = port FD.E8/65000

NiCreateHandle: state hdl 6 / socket -1 NI_INITIAL

nilh-localCheck: using local address list

nilh-localCheck: matched loopback address: 127.0.0.1

NiIBlockMode: switch off block-mode for hdl 6 / socket -1

NiLowLevCon: connect to: /tmp/.sapstream65000 fam 1

NiIInitSocket: set default settings for socket 12

NiISocket: hdl 6 got socket 12

NiLowLevCon: took local port FD.E8/65000

NiSetStat: state hdl 6 NI_CONNECTED

NiBufHdlInit: BufInit for hdl 6

NiBufSetStat: bufstat of hdl 6 changed from OFF to OK

NiBufIAlloc: malloc ICM_EXT, to 101 bytes

NiBufSend starting

NiIWrite: write 101, 1 packs, MESG_IO, hdl 6, data complete

DpIcmConnect: add icm handle 6 to read mask

DpAddSocketInfo: add info for socket 6 (type/idx=6/0)

NiSelSet: hdl 6, r-, 112fc1120

set state of ICM to ICM_LOGGED_IN

DpRqServiceQueue: 1 request(s) in DIA queue ===>>

all DIA wp's in use now ...

DpRqServiceQueue: 0 request(s) in UPD queue ===>>

DpRqServiceQueue: 0 request(s) in ENQ queue ===>>

DpRqServiceQueue: 0 request(s) in BTC queue ===>>

DpRqServiceQueue: 0 request(s) in SPO queue ===>>

DISPATCHER has 1 WP and 0 APPC blks

dp_mytypes: 90

dp_mytypes: 90

dp_mytypes: 90

dp_mytypes: 90

dp_mytypes: 90

dp_mytypes: 90

dp_mytypes: 90

dp_mytypes: 90

dp_mytypes: 90

dp_mytypes: 90

kill(811168,0) -> ESRCH: process died

info for SPO: wait/run/hold/killed = 0/0/0/1 wps

• ERROR => W10 (pid 811168) died [dpxxdisp.c 13050]

force unlock of wp_adm mutex W10

dp_mytypes: 80

my types changed after wp death/restart 0x90 --> 0x80

NiBufSend starting

NiIWrite: write 110, 1 packs, MESG_IO, hdl 3, data complete

MsINiWrite: sent 110 bytes

MsIModTypes: change msgtypes from SPO ICM to ICM

• DP_FATAL_ERROR => DpWPCheck: no more work processes

• DISPATCHER EMERGENCY SHUTDOWN ***

increase tracelevel of WPs

killing W0-577588 (SIGUSR2)

• ERROR => DpWpKill(577588, SIGUSR2) failed [dpxxtool.c 2508]

killing W1-729318 (SIGUSR2)

• ERROR => DpWpKill(729318, SIGUSR2) failed [dpxxtool.c 2508]

killing W2-774364 (SIGUSR2)

• ERROR => DpWpKill(774364, SIGUSR2) failed [dpxxtool.c 2508]

killing W3-676070 (SIGUSR2)

• ERROR => DpWpKill(676070, SIGUSR2) failed [dpxxtool.c 2508]

killing W4-503920 (SIGUSR2)

• ERROR => DpWpKill(503920, SIGUSR2) failed [dpxxtool.c 2508]

killing W5-692224 (SIGUSR2)

• ERROR => DpWpKill(692224, SIGUSR2) failed [dpxxtool.c 2508]

killing W6-671998 (SIGUSR2)

• ERROR => DpWpKill(671998, SIGUSR2) failed [dpxxtool.c 2508]

killing W7-544894 (SIGUSR2)

• ERROR => DpWpKill(544894, SIGUSR2) failed [dpxxtool.c 2508]

killing W8-765972 (SIGUSR2)

• ERROR => DpWpKill(765972, SIGUSR2) failed [dpxxtool.c 2508]

killing W9-561392 (SIGUSR2)

• ERROR => DpWpKill(561392, SIGUSR2) failed [dpxxtool.c 2508]

killing W10-811168 (SIGUSR2)

• ERROR => DpWpKill(811168, SIGUSR2) failed [dpxxtool.c 2508]

NiWait: sleep (10000 msecs) ...

NiISelect: timeout 10000 ms

NiISelect: maximum fd=13

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Fri Oct 3 00:50:18 2008

NiISelect: TIMEOUT occured (10000 ms)

dump system status

Workprocess Table (long) Fri Oct 3 00:50:18 2008

========================

No Ty. Pid Status Cause Start Err Sem CPU Time Program Cl User Action Table

-

---

0 DIA 577588 Ended no 1 0 0

1 DIA 729318 Ended no 1 0 0

2 DIA 774364 Ended no 1 0 0

3 DIA 676070 Ended no 1 0 0

4 DIA 503920 Ended no 1 0 0

5 UPD 692224 Ended no 1 0 0

6 UPD 671998 Ended no 1 0 0

7 ENQ 544894 Ended no 1 0 0

8 BTC 765972 Ended no 1 0 0

9 BTC 561392 Ended no 1 0 0

10 SPO 811168 Ended no 1 0 0

Dispatcher Queue Statistics Fri Oct 3 00:50:18 2008

===========================

--------

---

+

---

+

---

+

---

+--

---

+

Typ

now

high

max

writes

reads

--------

---

+

---

+

---

+

---

+--

---

+

NOWP

4

4

4000

16

12

--------

---

+

---

+

---

+

---

+--

---

+

DIA

2

3

4000

6

4

--------

---

+

---

+

---

+

---

+--

---

+

UPD

0

0

4000

0

0

--------

---

+

---

+

---

+

---

+--

---

+

ENQ

0

0

4000

0

0

--------

---

+

---

+

---

+

---

+--

---

+

BTC

0

0

4000

0

0

--------

---

+

---

+

---

+

---

+--

---

+

SPO

0

1

4000

1

1

--------

---

+

---

+

---

+

---

+--

---

+

UP2

0

0

4000

0

0

--------

---

+

---

+

---

+

---

+--

---

+

max_rq_id 26

wake_evt_udp_now 1

wake events total 22, udp 16 ( 72%), shm 6 ( 27%)

since last update total 22, udp 16 ( 72%), shm 6 ( 27%)

Dump of tm_adm structure: Fri Oct 3 00:50:18 2008

=========================

Term uid man user term lastop mod wp ta a/i (modes)

Workprocess Comm. Area Blocks Fri Oct 3 00:50:18 2008

=============================

Slots: 1000, Used: 0, Max: 0

--------

---

+

---

+--

---

+

id

owner

pid

eyecatcher

--------

---

+

---

+--

---

+

0

DISPATCHER

-1

WPCAAD000

NiWait: sleep (5000 msecs) ...

NiISelect: timeout 5000 ms

NiISelect: maximum fd=13

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Fri Oct 3 00:50:23 2008

NiISelect: TIMEOUT occured (5000 ms)

DpHalt: shutdown server >de08u1743_TP1_00 < (normal)

DpJ2eeDisableRestart

DpModState: buffer in state MBUF_PREPARED

NiBufSend starting

NiIWrite: write 110, 1 packs, MESG_IO, hdl 3, data complete

MsINiWrite: sent 110 bytes

MsIModState: change state to SHUTDOWN

DpModState: change server state from STARTING to SHUTDOWN

Switch off Shared memory profiling

ShmProtect( 57, 3 )

ShmProtect(SHM_PROFILE, SHM_PROT_RW

ShmProtect( 57, 1 )

ShmProtect(SHM_PROFILE, SHM_PROT_RD

DpWakeUpWps: wake up all wp's

Stop work processes...

Stop gateway

killing proc (679970) (SOFT_KILL)

Stop icman

killing proc (602130) (SOFT_KILL)

Terminate gui connections

kill(679970,0) successful -> process alive

waiting for termination of gateway

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=13

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Fri Oct 3 00:50:24 2008

NiISelect: TIMEOUT occured (1000 ms)

kill(679970,0) -> ESRCH: process died

kill(602130,0) successful -> process alive

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=13

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Fri Oct 3 00:50:25 2008

NiISelect: TIMEOUT occured (1000 ms)

kill(602130,0) successful -> process alive

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=13

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Fri Oct 3 00:50:26 2008

NiISelect: TIMEOUT occured (1000 ms)

kill(602130,0) successful -> process alive

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=13

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Fri Oct 3 00:50:27 2008

NiISelect: TIMEOUT occured (1000 ms)

kill(602130,0) successful -> process alive

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=13

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Fri Oct 3 00:50:28 2008

NiISelect: TIMEOUT occured (1000 ms)

kill(602130,0) successful -> process alive

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=13

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Fri Oct 3 00:50:29 2008

NiISelect: TIMEOUT occured (1000 ms)

kill(602130,0) successful -> process alive

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=13

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Fri Oct 3 00:50:30 2008

NiISelect: TIMEOUT occured (1000 ms)

kill(602130,0) successful -> process alive

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=13

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Fri Oct 3 00:50:31 2008

NiISelect: TIMEOUT occured (1000 ms)

kill(602130,0) successful -> process alive

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=13

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Fri Oct 3 00:50:32 2008

NiISelect: TIMEOUT occured (1000 ms)

kill(602130,0) successful -> process alive

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=13

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Fri Oct 3 00:50:33 2008

NiISelect: TIMEOUT occured (1000 ms)

kill(602130,0) successful -> process alive

waiting for termination of icman

NiWait: sleep (1000 msecs) ...

NiISelect: timeout 1000 ms

NiISelect: maximum fd=13

NiISelect: read-mask is NULL

NiISelect: write-mask is NULL

Fri Oct 3 00:50:34 2008

NiISelect: TIMEOUT occured (1000 ms)

kill(602130,0) -> ESRCH: process died

DpHalt: cancel all lcom connections

MPI CancelAll 2 -> 0

MPI DeleteAll 2 -> 0

DpStartStopMsg: send stop message (myname is >de08u1743_TP1_00 <)

NiIMyHostName: hostname = 'de08u1743'

AdGetSelfIdentRecord: > <

AdCvtRecToExt: opcode 60 (AD_SELFIDENT), ser 0, ex 0, errno 0

AdCvtRecToExt: opcode 4 (AD_STARTSTOP), ser 0, ex 0, errno 0

DpConvertRequest: net size = 163 bytes

NiBufSend starting

NiIWrite: write 562, 1 packs, MESG_IO, hdl 3, data complete

MsINiWrite: sent 562 bytes

send msg (len 110+452) to name -, type 4, key -

DpStartStopMsg: stop msg sent

NiIRead: read 229, 1 packs, MESG_IO, hdl 3, data complete

NiBufIn: NIBUF len=229

NiBufIn: Packet complete for hdl 3

NiBufReceive starting

MsINiRead: received 229 bytes

MSG received, len 110+119, flag 1, from MSG_SERVER, typ 0, key -

DpHalt: received 119 bytes from message server

NiIRead: read 229, 1 packs, MESG_IO, hdl 3, data complete

NiBufIn: NIBUF len=229

NiBufIn: Packet complete for hdl 3

NiBufReceive starting

MsINiRead: received 229 bytes

MSG received, len 110+119, flag 1, from MSG_SERVER, typ 0, key -

DpHalt: received 119 bytes from message server

DpHalt: no more messages from the message server

DpHalt: send keepalive to synchronize with the message server

NiBufSend starting

NiIWrite: write 114, 1 packs, MESG_IO, hdl 3, data complete

MsINiWrite: sent 114 bytes

send msg (len 110+4) to name MSG_SERVER, type 0, key -

MsSndName: MS_NOOP ok

Send 4 bytes to MSG_SERVER

NiIPeek: peek successful for hdl 3 / socket 9 (r)

NiIRead: read 114, 1 packs, MESG_IO, hdl 3, data complete

NiBufIn: NIBUF len=114

NiBufIn: Packet complete for hdl 3

NiBufReceive starting

MsINiRead: received 114 bytes

MSG received, len 110+4, flag 3, from MSG_SERVER, typ 0, key -

Received 4 bytes from MSG_SERVER

Received opcode MS_NOOP from msg_server, reply MSOP_OK

MsOpReceive: ok

MsSendKeepalive : keepalive sent to message server

Fri Oct 3 00:50:35 2008

NiIPeek: peek for hdl 3 / socket 9 timed out (r; 1000)

DpHalt: no more messages from the message server

DpHalt: sync with message server o.k.

detach from message server

***LOG Q0M=> DpMsDetach, ms_detach () [dpxxdisp.c 10757]

NiBufSend starting

NiIWrite: write 110, 1 packs, MESG_IO, hdl 3, data complete

MsINiWrite: sent 110 bytes

MsIDetach: send logout to msg_server

MsIDetach: call exit function

DpMsShutdownHook called

NiSelClear: removed hdl 3 from selectset

MBUF state OFF

AdGetSelfIdentRecord: > <

AdCvtRecToExt: opcode 60 (AD_SELFIDENT), ser 0, ex 0, errno 0

AdCvtRecToExt: opcode 40 (AD_MSBUF), ser 0, ex 0, errno 0

AdCvtRecToExt: opcode 40 (AD_MSBUF), ser 0, ex 0, errno 0

blks_in_queue/wp_ca_blk_no/wp_max_no = 0/1000/11

LOCK WP ca_blk 1

make DISP owner of wp_ca_blk 1

DpRqPutIntoQueue: put request into queue (reqtype 1, prio LOW, rq_id 33)

MBUF component DOWN

NiBufClose: clear extensions for hdl 3

NiBufSetStat: bufstat of hdl 3 changed from OK to OFF

NiICloseHandle: shutdown and close hdl 3 / socket 9

MsIDetach: detach MS-system

ESSHM: ====================> EsCleanup <====================

ShmDelete( 13 )

cleanup event management

cleanup shared memory/semaphores

ShmCleanup SHM_SYS_ADM_KEY

ShmCleanup( 1 )

ShmCreate( 1, 0, 2, 0xfffffffffffeb10 )

ShmProtect( 1, 3 )

ShmCreate( 1, 0, 2, -> 0x700000000096000 )

ShmCleanup SHM_DP_ADM_KEY

ShmCleanup( 2 )

ShmCreate( 2, 0, 2, 0xfffffffffffeb10 )

ShmProtect( 2, 3 )

ShmCreate( 2, 0, 2, -> 0x700000030002000 )

ShmCleanup SHM_DP_CA_KEY

ShmCleanup( 3 )

ShmCreate( 3, 0, 2, 0xfffffffffffeb10 )

ShmProtect( 3, 3 )

ShmCreate( 3, 0, 2, -> 0x700000034975000 )

ShmCleanup SHM_PF_KEY

ShmCleanup( 4 )

ShmCreate( 4, 0, 2, 0xfffffffffffeb10 )

ShmGet( 10, 4, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 10, 4, 0, 2, -> 0x70000000a3ae000 )

ShmProtect( 4, 3 )

ShmProtect: Key=4 not attached

ShmProtect( 4, 3 )

ShmCreate( 4, 0, 2, -> 0x70000000a3ae000 )

ShmCleanup SHM_PRES_BUF

ShmCleanup( 14 )

ShmCreate( 14, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 14, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 40, 14, 0, 2, -> 0x7000000402d8000 )

ShmProtect( 14, 3 )

ShmProtect: Key=14 not attached

ShmProtect( 14, 3 )

ShmCreate( 14, 0, 2, -> 0x7000000402d8000 )

ShmCleanup SHM_CALI_BUFFER

ShmCleanup( 11 )

ShmCreate( 11, 0, 2, 0xfffffffffffeb10 )

ShmGet( 10, 11, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 10, 11, 0, 2, -> 0x70000001c24c000 )

ShmProtect( 11, 3 )

ShmProtect: Key=11 not attached

ShmProtect( 11, 3 )

ShmCreate( 11, 0, 2, -> 0x70000001c24c000 )

ShmCleanup SHM_DB_TBUFF

ShmCleanup( 19 )

ShmCreate( 19, 0, 2, 0xfffffffffffeb10 )

ShmGet( 10, 19, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 10, 19, 0, 2, -> 0x70000000a430000 )

ShmProtect( 19, 3 )

ShmProtect: Key=19 not attached

ShmProtect( 19, 3 )

ShmCreate( 19, 0, 2, -> 0x70000000a430000 )

ShmCleanup SHM_DB_TBUFF_P

ShmCleanup( 33 )

ShmCreate( 33, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 33, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 40, 33, 0, 2, -> 0x700000051171000 )

ShmProtect( 33, 3 )

ShmProtect: Key=33 not attached

ShmProtect( 33, 3 )

ShmCreate( 33, 0, 2, -> 0x700000051171000 )

ShmCleanup SHM_DB_STBUFF

ShmCleanup( 41 )

ShmCreate( 41, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 41, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 40, 41, 0, 2, -> 0x70000004fdc6000 )

ShmProtect( 41, 3 )

ShmProtect: Key=41 not attached

ShmProtect( 41, 3 )

ShmCreate( 41, 0, 2, -> 0x70000004fdc6000 )

ShmCleanup SHM_DB_TTBUFF

ShmCleanup( 42 )

ShmCreate( 42, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 42, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 40, 42, 0, 2, -> 0x70000004f222000 )

ShmProtect( 42, 3 )

ShmProtect: Key=42 not attached

ShmProtect( 42, 3 )

ShmCreate( 42, 0, 2, -> 0x70000004f222000 )

ShmCleanup SHM_DB_FTBUFF

ShmCleanup( 43 )

ShmCreate( 43, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 43, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 40, 43, 0, 2, -> 0x700000048b0f000 )

ShmProtect( 43, 3 )

ShmProtect: Key=43 not attached

ShmProtect( 43, 3 )

ShmCreate( 43, 0, 2, -> 0x700000048b0f000 )

ShmCleanup SHM_DB_SNTBUFF

ShmCleanup( 45 )

ShmCreate( 45, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 45, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 40, 45, 0, 2, -> 0x70000004ee29000 )

ShmProtect( 45, 3 )

ShmProtect: Key=45 not attached

ShmProtect( 45, 3 )

ShmCreate( 45, 0, 2, -> 0x70000004ee29000 )

ShmCleanup SHM_DB_IRBUFF

ShmCleanup( 44 )

ShmCreate( 44, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 44, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 40, 44, 0, 2, -> 0x70000004e6c5000 )

ShmProtect( 44, 3 )

ShmProtect: Key=44 not attached

ShmProtect( 44, 3 )

ShmCreate( 44, 0, 2, -> 0x70000004e6c5000 )

ShmCleanup SHM_DB_OBJ_BUFFER

ShmCleanup( 54 )

ShmCreate( 54, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 54, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 40, 54, 0, 2, -> 0x70000005731f000 )

ShmProtect( 54, 3 )

ShmProtect: Key=54 not attached

ShmProtect( 54, 3 )

ShmCreate( 54, 0, 2, -> 0x70000005731f000 )

ShmCleanup SHM_DB_SYNC

ShmCleanup( 46 )

ShmCreate( 46, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 46, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 40, 46, 0, 2, -> 0x70000005731b000 )

ShmProtect( 46, 3 )

ShmProtect: Key=46 not attached

ShmProtect( 46, 3 )

ShmCreate( 46, 0, 2, -> 0x70000005731b000 )

ShmCleanup SHM_DB_CUA_BUFFER

ShmCleanup( 47 )

ShmCreate( 47, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 47, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 40, 47, 0, 2, -> 0x70000005c543000 )

ShmProtect( 47, 3 )

ShmProtect: Key=47 not attached

ShmProtect( 47, 3 )

ShmCreate( 47, 0, 2, -> 0x70000005c543000 )

ShmCleanup SHM_DB_OTR_BUFFER

ShmCleanup( 64 )

ShmCreate( 64, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 64, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 40, 64, 0, 2, -> 0x70000005e291000 )

ShmProtect( 64, 3 )

ShmProtect: Key=64 not attached

ShmProtect( 64, 3 )

ShmCreate( 64, 0, 2, -> 0x70000005e291000 )

ShmCleanup SHM_DB_ESM_BUFFER

ShmCleanup( 65 )

ShmCreate( 65, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 65, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 40, 65, 0, 2, -> 0x70000005c141000 )

ShmProtect( 65, 3 )

ShmProtect: Key=65 not attached

ShmProtect( 65, 3 )

ShmCreate( 65, 0, 2, -> 0x70000005c141000 )

ShmCleanup SHM_ROLL_AREA_KEY

ShmCleanup( 9 )

ShmCreate( 9, 0, 2, 0xfffffffffffeb10 )

ShmGet( 10, 9, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 10, 9, 0, 2, -> 0x700000002268000 )

ShmProtect( 9, 3 )

ShmProtect: Key=9 not attached

ShmProtect( 9, 3 )

ShmCreate( 9, 0, 2, -> 0x700000002268000 )

ShmCleanup SHM_PAGING_AREA_KEY

ShmCleanup( 8 )

ShmCreate( 8, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 8, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 40, 8, 0, 2, -> 0x70000004459b000 )

ShmProtect( 8, 3 )

ShmProtect: Key=8 not attached

ShmProtect( 8, 3 )

ShmCreate( 8, 0, 2, -> 0x70000004459b000 )

ShmCleanup SHM_ROLL_ADM_KEY

ShmCleanup( 17 )

ShmCreate( 17, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 17, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 40, 17, 0, 2, -> 0x70000004859c000 )

ShmProtect( 17, 3 )

ShmProtect: Key=17 not attached

ShmProtect( 17, 3 )

ShmCreate( 17, 0, 2, -> 0x70000004859c000 )

ShmCleanup SHM_PAGING_ADM_KEY

ShmCleanup( 18 )

ShmCreate( 18, 0, 2, 0xfffffffffffeb10 )

ShmGet( 10, 18, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 10, 18, 0, 2, -> 0x70000000a269000 )

ShmProtect( 18, 3 )

ShmProtect: Key=18 not attached

ShmProtect( 18, 3 )

ShmCreate( 18, 0, 2, -> 0x70000000a269000 )

ShmCleanup SHM_PXA_KEY

ShmCleanup( 6 )

ShmCreate( 6, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 6, 0, 2, 0xfffffffffffe9a8 )

ShmCleanup SHM_ENQ_TABLE_KEY

ShmCleanup( 34 )

ShmCreate( 34, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 34, 0, 2, 0xfffffffffffe9a8 )

ShmCleanup SHM_ENQID_KEY

ShmCleanup( 58 )

ShmCreate( 58, 0, 2, 0xfffffffffffeb10 )

ShmGet( 10, 58, 0, 2, 0xfffffffffffe9a8 )

ShmCleanup SHM_VB_ADM_KEY

ShmCleanup( 7 )

ShmCreate( 7, 0, 2, 0xfffffffffffeb10 )

ShmGet( 10, 7, 0, 2, 0xfffffffffffe9a8 )

ShmGet( 10, 7, 0, 2, -> 0x70000000a3ab000 )

ShmProtect( 7, 3 )

ShmProtect: Key=7 not attached

ShmProtect( 7, 3 )

ShmCreate( 7, 0, 2, -> 0x70000000a3ab000 )

ShmCleanup SHM_DB_POOL

ShmCleanup( 40 )

ShmCreate( 40, 0, 2, 0xfffffffffffeb10 )

ShmProtect( 40, 3 )

ShmCreate( 40, 0, 2, -> 0x700000030000000 )

Fri Oct 3 00:50:36 2008

ShmCleanup SHM_EM_ADM

ShmCleanup( 51 )

ShmCreate( 51, 0, 2, 0xfffffffffffeb10 )

ShmProtect( 51, 3 )

ShmCreate( 51, 0, 2, -> 0x7000000000a7000 )

ShmCleanup SHM_MSBUF

ShmCleanup( 52 )

ShmCreate( 52, 0, 2, 0xfffffffffffeb10 )

ShmProtect( 52, 3 )

ShmCreate( 52, 0, 2, -> 0x700000000098000 )

ShmCleanup SHM_THRUN_ADM_KEY(th run adm)

ShmCleanup( 30 )

ShmCreate( 30, 0, 2, 0xfffffffffffeb10 )

ShmGet( 10, 30, 0, 2, 0xfffffffffffe9a8 )

ShmCleanup SHM_JAVA

ShmCleanup( 70 )

ShmCreate( 70, 0, 2, 0xfffffffffffeb10 )

ShmKeyPermission( 70 ) = 0740 (octal)

ShmCleanup SHM_PF_AS_KEY(Appl.Statistics)

ShmCleanup( 56 )

ShmCreate( 56, 0, 2, 0xfffffffffffeb10 )

ShmGet( 10, 56, 0, 2, 0xfffffffffffe9a8 )

ShmCleanup SHM_POOL_0

ShmCleanup( 10 )

ShmCreate( 10, 0, 2, 0xfffffffffffeb10 )

ShmProtect( 10, 3 )

ShmCreate( 10, 0, 2, -> 0x700000000000000 )

ShmCleanup SHM_PROFILE

ShmCleanup( 57 )

ShmCreate( 57, 0, 2, 0xfffffffffffeb10 )

ShmGet( 10, 57, 0, 2, 0xfffffffffffe9a8 )

ShmCreate( 10, 0, 2, 0xfffffffffffe7f8 )

ShmKeyPermission( 10 ) = 0740 (octal)

ShmCleanup SHM_NO_BUFFER

ShmCleanup( 48 )

ShmCreate( 48, 0, 2, 0xfffffffffffeb10 )

ShmGet( 40, 48, 0, 2, 0xfffffffffffe9a8 )

ShmCreate( 40, 0, 2, 0xfffffffffffe7f8 )

ShmKeyPermission( 40 ) = 0740 (octal)

MiCleanup:

ShmCleanup( 62 )

ShmCreate( 62, 0, 2, 0xfffffffffffea80 )

ShmGet( 40, 62, 0, 2, 0xfffffffffffe918 )

ShmCreate( 40, 0, 2, 0xfffffffffffe768 )

ShmKeyPermission( 40 ) = 0740 (octal)

• ERROR => ShmCleanup(62) failed 3 [mpixx.c 3100]

MpiCleanup() -> 1

removing Semaphore-Management

removing request queue

ShmCleanup( 31 )

ShmCreate( 31, 0, 2, 0xfffffffffffea90 )

ShmGet( 40, 31, 0, 2, 0xfffffffffffe928 )

ShmCreate( 40, 0, 2, 0xfffffffffffe778 )

ShmKeyPermission( 40 ) = 0740 (octal)

• ERROR => DpRqIQRemove: ShmDelete [dpxxqueu.c 514]

closing connect handles (dgm + tcp)

DpDelSocketInfo: del info for socket 1 (type=2)

NiICloseHandle: shutdown and close hdl 1 / socket 6

DpDelSocketInfo: del info for socket 2 (type=1)

NiBufClose: clear extensions for hdl 2

NiBufSetStat: bufstat of hdl 2 changed from OK to OFF

NiICloseHandle: shutdown and close hdl 2 / socket 7

***LOG Q05=> DpHalt, DPStop ( 782442) [dpxxdisp.c 9341]

• shutdown completed - server stopped ***

Good Bye .....

tp1adm@de08u1743:/usr/sap/TP1/DVEBMGS00/work>

/>

[Former Member]

HI Mathew

First check vth r/3trans -d wat z de if it connects dere z no problem vth db

then check he startsap log then u can analyze de start up problem

o w

see work directory u can get some use information

[former_member204746]

please post dev_w0

[mathew_varughese]

---

trc file: "dev_w0", trc level: 2, release: "640"

-

---

*

• ACTIVE TRACE LEVEL 2

• ACTIVE TRACE COMPONENTS All

*

M Fri Oct 3 14:00:00 2008

M

M command line arg 0: dw.sapTP1_DVEBMGS00

M command line arg 1: pf=/usr/sap/TP1/SYS/profile/TP1_DVEBMGS00_de08u1743

M

M sysno 00

M sid TP1

M systemid 324 (IBM RS/6000 with AIX)

M relno 6400

M patchlevel 0

M patchno 249

M intno 20020600

M make: single threaded, ASCII, 64 bit

M pid 585868

M

M tskhstart: taskhandler started

M SigIRegisterExitRoutine: exithandler installed (ThSigHandler)

M SigIRegisterRoutine: handler for signal 20 installed (SIG_IGN)

M ThrRegisterHooks: register basic hooks

M SosRegisterHook: add hook >ThrBtcStartUp(110c83650)< for event AFTER_MSG_UP

M SosRegisterHook: add hook >ThrBtcStartUp(110c83650)< for event AFTER_OPMODE_SWITCH

M SosRegisterHook: add hook >ThrSaveSPAFields(110c83668)< for event BEFORE_DUMP

M SosRegisterHook: add hook >db_init(110c83680)< for event CREATE_SHM

M SosRegisterHook: add hook >ThVBDbaRequired(110c83698)< for event DB_ADMIN_REQUIRED

M SosRegisterHook: add hook >ThrRegisterSem(110c836b0)< for event BEFORE_DB_CONNECT

M ThrRegisterHooks o.k.

M

M ***LOG Q01=> tskh_init, WPStart (Workproc 0 1 585868) [thxxhead.c 1145]

M

M ThSetTraceLevel: save trace level

M nihsl-flush: cache flushed

M tskh_init: dummy spa pointer = 1128016d0

M tskh_init: save spa pointer 112baa5f0

M ThInitCpicStack: init cpic stack

M NiIMyHostName: hostname = 'de08u1743'

M ThPrivInit o.k.

M ThSysUsrCheck(1,1): check system user T0/U1/M0

M ThSysUsrCheck: clear T0

M DpITmSlotClear: clear slot 0

M DpListRemove: elem 0 not in tmadm_free_list

M DpListInsert: elem 0 already in tmadm_inuse_list

M ThCallHooks: call hook >ThrRegisterSem< for event BEFORE_DB_CONNECT

M SosRegisterSem: malloc/realloc table

M SosRegisterSem: add sem >SEM_TM_ADM_KEY< for wptype 0x77

M SosRegisterSem: add sem >SEM_COMM_ADM_KEY< for wptype 0x77

M SosRegisterSem: add sem >SEM_STAT_KEY< for wptype 0x73

M SosRegisterSem: add sem >SEM_SHM_ADM_AREA_KEY< for wptype 0x73

M SosRegisterSem: add sem >SEM_REQ_QUEUE_KEY< for wptype 0x77

M SosRegisterSem: add sem >SEM_REQ_QUEUE_DIA_KEY< for wptype 0x77

M SosRegisterSem: add sem >SEM_REQ_QUEUE_READ_KEY< for wptype 0x77

M SosRegisterSem: add sem >SEM_MSBUF< for wptype 0x73

M SosRegisterSem: add sem >SEM_ASTAT_KEY< for wptype 0x73

M SosRegisterSem: add sem >SEM_PROFILE< for wptype 0x73

M SosRegisterSem: malloc/realloc table

M SosRegisterSem: add sem >SEM_THRUN_ADM< for wptype 0x73

M SosRegisterSem: add sem >SEM_PXA_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_PXA_KEY o.k.

M SosRegisterSem: add sem >SEM_ROLL_ADM_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_ROLL_ADM_KEY o.k.

M SosRegisterSem: add sem >SEM_PAGING_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_PAGING_KEY o.k.

M SosRegisterSem: add sem >SEM_CALI_BUFFER< for wptype 0x73

M ThrRegisterSem: init SEM_CALI_BUFFER o.k.

M SosRegisterSem: add sem >SEM_CCC_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_CCC_KEY o.k.

M SosRegisterSem: add sem >SEM_VB_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_VB_KEY o.k.

M SosRegisterSem: add sem >SEM_PRES_BUF< for wptype 0x73

M ThrRegisterSem: init SEM_PRES_BUF o.k.

M SosRegisterSem: add sem >SEM_DB_TBUFF< for wptype 0x73

M ThrRegisterSem: init SEM_DB_TBUFF o.k.

M SosRegisterSem: add sem >SEM_DB_SYNC< for wptype 0x73

M ThrRegisterSem: init SEM_DB_SYNC o.k.

M SosRegisterSem: malloc/realloc table

M SosRegisterSem: add sem >SEM_DB_TTAB< for wptype 0x73

M ThrRegisterSem: init SEM_DB_TTAB o.k.

M SosRegisterSem: add sem >SEM_DB_SNTAB< for wptype 0x73

M ThrRegisterSem: init SEM_DB_SNTAB o.k.

M SosRegisterSem: add sem >SEM_DB_IREC< for wptype 0x73

M ThrRegisterSem: init SEM_DB_IREC o.k.

M SosRegisterSem: add sem >SEM_DB_FTAB< for wptype 0x73

M ThrRegisterSem: init SEM_DB_FTAB o.k.

M SosRegisterSem: add sem >SEM_LOGFILE_KEY< for wptype 0x77

M ThrRegisterSem: init SEM_LOGFILE_KEY o.k.

M SosRegisterSem: add sem >SEM_DB_TBUFF_P< for wptype 0x73

M ThrRegisterSem: init SEM_DB_TBUFF_P o.k.

M SosRegisterSem: add sem >SEM_ENQ_REQ_KEY< for wptype 0x77

M ThrRegisterSem: init SEM_ENQ_REQ_KEY o.k.

M SosRegisterSem: add sem >SEM_ENQ_TABLE_KEY< for wptype 0x77

M ThrRegisterSem: init SEM_ENQ_TABLE_KEY o.k.

M SosRegisterSem: add sem >SEM_SAPCOM_1< for wptype 0x73

M ThrRegisterSem: init SEM_SAPCOM_1 o.k.

M SosRegisterSem: add sem >SEM_SAPCOM_2< for wptype 0x73

M ThrRegisterSem: init SEM_SAPCOM_2 o.k.

M SosRegisterSem: malloc/realloc table

M SosRegisterSem: add sem >SEM_VMS_FIXADR_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_VMS_FIXADR_KEY o.k.

M SosRegisterSem: add sem >SEM_DB_CUA_BUFFER< for wptype 0x73

M ThrRegisterSem: init SEM_DB_CUA_BUFFER o.k.

M SosRegisterSem: add sem >SEM_RSPO_ADMIN< for wptype 0x73

M ThrRegisterSem: init SEM_RSPO_ADMIN o.k.

M SosRegisterSem: add sem >SEM_DB_OBJ_BUFFER< for wptype 0x73

M ThrRegisterSem: init SEM_DB_OBJ_BUFFER o.k.

M SosRegisterSem: add sem >SEM_CCMS_AS_MONI_KEY< for wptype 0x77

M ThrRegisterSem: init SEM_CCMS_AS_MONI_KEY o.k.

M SosRegisterSem: add sem >SEM_EG_ADM< for wptype 0x77

M ThrRegisterSem: init SEM_EG_ADM o.k.

M SosRegisterSem: add sem >SEM_STAT_BUF< for wptype 0x73

M ThrRegisterSem: init SEM_STAT_BUF o.k.

M SosRegisterSem: add sem >SEM_RSPO_CACHE< for wptype 0x73

M ThrRegisterSem: init SEM_RSPO_CACHE o.k.

M SosRegisterSem: add sem >SEM_AUDIT_SHM< for wptype 0x73

M ThrRegisterSem: init SEM_AUDIT_SHM o.k.

M SosRegisterSem: add sem >SEM_RSPO_RFC< for wptype 0x73

M ThrRegisterSem: init SEM_RSPO_RFC o.k.

M SosRegisterSem: malloc/realloc table

M SosRegisterSem: add sem >SEM_AB_VMIT< for wptype 0x73

M ThrRegisterSem: init SEM_AB_VMIT o.k.

M SosRegisterSem: add sem >SEM_ENQID_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_ENQID_KEY o.k.

M SosRegisterSem: add sem >SEM_ATRA_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_ATRA_KEY o.k.

M SosRegisterSem: add sem >SEM_COV_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_COV_KEY o.k.

M SosRegisterSem: add sem >SEM_ZDATE_TIMEINFO< for wptype 0x73

M ThrRegisterSem: init SEM_ZDATE_TIMEINFO o.k.

M SosRegisterSem: add sem >SEM_DB_OTR_BUFFER< for wptype 0x73

M ThrRegisterSem: init SEM_DB_OTR_BUFFER o.k.

M SosRegisterSem: add sem >SEM_DB_ESM_BUFFER< for wptype 0x73

M ThrRegisterSem: init SEM_DB_ESM_BUFFER o.k.

M SosRegisterSem: add sem >SEM_RTM_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_RTM_KEY o.k.

M SosRegisterSem: add sem >SEM_JAVA_1< for wptype 0x1

M ThrRegisterSem: init SEM_JAVA_1 o.k.

M calling db_connect ...

B db_con_connect (con_name=R/3)

B find_con_by_name found the following connection for reuse:

B 0: name = R/3, con_id = 000000000 state = DISCONNECTED, perm = YES, reco = NO , timeout = 000, con_max = 255, con_opt = 255, occ = NO

C Got ORACLE_HOME=/oracle/client/92x_64 from environment

C -->oci_initialize (con_hdl=0)

C got NLS_LANG='AMERICAN_AMERICA.US7ASCII' from environment

C Client NLS settings: AMERICAN_AMERICA.US7ASCII

C Logon as OPS$-user to get SAPR3's password

C Connecting as /@TP1 on connection 0 (nls_hdl 0) ... (dbsl 640 070208)

C Nls CharacterSet NationalCharSet C EnvHp ErrHp ErrHpBatch

C 0 US7ASCII 1 11309a980 1130a62b8 1130a5b68

C Allocating service context handle for con_hdl=0

C Allocating server context handle

C Attaching to DB Server TP1 (con_hdl=0,svchp=1130a3398,srvhp=1130a6ab8)

C Assigning server context 1130a6ab8 to service context 1130a3398

C Allocating user session handle

C Starting user session (con_hdl=0,svchp=1130a3398,srvhp=1130a6ab8,usrhp=1130a27a0)

C Assigning user session 1130a27a0 to service context 1130a3398

C BEGIN DBMS_APPLICATION_INFO.SET_MODULE (:A0, :A1); END;

C BEGIN DBMS_APPLICATION_INFO.SET_CLIENT_INFO (:A0); END;

C SELECT SID FROM V$MYSTAT WHERE ROWNUM<2

C Connected to session 55.

C Now '/@TP1' is connected: con_hdl=0, nls_hdl=0, session_id=55.

C ALTER SESSION SET NLS_SORT = BINARY

C SELECT USERID,PASSWD FROM SAPUSER WHERE USERID IN (:A0, :A1)

C Got SAPR3's password from OPS$-user

C Disconnecting from connection 0 ...

C Rolling back transaction ...

C Close user session (con_hdl=0,svchp=1130a3398,usrhp=1130a27a0)

C Now I'm disconnected from ORACLE

C Connecting as SAPR3/<pwd>@TP1 on connection 0 (nls_hdl 0) ... (dbsl 640 070208)

C Nls CharacterSet NationalCharSet C EnvHp ErrHp ErrHpBatch

C 0 US7ASCII 1 11309a980 1130a62b8 1130a5b68

C Assigning username to user session 1130a27a0

C Assigning password to user session 1130a27a0

C Starting user session (con_hdl=0,svchp=1130a3398,srvhp=1130a6ab8,usrhp=1130a27a0)

C Assigning user session 1130a27a0 to service context 1130a3398

C BEGIN DBMS_APPLICATION_INFO.SET_MODULE (:A0, :A1); END;

C BEGIN DBMS_APPLICATION_INFO.SET_CLIENT_INFO (:A0); END;

C SELECT SID FROM V$MYSTAT WHERE ROWNUM<2

C Connected to session 55.

C Now 'SAPR3/<pwd>@TP1' is connected: con_hdl=0, nls_hdl=0, session_id=55.

C ALTER SESSION SET NLS_SORT = BINARY

C SELECT VALUE FROM V$NLS_PARAMETERS WHERE PARAMETER IN ('NLS_LANGUAGE', 'NLS_TERRITORY', 'NLS_CHARACTERSET') ORDER BY PARAM

C ETER

C Database NLS settings: AMERICAN_AMERICA.US7ASCII

C SELECT UPPER(INSTANCE_NAME),HOST_NAME,VERSION,TO_CHAR(STARTUP_TIME,'MON DD, YYYY, HH24:MI:SS') FROM V$INSTANCE

C DB instance TP1 is running on de08u1641 with ORACLE version 10.2.0.2.0 since OCT 03, 2008, 00:27:57

C SELECT SUBSTR(NAME,1,3), TO_CHAR(CREATED,'YYYYMMDDHHMMSS') FROM V$DATABASE

B Connection 0 opened (DBSL handle 0)

B Wp Hdl ConName ConId ConState TX PRM RCT TIM MAX OPT Date Time DBHost

B 000 000 R/3 000000000 ACTIVE NO YES NO 000 255 255 20081003 140000 de08u1641

C SELECT VERSION FROM SVERS

M db_connect o.k.

M ICT: HTTP compression only above 1024 bytes

M ICT: maximum size of HTTP messages: 101 MB

M ICT: exclude compression: .zip,.cs,.rar,.arj,.z,.gz,.tar,.lzh,.cab,.hqx,.ace,.jar,.ear,.war,.css,.pdf,.js,.gzip,.uue,.bz2,.iso,.sda,.sar,.gif

M ICT: exclude_compression: *.zip

M ICT: exclude_compression: *.cs

M ICT: exclude_compression: *.rar

M ICT: exclude_compression: *.arj

M ICT: exclude_compression: *.z

M ICT: exclude_compression: *.gz

M ICT: exclude_compression: *.tar

M ICT: exclude_compression: *.lzh

M ICT: exclude_compression: *.cab

M ICT: exclude_compression: *.hqx

M ICT: exclude_compression: *.ace

M ICT: exclude_compression: *.jar

M ICT: exclude_compression: *.ear

M ICT: exclude_compression: *.war

M ICT: exclude_compression: *.css

M ICT: exclude_compression: *.pdf

M ICT: exclude_compression: *.js

M ICT: exclude_compression: *.gzip

M ICT: exclude_compression: *.uue

M ICT: exclude_compression: *.bz2

M ICT: exclude_compression: *.iso

M ICT: exclude_compression: *.sda

M ICT: exclude_compression: *.sar

M ICT: exclude_compression: *.gif

M ICT: client-side urlencoding of cookie values enabled

M ThJInit: init java layer

M ThPlgRegister: register handler for protocol 17 (HTTP(JAVA))

M ThJInit: java not active

M Fri Oct 3 14:00:20 2008

M

M ThSetThParam : zcsa/presentation_buffer_area=70000640

M zcsa/presentation_buffer_area = 70000640

M ThSetThParam : rdisp/ro_blks=NULL

M rdisp/ro_blks = 8192

M ThSetThParam : rdisp/ROLL_MAXFS=65920

M rdisp/ROLL_MAXFS = 65920

M ThSetThParam : rdisp/ROLL_SHM=16384

M rdisp/ROLL_SHM = 16384

M ThSetThParam : rdisp/rrol_file=/usr/sap/TP1/DVEBMGS00/data/ROLLFL00

M rdisp/rrol_file = /usr/sap/TP1/DVEBMGS00/data/ROLLFL00

M ThSetThParam : rdisp/PG_MAXFS=65536

M rdisp/PG_MAXFS = 65536

M ThSetThParam : rdisp/PG_SHM=8192

M rdisp/PG_SHM = 8192

M ThSetThParam : rdisp/rpag_file=/usr/sap/TP1/DVEBMGS00/data/PAGFIL00

M rdisp/rpag_file = /usr/sap/TP1/DVEBMGS00/data/PAGFIL00

M ThSetThParam : rdisp/wpdbug_max_no=1

M rdisp/wpdbug_max_no = 1

M gw_hostname : de08u1743

M nihsl-getHostAddr: got hostname 'de08u1743' from operating system

M nihsi-getHostAddr: hostname 'de08u1743' = addr 10.220.136.68

M ThSetGwParam : gw_hostaddr = [10.220.136.68]

M ThSetGwParam : gw_service = sapgw00

M ThSetThParam : rdisp/sna_gateway=de08u1743

M nihsl-getHostAddr: found hostname 'de08u1743' in cache

M nihsi-getHostAddr: hostname 'de08u1743' = addr 10.220.136.68

M ThSetThParam : rdisp/sna_gw_service=sapgw00

M nihsl-getServNo: got servicename 'sapgw00' from operating system

M nihsi-getServNo: servicename 'sapgw00' = port 0C.E4/3300

M rdisp/sna_gw_service = sapgw00

M rdisp/sna_gateway = de08u1743

M sna_gw_hostaddr = [10.220.136.68]

M ThSetThParam : rdisp/max_arq=500

M rdisp/max_arq = 500

M ThSetThParam : nobuf/max_no_buffer_entries=1000

M nobuf/max_no_buffer_entries = 1000

M ThSetThParam : nobuf/hash_factor=NULL

M nobuf/hash_factor = 2.5

M ThSetThParam : rdisp/vb_dispatching=1

M rdisp/vb_dispatching = 1

M ThSetThParam : rdisp/accept_remote_trace_level=1

M rdisp/accept_remote_trace_level = 1

M ThSetThParam : rdisp/max_vb_server=50

M rdisp/max_vb_server = 50

M ThSetThParam : rdisp/max_alt_modes=6

M rdisp/max_alt_modes = 6

M ThSetThParam : rdisp/rfc_server_pooling=OFF

M rdisp/rfc_server_pooling = 0

M ThSetThParam : rdisp/rfc_pool_timeout=300

M rdisp/rfc_pool_timeout = 300 sec

M ThSetThParam : rdisp/rfc_pool_size=10

M rdisp/rfc_pool_size = 500

M ThSetThParam : rdisp/plugin_server_pooling=OFF

M rdisp/plugin_server_pooling = 0

M ThSetThParam : rdisp/plugin_pool_timeout=300

M rdisp/plugin_pool_timeout = 300 sec

M ThSetThParam : rdisp/context_pool_size=90

M rdisp/context_pool_size = 4500

M ThSetThParam : rdisp/rfc_server_auto_logout=0

M rdisp/rfc_server_auto_logout = 0 sec

M ThSetThParam : rdisp/plugin_auto_logout=1800

M rdisp/plugin_auto_logout = 1800 sec

M ThInitializeSysId: my sid is >TP1<

M write th-profile-values into sys_adm

M

I MtxInit: 0 0 0

I ShmGet( 40, 14, 70000640, 3, -> 0x7000000a02d8000 )

I ShmProtect( 14, 3 )

I ShmProtect: Key=14 not attached

I ShmProtect( 14, 3 )

I ShmCreate( 14, 70000640, 3, -> 0x7000000a02d8000 )

M SHM_PRES_BUF (addr: 7000000a02d8000, size: 70000640)

I ShmGet( 10, 9, 134217728, 3, -> 0x700000002268000 )

I ShmProtect( 9, 3 )

I ShmProtect: Key=9 not attached

I ShmProtect( 9, 3 )

I ShmCreate( 9, 134217728, 3, -> 0x700000002268000 )

M SHM_ROLL_AREA (addr: 700000002268000, size: 134217728)

I ShmGet( 40, 8, 67108864, 3, -> 0x7000000a459b000 )

I ShmProtect( 8, 3 )

I ShmProtect: Key=8 not attached

I ShmProtect( 8, 3 )

I ShmCreate( 8, 67108864, 3, -> 0x7000000a459b000 )

M SHM_PAGING_AREA (addr: 7000000a459b000, size: 67108864)

I ShmGet( 40, 17, 5374766, 3, -> 0x7000000a859c000 )

I ShmProtect( 17, 3 )

I ShmProtect: Key=17 not attached

I ShmProtect( 17, 3 )

I ShmCreate( 17, 5374766, 3, -> 0x7000000a859c000 )

M SHM_ROLL_ADM (addr: 7000000a859c000, size: 5374766)

I ShmGet( 10, 18, 1311776, 3, -> 0x70000000a269000 )

I ShmProtect( 18, 3 )

I ShmProtect: Key=18 not attached

I ShmProtect( 18, 3 )

I ShmCreate( 18, 1311776, 3, -> 0x70000000a269000 )

M SHM_PAGING_ADM (addr: 70000000a269000, size: 1311776)

I ShmGet( 40, 48, 324144, 3, -> 0x7000000a8abe000 )

I ShmProtect( 48, 3 )

I ShmProtect: Key=48 not attached

I ShmProtect( 48, 3 )

I ShmCreate( 48, 324144, 3, -> 0x7000000a8abe000 )

M ThCreateNoBuffer allocated 324144 bytes for 1000 entries at 7000000a8abe000

M ThCreateNoBuffer index size: 3000 elems

M SosRegisterMemAddr: alloc/realloc table

M SosRegisterMemAddr: add mem addr 7000000a8abe000 / >num_buf_hdr<

M SosRegisterMemAddr: add mem addr 7000000a8abe090 / >num_buf_idx<

M SosRegisterMemAddr: add mem addr 7000000a8adb550 / >num_buf_data<

M nobuf/max_attempts = 1200

M SosRegisterHook: add hook >ThNoClearPrevErr(110c6c1a8)< for event AFTER_COMMIT

M SosRegisterHook: add hook >ThNoClearPrevErr(110c6c1a8)< for event AFTER_ROLLBACK

M SosRegisterHook: add hook >ThNoResetMutex(110c6c1c0)< for event AFTER_ROLLBACK

M SosRegisterSem: add sem >SEM_NO_BUFFER< for wptype 0x73

M ThCreateNoBuffer max attempts: 1200

M ThNoCheckMutex: check for remaining locked intervals

M ThCreateNoBuffer: my code page = >1100<

M ThJRegNatives: java not active

M ThCreateNoBuffer o.k.

M rdisp/vb_stop_active = 1

I ShmGet( 10, 7, 7040, 3, -> 0x70000000a3ab000 )

I ShmProtect( 7, 3 )

I ShmProtect: Key=7 not attached

I ShmProtect( 7, 3 )

I ShmCreate( 7, 7040, 3, -> 0x70000000a3ab000 )

M ThCreateVBAdm allocated 7040 bytes (50 server) at 70000000a3ab000

M SosRegisterMemAddr: add mem addr 70000000a3ab000 / >vb_info<

M SosRegisterMemAddr: add mem addr 70000000a3ab030 / >vb_disp<

M SosRegisterMemAddr: add mem addr 70000000a3ab188 / >vb_stat<

M SosRegisterMemAddr: add mem addr 70000000a3ab280 / >vb_server<

M ThCreateVBAdm: my code page = >1100<

M ThCreateVBAdm o.k.

X EmInit () Lock method = 2

X EmInit: MmSetImplementation( 2 ).

X ESSHM: ====================> EsGetAdmSize <====================

I ShmProtect( 51, 3 )

I ShmCreate( 51, 15239956, 2, -> 0x7000000000a7000 )

X ESSHM: ====================> EsINit <====================

X EM/TOTAL_SIZE_MB = 8192

X ESSHM: ====================> NOTICE <====================

X ESSHM: INIT for client : 0

X ESSHM: Segment Count : 4096

X ESSHM: Segment Size : 0x40000000 1024MB

X ESSHM: Base Addr : 0x0

X ESSHM: Process Seg Count : 64

X ESSHM: Block Size : 4096KB

X ESSHM: User Count : 4096

X ESSHM: Client Count : 30

X ESSHM: Sys Free Max : 20

X ESSHM: Sys Init Segs : 25

X ESSHM: DiscBlockPerSeg : 2

X ESSHM: DiscSegments : 1024

X ESSHM: DiscSegInitCount : 100

X ESSHM: DiscSegMax : 4096

X ESSHM: DiscSegMin : 10

X ESSHM: DiscBlkMax : 4

X ESSHM: DiscBlkMin : 0

X ESSHM: Idle Segments : 10

X ESSHM: Max Blocks : 8192

X ESSHM: Max Private Segs : 63

X ESSHM: Max Shared Segs : 1

X ESSHM: Flags : 3620866 (0x374002)

X ESSHM: Block Mapping : 0

X ESSHM: ====================> NOTICE <====================

X EgInit: esBlkSz = 4194304, maxEsBlocks = 68, segSz = (285212672 EG_MAX_SEGSIZE = 34359738367

X ESSHM: ====================> EsAttach <====================

X esATTACH handle=1 BlockCurrent=68

X ESSHM: attach Context=1 handle=1 BlockPerSeg=255 BlocksFree=187 SegId=0x21003a5

X esATTACH return

X #29 EG-Memory attached. client 0, size 272 MB, base 7000010c0221000, admBase 700000000ab9ee0

X MmxRegisterRscAdm 4 110c616f0 110c61708

X MmxRegisterRscAdm 5 110c61720 110c61738

M EmInit o.k.

M PfDdicCheck: ddic structures o.k.

M PfReadSharedParam: attach to shared parameter area

M PfReadSharedParam: stat/max_files = 48

M PfReadSharedParam: save stat-file each 3600 seconds

M max_buf_size 500000

M compression 1

M checks 0

I ShmGet( 10, 4, 525976, 3, -> 0x70000000a3ae000 )

I ShmProtect( 4, 3 )

I ShmProtect: Key=4 not attached

I ShmProtect( 4, 3 )

I ShmCreate( 4, 525976, 3, -> 0x70000000a3ae000 )

M PfCreateShm allocated 525976 bytes

M PfCreateShm: pf_sh_eye_begin: 70000000a3ae000

M PfCreateShm: pf_adm: 70000000a3ae008 (size=160)

M PfCreateShm: pf_shared_rec_area: 70000000a3ae0a8 (size=5880)

M PfCreateShm: pf_shared_statindrec: 70000000a3af7a0 (size=136)

M PfCreateShm: pf_shared_buf_head: 70000000a3af828 (size=152)

M PfCreateShm: pf_shared_buf: 70000000a3af8c0 (size=500000)

M PfCreateShm: pf_hyperindex header: 70000000a4299e0 (size=19632)

M records=<70000000a429a10>

M PfCreateShm: pf_sh_eye_end: 70000000a42e690

M PfCreateShm o.k.

M ThJCreateShm: java not available

M ThShMCreate: ThJCreateShm failed

M ThCallHooks: call hook >db_init< for event CREATE_SHM

M SosRegisterHook: add hook >db_x_short_mem_sync_point(110c82438)< for event AFTER_STORAGE_FREE

B NTAB: attach to shared memory buffers

I ShmGet( 40, 43, 0, 2, -> 0x7000000a8b0f000 )

I ShmProtect( 43, 3 )

I ShmProtect: Key=43 not attached

I ShmProtect( 43, 3 )

I ShmCreate( 43, 0, 2, -> 0x7000000a8b0f000 )

I ShmGet( 40, 44, 0, 2, -> 0x7000000ae6c5000 )

I ShmProtect( 44, 3 )

I ShmProtect: Key=44 not attached

I ShmProtect( 44, 3 )

I ShmCreate( 44, 0, 2, -> 0x7000000ae6c5000 )

I ShmGet( 40, 45, 0, 2, -> 0x7000000aee29000 )

I ShmProtect( 45, 3 )

I ShmProtect: Key=45 not attached

I ShmProtect( 45, 3 )

I ShmCreate( 45, 0, 2, -> 0x7000000aee29000 )

I ShmGet( 40, 42, 0, 2, -> 0x7000000af222000 )

I ShmProtect( 42, 3 )

I ShmProtect: Key=42 not attached

I ShmProtect( 42, 3 )

I ShmCreate( 42, 0, 2, -> 0x7000000af222000 )

B dbntab: NTAB buffers attached

B dbntab: Buffer FTAB(hash header) (addr: 7000000a8b0f088, size: 504)

B dbntab: Buffer FTAB(anchor array) (addr: 7000000a8b0f280, size: 800024)

B dbntab: Buffer FTAB(item array) (addr: 7000000a8bd2798, size: 3200000)

B dbntab: Buffer FTAB(data area) (addr: 7000000a8edfb98, size: 92160000)

B dbntab: Buffer IREC(hash header) (addr: 7000000ae6c5088, size: 504)

B dbntab: Buffer IREC(anchor array) (addr: 7000000ae6c5280, size: 800024)

B dbntab: Buffer IREC(item array) (addr: 7000000ae788798, size: 800000)

B dbntab: Buffer IREC(data area) (addr: 7000000ae84bc98, size: 6144000)

B dbntab: Buffer STAB(hash header) (addr: 7000000aee29088, size: 504)

B dbntab: Buffer STAB(anchor array) (addr: 7000000aee29280, size: 800024)

B dbntab: Buffer STAB(item array) (addr: 7000000aeeec798, size: 800000)

B dbntab: Buffer STAB(data area) (addr: 7000000aefafc98, size: 2560000)

B dbntab: Buffer TTAB(hash header) (addr: 7000000af222088, size: 1544)

B dbntab: Buffer TTAB(anchor array) (addr: 7000000af222690, size: 800024)

B dbntab: Buffer TTAB(item array) (addr: 7000000af2e5ba8, size: 2000000)

B dbntab: Buffer TTAB(data area) (addr: 7000000af4ce028, size: 9400000)

B NTAB: ntab_mem_protocol 7000000af222088, ntab_mp_p 7000000af222088, ntab_proc_id 0

B NTAB: FTAB: hh_p 7000000a8b0f088, ha_list 7000000a8b0f280, hi_list 7000000a8bd2798, buffer 7000000a8edfb98

B NTAB: IREC: hh_p 7000000ae6c5088, ha_list 7000000ae6c5280, hi_list 7000000ae788798, buffer 7000000ae84bc98

B NTAB: STAB: hh_p 7000000aee29088, ha_list 7000000aee29280, hi_list 7000000aeeec798, buffer 7000000aefafc98

B NTAB: TTAB: hh_p 7000000af222498, ha_list 7000000af222690, hi_list 7000000af2e5ba8, buffer 7000000af4ce028

B NTAB: maxcnt 50000.

B NTAB: hfactor 100003.

B NTAB: mem_protocol_size 1040

B NTAB: hdr_backpack_offset 152

B NTAB: hdr_backpack_size 36

B NTAB: FTAB: header_size 504.

B NTAB: FTAB: item_size 64.

B NTAB: FTAB: item_cnt 50000.

B NTAB: FTAB: unit_size 4

B NTAB: FTAB: unit_cnt 23040000.

B NTAB: FTAB: data_size 92160000.

B NTAB: IREC: header_size 504.

B NTAB: IREC: item_size 64.

B NTAB: IREC: item_cnt 12500.

B NTAB: IREC: unit_size 8

B NTAB: IREC: unit_cnt 768000.

B NTAB: IREC: data_size 6144000.

B NTAB: STAB: header_size 504.

B NTAB: STAB: item_size 64.

B NTAB: STAB: item_cnt 12500.

B NTAB: STAB: unit_size 4

B NTAB: STAB: unit_cnt 640000.

B NTAB: STAB: data_size 2560000.

B NTAB: TTAB: header_size 1544.

B NTAB: TTAB: item_size 40.

B NTAB: TTAB: item_cnt 50000.

B NTAB: TTAB: unit_size 188

B NTAB: TTAB: unit_cnt 50000.

B NTAB: TTAB: data_size 9400000.

B entering db_stinit

I ShmGet( 40, 41, 0, 2, -> 0x7000000afdc6000 )

I ShmProtect( 41, 3 )

I ShmProtect: Key=41 not attached

I ShmProtect( 41, 3 )

I ShmCreate( 41, 0, 2, -> 0x7000000afdc6000 )

B db_con_shm_ini: WP_ID = 0, WP_CNT = 10, CON_ID = -1

B db_con_shm_ini: size of allocated shared memory = 18600 bytes

B db_con_shm_ini: size of used shared memory = 18600 bytes

B dbstat: TABSTAT buffer attached (addr: 7000000afdcab08)

I ShmGet( 10, 19, 0, 2, -> 0x70000000a430000 )

I ShmProtect( 19, 3 )

I ShmProtect: Key=19 not attached

I ShmProtect( 19, 3 )

I ShmCreate( 19, 0, 2, -> 0x70000000a430000 )

B dbtbxbuf: Buffer TABL (addr: 70000000a430100, size: 300000256, end: 70000001c24a500)

I ShmGet( 40, 33, 0, 2, -> 0x7000000b1171000 )

I ShmProtect( 33, 3 )

I ShmProtect: Key=33 not attached

I ShmProtect( 33, 3 )

I ShmCreate( 33, 0, 2, -> 0x7000000b1171000 )

B dbtbxbuf: Buffer TABLP (addr: 7000000b1171100, size: 102400000, end: 7000000b7319100)

I ShmGet( 40, 46, 0, 2, -> 0x7000000b731b000 )

I ShmProtect( 46, 3 )

I ShmProtect: Key=46 not attached

I ShmProtect( 46, 3 )

I ShmCreate( 46, 0, 2, -> 0x7000000b731b000 )

I ShmGet( 40, 54, 0, 2, -> 0x7000000b731f000 )

I ShmProtect( 54, 3 )

I ShmProtect: Key=54 not attached

I ShmProtect( 54, 3 )

I ShmCreate( 54, 0, 2, -> 0x7000000b731f000 )

B dbexpbuf: Buffer EIBUF (addr: 7000000b731f108, size: 81920000, end: 7000000bc13f108)

I ShmGet( 40, 65, 0, 2, -> 0x7000000bc141000 )

I ShmProtect( 65, 3 )

I ShmProtect: Key=65 not attached

I ShmProtect( 65, 3 )

I ShmCreate( 65, 0, 2, -> 0x7000000bc141000 )

B dbexpbuf: Buffer ESM (addr: 7000000bc141108, size: 4194304, end: 7000000bc541108)

I ShmGet( 40, 47, 0, 2, -> 0x7000000bc543000 )

I ShmProtect( 47, 3 )

I ShmProtect: Key=47 not attached

I ShmProtect( 47, 3 )

I ShmCreate( 47, 0, 2, -> 0x7000000bc543000 )

B dbexpbuf: Buffer CUA (addr: 7000000bc543108, size: 30720000, end: 7000000be28f108)

I ShmGet( 40, 64, 0, 2, -> 0x7000000be291000 )

I ShmProtect( 64, 3 )

I ShmProtect: Key=64 not attached

I ShmProtect( 64, 3 )

I ShmCreate( 64, 0, 2, -> 0x7000000be291000 )

B dbexpbuf: Buffer OTR (addr: 7000000be291108, size: 4194304, end: 7000000be691108)

I ShmGet( 10, 11, 0, 2, -> 0x70000001c24c000 )

I ShmProtect( 11, 3 )

I ShmProtect: Key=11 not attached

I ShmProtect( 11, 3 )

I ShmCreate( 11, 0, 2, -> 0x70000001c24c000 )

B dbcalbuf: Buffer CALE (addr: 70000001c24c000, size: 500000, end: 70000001c2c6120)

M ThSetMBufHooks: set hooks for server buffer

M Set Hook for BtcServerChange o.k.

M pfinit_clock o.k.

M pg_init_shm o.k.

M ThInitNoBuffer: initialize header

M ThInitNoBuffer: prime no: 2503

M ThInitNoBuffer: initialize entry area

M ThInitNoBuffer: initialize index area

M ThInitNoBuffer o.k.

Y ===> ===> ===> ===> ===> message: JCB 00 010 ABAP: 0: ExcHdlNoHdl

Y ===> Var_1<MtxOpen PbufAll>

M -

---

C-STACK -

---

-

---

=> 64 bit R/3 Kernel

=> 64 bit AIX Kernel

=> Heap limit = unlimited

=> Stack limit = unlimited

=> Core limit = 1073741312

=> File size limit = unlimited

=> Heap address = 0x11314fe20

=> Stack address = 0xfffffffffffdc70

=> Stack low = 0xfffffffffffdc70

=> Stack high = 0xffffffffffff860

-

---

=> Stack Trace:

AixStack() at 0x1000209ec

CTrcStack2() at 0x100020ae4

ThControl() at 0x1004e0e34

dyThControl() at 0x10047c58c

dextIntern() at 0x10047b588

sap_dext() at 0x10048143c

pbufinit() at 0x100d097d4

ThrtPBufInit() at 0x1004e0264

ThShMInit() at 0x100094cd8

ThIPCInit() at 0x1000a93a0

tskh_init() at 0x1000aa458

tskhstart() at 0x1000be2f0

DpMain() at 0x1015fc87c

nlsui_main() at 0x1015cdab8

M -

---

M ***LOG R1B=> ThControl, sap_dext-call (00 ) [thxxdynp.c 380]

M in_ThErrHandle: 1

M *** ERROR => ThControl: illegal sap_dext call !!! (step 1, th_errno 5, action 1, level 1) [thxxhead.c 9708]

M

M Info for wp 0

M

M stat = 4

M reqtype = 1

M act_reqtype = -1

M rq_info = 0

M tid = -1

M mode = 255

M len = -1

M rq_id = 65535

M rq_source = 255

M last_tid = 0

M last_mode = 0

M int_checked_resource(RFC) = 0

M ext_checked_resource(RFC) = 0

M int_checked_resource(HTTP) = 0

M ext_checked_resource(HTTP) = 0

M report = > <

M action = 0

M tab_name = > <

M PfStatDisconnect: disconnect statistics

M Entering ThSetStatError

M DpLockWpBlkEx: my wp blk start index is 166

M LOCK WP ca_blk 166

M ThAtWpBlk: set zttatiln to zero

M ThAtWpBlk: set zttatoln to zero

M DpLockAppcBlk: my appc blk start index is 332

M LOCK APPC ca_blk 332

M Entering ThReadDetachMode

M ThISend: (tm/user/type/info = -1/ ?/0x41/0x0, mode_deleted=0)

M TASK_SWITCH

M DISP

M ThRqOutCheck: o.k.

M abap strategy ROLL / O.K.

M ThNewWpStat (type=0x41, task_switch=1, inline_hold=0, hand_shake=0, debug=0, ..)

M ThISend: new wp stat: 0x2

M rdisp/wp_auto_restart = 0 sec

M rdisp/wp_abap_restart = 0

M ThSetThToolParam : restartable_wp[0] = FALSE

M ThSetThToolParam : restartable_wp[1] = TRUE

M ThSetThToolParam : restartable_wp[2] = TRUE

M ThSetThToolParam : restartable_wp[3] = FALSE

M ThSetThToolParam : restartable_wp[4] = TRUE

M ThSetThToolParam : restartable_wp[5] = TRUE

M ThSetThToolParam : restartable_wp[6] = TRUE

M ThISend: clear stat record

M ThISend: th_wp_waits_for_rq = TH_WP_WAITS_FOR_RQ

M make DISP owner of wp_ca_blk 166

M DpSemRq: key: 23, units: 1, timeout: -1

M DpSemRel: key: 23, units: 1

M DpRqPutIntoQueue: put request into queue (reqtype 0, prio LOW, rq_id 21)

M -OUT- sender_id WORK_PROCESS tid -1 wp_ca_blk 166 wp_id 0

M -OUT- action SEND_TO_DP uid -1 appc_ca_blk -1 type NOWP

M -OUT- new_stat WP_WAIT mode 0 len 8 rq_id 21

M wake_evt_udp_now 1, no dgram needed

M REL APPC ca_blk 332

P enter pg_com

P pg_com: 0 blks in buf, 0 in file, 0 new since last commit

M ThISend: still inside critical section semaphore ?

[Former Member]

Hi,

If your system is running on unix, then clean the SHM using cleanipc tool and restart the system.

Hope this may help you else sorry.

Regards

Kumar

[mathew_varughese]

Guys,

Please note R3trans -d, x works ..but the issue was workprocess die after 5mins

The reason was someone switched on the table logging and when sap tries to come up, it goes out of memory.

Issue is resolved

Thanks,

Mathew

[Former Member]

Hi Mathew,

Check trace file dev_w0 for exact error infromation or paste it here.

open a command prompt and execute command r3trans -d it will generate a log file trans.log.check that one for errors.

Regards

Ashok

[mathew_varughese]

R3trans -x and -d works and RC 00

dev_wp0

-

---

trc file: "dev_w0", trc level: 3, release: "640"

-

---

*

• ACTIVE TRACE LEVEL 3

• ACTIVE TRACE COMPONENTS All

*

M Fri Oct 3 05:29:07 2008

M

M command line arg 0: dw.sapTP1_DVEBMGS00

M command line arg 1: pf=/usr/sap/TP1/SYS/profile/TP1_DVEBMGS00_de08u1743

M

M sysno 00

M sid TP1

M systemid 324 (IBM RS/6000 with AIX)

M relno 6400

M patchlevel 0

M patchno 249

M intno 20020600

M make: single threaded, ASCII, 64 bit

M pid 712924

M

M tskhstart: taskhandler started

M SigIRegisterExitRoutine: exithandler installed (ThSigHandler)

M SigIRegisterRoutine: handler for signal 20 installed (SIG_IGN)

M ThrRegisterHooks: register basic hooks

M SosRegisterHook: add hook >ThrBtcStartUp(110c83650)< for event AFTER_MSG_UP

M SosRegisterHook: add hook >ThrBtcStartUp(110c83650)< for event AFTER_OPMODE_SWITCH

M SosRegisterHook: add hook >ThrSaveSPAFields(110c83668)< for event BEFORE_DUMP

M SosRegisterHook: add hook >db_init(110c83680)< for event CREATE_SHM

M SosRegisterHook: add hook >ThVBDbaRequired(110c83698)< for event DB_ADMIN_REQUIRED

M SosRegisterHook: add hook >ThrRegisterSem(110c836b0)< for event BEFORE_DB_CONNECT

M ThrRegisterHooks o.k.

M

M ***LOG Q01=> tskh_init, WPStart (Workproc 0 1 712924) [thxxhead.c 1145]

M

M ThSetTraceLevel: save trace level

M nihsl-flush: cache flushed

M tskh_init: dummy spa pointer = 1128016d0

M tskh_init: save spa pointer 112baa5f0

M ThInitCpicStack: init cpic stack

M NiIMyHostName: hostname = 'de08u1743'

M ThPrivInit o.k.

M ThSysUsrCheck(1,1): check system user T0/U1/M0

M ThSysUsrCheck: clear T0

M DpITmSlotClear: clear slot 0

M DpListRemove: elem 0 not in tmadm_free_list

M DpListInsert: elem 0 already in tmadm_inuse_list

M ThCallHooks: call hook >ThrRegisterSem< for event BEFORE_DB_CONNECT

M SosRegisterSem: malloc/realloc table

M SosRegisterSem: add sem >SEM_TM_ADM_KEY< for wptype 0x77

M SosRegisterSem: add sem >SEM_COMM_ADM_KEY< for wptype 0x77

M SosRegisterSem: add sem >SEM_STAT_KEY< for wptype 0x73

M SosRegisterSem: add sem >SEM_SHM_ADM_AREA_KEY< for wptype 0x73

M SosRegisterSem: add sem >SEM_REQ_QUEUE_KEY< for wptype 0x77

M SosRegisterSem: add sem >SEM_REQ_QUEUE_DIA_KEY< for wptype 0x77

M SosRegisterSem: add sem >SEM_REQ_QUEUE_READ_KEY< for wptype 0x77

M SosRegisterSem: add sem >SEM_MSBUF< for wptype 0x73

M SosRegisterSem: add sem >SEM_ASTAT_KEY< for wptype 0x73

M SosRegisterSem: add sem >SEM_PROFILE< for wptype 0x73

M SosRegisterSem: malloc/realloc table

M SosRegisterSem: add sem >SEM_THRUN_ADM< for wptype 0x73

M SosRegisterSem: add sem >SEM_PXA_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_PXA_KEY o.k.

M SosRegisterSem: add sem >SEM_ROLL_ADM_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_ROLL_ADM_KEY o.k.

M SosRegisterSem: add sem >SEM_PAGING_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_PAGING_KEY o.k.

M SosRegisterSem: add sem >SEM_CALI_BUFFER< for wptype 0x73

M ThrRegisterSem: init SEM_CALI_BUFFER o.k.

M SosRegisterSem: add sem >SEM_CCC_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_CCC_KEY o.k.

M SosRegisterSem: add sem >SEM_VB_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_VB_KEY o.k.

M SosRegisterSem: add sem >SEM_PRES_BUF< for wptype 0x73

M ThrRegisterSem: init SEM_PRES_BUF o.k.

M SosRegisterSem: add sem >SEM_DB_TBUFF< for wptype 0x73

M ThrRegisterSem: init SEM_DB_TBUFF o.k.

M SosRegisterSem: add sem >SEM_DB_SYNC< for wptype 0x73

M ThrRegisterSem: init SEM_DB_SYNC o.k.

M SosRegisterSem: malloc/realloc table

M SosRegisterSem: add sem >SEM_DB_TTAB< for wptype 0x73

M ThrRegisterSem: init SEM_DB_TTAB o.k.

M SosRegisterSem: add sem >SEM_DB_SNTAB< for wptype 0x73

M ThrRegisterSem: init SEM_DB_SNTAB o.k.

M SosRegisterSem: add sem >SEM_DB_IREC< for wptype 0x73

M ThrRegisterSem: init SEM_DB_IREC o.k.

M SosRegisterSem: add sem >SEM_DB_FTAB< for wptype 0x73

M ThrRegisterSem: init SEM_DB_FTAB o.k.

M SosRegisterSem: add sem >SEM_LOGFILE_KEY< for wptype 0x77

M ThrRegisterSem: init SEM_LOGFILE_KEY o.k.

M SosRegisterSem: add sem >SEM_DB_TBUFF_P< for wptype 0x73

M ThrRegisterSem: init SEM_DB_TBUFF_P o.k.

M SosRegisterSem: add sem >SEM_ENQ_REQ_KEY< for wptype 0x77

M ThrRegisterSem: init SEM_ENQ_REQ_KEY o.k.

M SosRegisterSem: add sem >SEM_ENQ_TABLE_KEY< for wptype 0x77

M ThrRegisterSem: init SEM_ENQ_TABLE_KEY o.k.

M SosRegisterSem: add sem >SEM_SAPCOM_1< for wptype 0x73

M ThrRegisterSem: init SEM_SAPCOM_1 o.k.

M SosRegisterSem: add sem >SEM_SAPCOM_2< for wptype 0x73

M ThrRegisterSem: init SEM_SAPCOM_2 o.k.

M SosRegisterSem: malloc/realloc table

M SosRegisterSem: add sem >SEM_VMS_FIXADR_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_VMS_FIXADR_KEY o.k.

M SosRegisterSem: add sem >SEM_DB_CUA_BUFFER< for wptype 0x73

M ThrRegisterSem: init SEM_DB_CUA_BUFFER o.k.

M SosRegisterSem: add sem >SEM_RSPO_ADMIN< for wptype 0x73

M ThrRegisterSem: init SEM_RSPO_ADMIN o.k.

M SosRegisterSem: add sem >SEM_DB_OBJ_BUFFER< for wptype 0x73

M ThrRegisterSem: init SEM_DB_OBJ_BUFFER o.k.

M SosRegisterSem: add sem >SEM_CCMS_AS_MONI_KEY< for wptype 0x77

M ThrRegisterSem: init SEM_CCMS_AS_MONI_KEY o.k.

M SosRegisterSem: add sem >SEM_EG_ADM< for wptype 0x77

M ThrRegisterSem: init SEM_EG_ADM o.k.

M SosRegisterSem: add sem >SEM_STAT_BUF< for wptype 0x73

M ThrRegisterSem: init SEM_STAT_BUF o.k.

M SosRegisterSem: add sem >SEM_RSPO_CACHE< for wptype 0x73

M ThrRegisterSem: init SEM_RSPO_CACHE o.k.

M SosRegisterSem: add sem >SEM_AUDIT_SHM< for wptype 0x73

M ThrRegisterSem: init SEM_AUDIT_SHM o.k.

M SosRegisterSem: add sem >SEM_RSPO_RFC< for wptype 0x73

M ThrRegisterSem: init SEM_RSPO_RFC o.k.

M SosRegisterSem: malloc/realloc table

M SosRegisterSem: add sem >SEM_AB_VMIT< for wptype 0x73

M ThrRegisterSem: init SEM_AB_VMIT o.k.

M SosRegisterSem: add sem >SEM_ENQID_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_ENQID_KEY o.k.

M SosRegisterSem: add sem >SEM_ATRA_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_ATRA_KEY o.k.

M SosRegisterSem: add sem >SEM_COV_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_COV_KEY o.k.

M SosRegisterSem: add sem >SEM_ZDATE_TIMEINFO< for wptype 0x73

M ThrRegisterSem: init SEM_ZDATE_TIMEINFO o.k.

M SosRegisterSem: add sem >SEM_DB_OTR_BUFFER< for wptype 0x73

M ThrRegisterSem: init SEM_DB_OTR_BUFFER o.k.

M SosRegisterSem: add sem >SEM_DB_ESM_BUFFER< for wptype 0x73

M ThrRegisterSem: init SEM_DB_ESM_BUFFER o.k.

M SosRegisterSem: add sem >SEM_RTM_KEY< for wptype 0x73

M ThrRegisterSem: init SEM_RTM_KEY o.k.

M SosRegisterSem: add sem >SEM_JAVA_1< for wptype 0x1

M ThrRegisterSem: init SEM_JAVA_1 o.k.

M calling db_connect ...

B db_con_connect (con_name=R/3)

B find_con_by_name found the following connection for reuse:

B 0: name = R/3, con_id = 000000000 state = DISCONNECTED, perm = YES, reco = NO , timeout = 000, con_max = 255, con_opt = 255, occ = NO

C my_malloc: size=4, sum=mem_dbsl=20

C my_malloc: size=6, sum=mem_dbsl=42

C my_malloc: size=3, sum=mem_dbsl=61

C my_malloc: size=3, sum=mem_dbsl=80

C my_malloc: size=19, sum=mem_dbsl=115

C my_malloc: size=2, sum=mem_dbsl=133

C my_malloc: size=2, sum=mem_dbsl=151

C my_malloc: size=30000, sum=mem_dbsl=30167

C my_malloc: size=64000, sum=mem_dbsl=94183

C my_malloc: size=48000, sum=mem_dbsl=142199

C my_malloc: size=4912, sum=mem_dbsl=147127

C my_malloc: size=8048, sum=mem_dbsl=155191

C Got ORACLE_HOME=/oracle/client/92x_64 from environment

C -->oci_init1

C -->oci_initialize (con_hdl=0)

C got NLS_LANG='AMERICAN_AMERICA.US7ASCII' from environment

C my_malloc1: size=400, sum=mem_dbms=416

C my_malloc1: size=8, sum=mem_dbms=440

C my_malloc1: size=264, sum=mem_dbms=720

C my_malloc1: size=384987, sum=mem_dbms=385723

C my_malloc1: size=31512, sum=mem_dbms=417251

C my_malloc1: size=4136, sum=mem_dbms=421403

C my_malloc1: size=4200, sum=mem_dbms=425619

C Client NLS settings: AMERICAN_AMERICA.US7ASCII

C Logon as OPS$-user to get SAPR3's password

C Connecting as /@TP1 on connection 0 (nls_hdl 0) ... (dbsl 640 070208)

C Nls CharacterSet NationalCharSet C EnvHp ErrHp ErrHpBatch

C 0 US7ASCII 1 11309ae80 1130a67b8 1130a6068

C Allocating service context handle for con_hdl=0

C Allocating server context handle

C my_malloc1: size=21064, sum=mem_dbms=446699

C Attaching to DB Server TP1 (con_hdl=0,svchp=1130a3898,srvhp=1130a6fb8)

C my_malloc1: size=24904, sum=mem_dbms=471619

C Assigning server context 1130a6fb8 to service context 1130a3898

C Allocating user session handle

C Starting user session (con_hdl=0,svchp=1130a3898,srvhp=1130a6fb8,usrhp=1130a2ca0)

C my_malloc1: size=4240, sum=mem_dbms=475875

C Assigning user session 1130a2ca0 to service context 1130a3898

C -->oci_alloc_stmt (con_hdl=0)

C <--oci_alloc_stmt (stmth_p=1130b09f8)

C -->oci_alloc_stmt (con_hdl=0)

C <--oci_alloc_stmt (stmth_p=1130af9a0)

C -->oci_prepare_stmt(con_hdl=0, len=55, stmth_p=1130af9a0)

C BEGIN DBMS_APPLICATION_INFO.SET_MODULE (:A0, :A1); END;

C -->oci_bind ( 0: SQLT_STR, l= 64, con_hdl=0, stmth_p=1130af9a0, vp=9001000a04fc878,ip=9001000a04fc7b8,rlp=0,rcp=0)

C -->oci_bind ( 1: SQLT_STR, l= 64, con_hdl=0, stmth_p=1130af9a0, vp=9001000a04fc838,ip=9001000a04fc7ba,rlp=0,rcp=0)

C -->oci_prepare_stmt(con_hdl=0, len=55, stmth_p=1130b09f8)

C BEGIN DBMS_APPLICATION_INFO.SET_CLIENT_INFO (:A0); END;

C -->oci_bind ( 0: SQLT_STR, l= 64, con_hdl=0, stmth_p=1130b09f8, vp=fffffffffffe890,ip=0,rlp=0,rcp=0)

C -->oci_execute_stmt(con_hdl=0, stmth_p=1130b09f8, iters=1, rowoff=0, dml_batch_errors=0)

C <--oci_execute_stmt(rc=0, iters-errors = 1-0) [0,004072s]

C -->oci_free_stmt (stmth_p=1130b09f8)

C -->oci_alloc_stmt (con_hdl=0)

C <--oci_alloc_stmt (stmth_p=1130b09f8)

C -->oci_prepare_stmt(con_hdl=0, len=39, stmth_p=1130b09f8)

C SELECT SID FROM V$MYSTAT WHERE ROWNUM<2

C -->oci_define ( 0: SQLT_INT, l= 4, con_hdl=0, stmth_p=1130b09f8, vp=fffffffffffea54,ip=fffffffffffea50,rlp=0,rcp=0)

C -->oci_execute_stmt(con_hdl=0, stmth_p=1130b09f8, iters=1, rowoff=0, dml_batch_errors=0)

C <--oci_execute_stmt(rc=0, iters-errors = 1-0) [0,001228s]

C -->oci_free_stmt (stmth_p=1130b09f8)

C Connected to session 71.

C Now '/@TP1' is connected: con_hdl=0, nls_hdl=0, session_id=71.

C -->oci_alloc_stmt (con_hdl=0)

C <--oci_alloc_stmt (stmth_p=1130b09f8)

C -->oci_prepare_stmt(con_hdl=0, len=35, stmth_p=1130b09f8)

C ALTER SESSION SET NLS_SORT = BINARY

C -->oci_execute_stmt(con_hdl=0, stmth_p=1130b09f8, iters=1, rowoff=0, dml_batch_errors=0)

C <--oci_execute_stmt(rc=0, iters-errors = 1-0) [0,000527s]

C -->oci_free_stmt (stmth_p=1130b09f8)

C -->oci_alloc_stmt (con_hdl=0)

C <--oci_alloc_stmt (stmth_p=1130b09f8)

C -->oci_prepare_stmt(con_hdl=0, len=60, stmth_p=1130b09f8)

C SELECT USERID,PASSWD FROM SAPUSER WHERE USERID IN (:A0, :A1)

C -->oci_bind ( 0: SQLT_STR, l=256, con_hdl=0, stmth_p=1130b09f8, vp=9001000a04ffa63,ip=0,rlp=0,rcp=0)

C -->oci_bind ( 1: SQLT_STR, l=256, con_hdl=0, stmth_p=1130b09f8, vp=fffffffffffe8b8,ip=0,rlp=0,rcp=0)

C -->oci_define ( 0: SQLT_STR, l=256, con_hdl=0, stmth_p=1130b09f8, vp=fffffffffffe9b8,ip=fffffffffffe6e0,rlp=0,rcp=0)

C -->oci_define ( 1: SQLT_AFC, l=256, con_hdl=0, stmth_p=1130b09f8, vp=fffffffffffeab8,ip=fffffffffffe6e2,rlp=0,rcp=0)

C -->oci_execute_stmt(con_hdl=0, stmth_p=1130b09f8, iters=1, rowoff=0, dml_batch_errors=0)

C <--oci_execute_stmt(rc=0, iters-errors = 1-0) [0,000563s]

C -->oci_free_stmt (stmth_p=1130b09f8)

C Got SAPR3's password from OPS$-user

C Disconnecting from connection 0 ...

C Rolling back transaction ...

C Close user session (con_hdl=0,svchp=1130a3898,usrhp=1130a2ca0)

C -->oci_free_stmt (stmth_p=1130af9a0)

C Now I'm disconnected from ORACLE

C Connecting as SAPR3/<pwd>@TP1 on connection 0 (nls_hdl 0) ... (dbsl 640 070208)

C Nls CharacterSet NationalCharSet C EnvHp ErrHp ErrHpBatch

C 0 US7ASCII 1 11309ae80 1130a67b8 1130a6068

C Assigning username to user session 1130a2ca0

C Assigning password to user session 1130a2ca0

C Starting user session (con_hdl=0,svchp=1130a3898,srvhp=1130a6fb8,usrhp=1130a2ca0)

C Assigning user session 1130a2ca0 to service context 1130a3898

C -->oci_alloc_stmt (con_hdl=0)

C <--oci_alloc_stmt (stmth_p=1130af9a0)

C -->oci_alloc_stmt (con_hdl=0)

C <--oci_alloc_stmt (stmth_p=1130ae948)

C -->oci_prepare_stmt(con_hdl=0, len=55, stmth_p=1130ae948)

C my_malloc: size=256, sum=mem_dbsl=155735

C -->oci_alloc_stmt (con_hdl=0)

C <--oci_alloc_stmt (stmth_p=1130af9a0)

C -->oci_prepare_stmt(con_hdl=0, len=26, stmth_p=1130af9a0)

C SELECT VERSION FROM SVERS

C DbSlRead(con_hdl=0, ss_p=ffffffffffff3a0, da_p=ffffffffffff408)

C -->oci_alloc_stmt (con_hdl=0)

C <--oci_alloc_stmt (stmth_p=1130ad8c8)

C rc =100=OCI_NO_DATA

C -->oci_free_stmt (stmth_p=1130ad8c8)

C my_malloc: size=1792, sum=mem_dbsl=157543

C my_malloc: size=48, sum=mem_dbsl=157607

C my_malloc: size=65000, sum=mem_dbsl=222623

C -->oci_define ( 0: SQLT_CHR, l= 72, con_hdl=0, stmth_p=1130af9a0, vp=113131f7c,ip=1131318a0,rlp=0,rcp=0)

C -->oci_execute_stmt(con_hdl=0, stmth_p=1130af9a0, iters=1, rowoff=0, dml_batch_errors=0)

C <--oci_execute_stmt(rc=0, rows processed 1, iters-errors = 1-0) [0,000577s]

M db_connect o.k.

M ICT: HTTP compression only above 1024 bytes

M ICT: maximum size of HTTP messages: 101 MB

M ICT: exclude compression: .zip,.cs,.rar,.arj,.z,.gz,.tar,.lzh,.cab,.hqx,.ace,.jar,.ear,.war,.css,.pdf,.js,.gzip,.uue,.bz2,.iso,.sda,.sar,.gif

M ICT: exclude_compression: *.zip

M ICT: exclude_compression: *.cs

M ICT: exclude_compression: *.rar

M ICT: exclude_compression: *.arj

M ICT: exclude_compression: *.z

M ICT: exclude_compression: *.gz

M ICT: exclude_compression: *.tar

M ICT: exclude_compression: *.lzh

M ICT: exclude_compression: *.cab

M ICT: exclude_compression: *.hqx

M ICT: exclude_compression: *.ace

M ICT: exclude_compression: *.jar

M ICT: exclude_compression: *.ear

M ICT: exclude_compression: *.war

M ICT: exclude_compression: *.css

M ICT: exclude_compression: *.pdf

M ICT: exclude_compression: *.js

M ICT: exclude_compression: *.gzip

M ICT: exclude_compression: *.uue

M ICT: exclude_compression: *.bz2

M ICT: exclude_compression: *.iso

M ICT: exclude_compression: *.sda

M ICT: exclude_compression: *.sar

M ICT: exclude_compression: *.gif

M ICT: client-side urlencoding of cookie values enabled

M ThJInit: init java layer

M ThPlgRegister: register handler for protocol 17 (HTTP(JAVA))

M ThJInit: java not active

M

M ThSetThParam : zcsa/presentation_buffer_area=70000640

M zcsa/presentation_buffer_area = 70000640

M ThSetThParam : rdisp/ro_blks=NULL

M rdisp/ro_blks = 8192

M ThSetThParam : rdisp/ROLL_MAXFS=65920

M rdisp/ROLL_MAXFS = 65920

M ThSetThParam : rdisp/ROLL_SHM=16384

M rdisp/ROLL_SHM = 16384

M ThSetThParam : rdisp/rrol_file=/usr/sap/TP1/DVEBMGS00/data/ROLLFL00

M rdisp/rrol_file = /usr/sap/TP1/DVEBMGS00/data/ROLLFL00

M ThSetThParam : rdisp/PG_MAXFS=65536

M rdisp/PG_MAXFS = 65536

M ThSetThParam : rdisp/PG_SHM=8192

M rdisp/PG_SHM = 8192

M ThSetThParam : rdisp/rpag_file=/usr/sap/TP1/DVEBMGS00/data/PAGFIL00

M rdisp/rpag_file = /usr/sap/TP1/DVEBMGS00/data/PAGFIL00

M ThSetThParam : rdisp/wpdbug_max_no=1

M rdisp/wpdbug_max_no = 1

M gw_hostname : de08u1743

M nihsl-getHostAddr: got hostname 'de08u1743' from operating system

M HN='de08u1743.honeywell.com'; Ali[0]='de08u1743.dcesap.honeywell.com'; Add[0]=10.220.136.68

M nihsi-getHostAddr: hostname 'de08u1743' = addr 10.220.136.68

M ThSetGwParam : gw_hostaddr = [10.220.136.68]

M ThSetGwParam : gw_service = sapgw00

M ThSetThParam : rdisp/sna_gateway=de08u1743

M nihsl-getHostAddr: found hostname 'de08u1743' in cache

M nihsi-getHostAddr: hostname 'de08u1743' = addr 10.220.136.68

M ThSetThParam : rdisp/sna_gw_service=sapgw00

M nihsl-getServNo: got servicename 'sapgw00' from operating system

M SN='sapgw00'; Port=0C.E4/3300; PC=tcp

M nihsi-getServNo: servicename 'sapgw00' = port 0C.E4/3300

M rdisp/sna_gw_service = sapgw00

M rdisp/sna_gateway = de08u1743

M sna_gw_hostaddr = [10.220.136.68]

M ThSetThParam : rdisp/max_arq=500

M rdisp/max_arq = 500

M ThSetThParam : nobuf/max_no_buffer_entries=1000

M nobuf/max_no_buffer_entries = 1000

M ThSetThParam : nobuf/hash_factor=NULL

M nobuf/hash_factor = 2.5

M ThSetThParam : rdisp/vb_dispatching=1

M rdisp/vb_dispatching = 1

M ThSetThParam : rdisp/accept_remote_trace_level=1

M rdisp/accept_remote_trace_level = 1

M ThSetThParam : rdisp/max_vb_server=50

M rdisp/max_vb_server = 50

M ThSetThParam : rdisp/max_alt_modes=6

M rdisp/max_alt_modes = 6

M ThSetThParam : rdisp/rfc_server_pooling=OFF

M rdisp/rfc_server_pooling = 0

M ThSetThParam : rdisp/rfc_pool_timeout=300

M rdisp/rfc_pool_timeout = 300 sec

M ThSetThParam : rdisp/rfc_pool_size=10

M rdisp/rfc_pool_size = 500

M ThSetThParam : rdisp/plugin_server_pooling=OFF

M rdisp/plugin_server_pooling = 0

M ThSetThParam : rdisp/plugin_pool_timeout=300

M rdisp/plugin_pool_timeout = 300 sec

M ThSetThParam : rdisp/context_pool_size=90

M rdisp/context_pool_size = 4500

M ThSetThParam : rdisp/rfc_server_auto_logout=0

M rdisp/rfc_server_auto_logout = 0 sec

M ThSetThParam : rdisp/plugin_auto_logout=1800

M rdisp/plugin_auto_logout = 1800 sec

M ThInitializeSysId: my sid is >TP1<

M write th-profile-values into sys_adm

M

I MtxInit: 0 0 0

I ShmCreate( 14, 70000640, 3, 0xffffffffffff440 )

I ShmGet( 40, 14, 70000640, 3, 0xffffffffffff2c8 )

I ShmGet( 40, 14, 70000640, 3, -> 0x7000000a02d8000 )

I Ofs 0x102d8000, Ec1 0x102d7000, Ec2 0x1459a000, Nf 0x1459a000

I ShmProtect( 14, 3 )

dev_w0 (8%)

I ShmProtect: Key=14 not attached

I ShmProtect( 14, 3 )

I ShmCreate( 14, 70000640, 3, -> 0x7000000a02d8000 )

M SHM_PRES_BUF (addr: 7000000a02d8000, size: 70000640)

I ShmCreate( 9, 134217728, 3, 0xffffffffffff440 )

I ShmGet( 10, 9, 134217728, 3, 0xffffffffffff2c8 )

I ShmGet( 10, 9, 134217728, 3, -> 0x700000002268000 )

I Ofs 0x2268000, Ec1 0x2267000, Ec2 0xa268000, Nf 0xa268000

I ShmProtect( 9, 3 )

I ShmProtect: Key=9 not attached

I ShmProtect( 9, 3 )

I ShmCreate( 9, 134217728, 3, -> 0x700000002268000 )

M SHM_ROLL_AREA (addr: 700000002268000, size: 134217728)

I ShmCreate( 8, 67108864, 3, 0xffffffffffff440 )

I ShmGet( 40, 8, 67108864, 3, 0xffffffffffff2c8 )

I ShmGet( 40, 8, 67108864, 3, -> 0x7000000a459b000 )

I Ofs 0x1459b000, Ec1 0x1459a000, Ec2 0x1859b000, Nf 0x1859b000

I ShmProtect( 8, 3 )

I ShmProtect: Key=8 not attached

I ShmProtect( 8, 3 )

I ShmCreate( 8, 67108864, 3, -> 0x7000000a459b000 )

M SHM_PAGING_AREA (addr: 7000000a459b000, size: 67108864)

I ShmCreate( 17, 5374766, 3, 0xffffffffffff440 )

I ShmGet( 40, 17, 5374766, 3, 0xffffffffffff2c8 )

I ShmGet( 40, 17, 5374766, 3, -> 0x7000000a859c000 )

I Ofs 0x1859c000, Ec1 0x1859b000, Ec2 0x18abd000, Nf 0x18abd000

I ShmProtect( 17, 3 )

I ShmProtect: Key=17 not attached

I ShmProtect( 17, 3 )

I ShmCreate( 17, 5374766, 3, -> 0x7000000a859c000 )

M SHM_ROLL_ADM (addr: 7000000a859c000, size: 5374766)

I ShmCreate( 18, 1311776, 3, 0xffffffffffff440 )

I ShmGet( 10, 18, 1311776, 3, 0xffffffffffff2c8 )

I ShmGet( 10, 18, 1311776, 3, -> 0x70000000a269000 )

I Ofs 0xa269000, Ec1 0xa268000, Ec2 0xa3aa000, Nf 0xa3aa000

I ShmProtect( 18, 3 )

I ShmProtect: Key=18 not attached

I ShmProtect( 18, 3 )

I ShmCreate( 18, 1311776, 3, -> 0x70000000a269000 )

M SHM_PAGING_ADM (addr: 70000000a269000, size: 1311776)

I ShmCreate( 48, 324144, 3, 0xffffffffffff330 )

I ShmGet( 40, 48, 324144, 3, 0xffffffffffff1b8 )

I ShmGet( 40, 48, 324144, 3, -> 0x7000000a8abe000 )

I Ofs 0x18abe000, Ec1 0x18abd000, Ec2 0x18b0e000, Nf 0x18b0e000

I ShmProtect( 48, 3 )

I ShmProtect: Key=48 not attached

I ShmProtect( 48, 3 )

I ShmCreate( 48, 324144, 3, -> 0x7000000a8abe000 )

M ThCreateNoBuffer allocated 324144 bytes for 1000 entries at 7000000a8abe000

M ThCreateNoBuffer index size: 3000 elems

M SosRegisterMemAddr: alloc/realloc table

M SosRegisterMemAddr: add mem addr 7000000a8abe000 / >num_buf_hdr<

M SosRegisterMemAddr: add mem addr 7000000a8abe090 / >num_buf_idx<

M SosRegisterMemAddr: add mem addr 7000000a8adb550 / >num_buf_data<

M ThSetNoBufParam : nobuf/max_attempts=1200

M nobuf/max_attempts = 1200

M SosRegisterHook: add hook >ThNoClearPrevErr(110c6c1a8)< for event AFTER_COMMIT

M SosRegisterHook: add hook >ThNoClearPrevErr(110c6c1a8)< for event AFTER_ROLLBACK

M SosRegisterHook: add hook >ThNoResetMutex(110c6c1c0)< for event AFTER_ROLLBACK

M SosRegisterSem: add sem >SEM_NO_BUFFER< for wptype 0x73

M ThCreateNoBuffer max attempts: 1200

M ThNoCheckMutex: check for remaining locked intervals

M ThCreateNoBuffer: my code page = >1100<

M ThJRegNatives: java not active

M ThCreateNoBuffer o.k.

M ThSetThVbParam : rdisp/vb_stop_active=1

M rdisp/vb_stop_active = 1

I ShmCreate( 7, 7040, 3, 0xffffffffffff380 )

I ShmGet( 10, 7, 7040, 3, 0xffffffffffff208 )

I ShmGet( 10, 7, 7040, 3, -> 0x70000000a3ab000 )

I Ofs 0xa3ab000, Ec1 0xa3aa000, Ec2 0xa3ad000, Nf 0xa3ad000

I ShmProtect( 7, 3 )

I ShmProtect: Key=7 not attached

I ShmProtect( 7, 3 )

I ShmCreate( 7, 7040, 3, -> 0x70000000a3ab000 )

M ThCreateVBAdm allocated 7040 bytes (50 server) at 70000000a3ab000

M SosRegisterMemAddr: add mem addr 70000000a3ab000 / >vb_info<

M SosRegisterMemAddr: add mem addr 70000000a3ab030 / >vb_disp<

M SosRegisterMemAddr: add mem addr 70000000a3ab188 / >vb_stat<

M SosRegisterMemAddr: add mem addr 70000000a3ab280 / >vb_server<

M ThCreateVBAdm: my code page = >1100<

M ThCreateVBAdm o.k.

X EmInit () Lock method = 2

X EmInit: MmSetImplementation( 2 ).

X ESSHM: ====================> EsGetAdmSize <====================

I ShmCreate( 51, 15239956, 2, 0x110e946f0 )

I ShmProtect( 51, 3 )

I ShmCreate( 51, 15239956, 2, -> 0x7000000000a7000 )

X ESSHM: ====================> EsINit <====================

X EM/TOTAL_SIZE_MB = 8192

X ESSHM: ====================> NOTICE <====================

X ESSHM: INIT for client : 0

X ESSHM: Segment Count : 4096

X ESSHM: Segment Size : 0x40000000 1024MB

X ESSHM: Base Addr : 0x0

X ESSHM: Process Seg Count : 64

X ESSHM: Block Size : 4096KB

X ESSHM: User Count : 4096

X ESSHM: Client Count : 30

X ESSHM: Sys Free Max : 20

X ESSHM: Sys Init Segs : 25

X ESSHM: DiscBlockPerSeg : 2

X ESSHM: DiscSegments : 1024

X ESSHM: DiscSegInitCount : 100

X ESSHM: DiscSegMax : 4096

X ESSHM: DiscSegMin : 10

X ESSHM: DiscBlkMax : 4

X ESSHM: DiscBlkMin : 0

X ESSHM: Idle Segments : 10

X ESSHM: Max Blocks : 8192

X ESSHM: Max Private Segs : 63

X ESSHM: Max Shared Segs : 1

X ESSHM: Flags : 3620866 (0x374002)

X ESSHM: Block Mapping : 0

X ESSHM: ====================> NOTICE <====================

X EgInit: esBlkSz = 4194304, maxEsBlocks = 68, segSz = (285212672 EG_MAX_SEGSIZE = 34359738367

X ESSHM: ====================> EsAttach <====================

X esATTACH handle=1 BlockCurrent=68

X ESSHM: attach Context=1 handle=1 BlockPerSeg=255 BlocksFree=187 SegId=0x19003a8

X esATTACH return

X #1 EG-Memory attached. client 0, size 272 MB, base 7000010c0221000, admBase 700000000ab9ee0

X MmxRegisterRscAdm 4 110c616f0 110c61708

X MmxRegisterRscAdm 5 110c61720 110c61738

M EmInit o.k.

M PfDdicCheck: ddic structures o.k.

X EgGetPtr#2: client=0, id='STAT-SHARED-PARAM', ptr=0

X EgAlloc#3 0 'STAT'(5) size=8, ptr=7000010c02219d8, mmerr=0

X EgRegisterPtr#4: client=0, id='STAT-SHARED-PARAM' ptr=7000010c02219d8

M PfReadSharedParam: init shared parameter area

M PfReadSharedParam: stat/max_files = 48

M PfReadSharedParam: save stat-file each 3600 seconds

M max_buf_size 500000

M compression 1

M checks 0

I ShmCreate( 4, 525976, 3, 0xffffffffffff380 )

I ShmGet( 10, 4, 525976, 3, 0xffffffffffff218 )

I ShmGet( 10, 4, 525976, 3, -> 0x70000000a3ae000 )

I Ofs 0xa3ae000, Ec1 0xa3ad000, Ec2 0xa42f000, Nf 0xa42f000

I ShmProtect( 4, 3 )

I ShmProtect: Key=4 not attached

I ShmProtect( 4, 3 )

I ShmCreate( 4, 525976, 3, -> 0x70000000a3ae000 )

M PfCreateShm allocated 525976 bytes

M PfCreateShm: pf_sh_eye_begin: 70000000a3ae000

M PfCreateShm: pf_adm: 70000000a3ae008 (size=160)

M PfCreateShm: pf_shared_rec_area: 70000000a3ae0a8 (size=5880)

M PfCreateShm: pf_shared_statindrec: 70000000a3af7a0 (size=136)

M PfCreateShm: pf_shared_buf_head: 70000000a3af828 (size=152)

M PfCreateShm: pf_shared_buf: 70000000a3af8c0 (size=500000)

M PfCreateShm: pf_hyperindex header: 70000000a4299e0 (size=19632)

M records=<70000000a429a10>

M PfCreateShm: pf_sh_eye_end: 70000000a42e690

M PfCreateShm o.k.

M ThJCreateShm: java not available

M ThShMCreate: ThJCreateShm failed

M ThCallHooks: call hook >db_init< for event CREATE_SHM

M SosRegisterHook: add hook >db_x_short_mem_sync_point(110c82438)< for event AFTER_STORAGE_FREE

B NTAB: attach to shared memory buffers

I ShmCreate( 43, 0, 2, 0xfffffffffffef90 )

I ShmGet( 40, 43, 0, 2, 0xfffffffffffee28 )

B NTAB: attach buffer failed, shm_key 43, shm_rc 3

B NTAB: initialization of shared memory buffers

B NTAB: read profile

B NTAB: rsdb/ntab/entrycount 50000.

B NTAB: rsdb/ntab/ftabsize 90000.

B NTAB: rsdb/ntab/irbdsize 6000.

B NTAB: rsdb/ntab/sntabsize 2500.

B NTAB: compute_hash_card: 100003.

B NTAB: maxcnt 50000.

B NTAB: hfactor 100003.

B NTAB: mem_protocol_size 1040

B NTAB: hdr_backpack_offset 152

B NTAB: hdr_backpack_size 36

B NTAB: FTAB: header_size 504.

B NTAB: FTAB: item_size 64.

B NTAB: FTAB: item_cnt 50000.

B NTAB: FTAB: unit_size 4

B NTAB: FTAB: unit_cnt 23040000.

B NTAB: FTAB: data_size 92160000.

B NTAB: IREC: header_size 504.

B NTAB: IREC: item_size 64.

B NTAB: IREC: item_cnt 12500.

B NTAB: IREC: unit_size 8

B NTAB: IREC: unit_cnt 768000.

B NTAB: IREC: data_size 6144000.

B NTAB: STAB: header_size 504.

B NTAB: STAB: item_size 64.

B NTAB: STAB: item_cnt 12500.

B NTAB: STAB: unit_size 4

B NTAB: STAB: unit_cnt 640000.

B NTAB: STAB: data_size 2560000.

B NTAB: TTAB: header_size 1544.

B NTAB: TTAB: item_size 40.

B NTAB: TTAB: item_cnt 50000.

B NTAB: TTAB: unit_size 188

B NTAB: TTAB: unit_cnt 50000.

B NTAB: TTAB: data_size 9400000.

I ShmCreate( 43, 96160664, 1, 0xfffffffffffefc0 )

I ShmGet( 40, 43, 96160664, 1, 0xfffffffffffee58 )

I ShmGet( 40, 43, 96160664, 1, -> 0x7000000a8b0f000 )

I Ofs 0x18b0f000, Ec1 0x18b0e000, Ec2 0x1e6c4000, Nf 0x1e6c4000

I ShmProtect( 43, 3 )

I ShmProtect: Key=43 not attached

I ShmProtect( 43, 3 )

I ShmCreate( 43, 96160664, 1, -> 0x7000000a8b0f000 )

I ShmCreate( 44, 7744664, 1, 0xfffffffffffefc0 )

I ShmGet( 40, 44, 7744664, 1, 0xfffffffffffee58 )

I ShmGet( 40, 44, 7744664, 1, -> 0x7000000ae6c5000 )

I Ofs 0x1e6c5000, Ec1 0x1e6c4000, Ec2 0x1ee28000, Nf 0x1ee28000

I ShmProtect( 44, 3 )

I ShmProtect: Key=44 not attached

I ShmProtect( 44, 3 )

I ShmCreate( 44, 7744664, 1, -> 0x7000000ae6c5000 )

I ShmCreate( 45, 4160664, 1, 0xfffffffffffefc0 )

I ShmGet( 40, 45, 4160664, 1, 0xfffffffffffee58 )

I ShmGet( 40, 45, 4160664, 1, -> 0x7000000aee29000 )

I Ofs 0x1ee29000, Ec1 0x1ee28000, Ec2 0x1f221000, Nf 0x1f221000

I ShmProtect( 45, 3 )

I ShmProtect: Key=45 not attached

I ShmProtect( 45, 3 )

I ShmCreate( 45, 4160664, 1, -> 0x7000000aee29000 )

I ShmCreate( 42, 12201704, 1, 0xfffffffffffefc0 )

I ShmGet( 40, 42, 12201704, 1, 0xfffffffffffee58 )

I ShmGet( 40, 42, 12201704, 1, -> 0x7000000af222000 )

I Ofs 0x1f222000, Ec1 0x1f221000, Ec2 0x1fdc5000, Nf 0x1fdc5000

I ShmProtect( 42, 3 )

I ShmProtect: Key=42 not attached

I ShmProtect( 42, 3 )

I ShmCreate( 42, 12201704, 1, -> 0x7000000af222000 )

B dbntab: NTAB buffers created

B dbntab: Buffer FTAB(hash header) (addr: 7000000a8b0f088, size: 504)

B dbntab: Buffer FTAB(anchor array) (addr: 7000000a8b0f280, size: 800024)

B dbntab: Buffer FTAB(item array) (addr: 7000000a8bd2798, size: 3200000)

B dbntab: Buffer FTAB(data area) (addr: 7000000a8edfb98, size: 92160000)

B dbntab: Buffer IREC(hash header) (addr: 7000000ae6c5088, size: 504)

B dbntab: Buffer IREC(anchor array) (addr: 7000000ae6c5280, size: 800024)

B dbntab: Buffer IREC(item array) (addr: 7000000ae788798, size: 800000)

B dbntab: Buffer IREC(data area) (addr: 7000000ae84bc98, size: 6144000)

B dbntab: Buffer STAB(hash header) (addr: 7000000aee29088, size: 504)

B dbntab: Buffer STAB(anchor array) (addr: 7000000aee29280, size: 800024)

B dbntab: Buffer STAB(item array) (addr: 7000000aeeec798, size: 800000)

B dbntab: Buffer STAB(data area) (addr: 7000000aefafc98, size: 2560000)

B dbntab: Buffer TTAB(hash header) (addr: 7000000af222088, size: 1544)

B dbntab: Buffer TTAB(anchor array) (addr: 7000000af222690, size: 800024)

B dbntab: Buffer TTAB(data area) (addr: 7000000af4ce028, size: 9400000)

B NTAB: ntab_mem_protocol 7000000af222088, ntab_mp_p 7000000af222088, ntab_proc_id 0

B NTAB: FTAB: hh_p 7000000a8b0f088, ha_list 7000000a8b0f280, hi_list 7000000a8bd2798, buffer 7000000a8edfb98

B NTAB: IREC: hh_p 7000000ae6c5088, ha_list 7000000ae6c5280, hi_list 7000000ae788798, buffer 7000000ae84bc98

B NTAB: STAB: hh_p 7000000aee29088, ha_list 7000000aee29280, hi_list 7000000aeeec798, buffer 7000000aefafc98

B NTAB: TTAB: hh_p 7000000af222498, ha_list 7000000af222690, hi_list 7000000af2e5ba8, buffer 7000000af4ce028

B entering db_stinit

I ShmCreate( 41, 0, 2, 0x1102d73a8 )

I ShmGet( 40, 41, 0, 2, 0xffffffffffff1a8 )

B dbstat: staton is 1

C my_malloc: size=256, sum=mem_dbsl=222895

C -->oci_alloc_stmt (con_hdl=0)

C <--oci_alloc_stmt (stmth_p=1130ad8c8)

C -->oci_prepare_stmt(con_hdl=0, len=66, stmth_p=1130ad8c8)

C SELECT COUNT(*) FROM DDNTT WHERE TABFORM <> 'J' AND TABFORM <> 'V'

C DbSlRead(con_hdl=0, ss_p=ffffffffffff0c0, da_p=ffffffffffff170)

C -->oci_alloc_stmt (con_hdl=0)

C <--oci_alloc_stmt (stmth_p=1130ac870)

C my_malloc1: size=4240, sum=mem_dbms=480131

C rc =100=OCI_NO_DATA

C -->oci_free_stmt (stmth_p=1130ac870)

C my_malloc: size=1792, sum=mem_dbsl=224703

C -->oci_define ( 0: SQLT_INT, l= 4, con_hdl=0, stmth_p=1130ad8c8, vp=1131318a0,ip=11313c1e4,rlp=0,rcp=0)

C -->oci_execute_stmt(con_hdl=0, stmth_p=1130ad8c8, iters=1, rowoff=0, dml_batch_errors=0)

C <--oci_execute_stmt(rc=0, rows processed 1, iters-errors = 1-0) [0,141711s]

B dbstat: table statistics switched on for 48567 tables

I ShmCreate( 41, 20617152, 1, 0x1102d73a8 )

I ShmGet( 40, 41, 20617152, 1, 0xffffffffffff1a8 )

I ShmGet( 40, 41, 20617152, 1, -> 0x7000000afdc6000 )

I Ofs 0x1fdc6000, Ec1 0x1fdc5000, Ec2 0x21170000, Nf 0x21170000

I ShmProtect( 41, 3 )

I ShmProtect: Key=41 not attached

I ShmProtect( 41, 3 )

I ShmCreate( 41, 20617152, 1, -> 0x7000000afdc6000 )

B dbstat: TABSTAT buffer created (addr: 7000000afdc6000, size: 20617152)

B db_con_shm_ini: WP_ID = 0, WP_CNT = 10, CON_ID = -1

B db_con_shm_ini: size of allocated shared memory = 18600 bytes

B db_con_shm_ini: size of used shared memory = 18600 bytes

C my_malloc: size=256, sum=mem_dbsl=224975

C -->oci_alloc_stmt (con_hdl=0)

C <--oci_alloc_stmt (stmth_p=1130ac870)

C -->oci_prepare_stmt(con_hdl=0, len=100, stmth_p=1130ac870)

C SELECT TABNAME, TABFORM, REFNAME FROM DDNTT WHERE TABFORM <> 'J' AND TABFORM <> 'V' ORDER BY TABNAME

C DbSlBegRead(con_hdl=0, ss_p=ffffffffffff028, da_p=ffffffffffff0b0)

C -->oci_alloc_stmt (con_hdl=0)

C my_malloc1: size=4200, sum=mem_dbms=484347

C <--oci_alloc_stmt (stmth_p=1131439f8)

C rc =100=OCI_NO_DATA

C -->oci_free_stmt (stmth_p=1131439f8)

C my_malloc: size=1792, sum=mem_dbsl=226783

C -->oci_define ( 0: SQLT_CHR, l= 30, con_hdl=0, stmth_p=1130ac870, vp=113132f5c,ip=1131318a0,rlp=0,rcp=0)

C -->oci_define ( 1: SQLT_CHR, l= 1, con_hdl=0, stmth_p=1130ac870, vp=11313a108,ip=113132034,rlp=0,rcp=0)

C -->oci_define ( 2: SQLT_CHR, l= 30, con_hdl=0, stmth_p=1130ac870, vp=11313a4d2,ip=1131327c8,rlp=0,rcp=0)

C DbSlExeRead(con_hdl=0, ss_p=ffffffffffff028, da_p=ffffffffffff0b0)

C -->oci_execute_stmt(con_hdl=0, stmth_p=1130ac870, iters=970, rowoff=0, dml_batch_errors=0)

C <--oci_execute_stmt(rc=0, rows processed 970, iters-errors = 970-0) [0,178721s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 1940) [0,003047s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 2910) [0,002931s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 3880) [0,002967s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 4850) [0,002995s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 5820) [0,002999s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 6790) [0,002983s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 7760) [0,002965s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 8730) [0,003046s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 9700) [0,003032s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 10670) [0,002952s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 11640) [0,002933s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 12610) [0,002965s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 13580) [0,002986s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 14550) [0,002905s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 15520) [0,003007s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 16490) [0,002933s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 17460) [0,003019s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 18430) [0,002974s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 19400) [0,002965s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 20370) [0,002978s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 21340) [0,002952s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 22310) [0,003082s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 23280) [0,003026s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 24250) [0,003163s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 25220) [0,003117s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 26190) [0,003019s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 27160) [0,002951s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 28130) [0,003002s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 29100) [0,002982s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 30070) [0,002988s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 31040) [0,002985s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

C <--oci_fetch_stmt (rows processed 32010) [0,003025s]

C -->oci_fetch_stmt(con_hdl=0, stmth_p=1130ac870, nrows=970)

I ShmProtect: Key=33 not attached

I ShmProtect( 33, 3 )

I ShmCreate( 33, 102400048, 1, -> 0x7000000b1171000 )

B dbtbxbuf: Buffer TABLP (addr: 7000000b1171100, size: 102400000, end: 7000000b7319100)

B dbtbxbuf: Profile: max_objects = 500, displace = 1, reorg = 1

B dbtbxbuf: request_unit = 2000, sync_reload = 5, inval_reload = 5

B dbtbxbuf: protect_shm = 0, force_checks = 0

B dbtbxbuf: tsize_retry = 51136576

B ***LOG BB0=> buffer TABLP started with length 102400000 bytes [dbtbxbuf#8 @ 16037] [dbtbxbuf1603 7]

B < db_tbxcommit(TBX_SINGLE): # 0, TBX_ROLLBACK, hold_cursor = 0

B > db_tbxcommit(TBX_SINGLE): # 0, rc = DBTXE_OK

B ---> read_file(TBX_SINGLE)

B dbtbxbuf: Reading TBX statistics:

B dbtbxbuf: Opening /usr/sap/TP1/DVEBMGS00/data/tbxnew failed (No such file or directory).

B dbtbxbuf: Renaming /usr/sap/TP1/DVEBMGS00/data/tbxnew to /usr/sap/TP1/DVEBMGS00/data/tbxstat failed (No such file or directory).

B <--- read_file(TBX_SINGLE)

B dbtbxbuf: 0 object entries precreated

B > db_tbxinit(TBX_SINGLE): mode = TBX_INIT, rc = 0

I ShmCreate( 46, 0, 2, 0x1106b6478 )

I ShmGet( 40, 46, 0, 2, 0xffffffffffff1b8 )

I ShmCreate( 46, 10240, 1, 0x1106b6478 )

I ShmGet( 40, 46, 10240, 1, 0xffffffffffff1b8 )

I ShmGet( 40, 46, 10240, 1, -> 0x7000000b731b000 )

I Ofs 0x2731b000, Ec1 0x2731a000, Ec2 0x2731e000, Nf 0x2731e000

I ShmProtect( 46, 3 )

I ShmProtect: Key=46 not attached

I ShmProtect( 46, 3 )

I ShmCreate( 46, 10240, 1, -> 0x7000000b731b000 )

C my_malloc: size=256, sum=mem_dbsl=227055

C -->oci_alloc_stmt (con_hdl=0)

C <--oci_alloc_stmt (stmth_p=1131439f8)

C -->oci_prepare_stmt(con_hdl=0, len=32, stmth_p=1131439f8)

C SELECT MAX(SEQNUMBER) FROM DDLOG

C DbSlRead(con_hdl=0, ss_p=112112438, da_p=112112790)

C -->oci_alloc_stmt (con_hdl=0)

C my_malloc1: size=4200, sum=mem_dbms=488563

C <--oci_alloc_stmt (stmth_p=1131453d8)

C rc =100=OCI_NO_DATA

C -->oci_free_stmt (stmth_p=1131453d8)

C my_malloc: size=1792, sum=mem_dbsl=228863

C -->oci_define ( 0: SQLT_INT, l= 4, con_hdl=0, stmth_p=1131439f8, vp=1131318a0,ip=11313c1e4,rlp=0,rcp=0)

C -->oci_execute_stmt(con_hdl=0, stmth_p=1131439f8, iters=1, rowoff=0, dml_batch_errors=0)

C <--oci_execute_stmt(rc=0, rows processed 1, iters-errors = 1-0) [0,000646s]

I ShmCreate( 54, 0, 2, 0xfffffffffffefd0 )

I ShmGet( 40, 54, 0, 2, 0xfffffffffffee68 )

B Layout of EIBUF buffer shared memory:

B 0: 1 * 4 = 4

B 1: 1 * 360 = 360

B 2: 10 * 40 = 400

B 3: 100003 * 40 = 4000120

B 4: 50000 * 160 = 8000000

B 5: 100003 * 8 = 800024

B 6: 1 * 200 = 200

B 7: 157 * 8 = 1256

B 8: 539981 * 128 = 69117568

B Tracing = 0, Shm Protection = 0, Force checks = 0, Recovery delay = 500000

I ShmCreate( 54, 81920192, 1, 0xfffffffffffefd0 )

I ShmGet( 40, 54, 81920192, 1, 0xfffffffffffee68 )

I ShmGet( 40, 54, 81920192, 1, -> 0x7000000b731f000 )

I Ofs 0x2731f000, Ec1 0x2731e000, Ec2 0x2c140000, Nf 0x2c140000

I ShmProtect( 54, 3 )

B 3: 4001 * 40 = 160040

B 4: 2000 * 160 = 320000

B 5: 4001 * 8 = 32008

B 6: 1 * 200 = 200

B 7: 81 * 8 = 648

B 8: 28755 * 128 = 3680640

B Tracing = 0, Shm Protection = 0, Force checks = 0, Recovery delay = 500000

I ShmCreate( 64, 4194560, 1, 0xfffffffffffefd0 )

I ShmGet( 40, 64, 4194560, 1, 0xfffffffffffee68 )

I ShmGet( 40, 64, 4194560, 1, -> 0x7000000be291000 )

I Ofs 0x2e291000, Ec1 0x2e290000, Ec2 0x2e692000, Nf 0x2e692000

I ShmProtect( 64, 3 )

I ShmProtect: Key=64 not attached

I ShmProtect( 64, 3 )

I ShmCreate( 64, 4194560, 1, -> 0x7000000be291000 )

B dbexpbuf: Buffer OTR (addr: 7000000be291108, size: 4194304, end: 7000000be691108)

B ***LOG BB0=> buffer OTR started with length 4096k bytes [dbexpbuf#6 @ 2342] [dbexpbuf2342 ]

B > db_exp_init : fcode = EXP_INIT, rc = 0

B < db_cbufinst

I ShmCreate( 11, 0, 2, 0x1106ef608 )

I ShmGet( 10, 11, 0, 2, 0xffffffffffff0f8 )

B ---> init_client_handling

C my_malloc: size=256, sum=mem_dbsl=229135

C -->oci_alloc_stmt (con_hdl=0)

C <--oci_alloc_stmt (stmth_p=1131453d8)

C -->oci_prepare_stmt(con_hdl=0, len=62, stmth_p=1131453d8)

C SELECT COMPCNT, UNICODELG FROM "DDNTT" WHERE TABNAME = 'SVERS'

C DbSlRead(con_hdl=0, ss_p=fffffffffffeca8, da_p=fffffffffffed10)

C -->oci_alloc_stmt (con_hdl=0)

C my_malloc1: size=4200, sum=mem_dbms=492779

C <--oci_alloc_stmt (stmth_p=113146db8)

C rc =100=OCI_NO_DATA

C -->oci_free_stmt (stmth_p=113146db8)

C my_malloc: size=1792, sum=mem_dbsl=230943

C -->oci_define ( 0: SQLT_INT, l= 4, con_hdl=0, stmth_p=1131453d8, vp=1131318a0,ip=11313c1e0,rlp=0,rcp=0)

C -->oci_define ( 1: SQLT_INT, l= 4, con_hdl=0, stmth_p=1131453d8, vp=113136d40,ip=11313ec30,rlp=0,rcp=0)

C -->oci_execute_stmt(con_hdl=0, stmth_p=1131453d8, iters=1, rowoff=0, dml_batch_errors=0)

C <--oci_execute_stmt(rc=0, rows processed 1, iters-errors = 1-0) [0,000577s]

B NTAB: Structure of NTAB on DB is VERS_B

B NTAB: UNICODELG(SVERS) = 1, SAP_UC_LN = 1

B NTAB: mem_handler: alloc for 500 elems, task 0, art 0

B NTAB: mem_handler: alloc for 500 elems, task 0, art 1

B NTAB: mem_handler: alloc for 500 elems, task 0, art 2

B NTAB: mem_handler: alloc for 500 elems, task 0, art 3

B NTAB: tabname: TFACS , fcode: 1

C my_malloc: size=256, sum=mem_dbsl=231215

C -->oci_alloc_stmt (con_hdl=0)

C my_malloc1: size=4200, sum=mem_dbms=496995

C <--oci_alloc_stmt (stmth_p=11315c198)

C -->oci_prepare_stmt(con_hdl=0, len=238, stmth_p=11315c198)

C SELECT VERSION, UUID, CRTIMESTMP, ABTIMESTMP, DYTIMESTMP, FLDCNT, TABLENGTH, KEYCNT, KEYLENGTH, CLIENTPOS, TABTYPE, TABFOR

C M, REFNAME, HFLAG, BUFSTATE, BUFPARM, ALIGN, POINTERLG, UNICODELG, COMPCNT, LEAFCNT FROM "DDNTT" WHERE TABNAME = :A0

C DbSlRead(con_hdl=0, ss_p=112808158, da_p=112808298)

C -->oci_alloc_stmt (con_hdl=0)

C my_malloc1: size=4200, sum=mem_dbms=501211

C <--oci_alloc_stmt (stmth_p=11315d278)

C rc =100=OCI_NO_DATA

C -->oci_free_stmt (stmth_p=11315d278)

C my_malloc: size=1792, sum=mem_dbsl=233023

C -->oci_bind ( 0: SQLT_AFC, l= 30, con_hdl=0, stmth_p=11315c198, vp=11313367c,ip=1131318a0,rlp=11313278e,rcp=0)

C my_malloc: size=1792, sum=mem_dbsl=234831

B { db_tran94( fcode = RS_CLOSE, xfer = 5, tname = 'SVERS', stmt { no ID }, 0x111079740 )

B } db_tran94( retcode = 0, mod_cnt = 1, cursor = 1 )

B } db_rsql1994[0]( retcode = 2048, mod_cnt = 1, cursor = 2 )

B < db_tbxld_end(TBX_GENERIC): # 2, Table = SVERS , key_l = 72, key_fld_n = 1, genkey_fld_n = 0

B ---> data_load(TBX_GENERIC): #1 Records

B Fast load extent

B ---> split_ext(TBX_GENERIC): ext_nr = 1

B <--- split_ext(TBX_GENERIC):ext_nr = 1, length = 1 new_ext_nr = 2, new_lngth = 65533

B <--- data_load

B > db_tbxld_end(TBX_GENERIC): # 2, rc = 0

B { db_rsql1994[0]( fcode = RS_CLOSE, xfer = 5, tname = 'SVERS', stmt { no ID } ) {dbrsbuf.c:1756}

B } db_rsql1994[0]( retcode = 0, mod_cnt = 1, cursor = 2 )

B < db_tbxopen(TBX_GENERIC): # 2, Table = SVERS , key_l = 72

B lo_bound_l = 72, lo_bound =

B > db_tbxopen(TBX_GENERIC): # 2, rc = DBTXE_OK

B } db_setget( fcode = 'RT_SETB', retcode = 0 )

B { db_setget( cid = 0, fcode = 'RT_GET', tname = 'SVERS' ) {sapicc.c:1226}

B < db_tbxfetch(TBX_GENERIC): # 2, Table = SVERS , key_fld_n = 1, genkey_fld_n = 0

B > db_tbxfetch(TBX_GENERIC): # 2, fetched = 1, rc = 0

B } db_setget( fcode = 'RT_GET', retcode = 0 )

B { db_setget( cid = 0, fcode = 'RT_RELEASE', tname = 'SVERS' ) {sapicc.c:1228}

B < db_tbxclose(TBX_GENERIC): # 2, Table = SVERS

B > db_tbxclose(TBX_GENERIC): # 2, rc = DBTXE_OK

B } db_setget( fcode = 'RT_RELEASE', retcode = 0 )

M ThrtReadDBVersion: found db version >620<

M ThrtReadDBVersion o.k.

M PfReadDBVersion: new db version (620)

C -->oci_bind ( 2: SQLT_LBI, l= 16, con_hdl=0, stmth_p=11322b9f8, vp=11313fef8,ip=113134bd0,rlp=113137abc,rcp=0)

C -->oci_bind ( 3: SQLT_AFC, l= 14, con_hdl=0, stmth_p=11322b9f8, vp=11313aefd,ip=113134df2,rlp=113137cde,rcp=0)

C -->oci_bind ( 4: SQLT_AFC, l= 14, con_hdl=0, stmth_p=11322b9f8, vp=11313bdeb,ip=113135014,rlp=113137f00,rcp=0)

C -->oci_bind ( 5: SQLT_AFC, l= 14, con_hdl=0, stmth_p=11322b9f8, vp=11313ccd9,ip=113135236,rlp=113138122,rcp=0)

C -->oci_bind ( 6: SQLT_INT, l= 4, con_hdl=0, stmth_p=11322b9f8, vp=1131318a0,ip=113135458,rlp=0,rcp=0)

C -->oci_bind ( 7: SQLT_INT, l= 4, con_hdl=0, stmth_p=11322b9f8, vp=113131ce4,ip=11313567a,rlp=0,rcp=0)

C -->oci_bind ( 8: SQLT_INT, l= 4, con_hdl=0, stmth_p=11322b9f8, vp=113132128,ip=11313589c,rlp=0,rcp=0)

C -->oci_bind ( 9: SQLT_INT, l= 4, con_hdl=0, stmth_p=11322b9f8, vp=11313256c,ip=113135abe,rlp=0,rcp=0)

C -->oci_bind (10: SQLT_INT, l= 4, con_hdl=0, stmth_p=11322b9f8, vp=1131329b0,ip=113135ce0,rlp=0,rcp=0)

C -->oci_bind (11: SQLT_AFC, l= 1, con_hdl=0, stmth_p=11322b9f8, vp=11313dbc7,ip=113135f02,rlp=113138344,rcp=0)

C -->oci_bind (12: SQLT_AFC, l= 1, con_hdl=0, stmth_p=11322b9f8, vp=11313dcd8,ip=113136124,rlp=113138566,rcp=0)

C -->oci_bind (13: SQLT_AFC, l= 30, con_hdl=0, stmth_p=11322b9f8, vp=11313dde9,ip=113136346,rlp=113138788,rcp=0)

C -->oci_bind (14: SQLT_LBI, l= 6, con_hdl=0, stmth_p=11322b9f8, vp=113141008,ip=113136568,rlp=1131389aa,rcp=0)

C -->oci_bind (15: SQLT_AFC, l= 1, con_hdl=0, stmth_p=11322b9f8, vp=11313fde7,ip=11313678a,rlp=113138bcc,rcp=0)

C -->oci_bind (16: SQLT_INT, l= 4, con_hdl=0, stmth_p=11322b9f8, vp=113132df4,ip=1131369ac,rlp=0,rcp=0)

C -->oci_bind (17: SQLT_INT, l= 4, con_hdl=0, stmth_p=11322b9f8, vp=113133238,ip=113136bce,rlp=0,rcp=0)

C -->oci_bind (18: SQLT_INT, l= 4, con_hdl=0, stmth_p=11322b9f8, vp=11313367c,ip=113136df0,rlp=0,rcp=0)

C -->oci_bind (19: SQLT_INT, l= 4, con_hdl=0, stmth_p=11322b9f8, vp=113133ac0,ip=113137012,rlp=0,rcp=0)

C -->oci_bind (20: SQLT_INT, l= 4, con_hdl=0, stmth_p=11322b9f8, vp=113133f04,ip=113137234,rlp=0,rcp=0)

C -->oci_bind (21: SQLT_INT, l= 4, con_hdl=0, stmth_p=11322b9f8, vp=113134348,ip=113137456,rlp=0,rcp=0)

C -->oci_execute_stmt(con_hdl=0, stmth_p=11322b9f8, iters=1, rowoff=0, dml_batch_errors=0)

C <--oci_execute_stmt(rc=0, rows processed 1, iters-errors = 1-0) [0,000569s]

M SigIGenAction, caught signal 11 (pid=712924)

M SigIRegisterRoutine: handler for signal 14 installed (DpSigAlrm)

M signal 11 was in UNBLOCKED mode

-

---

Signal 11 encountered.

=> 64 bit R/3 Kernel

=> 64 bit AIX Kernel

=> Heap limit = unlimited

=> Stack limit = unlimited

=> Core limit = 1073741312

=> File size limit = unlimited

=> Heap address = 0x11323ffc0

=> Stack address = 0xfffffffffffe200

=> Stack low = 0xfffffffffffc940

=> Stack high = 0xffffffffffff860

-

---

=> Stack Trace:

lruBufferFull__FUi() at 0x100429ed8

touch_object__FPccT29TOUCH_MOD10TOUCH_TYPE() at 0x100425d1c

ab_tviewbuf() at 0x100425fe8

note_modify() at 0x100336b60

db_ntab_hist_write() at 0x100d2744c

write_log() at 0x10152bd6c

log_wa() at 0x10152e73c

db_prt() at 0x10152ef98

rtab_exec() at 0x10053ab00

db_rtab0() at 0x10053d034

NotifyPatchHistory() at 0x100120574

ThShMInit() at 0x1000951d4

ThIPCInit() at 0x1000a93a0

tskh_init() at 0x1000aa458

tskhstart() at 0x1000be2f0

DpMain() at 0x1015fc87c

nlsui_main() at 0x1015cdab8

-

---

=> CPU Registers:

msr = 0xa00000000000d0b2 iar = 0x0000000100429ed8

ctr = 0x0000000000000000 lr = 0x0000000100428d00

xer = 0x0000000000000000 cr = 0x0000000042448880

r00 = 0x0000000000000000 r01 = 0x0fffffffffffe200

r02 = 0x0000000110c8e890 r03 = 0x7d00f0507cc607b4

r04 = 0x0000000000000000 r05 = 0x0000000000000000

r06 = 0x000000010182dee8 r07 = 0x000000000000c6ea

r08 = 0x0000000060606060 r09 = 0x0000000000000000

r10 = 0x0000000060606060 r11 = 0x00000000c0d1c6e5

r12 = 0x0000000100428b5c r13 = 0x00000001128de900

r14 = 0x0000000000001c5b r15 = 0x00000000000042e0

r16 = 0x00000000fffeffff r17 = 0x0000000000000000

r18 = 0x0000000000000000 r19 = 0x0000000000000000

r20 = 0x0000000000000001 r21 = 0x0000000000000000

r22 = 0x0000000000000000 r23 = 0x0000000000000000

r24 = 0x0000000000000000 r25 = 0x00000001100e1500

r26 = 0x0000000000000001 r27 = 0x0000000000000001

r28 = 0x000000000000085c r29 = 0x0000000000000001

r30 = 0x0000000000000000 r31 = 0x0000000000000000

-

---

M ***LOG Q0E=> SigIGenAction, signal ( 11) [sigux.c 903]

M SigIGenAction: call exithandler ThSigHandler(FALSE)

M ThSigHandler: call ThErrHandle

M in_ThErrHandle: 1

M ThSigHandler: signal (step 1, th_errno 11, action 4)

M

M Info for wp 0

M

M stat = 4

M reqtype = 1

M act_reqtype = -1

M rq_info = 0

M tid = -1

M mode = 255

M len = -1

M rq_id = 65535

M rq_source = 255

M last_tid = 0

M last_mode = 0

M int_checked_resource(RFC) = 0

M ext_checked_resource(RFC) = 0

M int_checked_resource(HTTP) = 0

M ext_checked_resource(HTTP) = 0

M report = > <

M action = 0

M tab_name = > <

M PfStatDisconnect: disconnect statistics

M Entering TH_CALLHOOKS

M ThCallHooks: call hook >ThrSaveSPAFields< for event BEFORE_DUMP

M *** ERROR => ThrSaveSPAFields: no valid thr_wpadm [thxxrun1.c 730]

M *** ERROR => ThCallHooks: event handler ThrSaveSPAFields for event BEFORE_DUMP failed [thxxtool3.c 255]

M Entering ThSetStatError

M ThIErrHandle: call ThrCoreInfo

A + not rolled in

A RABAX in run level 0

A RABAX in unkown environment: task_type=0, run level=0, rabax state=80000000 ztta_task_type=0

A -

---

C-STACK -

---

-

---

=> 64 bit R/3 Kernel

=> 64 bit AIX Kernel

=> Heap limit = unlimited

=> Stack limit = unlimited

=> Core limit = 1073741312

=> File size limit = unlimited

=> Heap address = 0x11323ffc0

=> Stack address = 0xfffffffffffe200

=> Stack low = 0xfffffffffffaad0

=> Stack high = 0xffffffffffff860

-

---

=> Stack Trace:

lruBufferFull__FUi() at 0x100429ed8

touch_object__FPccT29TOUCH_MOD10TOUCH_TYPE() at 0x100425d1c

ab_tviewbuf() at 0x100425fe8

note_modify() at 0x100336b60

db_ntab_hist_write() at 0x100d2744c

write_log() at 0x10152bd6c

log_wa() at 0x10152e73c

db_prt() at 0x10152ef98

rtab_exec() at 0x10053ab00

db_rtab0() at 0x10053d034

NotifyPatchHistory() at 0x100120574

ThShMInit() at 0x1000951d4

ThIPCInit() at 0x1000a93a0

tskh_init() at 0x1000aa458

tskhstart() at 0x1000be2f0

DpMain() at 0x1015fc87c

nlsui_main() at 0x1015cdab8

-

---

=> CPU Registers:

msr = 0xa00000000000d0b2 iar = 0x0000000100429ed8

ctr = 0x0000000000000000 lr = 0x0000000100428d00

xer = 0x0000000000000000 cr = 0x0000000042448880

r00 = 0x0000000000000000 r01 = 0x0fffffffffffe200

r02 = 0x0000000110c8e890 r03 = 0x7d00f0507cc607b4

r04 = 0x0000000000000000 r05 = 0x0000000000000000

r06 = 0x000000010182dee8 r07 = 0x000000000000c6ea

r08 = 0x0000000060606060 r09 = 0x0000000000000000

r10 = 0x0000000060606060 r11 = 0x00000000c0d1c6e5

r12 = 0x0000000100428b5c r13 = 0x00000001128de900

r14 = 0x0000000000001c5b r15 = 0x00000000000042e0

r16 = 0x00000000fffeffff r17 = 0x0000000000000000

r18 = 0x0000000000000000 r19 = 0x0000000000000000

r20 = 0x0000000000000001 r21 = 0x0000000000000000

r22 = 0x0000000000000000 r23 = 0x0000000000000000

r24 = 0x0000000000000000 r25 = 0x00000001100e1500

r26 = 0x0000000000000001 r27 = 0x0000000000000001

r28 = 0x000000000000085c r29 = 0x0000000000000001

r30 = 0x0000000000000000 r31 = 0x0000000000000000

-

---

A -

---

A RFC> get SPA_SESSION_ARFC: NORMAL

A TH VERBOSE LEVEL FULL

M SigIRegisterRoutine: handler for signal 11 installed (ab_catch_dumperror)

M SigIRegisterRoutine: handler for signal 10 installed (ab_catch_dumperror)

A ** RABAX: level LEV_RX_PXA_RELEASE_MTX entered.

A ** RABAX: level LEV_RX_PXA_RELEASE_MTX completed.

A ** RABAX: level LEV_RX_VMIT_CLEANUP entered.

A ** RABAX: level LEV_RX_VMIT_CLEANUP completed.

A ** RABAX: level LEV_RX_ROLLBACK entered.

A ** RABAX: level LEV_RX_ROLLBACK completed.

A ** RABAX: level LEV_RX_DB_ALIVE entered.

A ** RABAX: level LEV_RX_DB_ALIVE completed.

A ** RABAX: level LEV_RX_HOOKS entered.

A ** RABAX: level LEV_RX_HOOKS completed.

A ** RABAX: level LEV_RX_STANDARD entered.

A ** RABAX: level LEV_RX_STANDARD completed.

A ** RABAX: level LEV_RX_C_STACK entered.

A ** RABAX: level LEV_RX_C_STACK completed.

A ** RABAX: level LEV_RX_MEMO_CHECK entered.

A ** RABAX: level LEV_RX_MEMO_CHECK completed.

A ** RABAX: level LEV_RX_INTERFACES entered.

A ** RABAX: level LEV_RX_INTERFACES completed.

A ** RABAX: level LEV_RX_GET_MESS entered.

A ** RABAX: level LEV_RX_GET_MESS completed.

A ** RABAX: level LEV_RX_INIT_SNAP entered.

A ** RABAX: level LEV_RX_INIT_SNAP completed.

A ** RABAX: level LEV_RX_WRITE_SYSLOG entered.

A ** RABAX: level LEV_RX_WRITE_SYSLOG completed.

A ** RABAX: level LEV_RX_WRITE_SNAP entered.

X *** ERROR => invalid MM administration (adm) 0 (admCheck)0 [mmxx.c 2127]

A + not rolled in

A ** RABAX: level LEV_RX_WRITE_SNAP failed.

M SigIRegisterRoutine: handler for signal 11 installed (ab_catch_dumperror)

M SigIRegisterRoutine: handler for signal 10 installed (ab_catch_dumperror)

A ** RABAX: level LEV_RX_SET_ALERT entered.

A ** RABAX: level LEV_RX_SET_ALERT completed.

A ** RABAX: level LEV_RX_COMMIT entered.

A ** RABAX: level LEV_RX_COMMIT completed.

A ** RABAX: level LEV_RX_SNAP_SYSLOG entered.

A ** RABAX: level LEV_RX_SNAP_SYSLOG completed.

A ** RABAX: level LEV_RX_RESET_PROGS entered.

A ** RABAX: level LEV_RX_RESET_PROGS completed.

A ** RABAX: level LEV_RX_STDERR entered.

A ** RABAX: level LEV_RX_STDERR completed.

A ** RABAX: level LEV_RX_RFC_ERROR entered.

A ** RABAX: level LEV_RX_RFC_ERROR completed.

A ** RABAX: level LEV_RX_RFC_CLOSE entered.

A ** RABAX: level LEV_RX_RFC_CLOSE completed.

A ** RABAX: level LEV_RX_IMC_ERROR entered.

A ** RABAX: level LEV_RX_IMC_ERROR completed.

A ** RABAX: level LEV_RX_DATASET_CLOSE entered.

A ** RABAX: level LEV_RX_DATASET_CLOSE completed.

A ** RABAX: level LEV_RX_RESET_SHMLOCKS entered.

A ** RABAX: level LEV_RX_RESET_SHMLOCKS completed.

A ** RABAX: level LEV_RX_ERROR_SAVE entered.

A ** RABAX: level LEV_RX_ERROR_SAVE completed.

A ** RABAX: level LEV_RX_ERROR_TPDA entered.

A ** RABAX: level LEV_RX_ERROR_TPDA completed.

A ** RABAX: level LEV_RX_PXA_RELEASE_RUDI entered.

A ** RABAX: level LEV_RX_PXA_RELEASE_RUDI completed.

A ** RABAX: level LEV_RX_LIVE_CACHE_CLEANUP entered.

M RmCleanUpResources3: hdr/tbl/ext_tbl/free/keep = 110fd5428/0/0/0/1

M RmCleanUpResources3: no resources registered

A ** RABAX: level LEV_RX_LIVE_CACHE_CLEANUP completed.

A ** RABAX: level LEV_RX_END entered.

A ** RABAX: level LEV_RX_END completed.

M SigIRegisterRoutine: handler for signal 11 installed (SigIGenAction)

M SigIRegisterRoutine: handler for signal 10 installed (SigIGenAction)

A ** RABAX: end RX_RETURN

M Entering ThReadDetachMode

X MmCheck 7000010c0221000 'ThIErr'

X MmCHK 7000010c0221968: ABC9534E 544E4C58 00000000 00000038 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000 07000010 C0221910

X MmCHK 7000010c02219a0: ABC35354 41540000 00000000 00000040 00000000 00000038 00000000 00000000 00000000 00000000 00000005 00000000 00000000 00000008

X MmCHK 7000010c02219e0: ABC34943 54434D31 00000000 00000148 00000000 00000040 00000000 00000000 07000010 C0221B40 00000000 00000000 00000000 00000110

X MmCHK 7000010c0221b28: ABC34943 54434D32 00000000 000520E0 00000000 00000148 07000010 C02219F8 07000010 C0273C20 00000000 00000000 00000000 000520A8

X MmCHK 7000010c0273c08: ABC34943 54434D34 00000000 0002A920 00000000 000520E0 07000010 C0221B40 07000010 C029E540 00000000 00000000 00000000 0002A8E8

X MmCHK 7000010c029e528: ABC34943 54434D35 00000000 00000260 00000000 0002A920 07000010 C0273C20 07000010 C029E7A0 00000000 00000000 00000000 00000228

X MmCHK 7000010c029e788: ABC34943 54434D36 00000000 0000B1C0 00000000 00000260 07000010 C029E540 07000010 C02A9960 00000000 00000000 00000000 0000B188

X MmCHK 7000010c02a9948: ABC34943 54434D37 00000000 00061AE0 00000000 0000B1C0 07000010 C029E7A0 00000000 00000000 00000000 00000000 00000000 00061AA8

X MmCHK 7000010c030b428: ABC20000 00000000 00000000 10F15BA0 00000000 00061AE0 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000

X MmCHK 7000010d1220fc8: ABC9534E 544E4C58 00000000 00000038 00000000 10F15BA0 00000000 00000000 00000000 00000000 00000000 00000000 07000010 C0221910

X MmCHK 7000010c030b428: ABC60000 00000000 00000000 10F15BA0 00000000 00061AE0 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000

X MmxInfo: 934 277590 277590 7 1 1

M ***LOG Q02=> wp_halt, WPStop (Workproc 0 712924) [dpuxtool.c 318]

M SigIGenAction: return from exithandler ThSigHandler(FALSE)

M set mode of sig 11 to BLOCKED and send to own pid

M

M CORE DUMP ...

[Former Member]

Hi,

Please copy & paste the dev_disp & other imp logs here.

[Former Member]

Hi Mathew,

check below notes

Note 708659 - Workprocesses die

Note 534765 - db connect fails because of wrong db schema in environment

Regards

Ashok

[mathew_varughese]

Ashok,

Both notes are not valid, one is not for 6.40 and other is related to oralce schema..see r3trans output

/>R3trans -x

This is R3trans version 6.13 (release 640 - 17.07.08 - 08:11:00).

R3trans finished (0000).

[Former Member]

Hi,

shutdown OS restart it and try to start the sap server,

if problem is still persist then provide the dev_w0 log.

regards,

kaushal

[mathew_varughese]

We restarted the OS, also DB is another host, that also restarted but no luck

Aslo tried configuring CI in aother server, there also we have same error. So the issue looks closer to DB or Kernel.

History : This was a working system, and brought down for few memory parameter changes. (which we revert it back already)