Changeset 257

Timestamp:

Mar 1, 2006 8:47:39 PM (19 years ago)

Author:

bennylp

Message:

Trying to find out why CPU is idle while it should be working hard!!

Location:

pjproject/trunk/pjsip-apps

Files:

• build (modified) (1 prop)
• build/pjsip_perf.dsp (modified) (1 diff)
• src/pjsip-perf/main.c (modified) (5 diffs)
• src/pjsip-perf/pjsip_perf.h (modified) (1 diff)

pjproject/trunk/pjsip-apps/build

@@ -1 +1 @@
 *.ncb
 *.opt
+*.plg
 .*

@@ -1 +1 @@
 *.ncb
 *.opt
+*.plg
 .*

pjproject/trunk/pjsip-apps/build/pjsip_perf.dsp

@@ -75 +75 @@
 LINK32=link.exe
 # ADD BASE LINK32 kernel32.lib user32.lib gdi32.lib winspool.lib comdlg32.lib advapi32.lib shell32.lib ole32.lib oleaut32.lib uuid.lib odbc32.lib odbccp32.lib kernel32.lib user32.lib gdi32.lib winspool.lib comdlg32.lib advapi32.lib shell32.lib ole32.lib oleaut32.lib uuid.lib odbc32.lib odbccp32.lib /nologo /subsystem:console /debug /machine:I386 /pdbtype:sept
-# ADD LINK32 netapi32.lib mswsock.lib ws2_32.lib kernel32.lib user32.lib gdi32.lib winspool.lib comdlg32.lib advapi32.lib shell32.lib ole32.lib oleaut32.lib uuid.lib odbc32.lib odbccp32.lib kernel32.lib user32.lib gdi32.lib winspool.lib comdlg32.lib advapi32.lib shell32.lib ole32.lib oleaut32.lib uuid.lib odbc32.lib odbccp32.lib /nologo /subsystem:console /debug /machine:I386 /out:"..\bin\pjsip-perf-i386-win32-vc6d.exe" /pdbtype:sept
+# ADD LINK32 netapi32.lib mswsock.lib ws2_32.lib kernel32.lib user32.lib gdi32.lib winspool.lib comdlg32.lib advapi32.lib shell32.lib ole32.lib oleaut32.lib uuid.lib odbc32.lib odbccp32.lib kernel32.lib user32.lib gdi32.lib winspool.lib comdlg32.lib advapi32.lib shell32.lib ole32.lib oleaut32.lib uuid.lib odbc32.lib odbccp32.lib /nologo /subsystem:console /profile /debug /machine:I386 /out:"..\bin\pjsip-perf-i386-win32-vc6d.exe"
 
 !ENDIF 

pjproject/trunk/pjsip-apps/src/pjsip-perf/main.c

@@ -367 +367 @@
         elapsed = sess_elapsed = batch->end_time;
 
+        /* Batch time. */
         PJ_TIME_VAL_SUB(elapsed, batch->start_time);
-        PJ_TIME_VAL_SUB(sess_elapsed, settings.session->start_time);
         msec = PJ_TIME_VAL_MSEC(elapsed);
         if (msec == 0) msec = 1;
 
-        PJ_LOG(3,(THIS_FILE, "%02d:%02d:%02d: %d tasks in %d.%ds (%d tasks/sec)",
+        /* Session time */
+        PJ_TIME_VAL_SUB(sess_elapsed, settings.session->start_time);
+
+        /* Spawn time */
+        PJ_TIME_VAL_SUB(batch->spawned_time, batch->start_time);
+
+        PJ_LOG(3,(THIS_FILE, "%02d:%02d:%02d: %d tasks in %d.%ds (%d tasks/sec), "
+                             "spawn=time=%d.%d",
                              (sess_elapsed.sec / 3600),
                              (sess_elapsed.sec % 3600) / 60,
@@ -378 +385 @@
                              batch->rate,
                              elapsed.sec, elapsed.msec,
-                             batch->rate * 1000 / msec));
+                             batch->rate * 1000 / msec,
+                             batch->spawned_time.sec,
+                             batch->spawned_time.msec));
 
         if (!settings.session->stopping) {
@@ -408 +417 @@
     pj_status_t status = PJ_SUCCESS;
     pjsip_cred_info cred_info[1];
-    pj_time_val now, spawn_time, sess_time;
 
     unsigned i;
@@ -424 +432 @@
     batch->success = 0;
     batch->failed = 0;
+    pj_gettimeofday(&batch->start_time);
 
     pj_list_push_back(&sess->active_list, batch);
@@ -447 +456 @@
     }
 
-    pj_gettimeofday(&now);
-    spawn_time = sess_time = now;
-    PJ_TIME_VAL_SUB(spawn_time, batch->start_time);
-    PJ_TIME_VAL_SUB(sess_time, sess->start_time);
+    pj_gettimeofday(&batch->spawned_time);
 
     sess->total_created += batch->started;

pjproject/trunk/pjsip-apps/src/pjsip-perf/pjsip_perf.h

@@ -41 +41 @@
     unsigned     failed;
     pj_time_val  start_time;
+    pj_time_val  spawned_time;
     pj_time_val  end_time;
 };

@@ -1 +1 @@
 *.ncb
 *.opt
+*.plg
 .*