Thursday, 2020-06-18

kata-irc-bot<xwlpt> @eric.ernst @chen.bo Thanks for reply.  You  can  run `perf top` inside the VM (Need to  install this manually)  or `perf top -p <qemu process>`   to do the cpu profiling.  @eric.ernst What’s LiuBin’s observability PR? Can you share me the link? thanks00:10
kata-irc-bot<chen.bo> @xwlpt https://github.com/kata-containers/kata-containers/pull/29700:11
kata-irc-bot<eric.ernst> shameless plug to overview @xwlpt :)00:12
kata-irc-bot<eric.ernst> https://medium.com/kata-containers/kata-updates-from-the-project-teams-gathering-72cc565e567500:12
kata-irc-bot<chen.bo> @xwlpt Also, can you share the general command of qemu where you did the perf? I think the CLI to qemu will also make quite some differences for the profiling result.00:13
kata-irc-bot<xwlpt> perf top -g  -p  <qemu pid>00:13
kata-irc-bot<xwlpt> Really good feature. Thanks00:15
kata-irc-bot<chen.bo> What about the qemu command? Will that make difference?00:16
kata-irc-bot<chen.bo> I am assuming you are profiling qemu, which I thought how you ran qemu will make a (big) difference on it profiling result.00:17
kata-irc-bot<eric.ernst> I’m assuming he’s getting pid after doing docker run --runtime=kata-qemu ??00:18
kata-irc-bot<chen.bo> Oh, ok. @xwlpt is profiling the qemu launched by kata-runtime?00:18
kata-irc-bot<xwlpt> Yes.00:19
kata-irc-bot<eric.ernst> I assume so, but let’s see what @xwlpt can clarify00:19
kata-irc-bot<xwlpt> Just  the qemu process.00:19
kata-irc-bot<chen.bo> ok. That makes sense. Let me try it. Newbie to perf. I will keep you posted. :slightly_smiling_face:00:22
kata-irc-bot<xwlpt> @eric.ernst Really good informations. Thanks for this. Will follow this and check the update periodically.01:33
*** bpradipt has joined #kata-general03:17
*** sameo_ has joined #kata-general04:11
*** bpradipt has quit IRC04:21
*** bpradipt has joined #kata-general04:27
*** bpradipt has quit IRC04:46
*** bpradipt has joined #kata-general05:04
*** sameo_ has quit IRC06:18
*** sgarzare has joined #kata-general06:38
*** hashar has joined #kata-general06:54
*** sameo_ has joined #kata-general08:03
*** bpradipt has quit IRC08:33
*** bpradipt has joined #kata-general08:49
*** sgarzare has quit IRC08:53
*** sameo_ has quit IRC10:05
*** bpradipt has quit IRC10:10
*** bpradipt has joined #kata-general10:11
*** sameo has joined #kata-general10:11
*** sgarzare has joined #kata-general10:22
*** sgarzare has quit IRC10:42
*** bpradipt_ has joined #kata-general12:31
*** devimc has joined #kata-general12:32
*** bpradipt has quit IRC12:33
*** bpradipt_ has quit IRC12:42
*** sgarzare has joined #kata-general12:45
*** bpradipt has joined #kata-general12:50
*** devimc has quit IRC13:37
*** devimc has joined #kata-general13:38
kata-irc-bot<xwlpt> This is due to the guest vm’s cpu idle and then call hlt to make cpu idle. The qemu try to do the halt poll.13:54
*** hashar has quit IRC14:21
*** th0din_ has quit IRC14:50
*** th0din has joined #kata-general14:58
*** hashar has joined #kata-general15:42
*** bpradipt has quit IRC15:53
*** bpradipt has joined #kata-general16:04
*** sgarzare has quit IRC16:26
*** hashar has quit IRC17:05
kata-irc-bot<chen.bo> @xwlpt Run latestest kata w/ qemu on a very trivial workload `docker run --runtime=kata-qemu -itd alpine` , from where run perf on qemu launched by kata. So far got this:17:29
kata-irc-bot<chen.bo> ```Samples: 5K of event 'cycles', 4000 Hz, Event count (approx.): 10135068 lost: 0/0 drop: 0/0   Children      Self  Shared Object       Symbol +   73.85%     0.02%  [kernel]            [k] do_syscall_64                                            \u2592 +   62.73%     2.41%  [unknown]           [k] 0000000000000000                                         \u25c6     40.90%     2.38%  [kernel]            [k] kvm_arch_vcpu_ioctl_run17:29
kata-irc-bot                             \u2592 +   21.49%     1.75%  [kernel]            [k] do_sys_poll                                              \u2592     17.60%     0.01%  [kernel]            [k] kvm_vcpu_block                                           \u2592 +   16.40%     1.70%  [kernel]            [k] vcpu_enter_guest                                         \u2592     12.15%     0.01%  [kernel]            [k] schedule17:29
kata-irc-bot                     \u2592     11.82%     0.45%  [kernel]            [k] __schedule                                               \u2592 +    7.54%     0.98%  [kernel]            [k] eventfd_poll                                             \u2592      7.33%     0.00%  [kernel]            [k] entry_SYSCALL_64_after_hwframe                           \u2592      6.82%     0.07%  [kernel]            [k] vmx_handle_exit17:29
kata-irc-bot             \u2592      6.73%     1.32%  [kernel]            [k] try_to_wake_up                                           \u2592 +    6.56%     2.17%  [kernel]            [k] __pollwait                                               \u2592      5.96%     0.00%  [kernel]            [k] kvm_arch_vcpu_runnable                                   \u2592      5.17%     0.00%  [unknown]           [k] 0x007570632d34365f17:29
kata-irc-bot     \u2592      5.17%     0.00%  [unknown]           [k] 0x0000000000000238                                       \u2592      5.17%     0.00%  qemu-system-x86_64  [.] 0x000000000088fb80                                       \u2592      5.17%     0.00%  qemu-system-x86_64  [.] 0x0000000000968c0b                                       \u2592      5.17%     0.00%  [kernel]            [k] __x64_sys_ioctl17:29
kata-irc-bot\u2592      5.17%     0.00%  [kernel]            [k] ksys_ioctl                                               \u2592      5.17%     0.00%  [kernel]            [k] do_vfs_ioctl                                             \u2592      5.17%     0.00%  [kernel]            [k] kvm_vcpu_ioctl                                           \u2592      5.03%     0.71%  [kernel]            [k] vmx_vcpu_run                                             \u259217:29
kata-irc-bot+    4.72%     4.71%  [kernel]            [k] __fget                                                   \u2592      4.72%     0.00%  [kernel]            [k] __fget_light                                             \u2592      4.34%     0.00%  [kernel]            [k] __wake_up_common                                         \u2592      4.30%     4.30%  [kernel]            [k] entry_SYSCALL_64                                         \u259217:29
kata-irc-bot4.22%     0.43%  [kernel]            [k] kvm_apic_has_interrupt                                   \u2592      3.67%     0.00%  [kernel]            [k] kvm_sched_in                                             \u2592      3.58%     0.16%  [kernel]            [k] poll_schedule_timeout.constprop.11                       \u2592      3.40%     1.07%  [kernel]            [k] ____sys_sendmsg                                          \u2592 +    3.39%17:29
kata-irc-bot  1.64%  [kernel]            [k] vmx_vcpu_run.part.94                                     \u2592      3.26%     0.30%  [kernel]            [k] kvm_arch_vcpu_load                                       \u2592      3.09%     0.00%  [kernel]            [k] handle_ept_misconfig                                     \u2592      3.08%     1.04%  [kernel]            [k] ioeventfd_write                   ```17:29
kata-irc-bot<chen.bo> read_tsc seems to be fine for me. I am running on baremetal with my dev machine, bionic distro, kernel 5.3.0-59-generic.17:30
*** hashar has joined #kata-general18:09
*** Rene__ has quit IRC18:47
*** Rene__ has joined #kata-general19:08
*** bpradipt has quit IRC19:24
*** hashar has quit IRC20:47
*** devimc has quit IRC21:07
*** sameo has quit IRC22:57
*** sameo has joined #kata-general22:58
*** sameo has quit IRC23:09
*** sameo has joined #kata-general23:26
*** sameo has quit IRC23:55

Generated by irclog2html.py 2.17.2 by Marius Gedminas - find it at https://mg.pov.lt/irclog2html/!