debug.c 14 KB

123456789101112131415161718192021222324252627282930313233343536373839404142434445464748495051525354555657585960616263646566676869707172737475767778798081828384858687888990919293949596979899100101102103104105106107108109110111112113114115116117118119120121122123124125126127128129130131132133134135136137138139140141142143144145146147148149150151152153154155156157158159160161162163164165166167168169170171172173174175176177178179180181182183184185186187188189190191192193194195196197198199200201202203204205206207208209210211212213214215216217218219220221222223224225226227228229230231232233234235236237238239240241242243244245246247248249250251252253254255256257258259260261262263264265266267268269270271272273274275276277278279280281282283284285286287288289290291292293294295296297298299300301302303304305306307308309310311312313314315316317318319320321322323324325326327328329330331332333334335336337338339340341342343344345346347348349350351352353354355356357358359360361362363364365366367368369370371372373374375376377378379380381382383384385386387388389390391392393394395396397398399400401402403404405406407408409410411412413414415416417418419420421422423424425426427428429430431432433434435436437438439440441442443444445446447448449450451452453454455456457458459460461462463464465466467468469470471472473474475476477478479480481482483484485486487488489490491492493494495496497498499500501502503504505506507508509510511512513514515516517518519520521522523524525526527528529530531532533534535536537538539540541542543544545546547548549550551552553554555556557558559560561562563564565566567568569570571572573574575576577578579580581582583584585586587588589590591592593594595596597598599
  1. /*
  2. * kernel/sched/debug.c
  3. *
  4. * Print the CFS rbtree
  5. *
  6. * Copyright(C) 2007, Red Hat, Inc., Ingo Molnar
  7. *
  8. * This program is free software; you can redistribute it and/or modify
  9. * it under the terms of the GNU General Public License version 2 as
  10. * published by the Free Software Foundation.
  11. */
  12. #include <linux/proc_fs.h>
  13. #include <linux/sched.h>
  14. #include <linux/seq_file.h>
  15. #include <linux/kallsyms.h>
  16. #include <linux/utsname.h>
  17. #include "sched.h"
  18. static DEFINE_SPINLOCK(sched_debug_lock);
  19. /*
  20. * This allows printing both to /proc/sched_debug and
  21. * to the console
  22. */
  23. #define SEQ_printf(m, x...) \
  24. do { \
  25. if (m) \
  26. seq_printf(m, x); \
  27. else \
  28. printk(x); \
  29. } while (0)
  30. /*
  31. * Ease the printing of nsec fields:
  32. */
  33. static long long nsec_high(unsigned long long nsec)
  34. {
  35. if ((long long)nsec < 0) {
  36. nsec = -nsec;
  37. do_div(nsec, 1000000);
  38. return -nsec;
  39. }
  40. do_div(nsec, 1000000);
  41. return nsec;
  42. }
  43. static unsigned long nsec_low(unsigned long long nsec)
  44. {
  45. if ((long long)nsec < 0)
  46. nsec = -nsec;
  47. return do_div(nsec, 1000000);
  48. }
  49. #define SPLIT_NS(x) nsec_high(x), nsec_low(x)
  50. #ifdef CONFIG_FAIR_GROUP_SCHED
  51. static void print_cfs_group_stats(struct seq_file *m, int cpu, struct task_group *tg)
  52. {
  53. struct sched_entity *se = tg->se[cpu];
  54. #define P(F) \
  55. SEQ_printf(m, " .%-30s: %lld\n", #F, (long long)F)
  56. #define PN(F) \
  57. SEQ_printf(m, " .%-30s: %lld.%06ld\n", #F, SPLIT_NS((long long)F))
  58. if (!se) {
  59. struct sched_avg *avg = &cpu_rq(cpu)->avg;
  60. P(avg->runnable_avg_sum);
  61. P(avg->runnable_avg_period);
  62. return;
  63. }
  64. PN(se->exec_start);
  65. PN(se->vruntime);
  66. PN(se->sum_exec_runtime);
  67. #ifdef CONFIG_SCHEDSTATS
  68. PN(se->statistics.wait_start);
  69. PN(se->statistics.sleep_start);
  70. PN(se->statistics.block_start);
  71. PN(se->statistics.sleep_max);
  72. PN(se->statistics.block_max);
  73. PN(se->statistics.exec_max);
  74. PN(se->statistics.slice_max);
  75. PN(se->statistics.wait_max);
  76. PN(se->statistics.wait_sum);
  77. P(se->statistics.wait_count);
  78. #endif
  79. P(se->load.weight);
  80. #ifdef CONFIG_SMP
  81. P(se->avg.runnable_avg_sum);
  82. P(se->avg.runnable_avg_period);
  83. P(se->avg.load_avg_contrib);
  84. P(se->avg.decay_count);
  85. #endif
  86. #undef PN
  87. #undef P
  88. }
  89. #endif
  90. #ifdef CONFIG_CGROUP_SCHED
  91. static char group_path[PATH_MAX];
  92. static char *task_group_path(struct task_group *tg)
  93. {
  94. if (autogroup_path(tg, group_path, PATH_MAX))
  95. return group_path;
  96. /*
  97. * May be NULL if the underlying cgroup isn't fully-created yet
  98. */
  99. if (!tg->css.cgroup) {
  100. group_path[0] = '\0';
  101. return group_path;
  102. }
  103. cgroup_path(tg->css.cgroup, group_path, PATH_MAX);
  104. return group_path;
  105. }
  106. #endif
  107. static void
  108. print_task(struct seq_file *m, struct rq *rq, struct task_struct *p)
  109. {
  110. if (rq->curr == p)
  111. SEQ_printf(m, "R");
  112. else
  113. SEQ_printf(m, " ");
  114. SEQ_printf(m, "%15s %5d %9Ld.%06ld %9Ld %5d ",
  115. p->comm, p->pid,
  116. SPLIT_NS(p->se.vruntime),
  117. (long long)(p->nvcsw + p->nivcsw),
  118. p->prio);
  119. #ifdef CONFIG_SCHEDSTATS
  120. SEQ_printf(m, "%9Ld.%06ld %9Ld.%06ld %9Ld.%06ld",
  121. SPLIT_NS(p->se.vruntime),
  122. SPLIT_NS(p->se.sum_exec_runtime),
  123. SPLIT_NS(p->se.statistics.sum_sleep_runtime));
  124. #else
  125. SEQ_printf(m, "%15Ld %15Ld %15Ld.%06ld %15Ld.%06ld %15Ld.%06ld",
  126. 0LL, 0LL, 0LL, 0L, 0LL, 0L, 0LL, 0L);
  127. #endif
  128. #ifdef CONFIG_CGROUP_SCHED
  129. SEQ_printf(m, " %s", task_group_path(task_group(p)));
  130. #endif
  131. SEQ_printf(m, "\n");
  132. }
  133. static void print_rq(struct seq_file *m, struct rq *rq, int rq_cpu)
  134. {
  135. struct task_struct *g, *p;
  136. unsigned long flags;
  137. SEQ_printf(m,
  138. "\nrunnable tasks:\n"
  139. " task PID tree-key switches prio"
  140. " exec-runtime sum-exec sum-sleep\n"
  141. "------------------------------------------------------"
  142. "----------------------------------------------------\n");
  143. read_lock_irqsave(&tasklist_lock, flags);
  144. do_each_thread(g, p) {
  145. if (!p->on_rq || task_cpu(p) != rq_cpu)
  146. continue;
  147. print_task(m, rq, p);
  148. } while_each_thread(g, p);
  149. read_unlock_irqrestore(&tasklist_lock, flags);
  150. }
  151. void print_cfs_rq(struct seq_file *m, int cpu, struct cfs_rq *cfs_rq)
  152. {
  153. s64 MIN_vruntime = -1, min_vruntime, max_vruntime = -1,
  154. spread, rq0_min_vruntime, spread0;
  155. struct rq *rq = cpu_rq(cpu);
  156. struct sched_entity *last;
  157. unsigned long flags;
  158. #ifdef CONFIG_FAIR_GROUP_SCHED
  159. SEQ_printf(m, "\ncfs_rq[%d]:%s\n", cpu, task_group_path(cfs_rq->tg));
  160. #else
  161. SEQ_printf(m, "\ncfs_rq[%d]:\n", cpu);
  162. #endif
  163. SEQ_printf(m, " .%-30s: %Ld.%06ld\n", "exec_clock",
  164. SPLIT_NS(cfs_rq->exec_clock));
  165. raw_spin_lock_irqsave(&rq->lock, flags);
  166. if (cfs_rq->rb_leftmost)
  167. MIN_vruntime = (__pick_first_entity(cfs_rq))->vruntime;
  168. last = __pick_last_entity(cfs_rq);
  169. if (last)
  170. max_vruntime = last->vruntime;
  171. min_vruntime = cfs_rq->min_vruntime;
  172. rq0_min_vruntime = cpu_rq(0)->cfs.min_vruntime;
  173. raw_spin_unlock_irqrestore(&rq->lock, flags);
  174. SEQ_printf(m, " .%-30s: %Ld.%06ld\n", "MIN_vruntime",
  175. SPLIT_NS(MIN_vruntime));
  176. SEQ_printf(m, " .%-30s: %Ld.%06ld\n", "min_vruntime",
  177. SPLIT_NS(min_vruntime));
  178. SEQ_printf(m, " .%-30s: %Ld.%06ld\n", "max_vruntime",
  179. SPLIT_NS(max_vruntime));
  180. spread = max_vruntime - MIN_vruntime;
  181. SEQ_printf(m, " .%-30s: %Ld.%06ld\n", "spread",
  182. SPLIT_NS(spread));
  183. spread0 = min_vruntime - rq0_min_vruntime;
  184. SEQ_printf(m, " .%-30s: %Ld.%06ld\n", "spread0",
  185. SPLIT_NS(spread0));
  186. SEQ_printf(m, " .%-30s: %d\n", "nr_spread_over",
  187. cfs_rq->nr_spread_over);
  188. SEQ_printf(m, " .%-30s: %d\n", "nr_running", cfs_rq->nr_running);
  189. SEQ_printf(m, " .%-30s: %ld\n", "load", cfs_rq->load.weight);
  190. #ifdef CONFIG_FAIR_GROUP_SCHED
  191. #ifdef CONFIG_SMP
  192. SEQ_printf(m, " .%-30s: %lld\n", "runnable_load_avg",
  193. cfs_rq->runnable_load_avg);
  194. SEQ_printf(m, " .%-30s: %lld\n", "blocked_load_avg",
  195. cfs_rq->blocked_load_avg);
  196. SEQ_printf(m, " .%-30s: %lld\n", "tg_load_avg",
  197. (unsigned long long)atomic64_read(&cfs_rq->tg->load_avg));
  198. SEQ_printf(m, " .%-30s: %lld\n", "tg_load_contrib",
  199. cfs_rq->tg_load_contrib);
  200. SEQ_printf(m, " .%-30s: %d\n", "tg_runnable_contrib",
  201. cfs_rq->tg_runnable_contrib);
  202. SEQ_printf(m, " .%-30s: %d\n", "tg->runnable_avg",
  203. atomic_read(&cfs_rq->tg->runnable_avg));
  204. #endif
  205. print_cfs_group_stats(m, cpu, cfs_rq->tg);
  206. #endif
  207. }
  208. void print_rt_rq(struct seq_file *m, int cpu, struct rt_rq *rt_rq)
  209. {
  210. #ifdef CONFIG_RT_GROUP_SCHED
  211. SEQ_printf(m, "\nrt_rq[%d]:%s\n", cpu, task_group_path(rt_rq->tg));
  212. #else
  213. SEQ_printf(m, "\nrt_rq[%d]:\n", cpu);
  214. #endif
  215. #define P(x) \
  216. SEQ_printf(m, " .%-30s: %Ld\n", #x, (long long)(rt_rq->x))
  217. #define PN(x) \
  218. SEQ_printf(m, " .%-30s: %Ld.%06ld\n", #x, SPLIT_NS(rt_rq->x))
  219. P(rt_nr_running);
  220. P(rt_throttled);
  221. PN(rt_time);
  222. PN(rt_runtime);
  223. #undef PN
  224. #undef P
  225. }
  226. extern __read_mostly int sched_clock_running;
  227. static void print_cpu(struct seq_file *m, int cpu)
  228. {
  229. struct rq *rq = cpu_rq(cpu);
  230. unsigned long flags;
  231. #ifdef CONFIG_X86
  232. {
  233. unsigned int freq = cpu_khz ? : 1;
  234. SEQ_printf(m, "cpu#%d, %u.%03u MHz\n",
  235. cpu, freq / 1000, (freq % 1000));
  236. }
  237. #else
  238. SEQ_printf(m, "cpu#%d\n", cpu);
  239. #endif
  240. #define P(x) \
  241. do { \
  242. if (sizeof(rq->x) == 4) \
  243. SEQ_printf(m, " .%-30s: %ld\n", #x, (long)(rq->x)); \
  244. else \
  245. SEQ_printf(m, " .%-30s: %Ld\n", #x, (long long)(rq->x));\
  246. } while (0)
  247. #define PN(x) \
  248. SEQ_printf(m, " .%-30s: %Ld.%06ld\n", #x, SPLIT_NS(rq->x))
  249. P(nr_running);
  250. SEQ_printf(m, " .%-30s: %lu\n", "load",
  251. rq->load.weight);
  252. P(nr_switches);
  253. P(nr_load_updates);
  254. P(nr_uninterruptible);
  255. PN(next_balance);
  256. P(curr->pid);
  257. PN(clock);
  258. P(cpu_load[0]);
  259. P(cpu_load[1]);
  260. P(cpu_load[2]);
  261. P(cpu_load[3]);
  262. P(cpu_load[4]);
  263. #undef P
  264. #undef PN
  265. #ifdef CONFIG_SCHEDSTATS
  266. #define P(n) SEQ_printf(m, " .%-30s: %d\n", #n, rq->n);
  267. #define P64(n) SEQ_printf(m, " .%-30s: %Ld\n", #n, rq->n);
  268. P(yld_count);
  269. P(sched_count);
  270. P(sched_goidle);
  271. #ifdef CONFIG_SMP
  272. P64(avg_idle);
  273. #endif
  274. P(ttwu_count);
  275. P(ttwu_local);
  276. #undef P
  277. #undef P64
  278. #endif
  279. spin_lock_irqsave(&sched_debug_lock, flags);
  280. print_cfs_stats(m, cpu);
  281. print_rt_stats(m, cpu);
  282. rcu_read_lock();
  283. print_rq(m, rq, cpu);
  284. rcu_read_unlock();
  285. spin_unlock_irqrestore(&sched_debug_lock, flags);
  286. SEQ_printf(m, "\n");
  287. }
  288. static const char *sched_tunable_scaling_names[] = {
  289. "none",
  290. "logaritmic",
  291. "linear"
  292. };
  293. static void sched_debug_header(struct seq_file *m)
  294. {
  295. u64 ktime, sched_clk, cpu_clk;
  296. unsigned long flags;
  297. local_irq_save(flags);
  298. ktime = ktime_to_ns(ktime_get());
  299. sched_clk = sched_clock();
  300. cpu_clk = local_clock();
  301. local_irq_restore(flags);
  302. SEQ_printf(m, "Sched Debug Version: v0.10, %s %.*s\n",
  303. init_utsname()->release,
  304. (int)strcspn(init_utsname()->version, " "),
  305. init_utsname()->version);
  306. #define P(x) \
  307. SEQ_printf(m, "%-40s: %Ld\n", #x, (long long)(x))
  308. #define PN(x) \
  309. SEQ_printf(m, "%-40s: %Ld.%06ld\n", #x, SPLIT_NS(x))
  310. PN(ktime);
  311. PN(sched_clk);
  312. PN(cpu_clk);
  313. P(jiffies);
  314. #ifdef CONFIG_HAVE_UNSTABLE_SCHED_CLOCK
  315. P(sched_clock_stable);
  316. #endif
  317. #undef PN
  318. #undef P
  319. SEQ_printf(m, "\n");
  320. SEQ_printf(m, "sysctl_sched\n");
  321. #define P(x) \
  322. SEQ_printf(m, " .%-40s: %Ld\n", #x, (long long)(x))
  323. #define PN(x) \
  324. SEQ_printf(m, " .%-40s: %Ld.%06ld\n", #x, SPLIT_NS(x))
  325. PN(sysctl_sched_latency);
  326. PN(sysctl_sched_min_granularity);
  327. PN(sysctl_sched_wakeup_granularity);
  328. P(sysctl_sched_child_runs_first);
  329. P(sysctl_sched_features);
  330. #undef PN
  331. #undef P
  332. SEQ_printf(m, " .%-40s: %d (%s)\n",
  333. "sysctl_sched_tunable_scaling",
  334. sysctl_sched_tunable_scaling,
  335. sched_tunable_scaling_names[sysctl_sched_tunable_scaling]);
  336. SEQ_printf(m, "\n");
  337. }
  338. static int sched_debug_show(struct seq_file *m, void *v)
  339. {
  340. int cpu = (unsigned long)(v - 2);
  341. if (cpu != -1)
  342. print_cpu(m, cpu);
  343. else
  344. sched_debug_header(m);
  345. return 0;
  346. }
  347. void sysrq_sched_debug_show(void)
  348. {
  349. int cpu;
  350. sched_debug_header(NULL);
  351. for_each_online_cpu(cpu)
  352. print_cpu(NULL, cpu);
  353. }
  354. /*
  355. * This itererator needs some explanation.
  356. * It returns 1 for the header position.
  357. * This means 2 is cpu 0.
  358. * In a hotplugged system some cpus, including cpu 0, may be missing so we have
  359. * to use cpumask_* to iterate over the cpus.
  360. */
  361. static void *sched_debug_start(struct seq_file *file, loff_t *offset)
  362. {
  363. unsigned long n = *offset;
  364. if (n == 0)
  365. return (void *) 1;
  366. n--;
  367. if (n > 0)
  368. n = cpumask_next(n - 1, cpu_online_mask);
  369. else
  370. n = cpumask_first(cpu_online_mask);
  371. *offset = n + 1;
  372. if (n < nr_cpu_ids)
  373. return (void *)(unsigned long)(n + 2);
  374. return NULL;
  375. }
  376. static void *sched_debug_next(struct seq_file *file, void *data, loff_t *offset)
  377. {
  378. (*offset)++;
  379. return sched_debug_start(file, offset);
  380. }
  381. static void sched_debug_stop(struct seq_file *file, void *data)
  382. {
  383. }
  384. static const struct seq_operations sched_debug_sops = {
  385. .start = sched_debug_start,
  386. .next = sched_debug_next,
  387. .stop = sched_debug_stop,
  388. .show = sched_debug_show,
  389. };
  390. static int sched_debug_release(struct inode *inode, struct file *file)
  391. {
  392. seq_release(inode, file);
  393. return 0;
  394. }
  395. static int sched_debug_open(struct inode *inode, struct file *filp)
  396. {
  397. int ret = 0;
  398. ret = seq_open(filp, &sched_debug_sops);
  399. return ret;
  400. }
  401. static const struct file_operations sched_debug_fops = {
  402. .open = sched_debug_open,
  403. .read = seq_read,
  404. .llseek = seq_lseek,
  405. .release = sched_debug_release,
  406. };
  407. static int __init init_sched_debug_procfs(void)
  408. {
  409. struct proc_dir_entry *pe;
  410. pe = proc_create("sched_debug", 0444, NULL, &sched_debug_fops);
  411. if (!pe)
  412. return -ENOMEM;
  413. return 0;
  414. }
  415. __initcall(init_sched_debug_procfs);
  416. void proc_sched_show_task(struct task_struct *p, struct seq_file *m)
  417. {
  418. unsigned long nr_switches;
  419. SEQ_printf(m, "%s (%d, #threads: %d)\n", p->comm, p->pid,
  420. get_nr_threads(p));
  421. SEQ_printf(m,
  422. "---------------------------------------------------------\n");
  423. #define __P(F) \
  424. SEQ_printf(m, "%-35s:%21Ld\n", #F, (long long)F)
  425. #define P(F) \
  426. SEQ_printf(m, "%-35s:%21Ld\n", #F, (long long)p->F)
  427. #define __PN(F) \
  428. SEQ_printf(m, "%-35s:%14Ld.%06ld\n", #F, SPLIT_NS((long long)F))
  429. #define PN(F) \
  430. SEQ_printf(m, "%-35s:%14Ld.%06ld\n", #F, SPLIT_NS((long long)p->F))
  431. PN(se.exec_start);
  432. PN(se.vruntime);
  433. PN(se.sum_exec_runtime);
  434. nr_switches = p->nvcsw + p->nivcsw;
  435. #ifdef CONFIG_SCHEDSTATS
  436. PN(se.statistics.wait_start);
  437. PN(se.statistics.sleep_start);
  438. PN(se.statistics.block_start);
  439. PN(se.statistics.sleep_max);
  440. PN(se.statistics.block_max);
  441. PN(se.statistics.exec_max);
  442. PN(se.statistics.slice_max);
  443. PN(se.statistics.wait_max);
  444. PN(se.statistics.wait_sum);
  445. P(se.statistics.wait_count);
  446. PN(se.statistics.iowait_sum);
  447. P(se.statistics.iowait_count);
  448. P(se.nr_migrations);
  449. P(se.statistics.nr_migrations_cold);
  450. P(se.statistics.nr_failed_migrations_affine);
  451. P(se.statistics.nr_failed_migrations_running);
  452. P(se.statistics.nr_failed_migrations_hot);
  453. P(se.statistics.nr_forced_migrations);
  454. P(se.statistics.nr_wakeups);
  455. P(se.statistics.nr_wakeups_sync);
  456. P(se.statistics.nr_wakeups_migrate);
  457. P(se.statistics.nr_wakeups_local);
  458. P(se.statistics.nr_wakeups_remote);
  459. P(se.statistics.nr_wakeups_affine);
  460. P(se.statistics.nr_wakeups_affine_attempts);
  461. P(se.statistics.nr_wakeups_passive);
  462. P(se.statistics.nr_wakeups_idle);
  463. {
  464. u64 avg_atom, avg_per_cpu;
  465. avg_atom = p->se.sum_exec_runtime;
  466. if (nr_switches)
  467. do_div(avg_atom, nr_switches);
  468. else
  469. avg_atom = -1LL;
  470. avg_per_cpu = p->se.sum_exec_runtime;
  471. if (p->se.nr_migrations) {
  472. avg_per_cpu = div64_u64(avg_per_cpu,
  473. p->se.nr_migrations);
  474. } else {
  475. avg_per_cpu = -1LL;
  476. }
  477. __PN(avg_atom);
  478. __PN(avg_per_cpu);
  479. }
  480. #endif
  481. __P(nr_switches);
  482. SEQ_printf(m, "%-35s:%21Ld\n",
  483. "nr_voluntary_switches", (long long)p->nvcsw);
  484. SEQ_printf(m, "%-35s:%21Ld\n",
  485. "nr_involuntary_switches", (long long)p->nivcsw);
  486. P(se.load.weight);
  487. P(policy);
  488. P(prio);
  489. #undef PN
  490. #undef __PN
  491. #undef P
  492. #undef __P
  493. {
  494. unsigned int this_cpu = raw_smp_processor_id();
  495. u64 t0, t1;
  496. t0 = cpu_clock(this_cpu);
  497. t1 = cpu_clock(this_cpu);
  498. SEQ_printf(m, "%-35s:%21Ld\n",
  499. "clock-delta", (long long)(t1-t0));
  500. }
  501. }
  502. void proc_sched_set_task(struct task_struct *p)
  503. {
  504. #ifdef CONFIG_SCHEDSTATS
  505. memset(&p->se.statistics, 0, sizeof(p->se.statistics));
  506. #endif
  507. }