topic Re: Logging processing time in Application Development and Automation Discussions

Logging processing time

Former Member — Wed, 17 Oct 2007 08:19:39 GMT

Hi,

I have an interface, which is reading XML files, selecting data from sap tables and send a result in XML back.

I now wanted to log the incoming requests and also the processing time, used by SAP.

Therefore I have made a table, which is saving the request typ, time, date, and a field called processing time.

When the function is going to be called, I´m filling the fields date and time directly with sy-date and sy-uzeit.

After processing, I´m subtracting the old sy-uzeit from the new sy-uzeit and save that into the processing time field.

that works sometimes, but sometimes not.... I thought, I´m getting the difference in seconds later on in the field processing time. What am I doing wrong? Or how to record the processing time better?

The field processing time is an int4 field.

Thank you for you help.

Reward points guaranteed!

Re: Logging processing time

Former Member — Wed, 17 Oct 2007 08:26:38 GMT

data: a type sy-uzeit,
b type sy-datum,
c type sy-uzeit,
endtime type sy-uzeit,
enddate type sy-datum.

a = sy-uzeit.
b = sy-datum.
c = sy-uzeit.

CALL FUNCTION 'C14B_ADD_TIME'
EXPORTING
i_starttime = a
i_startdate = b
i_addtime = c
IMPORTING
E_ENDTIME = endtime
E_ENDDATE = enddate
.

write : endtime, enddate, a , c.

Re: Logging processing time

Former Member — Wed, 17 Oct 2007 08:34:58 GMT

Hi, thank you for the answer. But I just need the processing time in seconds...

How to do that?

Re: Logging processing time

Former Member — Wed, 17 Oct 2007 08:39:08 GMT

Hi Arne,

If you simply want to subtract the time, then

" l_time1 has old sy-uzeit value

l_time2 = sy-uzeit.

l_time = l_time2 - l_time1.

Above code should work fine everytime.

If the time zone of TIME1 and TIME2 are different, then you need to use sy-timlo instead of sy-uzeit.

Best regards,

Prashant

Re: Logging processing time

Former Member — Wed, 17 Oct 2007 08:43:31 GMT

Ok, and thats the way I have done it.

At first, I have written the time into a field and after all processing, I have subtracted that from the actual time into an integer field.


Data: timeFirst LIKE sy-uzeit,
         proc_time TYPE i.

timeFirst = sy-uzeit.

.... processing ....

proc_time = sy-uzeit - timeFirst.

But that seems not to work in all cases and I don´t know why! Because, sometimes I don´t have any value in the proc_time field, but the XML had been processed and has needed many seconds....

Re: Logging processing time

Former Member — Wed, 17 Oct 2007 08:47:04 GMT

Hi,

Two things are possible:

1. processing time is very less.. fraction of second..

FIRST TIME = SY-UZEIT. "101010

...

Process Time = sy-uzeit - first time. " Here value could be zero.. bcz of fast processing

2. The code is not executed ...

May be that the code is not executed..

Could you please put a breakpoint & debug. That should help to identify the bug.

Best regards,

Prashant

Re: Logging processing time

Former Member — Wed, 17 Oct 2007 08:50:22 GMT

Yes, sometimes, it is very less....

The function is going to be called over RFC from an external application. EVERYTIME, when I run the function manually, it records the right time. But often, when I run it from the external program, it is not working. But it is always writing the logging line to the database table...

Strange thing! At first, the field for processing time was of type NUMC, but now I have changed it to integer. Could that be a problem?

Re: Logging processing time

Former Member — Wed, 17 Oct 2007 08:53:28 GMT

Hi,

I hope the RFC is hit everytime correctly & the code of processing time calculation is not inside any IF condition which would fail occasionaly.

Please change the data type of TIme fields to SYUZEIT.

Data : l_time1 type syuzeit,
          l_time2 type syuzeit.

Best regards,

Prashant

Re: Logging processing time

Former Member — Wed, 17 Oct 2007 09:04:47 GMT

Here is the part of my code:


* Read incoming XML request
  PERFORM read_xml_request  USING  i_streamin.

  gs_request_data-req_date = sy-datum.
  gs_request_data-req_time = sy-uzeit.
  gs_request_data-req_type = g_request_type.

.........
.....
...

* Add request information to the log table
  PERFORM log_request.

FORM log_request.

* Update log with processing time
  gs_request_data-proc_time = sy-uzeit - gs_request_data-req_time.

  CALL METHOD g_log_instance->write_request_to_log
    EXPORTING
      it_request_detail = lt_request_detail
    IMPORTING
      e_return_code  =  l_return_code
    CHANGING
      is_request_info =  gs_request_data.

ENDFORM

For the time, I have made an own data element, but with the same domain like syuzeit: sytime

so that should definately not be the problem...

Re: Logging processing time

Former Member — Wed, 17 Oct 2007 09:08:46 GMT

I hope gs_request_data-proc_time also has syuzeit as data type.

Re: Logging processing time

Former Member — Wed, 17 Oct 2007 09:15:18 GMT

No! Because I would like to have the proecssing seconds....

It is of type integer. What I want to have in this field, are the seconds used by the function.

Re: Logging processing time

Former Member — Wed, 17 Oct 2007 09:22:20 GMT

Hi Arne,

Integer type should be fine.

The inconsistency in calculation is bcz the time taken for the processing is very less & hence the start & end time dont have any difference.

I have create a sample program & tested out.

REPORT  ztest11111111111.


DATA : l_time1 TYPE syuzeit,
       l_time2 TYPE syuzeit,
       l_ans   TYPE syuzeit,
       l_int   TYPE i.


l_time1 = sy-uzeit.

WAIT UP TO 2 SECONDS. " 1st execute with this as Uncommented
*do 10 times. " for 1st time this should be commented
*write :/ ' '.  " When WAIT UP TO is commented .. Uncomment DO..ENDDO
*enddo

l_time2 = sy-uzeit.


l_ans = l_time2 - l_time1.
l_int = l_time2 - l_time1.

WRITE :/ 'ANS with SYUZEIT datatype' , l_ans.
WRITE :/ 'ANS with INT datatype' , l_int.

Re: Logging processing time

Former Member — Wed, 17 Oct 2007 09:31:44 GMT

Thank you for the help!

So it should be ok... Maybe it is now working, after I have set it from NUMC5 to integer. Hope so!

Cheers