X-Git-Url: http://rtime.felk.cvut.cz/gitweb/can-benchmark.git/blobdiff_plain/4dfed603d7350f31bc27eac347eb824cafaf0c42..3ea7fae8477eb1f8a65e07a2e5f8c139110a2a81:/latester/latester.c

diff --git a/latester/latester.c b/latester/latester.c
index fb299aa..61b2b43 100644
--- a/latester/latester.c
+++ b/latester/latester.c
@@ -81,6 +81,9 @@ struct {
 	unsigned enobufs;
 	unsigned overrun;
 	unsigned lost;
+	struct timespec tic, tac;
+	unsigned timeouts;
+	unsigned invalid_frame;
 } stats;
 
 int num_interfaces = 0;
@@ -130,7 +133,7 @@ void sprint_canframe(char *buf , struct can_frame *cf, int sep) {
 		}
 }
 
-static inline struct msg_info *frame2info(struct can_frame *frame)
+static inline uint16_t frame_index(struct can_frame *frame)
 {
 	uint16_t idx;
 	if (frame->can_dlc >= 2) {
@@ -141,7 +144,12 @@ static inline struct msg_info *frame2info(struct can_frame *frame)
 
 		error(1, 0, "%s error", __FUNCTION__);
 	}
-	return &msg_infos[idx];
+	return idx;
+}
+
+static inline struct msg_info *frame2info(struct can_frame *frame)
+{
+	return &msg_infos[frame_index(frame)];
 }
 
 static inline char *tstamp_str(const void *ctx, struct timespec *tstamp)
@@ -187,33 +195,6 @@ void msg_info_print(FILE *f, struct msg_info *mi)
 	talloc_free (local);
 }
 
-int msg_info_store(FILE *f, struct msg_info *mi)
-{
-	struct timespec diff;
-	void *local = talloc_new (NULL);
-	static long num = 0;
-
-#define S(ts) tstamp_str(local, &ts)
-#define DIFF(a, b) (timespec_subtract(&diff, &b, &a), S(diff))
-
-	switch (num_interfaces) {
-	case 2:
-		fprintf(f, "%ld %d %d %s\n",
-			num, mi->id, mi->length,
-			DIFF(mi->ts_sent, mi->ts_rx_final_kern));
-		break;
-	case 3:
-		fprintf(f, "%ld %d %d %s\n",
-			num, mi->id, mi->length,
-			DIFF(mi->ts_rx_onwire_kern, mi->ts_rx_final_kern));
-		break;
-	}
-#undef S
-#undef DIFF
-	talloc_free (local);
-}
-
-
 /* Subtract the `struct timespec' values X and Y, storing the result in
    RESULT.  Return 1 if the difference is negative, otherwise 0.  */
 
@@ -251,13 +232,13 @@ static inline unsigned get_msg_latency_us(struct msg_info *mi)
 {
 	struct timespec diff;
 	switch (num_interfaces) {
-	case 2:
+	case 3:
 		if (opt.userhist)
 			timespec_subtract(&diff, &mi->ts_rx_final, &mi->ts_rx_onwire);
 		else
 			timespec_subtract(&diff, &mi->ts_rx_final_kern, &mi->ts_rx_onwire_kern);
 		break;
-	case 3:
+	case 2:
 		if (opt.userhist)
 			timespec_subtract(&diff, &mi->ts_rx_final, &mi->ts_sent);
 		else
@@ -359,11 +340,16 @@ static inline void trace_off(int ret)
 		write(trace_fd, "0", 1);
 }
 
-void msg_info_free(struct msg_info *mi)
+static inline void msg_info_free(struct msg_info *mi)
 {
 	mi->id = -1;
 }
 
+static inline bool msg_info_used(struct msg_info *mi)
+{
+	return mi->id != -1;
+}
+
 int send_frame(int socket)
 {
 	struct can_frame frame;
@@ -375,7 +361,7 @@ int send_frame(int socket)
 
 	MEMSET_ZERO(frame);
 	i = curr_msg+1;
-	while (msg_infos[i].id != -1 && i != curr_msg) {
+	while (msg_info_used(&msg_infos[i]) && i != curr_msg) {
 		i++;
 		if (i >= MAX_INFOS)
 			i = 0;
@@ -504,8 +490,11 @@ void process_on_wire_rx(int s)
 	struct msg_info *mi;
 	receive(s, &frame, &ts_kern, &ts_user);
 	mi = frame2info(&frame);
-	mi->ts_rx_onwire_kern = ts_kern;
-	mi->ts_rx_onwire = ts_user;
+	if (msg_info_used(mi)) {
+		mi->ts_rx_onwire_kern = ts_kern;
+		mi->ts_rx_onwire = ts_user;
+	} else
+		stats.invalid_frame++;
 }
 
 
@@ -536,6 +525,7 @@ void *measure_thread(void *arg)
 	struct timespec timeout;
 	struct sockaddr_can addr;
 	sigset_t set;
+	int consecutive_timeouts = 0;
 
 	MEMSET_ZERO(pfd);
 
@@ -582,6 +572,8 @@ void *measure_thread(void *arg)
 	if (opt.oneattime)
 		SEND();
 
+	get_tstamp(&stats.tic);
+
 	while (!finish_flag &&
 	       (opt.count == 0 || count < opt.count || msg_in_progress != 0)) {
 
@@ -600,7 +592,13 @@ void *measure_thread(void *arg)
 					SEND();
 				}
 			} else {
-				error(1, 0, "poll timeout");
+				/* Lost message - send a new one */
+				stats.timeouts++;
+				consecutive_timeouts++;
+				if (consecutive_timeouts < 10)
+					SEND();
+				else /* Something is really broken */
+					finish_flag = 1;
 			}
 			break;
 		default: // Event
@@ -613,13 +611,16 @@ void *measure_thread(void *arg)
 			}
 			pfd[0].revents = 0;
 
-			if (num_interfaces == 3 && pfd[1].revents != 0) {
+			if (num_interfaces == 3 && pfd[1].revents & POLLIN) {
 				process_on_wire_rx(pfd[1].fd);
 				pfd[1].revents = 0;
 			}
+			if (num_interfaces == 3 && pfd[1].revents & ~POLLIN)
+				error(1, 0, "Unexpected pfd[1].revents: 0x%04x\n", pfd[1].revents);
 
 			i = (num_interfaces == 2) ? 1 : 2;
-			if (pfd[i].revents != 0) {
+			if (pfd[i].revents & POLLIN) {
+				consecutive_timeouts = 0;
 				process_final_rx(pfd[i].fd);
 				msg_in_progress--;
 				pfd[i].revents = 0;
@@ -628,9 +629,13 @@ void *measure_thread(void *arg)
 					SEND();
 				}
 			}
+			if (pfd[i].revents & ~POLLIN)
+				error(1, 0, "Unexpected pfd[%d].revents: 0x%04x\n", pfd[i].revents);
 		}
 	}
 
+	get_tstamp(&stats.tac);
+
 	for (i=0; i<num_interfaces; i++)
 		close(pfd[i].fd);
 
@@ -800,6 +805,14 @@ int main(int argc, const char *argv[])
 	fclose(opt.f_hist);
 	fclose(opt.f_msgs);
 
+
+	fprintf(opt.f_stat, "cmdline='");
+	for (i=0; i<argc; i++)
+		fprintf(opt.f_stat, "%s%s", argv[i], i < argc-1 ? " " : "");
+	fprintf(opt.f_stat, "'\n");
+
+	timespec_subtract(&diff, &stats.tac, &stats.tic);
+	fprintf(opt.f_stat, "duration=%s # seconds\n", tstamp_str(NULL, &diff));
 	
 	fprintf(opt.f_stat, "sent=%d\n", count);
 	fprintf(opt.f_stat, "overrun=%d\n", stats.overrun);
@@ -811,6 +824,12 @@ int main(int argc, const char *argv[])
 	fprintf(opt.f_stat, "lost=%d\n", stats.lost);
 	if (stats.lost && !opt.quiet)
 		printf("lost=%d\n", stats.lost);
+	fprintf(opt.f_stat, "timeouts=%d\n", stats.timeouts);
+	if (stats.timeouts && !opt.quiet)
+		printf("timeouts=%d\n", stats.timeouts);
+	fprintf(opt.f_stat, "invalid_frame=%d\n", stats.timeouts);
+	if (stats.timeouts && !opt.quiet)
+		printf("invalid_frame=%d\n", stats.timeouts);
 
 	fclose(opt.f_stat);