blob: c1d76552446e592bc132d8de37073ecb589a1629 [file] [log] [blame]
Ingo Molnar8446f1d2005-09-06 15:16:27 -07001/*
2 * Detect Soft Lockups
3 *
Ingo Molnar6687a972006-03-24 03:18:41 -08004 * started by Ingo Molnar, Copyright (C) 2005, 2006 Red Hat, Inc.
Ingo Molnar8446f1d2005-09-06 15:16:27 -07005 *
6 * this code detects soft lockups: incidents in where on a CPU
7 * the kernel does not reschedule for 10 seconds or more.
8 */
Ingo Molnar8446f1d2005-09-06 15:16:27 -07009#include <linux/mm.h>
10#include <linux/cpu.h>
Ingo Molnar82a1fcb2008-01-25 21:08:02 +010011#include <linux/nmi.h>
Ingo Molnar8446f1d2005-09-06 15:16:27 -070012#include <linux/init.h>
13#include <linux/delay.h>
Rafael J. Wysocki83144182007-07-17 04:03:35 -070014#include <linux/freezer.h>
Ingo Molnar8446f1d2005-09-06 15:16:27 -070015#include <linux/kthread.h>
16#include <linux/notifier.h>
17#include <linux/module.h>
18
Ingo Molnar43581a12007-10-16 23:26:08 -070019#include <asm/irq_regs.h>
20
Ingo Molnar8446f1d2005-09-06 15:16:27 -070021static DEFINE_SPINLOCK(print_lock);
22
Ingo Molnar6687a972006-03-24 03:18:41 -080023static DEFINE_PER_CPU(unsigned long, touch_timestamp);
24static DEFINE_PER_CPU(unsigned long, print_timestamp);
Ingo Molnar8446f1d2005-09-06 15:16:27 -070025static DEFINE_PER_CPU(struct task_struct *, watchdog_task);
26
Ingo Molnar90739082008-01-25 21:08:34 +010027static int __read_mostly did_panic;
28unsigned long __read_mostly softlockup_thresh = 60;
Ingo Molnar6687a972006-03-24 03:18:41 -080029
30static int
31softlock_panic(struct notifier_block *this, unsigned long event, void *ptr)
Ingo Molnar8446f1d2005-09-06 15:16:27 -070032{
33 did_panic = 1;
34
35 return NOTIFY_DONE;
36}
37
38static struct notifier_block panic_block = {
39 .notifier_call = softlock_panic,
40};
41
Jeremy Fitzhardinge966812d2007-05-08 00:28:02 -070042/*
43 * Returns seconds, approximately. We don't need nanosecond
44 * resolution, and we don't need to waste time with a big divide when
45 * 2^30ns == 1.074s.
46 */
Ingo Molnara3b13c22007-10-16 23:26:06 -070047static unsigned long get_timestamp(int this_cpu)
Jeremy Fitzhardinge966812d2007-05-08 00:28:02 -070048{
Ingo Molnar82a1fcb2008-01-25 21:08:02 +010049 return cpu_clock(this_cpu) >> 30LL; /* 2^30 ~= 10^9 */
Jeremy Fitzhardinge966812d2007-05-08 00:28:02 -070050}
51
Ingo Molnar8446f1d2005-09-06 15:16:27 -070052void touch_softlockup_watchdog(void)
53{
Ingo Molnara3b13c22007-10-16 23:26:06 -070054 int this_cpu = raw_smp_processor_id();
55
56 __raw_get_cpu_var(touch_timestamp) = get_timestamp(this_cpu);
Ingo Molnar8446f1d2005-09-06 15:16:27 -070057}
58EXPORT_SYMBOL(touch_softlockup_watchdog);
59
Jeremy Fitzhardinge04c91672007-05-08 00:28:05 -070060void touch_all_softlockup_watchdogs(void)
61{
62 int cpu;
63
64 /* Cause each CPU to re-update its timestamp rather than complain */
65 for_each_online_cpu(cpu)
66 per_cpu(touch_timestamp, cpu) = 0;
67}
68EXPORT_SYMBOL(touch_all_softlockup_watchdogs);
69
Ingo Molnar8446f1d2005-09-06 15:16:27 -070070/*
71 * This callback runs from the timer interrupt, and checks
72 * whether the watchdog thread has hung or not:
73 */
Ingo Molnar6687a972006-03-24 03:18:41 -080074void softlockup_tick(void)
Ingo Molnar8446f1d2005-09-06 15:16:27 -070075{
76 int this_cpu = smp_processor_id();
Ingo Molnar6687a972006-03-24 03:18:41 -080077 unsigned long touch_timestamp = per_cpu(touch_timestamp, this_cpu);
Jeremy Fitzhardinge966812d2007-05-08 00:28:02 -070078 unsigned long print_timestamp;
Ingo Molnar43581a12007-10-16 23:26:08 -070079 struct pt_regs *regs = get_irq_regs();
Jeremy Fitzhardinge966812d2007-05-08 00:28:02 -070080 unsigned long now;
Ingo Molnar8446f1d2005-09-06 15:16:27 -070081
Jeremy Fitzhardinge04c91672007-05-08 00:28:05 -070082 if (touch_timestamp == 0) {
83 touch_softlockup_watchdog();
Jeremy Fitzhardinge966812d2007-05-08 00:28:02 -070084 return;
Jeremy Fitzhardinge04c91672007-05-08 00:28:05 -070085 }
Jeremy Fitzhardinge966812d2007-05-08 00:28:02 -070086
87 print_timestamp = per_cpu(print_timestamp, this_cpu);
88
89 /* report at most once a second */
Ingo Molnara115d5c2007-10-16 23:26:06 -070090 if ((print_timestamp >= touch_timestamp &&
91 print_timestamp < (touch_timestamp + 1)) ||
92 did_panic || !per_cpu(watchdog_task, this_cpu)) {
Ingo Molnar8446f1d2005-09-06 15:16:27 -070093 return;
Ingo Molnara115d5c2007-10-16 23:26:06 -070094 }
Ingo Molnar8446f1d2005-09-06 15:16:27 -070095
Ingo Molnar6687a972006-03-24 03:18:41 -080096 /* do not print during early bootup: */
97 if (unlikely(system_state != SYSTEM_RUNNING)) {
98 touch_softlockup_watchdog();
Ingo Molnar8446f1d2005-09-06 15:16:27 -070099 return;
Ingo Molnar6687a972006-03-24 03:18:41 -0800100 }
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700101
Ingo Molnara3b13c22007-10-16 23:26:06 -0700102 now = get_timestamp(this_cpu);
Jeremy Fitzhardinge966812d2007-05-08 00:28:02 -0700103
Ingo Molnar82a1fcb2008-01-25 21:08:02 +0100104 /* Warn about unreasonable delays: */
Ravikiran G Thirumalaic4f3b632007-10-16 23:26:09 -0700105 if (now <= (touch_timestamp + softlockup_thresh))
Ingo Molnar43581a12007-10-16 23:26:08 -0700106 return;
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700107
Ingo Molnar43581a12007-10-16 23:26:08 -0700108 per_cpu(print_timestamp, this_cpu) = touch_timestamp;
109
110 spin_lock(&print_lock);
Ravikiran G Thirumalaic4f3b632007-10-16 23:26:09 -0700111 printk(KERN_ERR "BUG: soft lockup - CPU#%d stuck for %lus! [%s:%d]\n",
112 this_cpu, now - touch_timestamp,
Pavel Emelyanovba25f9d2007-10-18 23:40:40 -0700113 current->comm, task_pid_nr(current));
Ingo Molnar43581a12007-10-16 23:26:08 -0700114 if (regs)
115 show_regs(regs);
116 else
Ingo Molnar6687a972006-03-24 03:18:41 -0800117 dump_stack();
Ingo Molnar43581a12007-10-16 23:26:08 -0700118 spin_unlock(&print_lock);
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700119}
120
121/*
Ingo Molnar82a1fcb2008-01-25 21:08:02 +0100122 * Have a reasonable limit on the number of tasks checked:
123 */
Ingo Molnar90739082008-01-25 21:08:34 +0100124unsigned long __read_mostly sysctl_hung_task_check_count = 1024;
Ingo Molnar82a1fcb2008-01-25 21:08:02 +0100125
126/*
127 * Zero means infinite timeout - no checking done:
128 */
Ingo Molnar90739082008-01-25 21:08:34 +0100129unsigned long __read_mostly sysctl_hung_task_timeout_secs = 120;
Ingo Molnar82a1fcb2008-01-25 21:08:02 +0100130
Ingo Molnar90739082008-01-25 21:08:34 +0100131unsigned long __read_mostly sysctl_hung_task_warnings = 10;
Ingo Molnar82a1fcb2008-01-25 21:08:02 +0100132
133/*
134 * Only do the hung-tasks check on one CPU:
135 */
136static int check_cpu __read_mostly = -1;
137
138static void check_hung_task(struct task_struct *t, unsigned long now)
139{
140 unsigned long switch_count = t->nvcsw + t->nivcsw;
141
142 if (t->flags & PF_FROZEN)
143 return;
144
145 if (switch_count != t->last_switch_count || !t->last_switch_timestamp) {
146 t->last_switch_count = switch_count;
147 t->last_switch_timestamp = now;
148 return;
149 }
150 if ((long)(now - t->last_switch_timestamp) <
151 sysctl_hung_task_timeout_secs)
152 return;
153 if (sysctl_hung_task_warnings < 0)
154 return;
155 sysctl_hung_task_warnings--;
156
157 /*
158 * Ok, the task did not get scheduled for more than 2 minutes,
159 * complain:
160 */
161 printk(KERN_ERR "INFO: task %s:%d blocked for more than "
162 "%ld seconds.\n", t->comm, t->pid,
163 sysctl_hung_task_timeout_secs);
164 printk(KERN_ERR "\"echo 0 > /proc/sys/kernel/hung_task_timeout_secs\""
165 " disables this message.\n");
166 sched_show_task(t);
167 __debug_show_held_locks(t);
168
169 t->last_switch_timestamp = now;
170 touch_nmi_watchdog();
171}
172
173/*
174 * Check whether a TASK_UNINTERRUPTIBLE does not get woken up for
175 * a really long time (120 seconds). If that happens, print out
176 * a warning.
177 */
178static void check_hung_uninterruptible_tasks(int this_cpu)
179{
180 int max_count = sysctl_hung_task_check_count;
181 unsigned long now = get_timestamp(this_cpu);
182 struct task_struct *g, *t;
183
184 /*
185 * If the system crashed already then all bets are off,
186 * do not report extra hung tasks:
187 */
188 if ((tainted & TAINT_DIE) || did_panic)
189 return;
190
191 read_lock(&tasklist_lock);
192 do_each_thread(g, t) {
193 if (!--max_count)
194 break;
195 if (t->state & TASK_UNINTERRUPTIBLE)
196 check_hung_task(t, now);
197 } while_each_thread(g, t);
198
199 read_unlock(&tasklist_lock);
200}
201
202/*
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700203 * The watchdog thread - runs every second and touches the timestamp.
204 */
Ingo Molnara5f2ce32007-10-16 23:26:08 -0700205static int watchdog(void *__bind_cpu)
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700206{
Oleg Nesterov02fb6142007-05-08 00:24:03 -0700207 struct sched_param param = { .sched_priority = MAX_RT_PRIO-1 };
Ingo Molnar82a1fcb2008-01-25 21:08:02 +0100208 int this_cpu = (long)__bind_cpu;
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700209
210 sched_setscheduler(current, SCHED_FIFO, &param);
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700211
Jeremy Fitzhardinge966812d2007-05-08 00:28:02 -0700212 /* initialize timestamp */
213 touch_softlockup_watchdog();
214
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700215 /*
Ingo Molnar6687a972006-03-24 03:18:41 -0800216 * Run briefly once per second to reset the softlockup timestamp.
Ingo Molnar82a1fcb2008-01-25 21:08:02 +0100217 * If this gets delayed for more than 60 seconds then the
Ingo Molnar6687a972006-03-24 03:18:41 -0800218 * debug-printout triggers in softlockup_tick().
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700219 */
220 while (!kthread_should_stop()) {
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700221 touch_softlockup_watchdog();
Ingo Molnar82a1fcb2008-01-25 21:08:02 +0100222 msleep_interruptible(10000);
223
224 if (this_cpu != check_cpu)
225 continue;
226
227 if (sysctl_hung_task_timeout_secs)
228 check_hung_uninterruptible_tasks(this_cpu);
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700229 }
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700230
231 return 0;
232}
233
234/*
235 * Create/destroy watchdog threads as CPUs come and go:
236 */
Chandra Seetharaman8c78f302006-07-30 03:03:35 -0700237static int __cpuinit
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700238cpu_callback(struct notifier_block *nfb, unsigned long action, void *hcpu)
239{
240 int hotcpu = (unsigned long)hcpu;
241 struct task_struct *p;
242
243 switch (action) {
244 case CPU_UP_PREPARE:
Rafael J. Wysocki8bb78442007-05-09 02:35:10 -0700245 case CPU_UP_PREPARE_FROZEN:
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700246 BUG_ON(per_cpu(watchdog_task, hotcpu));
247 p = kthread_create(watchdog, hcpu, "watchdog/%d", hotcpu);
248 if (IS_ERR(p)) {
Ingo Molnara5f2ce32007-10-16 23:26:08 -0700249 printk(KERN_ERR "watchdog for %i failed\n", hotcpu);
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700250 return NOTIFY_BAD;
251 }
Ingo Molnara5f2ce32007-10-16 23:26:08 -0700252 per_cpu(touch_timestamp, hotcpu) = 0;
253 per_cpu(watchdog_task, hotcpu) = p;
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700254 kthread_bind(p, hotcpu);
Ingo Molnara5f2ce32007-10-16 23:26:08 -0700255 break;
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700256 case CPU_ONLINE:
Rafael J. Wysocki8bb78442007-05-09 02:35:10 -0700257 case CPU_ONLINE_FROZEN:
Ingo Molnar82a1fcb2008-01-25 21:08:02 +0100258 check_cpu = any_online_cpu(cpu_online_map);
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700259 wake_up_process(per_cpu(watchdog_task, hotcpu));
260 break;
261#ifdef CONFIG_HOTPLUG_CPU
262 case CPU_UP_CANCELED:
Rafael J. Wysocki8bb78442007-05-09 02:35:10 -0700263 case CPU_UP_CANCELED_FROZEN:
Heiko Carstensfc75cdf2006-06-25 05:49:10 -0700264 if (!per_cpu(watchdog_task, hotcpu))
265 break;
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700266 /* Unbind so it can run. Fall thru. */
Heiko Carstensa4c4af72005-11-07 00:58:38 -0800267 kthread_bind(per_cpu(watchdog_task, hotcpu),
268 any_online_cpu(cpu_online_map));
Ingo Molnar82a1fcb2008-01-25 21:08:02 +0100269 case CPU_DOWN_PREPARE:
270 case CPU_DOWN_PREPARE_FROZEN:
271 if (hotcpu == check_cpu) {
272 cpumask_t temp_cpu_online_map = cpu_online_map;
273
274 cpu_clear(hotcpu, temp_cpu_online_map);
275 check_cpu = any_online_cpu(temp_cpu_online_map);
276 }
277 break;
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700278 case CPU_DEAD:
Rafael J. Wysocki8bb78442007-05-09 02:35:10 -0700279 case CPU_DEAD_FROZEN:
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700280 p = per_cpu(watchdog_task, hotcpu);
281 per_cpu(watchdog_task, hotcpu) = NULL;
282 kthread_stop(p);
283 break;
284#endif /* CONFIG_HOTPLUG_CPU */
Ingo Molnara5f2ce32007-10-16 23:26:08 -0700285 }
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700286 return NOTIFY_OK;
287}
288
Chandra Seetharaman8c78f302006-07-30 03:03:35 -0700289static struct notifier_block __cpuinitdata cpu_nfb = {
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700290 .notifier_call = cpu_callback
291};
292
293__init void spawn_softlockup_task(void)
294{
295 void *cpu = (void *)(long)smp_processor_id();
Akinobu Mita07dccf32006-09-29 02:00:22 -0700296 int err = cpu_callback(&cpu_nfb, CPU_UP_PREPARE, cpu);
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700297
Akinobu Mita07dccf32006-09-29 02:00:22 -0700298 BUG_ON(err == NOTIFY_BAD);
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700299 cpu_callback(&cpu_nfb, CPU_ONLINE, cpu);
300 register_cpu_notifier(&cpu_nfb);
301
Alan Sterne041c682006-03-27 01:16:30 -0800302 atomic_notifier_chain_register(&panic_notifier_list, &panic_block);
Ingo Molnar8446f1d2005-09-06 15:16:27 -0700303}