X-Git-Url: http://git.lttng.org./?a=blobdiff_plain;f=benchmark%2Fbenchmark.c;h=8fbe8ad78416bcfdd2843ef6b986fa486e9ab096;hb=01d92d2f4d254089531424e50f8e8fd9226dd5d6;hp=eda40b2156737ae83e19c20f2611179a9e341f86;hpb=3c6bae613ee55f949693dc8ca43141d3024d7d96;p=lttng-tools.git

diff --git a/benchmark/benchmark.c b/benchmark/benchmark.c
index eda40b215..8fbe8ad78 100644
--- a/benchmark/benchmark.c
+++ b/benchmark/benchmark.c
@@ -99,6 +99,121 @@ double bench_get_destroy_session(void)
 	return get_bench_time(time_destroy_session_start, time_destroy_session_end);
 }
 
+/*
+ * Complete UST notification process time break down in different actions.
+ */
+void bench_print_ust_notification(void)
+{
+	double res, total = 0;
+
+	fprintf(fp, "--- UST notification time ---\n");
+
+	if (time_ust_notify_mmap_start == 0 || time_ust_notify_mmap_stop == 0) {
+		goto no_data;
+	}
+
+	res = get_bench_time(time_ust_notify_mmap_start,
+			time_ust_notify_mmap_stop);
+	fprintf(fp, "mmap() call time\n");
+	fprintf(fp, "Time: %.20f sec.\n", res);
+
+	total += res;
+
+	if (time_ust_notify_perms_start == 0 || time_ust_notify_perms_stop == 0) {
+		goto no_data;
+	}
+
+	res = get_bench_time(time_ust_notify_perms_start,
+			time_ust_notify_perms_stop);
+	fprintf(fp, "Setting permissions (chown/chmod)\n");
+	fprintf(fp, "Time: %.20f sec.\n", res);
+
+	total += res;
+
+	if (time_ust_notify_shm_start == 0 || time_ust_notify_shm_stop == 0) {
+		goto no_data;
+	}
+
+	res = get_bench_time(time_ust_notify_shm_start,
+			time_ust_notify_shm_stop);
+	fprintf(fp, "shm_open/ftruncate/fchmod\n");
+	fprintf(fp, "Time: %.20f sec.\n", res);
+
+	total += res;
+
+	fprintf(fp, "Global UST nonification time\n");
+	fprintf(fp, "Time: %.20f sec.\n", total);
+	return;
+
+no_data:
+	fprintf(fp, "NO DATA\n");
+	return;
+}
+
+/*
+ * This time value is only coherent is an UST application registered.
+ */
+void bench_print_ust_register(void)
+{
+	double res, total = 0;
+
+	fprintf(fp, "--- UST registration time ---\n");
+
+	if (time_ust_register_start == 0 || time_ust_register_stop == 0) {
+		goto no_data;
+	}
+
+	res = get_bench_time(time_ust_register_start, time_ust_register_stop);
+	fprintf(fp, "UST registration received and send to dispatch time\n");
+	fprintf(fp, "Time: %.20f sec.\n", res);
+
+	total += res;
+
+	if (time_ust_dispatch_register_start == 0 ||
+			time_ust_dispatch_register_stop == 0) {
+		goto no_data;
+	}
+
+	res = get_bench_time(time_ust_dispatch_register_start,
+			time_ust_dispatch_register_stop);
+	fprintf(fp, "Dispatch UST registration request time\n");
+	fprintf(fp, "Time: %.20f sec.\n", res);
+
+	total += res;
+
+	fprintf(fp, "--> Manage registration breakdown\n");
+
+	res = get_bench_time(time_ust_register_read_start,
+			time_ust_register_read_stop);
+	fprintf(fp, "read() from pipe time\n");
+	fprintf(fp, "Time: %.20f sec.\n", res);
+
+	total += res;
+
+	res = get_bench_time(time_ust_register_add_start,
+			time_ust_register_add_stop);
+	fprintf(fp, "register_traceable_app time\n");
+	fprintf(fp, "Time: %.20f sec.\n", res);
+
+	total += res;
+
+	res = get_bench_time(time_ust_register_done_start,
+			time_ust_register_done_stop);
+	fprintf(fp, "send register done command time\n");
+	fprintf(fp, "Time: %.20f sec.\n", res);
+
+	total += res;
+
+	fprintf(fp, "Global time of an UST application registration\n");
+	fprintf(fp, "Time: %.20f sec.\n", total);
+	return;
+
+no_data:
+	fprintf(fp, "NO DATA\n");
+	return;
+}
+
+
 /*
  * Log results of the sessiond boot process.
  *
@@ -113,31 +228,45 @@ void bench_print_boot_process(void)
 
 	res = get_bench_time(time_sessiond_boot_start, time_sessiond_boot_end);
 
-	fprintf(fp, "Boot time inside main() from start to first pthread_join (blocking state)\n");
+	fprintf(fp, "Inside main() from start to first pthread_join"
+			"(blocking state)\n");
+	fprintf(fp, "Time: %.20f sec.\n", res);
+
+	global_boot_time += res;
+
+	res = get_bench_time(time_sessiond_th_kern_start,
+			time_sessiond_th_kern_poll);
+
+	fprintf(fp, "Kernel thread from start to poll() (ready state)\n");
 	fprintf(fp, "Time: %.20f sec.\n", res);
 
 	global_boot_time += res;
 
-	res = get_bench_time(time_sessiond_th_kern_start, time_sessiond_th_kern_poll);
+	res = get_bench_time(time_sessiond_th_apps_start,
+			time_sessiond_th_apps_poll);
 
-	fprintf(fp, "Boot time of the kernel thread from start to poll() (ready state)\n");
+	fprintf(fp, "Application thread from start to poll() (ready state)\n");
 	fprintf(fp, "Time: %.20f sec.\n", res);
 
 	global_boot_time += res;
 
-	res = get_bench_time(time_sessiond_th_apps_start, time_sessiond_th_apps_poll);
+	res = get_bench_time(time_sessiond_th_cli_start,
+			time_sessiond_th_cli_poll);
 
-	fprintf(fp, "Boot time of the application thread from start to poll() (ready state)\n");
+	fprintf(fp, "Client thread from start to poll() (ready state)\n");
 	fprintf(fp, "Time: %.20f sec.\n", res);
 
 	global_boot_time += res;
 
-	res = get_bench_time(time_sessiond_th_cli_start, time_sessiond_th_cli_poll);
+	res = get_bench_time(time_sessiond_th_dispatch_start,
+			time_sessiond_th_dispatch_block);
 
-	fprintf(fp, "Boot time of the client thread from start to poll() (ready state)\n");
+	fprintf(fp, "Dispatch registration thread from start to poll()"
+			"(ready state)\n");
 	fprintf(fp, "Time: %.20f sec.\n", res);
 
 	global_boot_time += res;
 
-	fprintf(fp, "Global Boot Time of ltt-sessiond: %0.20f sec.\n", global_boot_time);
+	fprintf(fp, "Global Boot Time\n");
+	fprintf(fp, "Time: %0.20f sec.\n", global_boot_time);
 }