[PATCH] 2.6.18-rt7: fix more issues with 32-bit cycles_t in latency_trace.c (take 3)

From: Sergei Shtylyov
Date: Mon Nov 13 2006 - 14:51:42 EST


In addition to the clock jump-back check being falsely triggered by clock wrap
with 32-bit cycles_t, as noticed by Kevin Hilman, there's another issue: using
%Lx format to print 32-bit values warrants erroneous values on 32-bit machines
like ARM and PPC32...

Signed-off-by: Sergei Shtylyov <sshtylyov@xxxxxxxxxxxxx>

---
There's a lot more of this cr*p with CONFIG_LATENCY_TRACE enabled, hence is
take 3 on this patch...

kernel/latency_trace.c | 42 +++++++++++++++++++++---------------------
1 files changed, 21 insertions(+), 21 deletions(-)

Index: linux-2.6/kernel/latency_trace.c
===================================================================
--- linux-2.6.orig/kernel/latency_trace.c
+++ linux-2.6/kernel/latency_trace.c
@@ -989,30 +989,30 @@ static void update_out_trace(void)
tmp_max = max_tr.traces + cpu;
entries = min(tmp_max->trace_idx, MAX_TRACE-1);
printk("CPU%d: %016Lx (%016Lx) ... #%d (%016Lx) %016Lx\n", cpu,
- tmp_max->trace[0].timestamp,
- tmp_max->trace[1].timestamp,
+ (u64)tmp_max->trace[0].timestamp,
+ (u64)tmp_max->trace[1].timestamp,
entries,
- tmp_max->trace[entries-2].timestamp,
- tmp_max->trace[entries-1].timestamp);
+ (u64)tmp_max->trace[entries-2].timestamp,
+ (u64)tmp_max->trace[entries-1].timestamp);
}
tmp_max = max_tr.traces + max_tr.cpu;
entries = min(tmp_max->trace_idx, MAX_TRACE-1);

printk("CPU%d entries: %d\n", max_tr.cpu, entries);
- printk("first stamp: %016Lx\n", first_stamp);
- printk(" last stamp: %016Lx\n", first_stamp);
+ printk("first stamp: %016Lx\n", (u64)first_stamp);
+ printk(" last stamp: %016Lx\n", (u64)first_stamp);
}

#if 0
- printk("first_stamp: %Ld [%016Lx]\n", first_stamp, first_stamp);
- printk(" last_stamp: %Ld [%016Lx]\n", last_stamp, last_stamp);
+ printk("first_stamp: %Ld [%016Lx]\n", (u64)first_stamp, (u64)first_stamp);
+ printk(" last_stamp: %Ld [%016Lx]\n", (u64)last_stamp, (u64)last_stamp);
printk(" +1 stamp: %Ld [%016Lx]\n",
- tmp_max->trace[entries].timestamp,
- tmp_max->trace[entries].timestamp);
+ (u64)tmp_max->trace[entries].timestamp,
+ (u64)tmp_max->trace[entries].timestamp);
printk(" +2 stamp: %Ld [%016Lx]\n",
- tmp_max->trace[entries+1].timestamp,
- tmp_max->trace[entries+1].timestamp);
- printk(" delta: %Ld\n", last_stamp-first_stamp);
+ (u64)tmp_max->trace[entries+1].timestamp,
+ (u64)tmp_max->trace[entries+1].timestamp);
+ printk(" delta: %Ld\n", (u64)(last_stamp-first_stamp));
printk(" entries: %d\n", entries);
#endif

@@ -1240,7 +1240,7 @@ static int notrace l_show_fn(struct seq_
pid_to_cmdline(entry->pid),
entry->pid, entry->cpu, entry->flags,
entry->preempt_count, trace_idx,
- entry->timestamp, abs_usecs/1000,
+ (u64)entry->timestamp, abs_usecs/1000,
abs_usecs % 1000, rel_usecs/1000, rel_usecs % 1000);
print_name_offset(m, entry->u.fn.eip);
seq_puts(m, " (");
@@ -1623,8 +1623,8 @@ check_critical_timing(int cpu, struct cp
#ifndef CONFIG_CRITICAL_LATENCY_HIST
if (!preempt_thresh && preempt_max_latency > delta) {
printk("bug: updating %016Lx > %016Lx?\n",
- preempt_max_latency, delta);
- printk(" [%016Lx %016Lx %016Lx]\n", T0, T1, T2);
+ (u64)preempt_max_latency, (u64)delta);
+ printk(" [%016Lx %016Lx %016Lx]\n", (u64)T0, (u64)T1, (u64)T2);
}
#endif

@@ -2006,7 +2006,7 @@ check_wakeup_timing(struct cpu_trace *tr
____trace(smp_processor_id(), TRACE_FN, tr, CALLER_ADDR0, parent_eip, 0, 0, 0, *flags);
T2 = get_cycles();
if (T2 < T1)
- printk("bug2: %016Lx < %016Lx!\n", T2, T1);
+ printk("bug2: %016Lx < %016Lx!\n", (u64)T2, (u64)T1);
delta = T2-T0;

latency = cycles_to_usecs(delta);
@@ -2023,8 +2023,8 @@ check_wakeup_timing(struct cpu_trace *tr
#ifndef CONFIG_WAKEUP_LATENCY_HIST
if (!preempt_thresh && preempt_max_latency > delta) {
printk("bug2: updating %016Lx > %016Lx?\n",
- preempt_max_latency, delta);
- printk(" [%016Lx %016Lx %016Lx]\n", T0, T1, T2);
+ (u64)preempt_max_latency, (u64)delta);
+ printk(" [%016Lx %016Lx %016Lx]\n", (u64)T0, (u64)T1, (u64)T2);
}
#endif

@@ -2273,8 +2273,8 @@ long user_trace_stop(void)
if (!preempt_thresh && preempt_max_latency > delta) {
local_irq_restore(flags);
printk("bug3: updating %016Lx > %016Lx [%016Lx]?\n",
- preempt_max_latency, delta, tmp0);
- printk(" [%016Lx %016Lx]\n", T0, T1);
+ (u64)preempt_max_latency, (u64)delta, tmp0);
+ printk(" [%016Lx %016Lx]\n", (u64)T0, (u64)T1);
local_irq_save(flags);
}


-
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
Please read the FAQ at http://www.tux.org/lkml/