{"id":1770,"date":"2015-05-06T19:00:06","date_gmt":"2015-05-06T10:00:06","guid":{"rendered":"http:\/\/tech.akat.info\/?p=1770"},"modified":"2015-05-06T19:20:23","modified_gmt":"2015-05-06T10:20:23","slug":"perf-interrupt-took-too-long","status":"publish","type":"post","link":"https:\/\/tech.akat.info\/?p=1770","title":{"rendered":"perf interrupt took too long"},"content":{"rendered":"<p>debian8 \u306esyslog\u306b\u4ee5\u4e0b\u306e\u30e1\u30c3\u30bb\u30fc\u30b8\u304c\u8868\u793a\u3055\u308c\u3066\u3044\u305f<\/p>\n<pre class=\"brush: plain; title: ; notranslate\" title=\"\">\r\nMay  5 21:43:53 hostname kernel: &#x5B; 4604.591417] perf interrupt took too long (2510 &gt; 2500), lowering kernel.perf_event_max_sample_rate to 50000\r\n<\/pre>\n<h1>\u5185\u5bb9<\/h1>\n<p>\u554f\u984c\u306a\u3044\u30e1\u30c3\u30bb\u30fc\u30b8\u3089\u3057\u3044<br \/>\n\u30ab\u30fc\u30cd\u30eb\u306bperf tool\u304c\u57cb\u3081\u8fbc\u307e\u308c\u3066\u3044\u3066\u3001\u30ab\u30fc\u30cd\u30eb\u306f\u81ea\u52d5\u7684\u306b\u30b7\u30b9\u30c6\u30e0\u3078\u306e\u5f71\u97ff\u3092\u4e0e\u3048\u306a\u3044\u7a0b\u5ea6\u306e\u30b5\u30f3\u30d7\u30eb\u30ec\u30fc\u30c8\u3092\u6c7a\u3081\u308b<br \/>\n\u305d\u306e\u30ec\u30fc\u30c8\u3092\u8d85\u3048\u305f\u308a\u3059\u308b\u3068\u51fa\u529b\u3055\u308c\u308b\u6a21\u69d8<br \/>\n\u305d\u3057\u3066\u3001perf\u304c\u30a4\u30f3\u30b9\u30c8\u30fc\u30eb\u3055\u308c\u3066\u3044\u306a\u304b\u3063\u305f\u308a\u3001\u6709\u52b9\u306b\u306a\u3063\u3066\u3044\u306a\u304f\u3066\u3082\u30ed\u30b0\u3068\u3057\u3066\u51fa\u529b\u3059\u308b\u3089\u3057\u3044<br \/>\nperf\u306f\u304a\u305d\u3089\u304fperformance analysis tool(\u6027\u80fd\u89e3\u6790\u30c4\u30fc\u30eb)\u306e\u3053\u3068<\/p>\n<h1>\u5bfe\u7b56<\/h1>\n<p>\u3068\u306f\u8a00\u3048\u305a\u3063\u3068syslog\u306b\u8a18\u9332\u3055\u308c\u308b\u306e\u306f\u5acc\u306a\u306e\u3067\u3001\u3001<br \/>\nperf_cpu_time_max_percent \u3092 25 \u2192 60 \u306b\u3057\u3066\u69d8\u5b50\u898b(0\u3060\u3068\u7121\u52b9\u306b\u3067\u304d\u308b\u306e\u3067\u30a8\u30e9\u30fc\u306f\u3067\u306a\u304f\u306a\u308b)<\/p>\n<pre class=\"brush: plain; title: ; notranslate\" title=\"\">\r\nroot@hostname:\/home\/shimizu# cat \/proc\/sys\/kernel\/perf_event_max_sample_rate\r\n6250\r\n### perf_cpu_time_max_percent ###\r\n### CPU\u6642\u9593\u3092perf\u306e\u30b5\u30f3\u30d7\u30eb\u53d6\u5f97\u306b\u3069\u308c\u304f\u3089\u3044\u4f7f\u3063\u3066\u3044\u3044\u306e\u304b\u6c7a\u3081\u308b\u30020\u3060\u3068\u7121\u5236\u9650 ###\r\nroot@hostname:\/home\/shimizu# cat \/proc\/sys\/kernel\/perf_cpu_time_max_percent\r\n25\r\n\r\n### \u4ee5\u4e0b\u3092\u8ffd\u52a0\u3059\u308b ###\r\nroot@hostname:\/home\/shimizu# vi \/etc\/sysctl.conf\r\n===\r\nkernel.perf_cpu_time_max_percent = 60\r\n===\r\nroot@hostname:\/home\/shimizu# vi \/etc\/sysctl.conf\r\nroot@hostname:\/home\/shimizu# sysctl -p\r\nkernel.perf_cpu_time_max_percent = 60\r\nroot@hostname:\/home\/shimizu# cat \/proc\/sys\/kernel\/perf_cpu_time_max_percent\r\n60\r\n<\/pre>\n<h1>perf\u3068\u306f<\/h1>\n<p>perf &#8211; performance analysis tools for Linux<br \/>\nLinux\u306b\u4ed8\u3044\u3066\u304f\u308b\u30d7\u30ed\u30d5\u30a1\u30a4\u30e9<\/p>\n<pre class=\"brush: plain; title: ; notranslate\" title=\"\">\r\n### \u30b3\u30de\u30f3\u30c9\u4e00\u89a7\u3092\u8868\u793a ###\r\nroot@hostname:\/home\/shimizu# perf help\r\n\r\n usage: perf &#x5B;--version] &#x5B;--help] COMMAND &#x5B;ARGS]\r\n\r\n The most commonly used perf commands are:\r\n   annotate        Read perf.data (created by perf record) and display annotated code\r\n   archive         Create archive with object files with build-ids found in perf.data file\r\n...\r\n\r\n### \u53d6\u5f97\u3067\u304d\u308b\u30a4\u30d9\u30f3\u30c8\u3092\u8868\u793a ###\r\nroot@hostname:\/home\/shimizu# perf list\r\n\r\nList of pre-defined events (to be used in -e):\r\n  cpu-cycles OR cycles                               &#x5B;Hardware event]\r\n  instructions                                       &#x5B;Hardware event]\r\n  cache-references                                   &#x5B;Hardware event]\r\n  cache-misses                                       &#x5B;Hardware event]\r\n...\r\n\r\n### \u30b3\u30de\u30f3\u30c9\u306e\u30d1\u30d5\u30a9\u30fc\u30de\u30f3\u30b9\u30c7\u30fc\u30bf\u3092\u53d6\u5f97\u3059\u308b ###\r\nroot@hostname:\/home\/shimizu# perf stat ls\r\ntest.txt  test2.txt\r\n\r\n Performance counter stats for 'ls':\r\n\r\n          1.946498      task-clock (msec)         #    0.615 CPUs utilized # \u5229\u7528\u3057\u305fCPU\u6642\u9593\r\n                 2      context-switches          #    0.001 M\/sec # \u30b3\u30f3\u30c6\u30ad\u30b9\u30c8\u30b9\u30a4\u30c3\u30c1\u6570\r\n                 0      cpu-migrations            #    0.000 K\/sec # \u5225\u306eCPU\u306b\u79fb\u52d5\u3057\u305f\u56de\u6570\r\n                96      page-faults               #    0.049 M\/sec # page-faults\u6570\r\n         2,408,669      cycles                    #    1.237 GHz # \u5229\u7528\u3057\u305fCPU\u306e\u30af\u30ed\u30c3\u30af\u6570\r\n         1,785,460      stalled-cycles-frontend   #   74.13% frontend cycles idle\r\n         1,053,546      stalled-cycles-backend    #   43.74% backend  cycles idle\r\n         1,248,885      instructions              #    0.52  insns per cycle\r\n                                                  #    1.43  stalled cycles per insn\r\n           248,726      branches                  #  127.781 M\/sec\r\n     &lt;not counted&gt;      branch-misses\r\n\r\n       0.003166378 seconds time elapsed\r\n\r\n\r\n### \u30b7\u30b9\u30c6\u30e0\u5168\u4f53\u306e\u60c5\u5831\u3092\u53d6\u5f97\u3057\u78ba\u8a8d\u53ef\u80fd ###\r\nroot@hostname:\/home\/shimizu# perf record -a\r\nLowering default frequency rate to 3250.\r\nPlease consider tweaking \/proc\/sys\/kernel\/perf_event_max_sample_rate.\r\n^C&#x5B; perf record: Woken up 1 times to write data ]\r\n&#x5B; perf record: Captured and wrote 0.071 MB perf.data (~3083 samples) ]\r\n\r\nroot@hostname:\/home\/shimizu# perf report\r\n\r\n### \u30a4\u30d9\u30f3\u30c8\u304c\u767a\u751f\u3057\u305f\u6570\u3092\u78ba\u8a8d\u53ef\u80fd ###\r\nroot@hostname:\/home\/shimizu# perf top\r\n<\/pre>\n<h1>\u53c2\u8003<\/h1>\n<p>Index\u00bb Kernel &#038; Hardware\u00bb [Solved] dmesg: perf interrupt took too long&#8230;<br \/>\nhttps:\/\/bbs.archlinux.org\/viewtopic.php?id=187636<br \/>\nperf\u306e\u4f7f\u3044\u65b9<br \/>\nhttp:\/\/int.main.jp\/txt\/perf\/<\/p>\n","protected":false},"excerpt":{"rendered":"<p>debian8 \u306esyslog\u306b\u4ee5\u4e0b\u306e\u30e1\u30c3\u30bb\u30fc\u30b8\u304c\u8868\u793a\u3055\u308c\u3066\u3044\u305f May 5 21:43:53 hostname kernel: &#x5B; 4604.591417] perf interrupt took too l [&hellip;]<\/p>\n","protected":false},"author":1,"featured_media":0,"comment_status":"open","ping_status":"closed","sticky":false,"template":"","format":"standard","meta":{"_monsterinsights_skip_tracking":false,"_monsterinsights_sitenote_active":false,"_monsterinsights_sitenote_note":"","_monsterinsights_sitenote_category":0,"footnotes":""},"categories":[68,23,26],"tags":[],"_links":{"self":[{"href":"https:\/\/tech.akat.info\/index.php?rest_route=\/wp\/v2\/posts\/1770"}],"collection":[{"href":"https:\/\/tech.akat.info\/index.php?rest_route=\/wp\/v2\/posts"}],"about":[{"href":"https:\/\/tech.akat.info\/index.php?rest_route=\/wp\/v2\/types\/post"}],"author":[{"embeddable":true,"href":"https:\/\/tech.akat.info\/index.php?rest_route=\/wp\/v2\/users\/1"}],"replies":[{"embeddable":true,"href":"https:\/\/tech.akat.info\/index.php?rest_route=%2Fwp%2Fv2%2Fcomments&post=1770"}],"version-history":[{"count":5,"href":"https:\/\/tech.akat.info\/index.php?rest_route=\/wp\/v2\/posts\/1770\/revisions"}],"predecessor-version":[{"id":1775,"href":"https:\/\/tech.akat.info\/index.php?rest_route=\/wp\/v2\/posts\/1770\/revisions\/1775"}],"wp:attachment":[{"href":"https:\/\/tech.akat.info\/index.php?rest_route=%2Fwp%2Fv2%2Fmedia&parent=1770"}],"wp:term":[{"taxonomy":"category","embeddable":true,"href":"https:\/\/tech.akat.info\/index.php?rest_route=%2Fwp%2Fv2%2Fcategories&post=1770"},{"taxonomy":"post_tag","embeddable":true,"href":"https:\/\/tech.akat.info\/index.php?rest_route=%2Fwp%2Fv2%2Ftags&post=1770"}],"curies":[{"name":"wp","href":"https:\/\/api.w.org\/{rel}","templated":true}]}}