6:57 a.m.
I found a presentation from IBM that shows how to do a bunch of
drilldown stuff:
http://linuxvm.org/present/SHARE103/S9303ob.pdf
It mentions a utility named strace that shows system calls. I ran one
for a couple seconds against the running virt-p2v-server - here are the
results:
[root@Fedora16-64P2V log]#
[root@Fedora16-64P2V log]# ps ax | grep p2v
23339 ? Ds 2:44 /usr/bin/perl /usr/bin/virt-p2v-server
24048 pts/0 S+ 0:00 grep --color=auto p2v
[root@Fedora16-64P2V log]#
[root@Fedora16-64P2V log]#
[root@Fedora16-64P2V log]# strace -p 23339 -tt -T -o greg.txt
Process 23339 attached - interrupt to quit
^CProcess 23339 detached
[root@Fedora16-64P2V log]# more greg.txt
06:53:04.258833 write(5, "R\0e\0q\0u\0e\0s\0t\0:\0 \0N\0u\0l\0l\0;\0 \0
\0"..., 8192) = 8
192 <0.000107>
06:53:04.259246 write(5, "I\0n\0s\0t\0a\0l\0l\0e\0d\0:\0
\0N\0u\0l\0l\0;\0"..., 8192) = 8
192 <0.000100>
06:53:04.259503 write(5,
"e\0.\0x\0m\0l\0_\0004\0005\0003\0009\0004\0_\0e\0n\0u\0_\0"...,
8192) = 8192 <0.000126>
06:53:04.259821 write(5, "m\0p\0o\0n\0e\0n\0t\0:\0
\0_\0_\0V\0W\0D\0_\0W\0"..., 8192) = 8
192 <0.227877>
06:53:04.487853 write(5, "n\0t\0:\0
\0_\0_\0_\0S\0Q\0L\0D\0B\0G\0_\0004\0008\0"..., 8192)
= 8192 <0.000105>
06:53:04.488107 write(5,
"P\0L\0A\0T\0E\0S\0_\0x\0008\0006\0_\0e\0n\0u\0.\0003\0"..., 819
2) = 8192 <0.000101>
06:53:04.488357 write(5,
"_\0A\0005\0003\0006\0_\0000\0000\0009\0000\0002\0007\0008\0A\00
01\0B\0"..., 8192) = 8192 <0.000101>
06:53:04.488664 write(5,
"3\0002\0_\0_\0_\0_\0_\0x\0008\0006\0.\0003\0006\0004\0003\0002\
0"..., 8192) = 8192 <0.228029>
06:53:04.716874 write(5,
"A\0005\0003\0006\0_\0000\0000\0009\0000\0002\0007\0008\0A\0001\
0B\0B\0"..., 8192) = 8192 <0.000112>
06:53:04.717150 write(5, " \0 \0 \0A\0c\0t\0i\0o\0n\0:\0
\0N\0u\0l\0l\0\r\0"..., 8192) =
8192 <0.000108>
06:53:04.717409 write(5, "\r\0\n\0M\0S\0I\0 \0(\0s\0)\0
\0(\0004\0004\0:\0F\0000\0"..., 8
192) = 8192 <0.000112>
06:53:04.717683 write(5, " \0(\0s\0)\0 \0(\0004\0004\0:\0F\0000\0)\0
\0[\0002\0002\0"...,
8192) = 8192 <0.227951>
06:53:04.945788 write(5, "S\0I\0 \0(\0s\0)\0
\0(\0004\0004\0:\0F\0000\0)\0 \0[\0"..., 819
2) = 8192 <0.000105>
06:53:04.946041 write(5, ")\0 \0(\0004\0004\0:\0F\0000\0)\0
\0[\0002\0002\0:\0003\0000\0"
..., 8192) = 8192 <0.000100>
06:53:04.946288 write(5,
".\0003\0006\0004\0003\0002\0003\0006\0F\0_\0F\0C\0007\0000\0_\0
001\0"..., 8192) = 8192 <0.000104>
06:53:04.946540 write(5,
"n\0g\0_\0_\0_\0V\0S\0D\0e\0b\0u\0g\0_\0R\0e\0g\0"..., 8192) = 8
192 <0.229116>
06:53:05.175810 write(5, "I\0n\0s\0t\0a\0l\0l\0e\0d\0:\0
\0N\0u\0l\0l\0;\0"..., 8192) = 8
192 <0.000105>
06:53:05.176062 write(5,
"_\0M\0i\0c\0r\0o\0s\0o\0f\0t\0_\0V\0C\0009\0000\0_\0"..., 8192)
= 8192 <0.000109>
06:53:05.176319 write(5,
"s\0_\0R\0e\0s\0_\0r\0u\0s\0_\0004\0002\0005\0003\0.\0003\0"...,
8192) = 8192 <0.000120>
06:53:05.176593 write(5, "l\0e\0d\0:\0 \0N\0u\0l\0l\0;\0 \0 \0
\0R\0e\0q\0"..., 8192) = 8
192 <0.228033>
06:53:05.404780 write(5, "u\0l\0l\0\r\0\n\0M\0S\0I\0 \0(\0s\0)\0
\0(\0004\0004\0"..., 819
2) = 8192 <0.000106>
06:53:05.405033 write(5, "l\0;\0 \0 \0 \0R\0e\0q\0u\0e\0s\0t\0:\0
\0N\0u\0"..., 8192) = 8
192 <0.000101>
06:53:05.405280 write(5,
"5\0009\0000\0000\0_\0e\0n\0u\0_\0x\0008\0006\0.\0003\0006\0004\
0"..., 8192) = 8192 <0.000104>
06:53:05.405641 write(5,
"b\0_\0002\0003\0002\0003\0000\0.\0003\0006\0004\0003\0002\0003\
0006\0F\0"..., 8192) = 8192 <0.230937>
06:53:05.636740 write(5, "e\0s\0t\0:\0 \0L\0o\0c\0a\0l\0;\0 \0 \0
\0A\0c\0"..., 8192) = 8
192 <0.000106>
06:53:05.636996 write(5, "o\0n\0:\0 \0N\0u\0l\0l\0\r\0\n\0M\0S\0I\0
\0(\0s\0"..., 8192) =
8192 <0.000106>
06:53:05.637250 write(5, "t\0:\0
\0_\0_\0K\0B\0009\0005\0004\0003\0004\0005\0.\0I\0n\0"..
., 8192) = 8192 <0.000104>
06:53:05.637512 write(5,
"A\0005\0003\0006\0_\0000\0000\0009\0000\0002\0007\0008\0A\0001\
0B\0B\0"..., 8192) = 8192 <0.233094>
06:53:05.870776 write(5,
"R\0i\0b\0b\0o\0n\0_\0C\0a\0t\0e\0g\0o\0r\0y\0C\0"..., 8192) = 8
192 <0.000108>
06:53:05.871042 write(5, "S\0I\0 \0(\0s\0)\0
\0(\0004\0004\0:\0F\0000\0)\0 \0[\0"..., 819
2) = 8192 <0.000101>
06:53:05.871296 write(5,
"_\0I\0n\0c\0l\0u\0d\0e\0_\0B\0l\0a\0c\0k\0_\0M\0"..., 8192) = 8
192 <0.000106>
06:53:05.871609 write(5, "(\0s\0)\0 \0(\0004\0004\0:\0F\0000\0)\0
\0[\0002\0002\0:\0"...,
8192) = 8192 <0.228984>
06:53:06.100767 write(5, "e\0d\0:\0 \0A\0b\0s\0e\0n\0t\0;\0 \0 \0
\0R\0e\0"..., 8192) = 8
192 <0.000109>
06:53:06.101038 write(5, "l\0e\0d\0:\0 \0L\0o\0c\0a\0l\0;\0 \0 \0
\0R\0e\0"..., 8192) = 8
192 <0.000105>
06:53:06.101293 write(5, "a\0l\0;\0 \0 \0 \0A\0c\0t\0i\0o\0n\0:\0
\0N\0u\0"..., 8192) = 8
192 <0.000103>
06:53:06.101553 write(5, "n\0t\0;\0 \0 \0 \0R\0e\0q\0u\0e\0s\0t\0:\0
\0L\0"..., 8192 <unf
inished ...>
[root@Fedora16-64P2V log]#