Trying strace against virt-p2v-server again, this time to stdout -
A bunch of writes, then another hang. I annotated this one a little bit. That hang went
for more than 3 **minutes** from one write to the next. Combined with a top display
showing the overall system is spending about 1/2 its time waiting for I/Os to complete,
does this tell us anything?
.
.
.
07:04:10.643462 write(5,
"\0\0\0\0\20\220\362\10\0\0\0\377\376\377R\\\0\\\0P\0e\0t\0a\0g\0o\0n"..., 8192)
= 8192 <0.227277>
07:04:10.870894 write(5,
"\1\0\0\0\0\0Iw\330@\377\376\377\10M\0o\0d\0i\0f\0i\0e\0d\0\5\0"..., 8192) =
8192 <0.000032>
07:04:10.871052 write(5,
"\0\7\201\377\376\377\0062\0001\0001\0001\0002\0003\0\2\0\0\210\360\2617\0G\4\4K\6"...,
8192) = 8192 <0.000035>
07:04:10.871211 write(5,
"-\vd\216^\7\10\266\274\330\214}N\2\2xN\263i,\271\5\242\242w\374v\357\315\233O\34"...,
8192) = 8192 <0.000033>
07:04:10.871423 write(5,
"\377\377\377\377\377\377\377\377L\377\0\0\377\377\377\377\377\377\377\377\377\377L\377\0\0\377\377\377\377\377\377"...,
8192) = 8192 <0.227303>
07:04:11.098886 write(5,
"\2\0\0\5\7\t\366\377\366\0\2\0\4\t\2\0\377\377\377\377\377\377\24\377\0\0\377\377\355\377\2\0"...,
8192) = 8192 <0.000034>
07:04:11.099092 write(5,
"\1\0\377\377\345\377\0\0\377\377\377\377\377\377\34\377\2\0\31\7\3\0\26\7\10\0\2\366\0\4\0\7"...,
8192) = 8192 <0.000031>
07:04:11.099294 write(5,
"\377\377L\377\0\0\377\377\377\377\377\377\377\377\377\377L\377\0\0\377\377\377\377\377\377\377\377\377\377L\377"...,
8192) = 8192 <0.000036>
07:04:11.099503 write(5,
"N\0D\0_\0P\0I\0N\0S\0\20\0\0\0\0\0\0\0\0\0\1\0\0\0\1\0\0\0"..., 8192
(I inserted blank space here - note the time indexes - more than 3 minutes when nothing
happened.)
) = 8192 <195.972190>
07:07:27.071878 write(5,
"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 8192) =
8192 <0.000100>
07:07:27.072111 write(5,
"\35\202\0\0\364\201\305\2\247\1\0\0\240\336\2469\362\201\0\0\364\201\305\2\307\1\0\0\365R\2659"...,
8192) = 8192 <0.000046>
07:07:27.072269 write(5,
"\5\0\0\0\35\202\0\0\364\201\366\1\247\1\0\0\240\336\2469\362\201\0\0\364\201\366\1\307\1\0\0"...,
8192) = 8192 <0.000049>
07:07:27.072420 write(5,
"\364\201\366\1\247\1\0\0\240\336\2469\362\201\0\0\364\201\366\1\307\1\0\0\365R\2659\2\0\0\0"...,
8192) = 8192 <0.226265>
07:07:27.298847 write(5,
"?\32p\303u\371\234\355?\2\352%S\253\374\354?\314\314\235\373&#\257\2770u.\301L\21\202"...,
8192) = 8192 <0.000106>
07:07:27.299069 write(5,
"\357\345\313\303\260\277\177\23\256\256B\370\376]\235_3\263\312U\t4'
\0006g\353\312\4\10$"..., 8192) = 8192 <0.000100>
07:07:27.299269 write(5, "@ 1\1\0010\261\1\327]\2\4\10\20 @\200\200\0h\16\20
@\200\0\1\2\4\22\23\20"..., 8192) = 8192 <0.000100>
07:07:27.299470 write(5,
"F%u\2\212?\1\0\0\0\0\0\0\0\377\376\377\0\377\376\377\0\0\0\0\0\0\0\0\0\0\0"...,
8192) = 8192 <0.227184>
07:07:27.526791 write(5,
"\303&\234\334\314\345?\0\0\0\0\0\0\0\0\347\260x\307\207\324\356?\3407\200et%\321?\330"...,
8192) = 8192 <0.000051>
07:07:27.526952 write(5,
"\6\2K\0\0\0x\235pI\5\0\0\0\377\377\377\377\0\0\0\0\3\202\0\0\364\201\6\2\366\0"...,
8192) = 8192 <0.000052>
07:07:27.527156 write(5,
"\300\300\240\0\360\373\377\0\244\240\240\0\200\200\200\0\0\0\377\0\0\377\0\0\0\377\377\0\377\0\0\0"...,
8192) = 8192 <0.000057>
07:07:27.527361 write(5,
"}\377\2\0T\7\2\0\1\7\1\0\2\7\5\0\4\366\2\0\2\7\1\10\2\366\3\377\1\t\2\0"...,
8192^C <unfinished ...>
Process 23339 detached
[root@Fedora16-64P2V log]#