Persistent reading from disk of mysql process on a stopped slave

Hi,

I don’t know if this is bug, but we haven’t seen this behaviour before.

We are running a Percona MySQL Slave Server 5.5.28-29.1-log on a Debian Squeeze 6.0.6 (64-Bit) system. When we start the MySQL daemon (without restoring the dumped pool buffer) or stop the slave process the MySQL process is permanently reading from disk:

iostat -kx sda 3Device: rrqm/s wrqm/s r/s w/s rkB/s wkB/s avgrq-sz avgqu-sz await svctm %utilsda 0,00 0,00 121,67 0,00 1946,67 0,00 32,00 0,98 8,09 8,07 98,13

iotop -P -o -b PID PRIO USER DISK READ DISK WRITE SWAPIN IO COMMAND 1779 be/4 mysql 1873.51 K/s 0.00 B/s 0.00 % 4.92 % mysqld --basedir=/usr --datadir=/srv/mysql --plugin-dir=/usr/lib/mysql/plugin --user=mysql --pid-file=/var/run/mysqld/mysqld.pid --socket=/var/run/mysqld/mysqld.sock --port=6033

mysqladmin processlist±-----±-----±----------±—±--------±-----±------±-----------------±----------±--------------±----------+| Id | User | Host | db | Command | Time | State | Info | Rows_sent | Rows_examined | Rows_read |±-----±-----±----------±—±--------±-----±------±-----------------±----------±--------------±----------+| 1133 | root | localhost | | Query | 0 | | show processlist | 0 | 0 | 1 |±-----±-----±----------±—±--------±-----±------±-----------------±----------±--------------±----------+

This slave is a backup server and there are no selects running on this host at all.

The MySQL config file:

cat /etc/mysql/my.cnf [client]port = 6033socket = /var/run/mysqld/mysqld.sock[mysqld_safe]socket = /var/run/mysqld/mysqld.socknice = 0syslog[mysqldump]max_allowed_packet = 16Mquickquote-names[mysqld]user = mysqlpid-file = /var/run/mysqld/mysqld.pidsocket = /var/run/mysqld/mysqld.socktmpdir = /tmpport = 6033datadir = /srv/mysqlbind-address = 0.0.0.0slow_query_log_file = /var/log/mysql/mysql-slow.logslow_query_log = 1long_query_time = 5log_queries_not_using_indexes = 1server-id = 61innodb_data_home_dir = /srv/mysqlinnodb_log_group_home_dir = /srv/ibloginnodb_log_buffer_size = 16Minnodb_data_file_path = ibdata1:512M:autoextendinnodb_buffer_pool_size = 2500Minnodb_additional_mem_pool_size = 64Minnodb_flush_log_at_trx_commit = 2innodb_flush_method = O_DIRECTinnodb_log_files_in_group = 2innodb_log_file_size = 200Minnodb_status_file = 1innodb_file_per_table = 1skip-slave-start = 1max_connections = 100max_binlog_size = 500Mexpire_logs_days = 3relay-log = dbjupiter-relay-binslave_net_timeout = 60log_warnings = 1log_warnings_suppress = '1592’read_only = 1thread_stack = 256Ktable_open_cache = 2048table_definition_cache = 1024thread_cache = 8read_buffer_size = 1Mjoin_buffer_size = 2Mquery_cache_size = 64Mquery_cache_limit = 1Mquery_cache_min_res_unit = 2Kmax_heap_table_size = 64Mtmp_table_size = 64Mkey_buffer_size = 128Mmax_allowed_packet = 16Minnodb_buffer_pool_restore_at_startup = 300innodb_blocking_buffer_pool_restore = 0skip-external-lockingskip-host-cacheskip-name-resolve

What is MySQL doing here or how can I find out?

Thanks in advance.

Perhaps try “strace -c -p 1779”, or for more detail “strace -Tttf -p 1779”? Note that strace can occasionally cause breakage, which may be less worrisome given that this is a slave.

Hi,

some data:

strace -c -p 1779Process 1779 attached - interrupt to quit^CProcess 1779 detached% time seconds usecs/call calls errors syscall------ ----------- ----------- --------- --------- ----------------100.00 0.040003 40003 1 restart_syscall 0.00 0.000000 0 1 accept 0.00 0.000000 0 1 getsockname 0.00 0.000000 0 4 setsockopt 0.00 0.000000 0 5 fcntl 0.00 0.000000 0 2 futex------ ----------- ----------- --------- --------- ----------------100.00 0.040003 14 total

strace -Tttf -p 1779Process 1779 attached with 20 threads - interrupt to quit[pid 1956] 09:27:48.126271 futex(0x1077b64, FUTEX_WAIT_PRIVATE, 9602, NULL <unfinished …>[pid 1838] 09:27:48.126376 futex(0x1077b64, FUTEX_WAIT_PRIVATE, 9604, NULL <unfinished …>[pid 1956] 09:27:48.126398 <… futex resumed> ) = -1 EAGAIN (Resource temporarily unavailable) <0.000034>[pid 1956] 09:27:48.126442 futex(0x1077b64, FUTEX_WAIT_PRIVATE, 9604, NULL <unfinished …>[pid 1834] 09:27:48.126469 rt_sigtimedwait([HUP QUIT ALRM TERM TSTP], NULL, NULL, 8 <unfinished …>[pid 1821] 09:27:48.126530 futex(0x1b789d4, FUTEX_WAIT_PRIVATE, 17, NULL <unfinished …>[pid 1820] 09:27:48.126554 select(0, NULL, NULL, NULL, {0, 951628} <unfinished …>[pid 1819] 09:27:48.126595 restart_syscall(<… resuming interrupted call …> <unfinished …>[pid 1818] 09:27:48.126633 restart_syscall(<… resuming interrupted call …> <unfinished …>[pid 1817] 09:27:48.126652 restart_syscall(<… resuming interrupted call …> <unfinished …>[pid 1791] 09:27:48.126697 io_getevents(140159307513856, 1, 256, <unfinished …>[pid 1790] 09:27:48.126721 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1789] 09:27:48.126743 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1788] 09:27:48.126783 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1787] 09:27:48.126805 io_getevents(140159307763712, 1, 256, <unfinished …>[pid 1786] 09:27:48.126839 io_getevents(140159307776000, 1, 256, <unfinished …>[pid 1785] 09:27:48.126878 io_getevents(140159307788288, 1, 256, <unfinished …>[pid 1784] 09:27:48.126912 io_getevents(140159307800576, 1, 256, <unfinished …>[pid 1783] 09:27:48.126950 io_getevents(140159308013568, 1, 256, <unfinished …>[pid 1782] 09:27:48.126985 io_getevents(140159308025856, 1, 256, <unfinished …>[pid 1779] 09:27:48.127005 restart_syscall(<… resuming interrupted call …> <unfinished …>[pid 1822] 09:27:48.140846 pread(62, “\2,)\367\0\n5\346\0\vA\354\0\3&\313\0\0\2\235\213\337\32RE\277\0\0\0\0\0\0”…, 16384, 10963484672) = 16384 <0.004880>[pid 1822] 09:27:48.146105 pread(62, “\335\365\356\271\0\7\276y\0\17\314\322\0\7\276h\0\0\2\235\241U~DE\277\0\0\0\0\0\0”…, 16384, 8315092992) = 16384 <0.009529>[pid 1822] 09:27:48.155794 pread(62, “\335G\364Z\0\rI\226\0\6\263\17\0\4]\327\0\0\2\235\235\3421\266E\277\0\0\0\0\0\0”…, 16384, 14267285504) = 16384 <0.008866>[pid 1822] 09:27:48.164809 pread(62, “\22\362\244r\0\6\263\17\0\2\333\f\0\rI\226\0\0\2\235\235\3421\266E\277\0\0\0\0\0\0”…, 16384, 7193477120) = 16384 <0.010752>[pid 1822] 09:27:48.175697 pread(62, “\3313G\235\0\4]\327\0\rI\226\0\4\\20\0\0\2\235\235\3421\266E\277\0\0\0\0\0\0”…, 16384, 4688560128) = 16384 <0.008840>[pid 1822] 09:27:48.184709 pread(62, “[iN\321\0\r8\226\0\6\254\375\0\1\2752\0\0\2\235\235\3421\307E\277\0\0\0\0\0\0”…, 16384, 14195982336) = 16384 <0.012030>[pid 1822] 09:27:48.196894 pread(62, “\342\5\211\31\0\6\254\375\0\2\2\264\0\r8\226\0\0\2\235\235\3421\307E\277\0\0\0\0\0\0”…, 16384, 7168016384) = 16384 <0.005207>[pid 1822] 09:27:48.202235 pread(62, “\260\266c\334\0\1\2752\0\r8\226\0\4\305\244\0\0\2\235\235\3421\307E\277\0\0\0\0\0\0”…, 16384, 1867284480) = 16384 <0.003944>[pid 1822] 09:27:48.206843 pread(62, “\241\33\221\351\0\r\253\207\0\17\345$\0\f\317\272\0\0\2\235\241\321c\336E\277\0\0\0\0\0\0”…, 16384, 14678081536) = 16384 <0.004313>[pid 1822] 09:27:48.211408 pread(62, “v\350&\21\0\10\3746\0\21#\305\0\17\2279\0\0\2\235\236\34\257\257E\277\0\0\0\0\0\0”…, 16384, 9647783936) = 16384 <0.008346>[pid 1822] 09:27:48.219915 pread(62, “\335\247\337 \0\t\203\211\0\16\202\277\0\0177\224\0\0\2\235\242\21\336\214E\277\0\0\0\0\0\0”…, 16384, 10215374848) = 16384 <0.004863>[pid 1822] 09:27:48.224981 pread(62, “NG\17Z\0\4\250\r\0\10J\36\0\f\32\360\0\0\2\235\242\21\336\353E\277\0\0\0\0\0\0”…, 16384, 4999823360) = 16384 <0.006466>[pid 1822] 09:27:48.231645 pread(62, “\304\230\322\210\0\0177\224\0\t\203\211\0\2S\222\0\0\2\235\242\21\336\332E\277\0\0\0\0\0\0”…, 16384, 16339238912) = 16384 <0.008581>[pid 1822] 09:27:48.240385 pread(62, “%/]F\0\f\32\360\0\4\250\r\0\20\27\20\0\0\2\235\242\21\336\353E\277\0\0\0\0\0\0”…, 16384, 12997885952) = 16384 <0.006357>[pid 1822] 09:27:48.246892 pread(62, “\321\264,\337\0\20\27\20\0\f\32\360\0\1\324E\0\0\2\235\242\21\336\353E\277\0\0\0\0\0\0”…, 16384, 17276600320) = 16384 <0.010820>[pid 1822] 09:27:48.258957 pread(62, “\302%\225\4\0\f\t\337\0\5;f\0\2\271\207\0\0\2\235\242\37\345eE\277\0\0\0\0\0\0”…, 16384, 12926304256) = 16384 <0.004111>[pid 1822] 09:27:48.263252 pread(62, “B\302-\253\0\5b"\0\10\342\366\0\v\37\24\0\0\2\235\237M\232\rE\277\0\0\0\0\0\0”…, 16384, 5780307968) = 16384 <0.009192>[pid 1822] 09:27:48.272593 pread(62, “\250\10\36\326\0\10\342\366\0\21\375\275\0\5b"\0\0\2\235\242\203\365rE\277\0\0\0\0\0\0”…, 16384, 9541877760) = 16384 <0.003915>[pid 1822] 09:27:48.276642 pread(62, “\330\252\314\367\0\v\37\24\0\5b"\0\r\357N\0\0\2\235\242\217O\372E\277\0\0\0\0\0\0”…, 16384, 11941511168) = 16384 <0.006677>[pid 1822] 09:27:48.283905 pread(62, “\374\372\311t\0\r\321\330\0\v\372\211\0\v\8\0\0\2\235\241X_:E\277\0\0\0\0\0\0”…, 16384, 14838792192) = 16384 <0.009130>[pid 1822] 09:27:48.293249 pread(62, “d\230=\345\0\n\373\355\0\4\247\316\0\2\f*\0\0\2\235\240U\235aE\277\0\0\0\0\0\0”…, 16384, 11794071552) = 16384 <0.014415>[pid 1822] 09:27:48.308490 pread(62, “\337\352\302\241\0\6K}\0\3\16\265\0\17\276\266\0\0\2\235\242\317\vRE\277\0\0\0\0\0\0”…, 16384, 6759071744) = 16384 <0.011258>[pid 1822] 09:27:48.320297 pread(62, “o\314\2 \0\7D\212\0\v\331:\0\f\260\363\0\0\2\235\242q-\364E\277\0\0\0\0\0\0”…, 16384, 7803666432) = 16384 <0.012229>[pid 1822] 09:27:48.333181 pread(62, “m\35a\324\0\7\245F\0\17#\236\0\4r:\0\0\2\235\242\214\n2E\277\0\0\0\0\0\0”…, 16384, 8209399808) = 16384 <0.009582>[pid 1822] 09:27:48.342954 pread(62, “O\307?b\0\7Gz\0\f\5F\0\17\307%\0\0\2\235\237\207F\365E\277\0\0\0\0\0\0”…, 16384, 7815987200) = 16384 <0.005441>[pid 1822] 09:27:48.348681 pread(62, “\20k\210i\0\v\2554\0\1\255\375\0\6\376Z\0\0\2\235\241\201\313\365E\277\0\0\0\0\0\0”…, 16384, 12537626624) = 16384 <0.011078>[pid 1822] 09:27:48.360076 pread(62, “W\361\253\270\0\t\322o\0\2\210,\0\5q\232\0\0\2\235\242\215\235\314E\277\0\0\0\0\0\0”…, 16384, 10546298880) = 16384 <0.008129>[pid 1822] 09:27:48.368703 pread(62, “d\327\262\4\0\t\n\215\0\0W`\0\5@\300\0\0\2\235\237d\372\301E\277\0\0\0\0\0\0”…, 16384, 9707929600) = 16384 <0.004807>[pid 1822] 09:27:48.373840 pread(62, “\264V$\356\0\6\220\21\0\f\324\216\0\4";\0\0\2\235\232k\10\273E\277\0\0\0\0\0\0”…, 16384, 7046709248) = 16384 <0.004809>[pid 1822] 09:27:48.378807 pread(62, “\32exg\0\4\262f\0\n\202\0\0\f\307\211\0\0\2\235\241\351\275\303E\277\0\0\0\0\0\0”…, 16384, 5043224576) = 16384 <0.006665>[pid 1822] 09:27:48.385618 pread(62, “H\200|\27\0\n\202\0\0\4\264\347\0\4\262f\0\0\2\235\241\351\275\303E\277\0\0\0\0\0\0”…, 16384, 11282677760) = 16384 <0.009849>[pid 1822] 09:27:48.395605 pread(62, “\344\351\317\237\0\f\307\211\0\4\262f\0\t\33\212\0\0\2\235\241\351\275\303E\277\0\0\0\0\0\0”…, 16384, 13721812992) = 16384 <0.009114>[pid 1822] 09:27:48.404904 pread(62, “>\321\17\327\0\16-O\0\4R\311\0\10\300\331\0\0\2\235\241\351\275\324E\277\0\0\0\0\0\0”…, 16384, 15222423552) = 16384 <0.005318>[pid 1822] 09:27:48.410371 pread(62, “{>\253\246\0\4R\311\0\f:M\0\16-O\0\0\2\235\241\351\275\324E\277\0\0\0\0\0\0”…, 16384, 4642193408) = 16384 <0.006629>[pid 1822] 09:27:48.417182 pread(62, “\327K\344\317\0\0\333\273\0\17\234\371\0\17u\20\0\0\2\235\241B\335\271E\277\0\0\0\0\0\0”…, 16384, 921616384) = 16384 <0.005595>[pid 1822] 09:27:48.422928 pread(62, “\315)\276T\0\17\234\371\0\7\244\232\0\0\333\273\0\0\2\235\241\343\5\372E\277\0\0\0\0\0\0”…, 16384, 16764518400) = 16384 <0.006454>[pid 1822] 09:27:48.429556 pread(62, “\307R\260\240\0\5\263\316\0\5\306\33\0\r\344\241\0\0\2\235\235\245*\356E\277\0\0\0\0\0\0”…, 16384, 6122864640) = 16384 <0.005248>[pid 1822] 09:27:48.434963 pread(62, “JH\365Q\0\10.:\0\2:\334\0\6\307\356\0\0\2\235\235\245*\377E\277\0\0\0\0\0\0”…, 16384, 8783822848) = 16384 <0.009619>[pid 1822] 09:27:48.445117 pread(62, “\34\200\30\246\0\f\277\23\0\3\206\6\0\7\260\352\0\0\2\235\237\221[ E\277\0\0\0\0\0\0”…, 16384, 13686325248) = 16384 <0.008835>[pid 1822] 09:27:48.454156 pread(62, “y\3770\277\0\00415\0\10\222\212\0\10\205\227\0\0\2\235\237\343A\345E\277\0\0\0\0\0\0”…, 16384, 4501356544) = 16384 <0.009777>[pid 1822] 09:27:48.464269 pread(62, “\354l:7\0\4\1\214\0\6\254l\0\7\316\267\0\0\2\235\235\273\v\33E\277\0\0\0\0\0\0”…, 16384, 4301455360) = 16384 <0.009963>[pid 1822] 09:27:48.474389 pread(62, “\36}\\\0\6\324!\0\fIn\0\v\240\275\0\0\2\235\240\216\2306E\277\0\0\0\0\0\0”…, 16384, 7332184064) = 16384 <0.002837>[pid 1822] 09:27:48.477380 pread(62, “P\24\373\307\0\n+\262\0\5g\336\0\t\202W\0\0\2\235\231\226\3040E\277\0\0\0\0\0\0”…, 16384, 10920689664) = 16384 <0.004686>[pid 1822] 09:27:48.490812 pwrite(4, “fn\313\323\0\1\227\3\0\0054\0\0\17&\214\0\0\2\235\242\177c\203E\277\0\0\0\0\0\0”…, 1048576, 1048576) = 1048576 <0.004397>[pid 1822] 09:27:48.495297 pwrite(4, “\216\350\375~\0\16(\256\0\10\201l\0\5\312\220\0\0\2\235\242\375\277@E\277\0\0\0\0\0\0”…, 901120, 2097152) = 901120 <0.042444>[pid 1822] 09:27:48.537834 fsync(4) = 0 <0.000131>[pid 1822] 09:27:48.538019 io_submit(140159307550720, 1, {…}) = 1 <0.000039>[pid 1822] 09:27:48.538099 io_submit(140159307550720, 1, {…}) = 1 <0.000028>[pid 1788] 09:27:48.538172 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.411377>[pid 1822] 09:27:48.538200 io_submit(140159307550720, 1, {…} <unfinished …>[pid 1788] 09:27:48.538237 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.538257 <… io_submit resumed> ) = 1 <0.000043>[pid 1788] 09:27:48.538275 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000025>[pid 1822] 09:27:48.538298 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1788] 09:27:48.538333 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.538352 <… io_submit resumed> ) = 1 <0.000029>[pid 1788] 09:27:48.538370 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000025>[pid 1822] 09:27:48.538393 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1788] 09:27:48.538434 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.538454 <… io_submit resumed> ) = 1 <0.000046>[pid 1822] 09:27:48.538486 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.538511 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.411754>[pid 1822] 09:27:48.538536 <… io_submit resumed> ) = 1 <0.000033>[pid 1789] 09:27:48.538559 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.538582 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.538601 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000027>[pid 1822] 09:27:48.538622 <… io_submit resumed> ) = 1 <0.000029>[pid 1789] 09:27:48.538645 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.538667 io_submit(140159307526144, 1, {…}) = 1 <0.000028>[pid 1822] 09:27:48.538727 io_submit(140159307526144, 1, {…}) = 1 <0.000026>[pid 1822] 09:27:48.538785 io_submit(140159307526144, 1, {…}) = 1 <0.000025>[pid 1822] 09:27:48.538844 io_submit(140159307526144, 1, {…}) = 1 <0.000026>[pid 1822] 09:27:48.538902 io_submit(140159307526144, 1, {…}) = 1 <0.000027>[pid 1822] 09:27:48.538961 io_submit(140159307526144, 1, {…}) = 1 <0.000027>[pid 1822] 09:27:48.539021 io_submit(140159307526144, 1, {…}) = 1 <0.000027>[pid 1822] 09:27:48.539080 io_submit(140159307526144, 1, {…}) = 1 <0.000026>[pid 1822] 09:27:48.539138 io_submit(140159307526144, 1, {…}) = 1 <0.000027>[pid 1822] 09:27:48.539197 io_submit(140159307526144, 1, {…}) = 1 <0.000026>[pid 1822] 09:27:48.539257 io_submit(140159307526144, 1, {…}) = 1 <0.000031>[pid 1789] 09:27:48.539313 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000656>[pid 1822] 09:27:48.539340 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1789] 09:27:48.539363 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.539384 <… io_submit resumed> ) = 1 <0.000029>[pid 1790] 09:27:48.539403 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.412670>[pid 1789] 09:27:48.539425 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000050>[pid 1790] 09:27:48.539450 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1789] 09:27:48.539474 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1790] 09:27:48.539494 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000028>[pid 1822] 09:27:48.539532 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.539573 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.539595 <… io_submit resumed> ) = 1 <0.000043>[pid 1790] 09:27:48.539624 <… io_getevents resumed> {…}{0, 500000000}) = 2 <0.000038>[pid 1822] 09:27:48.539649 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.539684 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.539715 <… io_submit resumed> ) = 1 <0.000031>[pid 1790] 09:27:48.539734 <… io_getevents resumed> {…}{0, 500000000}) = 2 <0.000037>[pid 1822] 09:27:48.539757 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.539791 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.539812 <… io_submit resumed> ) = 1 <0.000029>[pid 1790] 09:27:48.539828 <… io_getevents resumed> {…}{0, 500000000}) = 2 <0.000026>[pid 1822] 09:27:48.539851 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.539874 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.539894 <… io_submit resumed> ) = 1 <0.000028>[pid 1822] 09:27:48.539923 io_submit(140159307526144, 1, {…}) = 1 <0.000026>[pid 1822] 09:27:48.539983 io_submit(140159307526144, 1, {…}) = 1 <0.000026>[pid 1822] 09:27:48.540041 io_submit(140159307526144, 1, {…}) = 1 <0.000043>[pid 1790] 09:27:48.540107 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000222>[pid 1822] 09:27:48.540132 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.540169 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.540201 <… io_submit resumed> ) = 1 <0.000029>[pid 1790] 09:27:48.540220 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000039>[pid 1822] 09:27:48.540254 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.540278 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.540311 <… io_submit resumed> ) = 1 <0.000028>[pid 1790] 09:27:48.540329 <… io_getevents resumed> {…}{0, 500000000}) = 2 <0.000039>[pid 1822] 09:27:48.540363 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.540387 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.540419 <… io_submit resumed> ) = 1 <0.000039>[pid 1790] 09:27:48.540437 <… io_getevents resumed> {…}{0, 500000000}) = 3 <0.000028>[pid 1822] 09:27:48.540461 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.540495 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.540517 <… io_submit resumed> ) = 1 <0.000040>[pid 1790] 09:27:48.540545 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000038>[pid 1822] 09:27:48.540580 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.540604 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.540623 <… io_submit resumed> ) = 1 <0.000028>[pid 1790] 09:27:48.540653 <… io_getevents resumed> {…}{0, 500000000}) = 2 <0.000037>[pid 1822] 09:27:48.540677 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.540711 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.540731 <… io_submit resumed> ) = 1 <0.000028>[pid 1790] 09:27:48.540748 <… io_getevents resumed> {…}{0, 500000000}) = 2 <0.000025>[pid 1822] 09:27:48.540771 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.544883 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.544911 <… io_submit resumed> ) = 1 <0.004125>[pid 1790] 09:27:48.544980 <… io_getevents resumed> {…}{0, 500000000}) = 6 <0.000078>[pid 1790] 09:27:48.545107 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.545188 io_submit(140159307550720, 1, {…}) = 1 <0.000044>[pid 1822] 09:27:48.545270 io_submit(140159307550720, 1, {…}) = 1 <0.000027>[pid 1822] 09:27:48.545332 io_submit(140159307550720, 1, {…} <unfinished …>[pid 1788] 09:27:48.545357 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.006908>[pid 1822] 09:27:48.545380 <… io_submit resumed> ) = 1 <0.000036>[pid 1788] 09:27:48.545402 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.545425 io_submit(140159307550720, 1, {…} <unfinished …>[pid 1788] 09:27:48.545444 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000027>[pid 1822] 09:27:48.545465 <… io_submit resumed> ) = 1 <0.000029>[pid 1788] 09:27:48.545488 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.545511 io_submit(140159307550720, 1, {…} <unfinished …>[pid 1788] 09:27:48.545529 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000027>[pid 1822] 09:27:48.545565 <… io_submit resumed> ) = 1 <0.000042>[pid 1788] 09:27:48.545587 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.545610 io_submit(140159307550720, 1, {…} <unfinished …>[pid 1788] 09:27:48.545629 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000027>[pid 1822] 09:27:48.545651 <… io_submit resumed> ) = 1 <0.000029>[pid 1788] 09:27:48.545673 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.545695 io_submit(140159307550720, 1, {…} <unfinished …>[pid 1788] 09:27:48.545714 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000026>[pid 1822] 09:27:48.545735 <… io_submit resumed> ) = 1 <0.000029>[pid 1788] 09:27:48.545756 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.545779 io_submit(140159307550720, 1, {…} <unfinished …>[pid 1788] 09:27:48.545798 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000028>[pid 1822] 09:27:48.545857 <… io_submit resumed> ) = 1 <0.000066>[pid 1822] 09:27:48.545886 io_submit(140159307550720, 1, {…}) = 1 <0.000031>[pid 1788] 09:27:48.545947 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.545983 io_submit(140159307513856, 1, {…}) = 1 <0.000030>[pid 1822] 09:27:48.546054 io_submit(140159307513856, 1, {…}) = 1 <0.000030>[pid 1822] 09:27:48.546118 io_submit(140159307513856, 1, {…} <unfinished …>[pid 1788] 09:27:48.546151 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000183>[pid 1822] 09:27:48.546177 <… io_submit resumed> ) = 1 <0.000046>[pid 1788] 09:27:48.546218 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.546258 io_submit(140159307513856, 1, {…} <unfinished …>[pid 1788] 09:27:48.546279 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000045>[pid 1822] 09:27:48.546322 <… io_submit resumed> ) = 1 <0.000051>[pid 1791] 09:27:48.546341 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.419631>[pid 1822] 09:27:48.546381 io_submit(140159307513856, 1, {…} <unfinished …>[pid 1791] 09:27:48.546425 io_getevents(140159307513856, 1, 256, <unfinished …>[pid 1822] 09:27:48.546447 <… io_submit resumed> ) = 1 <0.000049>[pid 1791] 09:27:48.546464 <… io_getevents resumed> {…}{0, 500000000}) = 2 <0.000026>[pid 1822] 09:27:48.546488 io_submit(140159307513856, 1, {…} <unfinished …>[pid 1791] 09:27:48.546527 io_getevents(140159307513856, 1, 256, <unfinished …>[pid 1822] 09:27:48.546548 <… io_submit resumed> ) = 1 <0.000030>[pid 1791] 09:27:48.546565 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000026>[pid 1822] 09:27:48.546589 io_submit(140159307513856, 1, {…} <unfinished …>[pid 1791] 09:27:48.546628 io_getevents(140159307513856, 1, 256, <unfinished …>[pid 1822] 09:27:48.546649 <… io_submit resumed> ) = 1 <0.000045>[pid 1791] 09:27:48.546666 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000026>[pid 1822] 09:27:48.546706 io_submit(140159307513856, 1, {…} <unfinished …>[pid 1791] 09:27:48.546731 io_getevents(140159307513856, 1, 256, <unfinished …>[pid 1822] 09:27:48.546784 <… io_submit resumed> ) = 1 <0.000061>[pid 1791] 09:27:48.546804 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000062>[pid 1822] 09:27:48.546829 io_submit(140159307513856, 1, {…} <unfinished …>[pid 1791] 09:27:48.546852 io_getevents(140159307513856, 1, 256, <unfinished …>[pid 1822] 09:27:48.546874 <… io_submit resumed> ) = 1 <0.000031>[pid 1791] 09:27:48.546892 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000028>[pid 1822] 09:27:48.546916 io_submit(140159307513856, 1, {…} <unfinished …>[pid 1791] 09:27:48.546941 io_getevents(140159307513856, 1, 256, <unfinished …>[pid 1822] 09:27:48.546963 <… io_submit resumed> ) = 1 <0.000032>[pid 1788] 09:27:48.546986 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.547010 io_submit(140159307513856, 1, {…} <unfinished …>[pid 1788] 09:27:48.547029 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000028>[pid 1822] 09:27:48.547051 <… io_submit resumed> ) = 1 <0.000031>[pid 1788] 09:27:48.547074 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.547096 io_submit(140159307513856, 1, {…}) = 1 <0.000027>[pid 1791] 09:27:48.547148 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000196>[pid 1822] 09:27:48.547173 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1791] 09:27:48.547222 io_getevents(140159307513856, 1, 256, <unfinished …>[pid 1822] 09:27:48.547243 <… io_submit resumed> ) = 1 <0.000055>[pid 1791] 09:27:48.547261 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000026>[pid 1822] 09:27:48.547302 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1791] 09:27:48.547343 io_getevents(140159307513856, 1, 256, <unfinished …>[pid 1822] 09:27:48.547364 <… io_submit resumed> ) = 1 <0.000047>[pid 1791] 09:27:48.547399 <… io_getevents resumed> {…}{0, 500000000}) = 2 <0.000044>[pid 1822] 09:27:48.547424 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1791] 09:27:48.547447 io_getevents(140159307513856, 1, 256, <unfinished …>[pid 1822] 09:27:48.547467 <… io_submit resumed> ) = 1 <0.000027>[pid 1791] 09:27:48.547484 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000026>[pid 1822] 09:27:48.547528 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1791] 09:27:48.547552 io_getevents(140159307513856, 1, 256, <unfinished …>[pid 1822] 09:27:48.547590 <… io_submit resumed> ) = 1 <0.000044>[pid 1790] 09:27:48.547607 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.002485>[pid 1822] 09:27:48.547631 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.547670 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.547692 <… io_submit resumed> ) = 1 <0.000029>[pid 1790] 09:27:48.547727 <… io_getevents resumed> {…}{0, 500000000}) = 2 <0.000044>[pid 1822] 09:27:48.547751 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.547791 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.547811 <… io_submit resumed> ) = 1 <0.000030>[pid 1790] 09:27:48.547828 <… io_getevents resumed> {…}{0, 500000000}) = 2 <0.000026>[pid 1822] 09:27:48.547852 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.547894 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.547916 <… io_submit resumed> ) = 1 <0.000049>[pid 1790] 09:27:48.547933 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000026>[pid 1822] 09:27:48.547956 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.547979 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.547998 <… io_submit resumed> ) = 1 <0.000027>[pid 1822] 09:27:48.548029 io_submit(140159307526144, 1, {…}) = 1 <0.000027>[pid 1822] 09:27:48.548094 io_submit(140159307526144, 1, {…}) = 1 <0.000026>[pid 1822] 09:27:48.548155 io_submit(140159307526144, 1, {…}) = 1 <0.000027>[pid 1822] 09:27:48.548216 io_submit(140159307526144, 1, {…}) = 1 <0.000028>[pid 1822] 09:27:48.548279 io_submit(140159307526144, 1, {…}) = 1 <0.000034>[pid 1822] 09:27:48.548348 io_submit(140159307526144, 1, {…}) = 1 <0.000027>[pid 1822] 09:27:48.548410 io_submit(140159307526144, 1, {…}) = 1 <0.000027>[pid 1822] 09:27:48.548472 io_submit(140159307526144, 1, {…}) = 1 <0.000026>[pid 1822] 09:27:48.548532 io_submit(140159307526144, 1, {…}) = 1 <0.000027>[pid 1822] 09:27:48.548594 io_submit(140159307526144, 1, {…}) = 1 <0.000028>[pid 1822] 09:27:48.548655 io_submit(140159307538432, 1, {…}) = 1 <0.000028>[pid 1822] 09:27:48.548717 io_submit(140159307538432, 1, {…}) = 1 <0.000028>[pid 1822] 09:27:48.548779 io_submit(140159307538432, 1, {…}) = 1 <0.000027>[pid 1822] 09:27:48.548841 io_submit(140159307538432, 1, {…}) = 1 <0.000030>[pid 1822] 09:27:48.548904 io_submit(140159307538432, 1, {…}) = 1 <0.000027>[pid 1822] 09:27:48.548965 io_submit(140159307538432, 1, {…}) = 1 <0.000026>[pid 1822] 09:27:48.549025 io_submit(140159307526144, 1, {…}) = 1 <0.000026>[pid 1790] 09:27:48.549882 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.001891>[pid 1789] 09:27:48.549908 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.010423>[pid 1822] 09:27:48.549958 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.549985 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1789] 09:27:48.550007 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.550064 <… io_submit resumed> ) = 1 <0.000086>[pid 1790] 09:27:48.550084 <… io_getevents resumed> {…}{0, 500000000}) = 12 <0.000088>[pid 1789] 09:27:48.550109 <… io_getevents resumed> {…}{0, 500000000}) = 5 <0.000089>[pid 1790] 09:27:48.550186 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.550216 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.550237 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000031>[pid 1822] 09:27:48.550260 <… io_submit resumed> ) = 1 <0.000032>[pid 1790] 09:27:48.550283 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.550306 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1789] 09:27:48.550328 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.550346 <… io_submit resumed> ) = 1 <0.000027>[pid 1822] 09:27:48.550419 io_submit(140159307526144, 1, {…}) = 1 <0.000052>[pid 1790] 09:27:48.550499 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000204>[pid 1822] 09:27:48.550536 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.550579 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.550614 <… io_submit resumed> ) = 1 <0.000061>[pid 1822] 09:27:48.550645 io_submit(140159307526144, 1, {…} <unfinished …>[pid 1790] 09:27:48.550666 <… io_getevents resumed> {…}{0, 500000000}) = 2 <0.000058>[pid 1822] 09:27:48.550701 <… io_submit resumed> ) = 1 <0.000043>[pid 1822] 09:27:48.550737 io_submit(140159307550720, 1, {…} <unfinished …>[pid 1790] 09:27:48.550765 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1822] 09:27:48.550804 <… io_submit resumed> ) = 1 <0.000046>[pid 1790] 09:27:48.550823 <… io_getevents resumed> {…}{0, 500000000}) = 2 <0.000045>[pid 1822] 09:27:48.550848 io_submit(140159307550720, 1, {…} <unfinished …>[pid 1790] 09:27:48.550872 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1788] 09:27:48.550892 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.003803>[pid 1822] 09:27:48.550916 <… io_submit resumed> ) = 1 <0.000056>[pid 1788] 09:27:48.550938 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.550960 io_submit(140159307550720, 1, {…} <unfinished …>[pid 1788] 09:27:48.550999 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000045>[pid 1822] 09:27:48.551023 <… io_submit resumed> ) = 1 <0.000052>[pid 1788] 09:27:48.551045 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.551067 io_submit(140159307550720, 1, {…} <unfinished …>[pid 1788] 09:27:48.551111 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000050>[pid 1822] 09:27:48.551134 <… io_submit resumed> ) = 1 <0.000057>[pid 1788] 09:27:48.551156 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.551178 io_submit(140159307550720, 1, {…} <unfinished …>[pid 1788] 09:27:48.551214 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000042>[pid 1822] 09:27:48.551237 <… io_submit resumed> ) = 1 <0.000048>[pid 1788] 09:27:48.551259 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.551281 io_submit(140159307550720, 1, {…}) = 1 <0.000027>[pid 1822] 09:27:48.551343 io_submit(140159307550720, 1, {…}) = 1 <0.000029>[pid 1822] 09:27:48.551408 io_submit(140159307550720, 1, {…}) = 1 <0.000029>[pid 1822] 09:27:48.551476 io_submit(140159307550720, 1, {…}) = 1 <0.000028>[pid 1822] 09:27:48.551540 io_submit(140159307550720, 1, {…}) = 1 <0.000028>[pid 1822] 09:27:48.551603 io_submit(140159307550720, 1, {…}) = 1 <0.000048>[pid 1788] 09:27:48.551677 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000407>[pid 1822] 09:27:48.551735 io_submit(140159307550720, 1, {…} <unfinished …>[pid 1788] 09:27:48.551772 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.551833 <… io_submit resumed> ) = 1 <0.000080>[pid 1788] 09:27:48.551852 <… io_getevents resumed> {…}{0, 500000000}) = 2 <0.000069>[pid 1822] 09:27:48.551910 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1788] 09:27:48.551946 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.552010 <… io_submit resumed> ) = 1 <0.000082>[pid 1788] 09:27:48.552028 <… io_getevents resumed> {…}{0, 500000000}) = 3 <0.000070>[pid 1822] 09:27:48.552085 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1788] 09:27:48.552122 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.552187 <… io_submit resumed> ) = 1 <0.000071>[pid 1789] 09:27:48.552205 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.001866>[pid 1788] 09:27:48.552226 <… io_getevents resumed> {…}{0, 500000000}) = 2 <0.000093>[pid 1822] 09:27:48.552300 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.552324 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1788] 09:27:48.552345 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:48.552394 <… io_submit resumed> ) = 1 <0.000075>[pid 1789] 09:27:48.552412 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000077>[pid 1822] 09:27:48.552452 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.552476 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.552528 <… io_submit resumed> ) = 1 <0.000055>[pid 1822] 09:27:48.552561 io_submit(140159307538432, 1, {…}) = 1 <0.000026>[pid 1822] 09:27:48.552622 io_submit(140159307538432, 1, {…}) = 1 <0.000025>[pid 1822] 09:27:48.552681 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.552716 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000225>[pid 1822] 09:27:48.552739 <… io_submit resumed> ) = 1 <0.000046>[pid 1789] 09:27:48.552762 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.552783 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.552803 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000026>[pid 1822] 09:27:48.552833 <… io_submit resumed> ) = 1 <0.000038>[pid 1789] 09:27:48.552856 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.552880 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.552899 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000029>[pid 1822] 09:27:48.552920 <… io_submit resumed> ) = 1 <0.000029>[pid 1789] 09:27:48.552943 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.552966 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.552985 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000027>[pid 1822] 09:27:48.553006 <… io_submit resumed> ) = 1 <0.000029>[pid 1789] 09:27:48.553028 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.553050 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.553069 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000027>[pid 1822] 09:27:48.553091 <… io_submit resumed> ) = 1 <0.000030>[pid 1789] 09:27:48.553113 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.553148 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.553187 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000057>[pid 1822] 09:27:48.553211 <… io_submit resumed> ) = 1 <0.000049>[pid 1789] 09:27:48.553248 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.553273 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.556880 <… io_getevents resumed> {…}{0, 500000000}) = 2 <0.000027>[pid 1822] 09:27:48.556918 <… io_submit resumed> ) = 1 <0.003633>[pid 1789] 09:27:48.556940 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.556974 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.556994 <… io_getevents resumed> {…}{0, 500000000}) = 3 <0.000040>[pid 1822] 09:27:48.557050 <… io_submit resumed> ) = 1 <0.000062>[pid 1789] 09:27:48.557075 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.557098 io_submit(140159307538432, 1, {…}) = 1 <0.000040>[pid 1789] 09:27:48.557164 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000078>[pid 1822] 09:27:48.557189 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.557212 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.557231 <… io_submit resumed> ) = 1 <0.000028>[pid 1789] 09:27:48.557248 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000024>[pid 1822] 09:27:48.557271 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.557294 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.557313 <… io_submit resumed> ) = 1 <0.000028>[pid 1789] 09:27:48.557330 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000025>[pid 1822] 09:27:48.557354 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.557377 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.557396 <… io_submit resumed> ) = 1 <0.000027>[pid 1789] 09:27:48.557413 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000025>[pid 1822] 09:27:48.557436 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.557459 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.557478 <… io_submit resumed> ) = 1 <0.000028>[pid 1789] 09:27:48.557495 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000025>[pid 1822] 09:27:48.557518 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.557541 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.557560 <… io_submit resumed> ) = 1 <0.000028>[pid 1789] 09:27:48.557577 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000025>[pid 1822] 09:27:48.557601 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.557624 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.557656 <… io_submit resumed> ) = 1 <0.000038>[pid 1789] 09:27:48.557674 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000039>[pid 1822] 09:27:48.557697 io_submit(140159307538432, 1, {…} <unfinished …>[pid 1789] 09:27:48.557720 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.557747 <… io_submit resumed> ) = 1 <0.000034>[pid 1789] 09:27:48.557765 <… io_getevents resumed> {…}{0, 500000000}) = 1 <0.000035>[pid 1822] 09:27:48.557791 futex(0x3052ea4, FUTEX_WAIT_PRIVATE, 116877, NULL <unfinished …>[pid 1789] 09:27:48.557848 io_getevents(140159307538432, 1, 256, {…}{0, 500000000}) = 1 <0.000010>[pid 1789] 09:27:48.557896 futex(0x3052ea4, FUTEX_CMP_REQUEUE_PRIVATE, 1, 2147483647, 0x3052e60, 116878) = 1 <0.000014>[pid 1789] 09:27:48.557951 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:48.557974 <… futex resumed> ) = 0 <0.000165>[pid 1822] 09:27:48.558003 futex(0x3052e60, FUTEX_WAKE_PRIVATE, 1) = 0 <0.000012>[pid 1822] 09:27:48.558051 fsync(62) = 0 <0.000118>[pid 1822] 09:27:48.559025 pread(62, <unfinished …>[pid 1787] 09:27:48.624841 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.498015>[pid 1786] 09:27:48.624869 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.498018>[pid 1787] 09:27:48.624898 io_getevents(140159307763712, 1, 256, <unfinished …>[pid 1786] 09:27:48.624923 io_getevents(140159307776000, 1, 256, <unfinished …>[pid 1785] 09:27:48.624943 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.498049>[pid 1784] 09:27:48.624965 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.498041>[pid 1785] 09:27:48.624991 io_getevents(140159307788288, 1, 256, <unfinished …>[pid 1784] 09:27:48.625015 io_getevents(140159307800576, 1, 256, <unfinished …>[pid 1783] 09:27:48.625034 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.498069>[pid 1782] 09:27:48.625056 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.498060>[pid 1783] 09:27:48.625082 io_getevents(140159308013568, 1, 256, <unfinished …>[pid 1782] 09:27:48.625105 io_getevents(140159308025856, 1, 256, <unfinished …>[pid 1822] 09:27:48.648915 <… pread resumed> “!E\222\222\0\vV\327\0\17zZ\0\2g\271\0\0\2\235\237\356,\235E\277\0\0\0\0\0\0”…, 16384, 12175392768) = 16384 <0.089869>[pid 1822] 09:27:48.649103 pread(62, <unfinished …>[pid 1817] 09:27:48.669984 <… restart_syscall resumed> ) = -1 ETIMEDOUT (Connection timed out) <0.543315>[pid 1817] 09:27:48.670019 futex(0x2934420, FUTEX_WAKE_PRIVATE, 1) = 0 <0.000012>[pid 1817] 09:27:48.670504 futex(0x2934464, FUTEX_WAIT_BITSET_PRIVATE|FUTEX_CLOCK_REALTIME, 152439, {1354004869, 670489000}, ffffffff <unfinished …>[pid 1822] 09:27:48.698811 <… pread resumed> “P\37\337t\0\vf\375\0\rsN\0\16\233N\0\0\2\235\241\354fdE\277\0\0\0\0\0\0”…, 16384, 12243124224) = 16384 <0.049691>[pid 1822] 09:27:48.699014 pread(62, <unfinished …>[pid 1818] 09:27:48.721961 <… restart_syscall resumed> ) = -1 ETIMEDOUT (Connection timed out) <0.595311>[pid 1818] 09:27:48.721994 futex(0x2934390, FUTEX_WAKE_PRIVATE, 1) = 0 <0.000012>[pid 1818] 09:27:48.722924 futex(0x29343d4, FUTEX_WAIT_BITSET_PRIVATE|FUTEX_CLOCK_REALTIME, 152323, {1354004869, 722902000}, ffffffff <unfinished …>[pid 1822] 09:27:48.723546 <… pread resumed> “\230\223[\242\0\r\v\35\0\16\6\26\0\16h\21\0\0\2\235\233#A\367E\277\0\0\0\0\0\0”…, 16384, 14005256192) = 16384 <0.024515>[pid 1822] 09:27:48.723885 pread(62, “\327\227*o\0\1\32\307\0\10y\231\0\17d\250\0\0\2\235\240"~XE\277\0\0\0\0\0\0”…, 16384, 1186054144) = 16384 <0.110561>[pid 1822] 09:27:48.834702 pread(62, “x\6\325S\0\16\7\36\0\v\311u\0\16\331S\0\0\2\235\2404u\200E\277\0\0\0\0\0\0”…, 16384, 15062237184) = 16384 <0.006458>[pid 1822] 09:27:48.841384 pread(62, “\300w\265\247\0\f\367p\0\4\324\322\0\10\33\321\0\0\2\235\237\21\26aE\277\0\0\0\0\0\0”…, 16384, 13922729984) = 16384 <0.064116>[pid 1822] 09:27:48.905662 pread(62, “\225q\262\322\0\7\320\356\0\16\263 \0\v\305\264\0\0\2\235\242O\227\241E\277\0\0\0\0\0\0”…, 16384, 8392507392) = 16384 <0.007032>[pid 1822] 09:27:48.912879 pread(62, “\221\334\2053\0\f\204\376\0\20\17]\0\22mc\0\0\2\235\242O\227\262E\277\0\0\0\0\0\0”…, 16384, 13442711552) = 16384 <0.019776>[pid 1822] 09:27:48.933060 pread(62, “;Fh\303\0\6\376Z\0\v\2554\0\rv\257\0\0\2\235\236\f{oE\277\0\0\0\0\0\0”…, 16384, 7509278720) = 16384 <0.006598>[pid 1822] 09:27:48.939869 pread(62, “6\3310\337\0\6\304\33\0\fo\303\0\fw\352\0\0\2\235\234\266\350\310E\277\0\0\0\0\0\0”…, 16384, 7264976896) = 16384 <0.005361>[pid 1822] 09:27:48.945430 pread(62, “\33\356\224\247\0\v\333\31\0\2\345/\0\f\260\352\0\0\2\235\240}5{E\277\0\0\0\0\0\0”…, 16384, 12730122240) = 16384 <0.007184>[pid 1822] 09:27:48.952860 pread(62, “\feYv\0\3\221\304\0\4\262k\0\0\320\362\0\0\2\235\240\276\243rE\277\0\0\0\0\0\0”…, 16384, 3832610816) = 16384 <0.018063>[pid 1822] 09:27:48.971080 pread(62, “\246i\376\242\0\3g\24\0\v\305\350\0\10?\231\0\0\2\235\240\276\243\203E\277\0\0\0\0\0\0”…, 16384, 3653566464) = 16384 <0.012724>[pid 1822] 09:27:48.984181 pread(62, “\343\375c\265\0\17\32\343\0\6g\233\0\v\311u\0\0\2\235\237\213\371\227E\277\0\0\0\0\0\0”…, 16384, 16218898432) = 16384 <0.008403>[pid 1822] 09:27:48.992884 pread(62, “G$\367&\0\f$Y\0\0V\210\0\5[\333\0\0\2\235\240\20\354\27E\277\0\0\0\0\0\0”…, 16384, 13037355008) = 16384 <0.006746>[pid 1822] 09:27:48.999827 pread(62, “\341\350\223\263\0\16\213’\0\r\207|\0\1\3169\0\0\2\235\241\257r\336E\277\0\0\0\0\0\0”…, 16384, 15616032768) = 16384 <0.012626>[pid 1822] 09:27:49.013079 pread(62, “\2\324\360R\0\nw\222\0\5\321?\0\5\316\315\0\0\2\235\225#\242\225E\277\0\0\0\0\0\0”…, 16384, 11238932480) = 16384 <0.002749>[pid 1822] 09:27:49.015988 pread(62, “\331\0\32\223\0\16\355\261\0\7B>\0\2\354\263\0\0\2\235\231b\246^E\277\0\0\0\0\0\0”…, 16384, 16029335552) = 16384 <0.005944>[pid 1822] 09:27:49.022183 pread(62, “\352\214\366’\0\v\265~\0\5z\377\0\1E0\0\0\2\235\241\366?\271E\277\0\0\0\0\0\0”…, 16384, 12572393472) = 16384 <0.011568>[pid 1822] 09:27:49.033911 pread(62, “\25l\336\251\0\r\f\373\0\7\234G\0\f\210a\0\0\2\235\235\232\252YE\277\0\0\0\0\0\0”…, 16384, 14013087744) = 16384 <0.006574>[pid 1822] 09:27:49.040645 pread(62, <unfinished …>[pid 1791] 09:27:49.045834 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.498245>[pid 1791] 09:27:49.045878 io_getevents(140159307513856, 1, 256, <unfinished …>[pid 1822] 09:27:49.046454 <… pread resumed> “N\236\240\300\0\t\351\213\0\6z\376\0\17\353\272\0\0\2\235\230\301\\367E\277\0\0\0\0\0\0”…, 16384, 10643226624) = 16384 <0.005791>[pid 1822] 09:27:49.047004 pread(62, “D,\34\367\0\fP9\0\17\32\331\0\16G:\0\0\2\235\235\326\303\225E\277\0\0\0\0\0\0”…, 16384, 13221380096) = 16384 <0.002810>[pid 1790] 09:27:49.049862 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.498978>[pid 1788] 09:27:49.049887 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.497530>[pid 1790] 09:27:49.049925 io_getevents(140159307526144, 1, 256, <unfinished …>[pid 1788] 09:27:49.049953 io_getevents(140159307550720, 1, 256, <unfinished …>[pid 1822] 09:27:49.050489 pread(62, “\223\251\3054\0\rU\317\0\10g\250\0\4I,\0\0\2\235\225\367"\376E\277\0\0\0\0\0\0”…, 16384, 14318551040) = 16384 <0.005150>[pid 1822] 09:27:49.055796 pread(62, <unfinished …>[pid 1789] 09:27:49.057880 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.499910>[pid 1789] 09:27:49.057918 io_getevents(140159307538432, 1, 256, <unfinished …>[pid 1822] 09:27:49.066305 <… pread resumed> “\362\223\5\227\0\vt\200\0\4P\374\0\7\343f\0\0\2\235\240\260\361\361E\277\0\0\0\0\0\0”…, 16384, 12299796480) = 16384 <0.010491>[pid 1822] 09:27:49.066452 pread(62, “v\217\304\320\0\4P\374\0\f7\246\0\vt\200\0\0\2\235\240\260\361\361E\277\0\0\0\0\0\0”…, 16384, 4634640384) = 16384 <0.010731>[pid 1822] 09:27:49.077317 pread(62, <unfinished …>[pid 1820] 09:27:49.079178 <… select resumed> ) = 0 (Timeout) <0.952597>[pid 1820] 09:27:49.079208 select(0, NULL, NULL, NULL, {5, 0} <unfinished …>[pid 1822] 09:27:49.082915 <… pread resumed> “8\337qE\0\7\343f\0\vt\200\0\0p\37\0\0\2\235\240\357H\260E\277\0\0\0\0\0\0”…, 16384, 8469970944) = 16384 <0.005580>[pid 1822] 09:27:49.083079 pread(62, “\361\342\246Y\0\n\255\356\0\2\373\204\0\6\265b\0\0\2\235\240\260\362\22E\277\0\0\0\0\0\0”…, 16384, 11466932224) = 16384 <0.010566>[pid 1822] 09:27:49.093792 pread(62, “\23\252S~\0\2\373\204\0\7e4\0\n\255\356\0\0\2\235\240\260\362\22E\277\0\0\0\0\0\0”…, 16384, 3202416640) = 16384 <0.007416>[pid 1822] 09:27:49.101343 pread(62, “?\nR\10\0\6\265b\0\n\255\356\0\rl_\0\0\2\235\240\357H\301E\277\0\0\0\0\0\0”…, 16384, 7203225600) = 16384 <0.007612>[pid 1822] 09:27:49.109147 pread(62, “B\341\334\240\0\5\240\v\0\5\370<\0\20\22\r\0\0\2\235\240b\237\20E\277\0\0\0\0\0\0”…, 16384, 6039977984) = 16384 <0.008258>[pid 1822] 09:27:49.117564 pread(62, “2\360$\351\0\7\370\204\0\1t\324\0\5\\222\0\0\2\235\240b\237!E\277\0\0\0\0\0\0”…, 16384, 8558542848) = 16384 <0.008407>[pid 1787] 09:27:49.126015 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.501102>[pid 1786] 09:27:49.126039 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.501105>[pid 1787] 09:27:49.126068 io_getevents(140159307763712, 1, 256, <unfinished …>[pid 1786] 09:27:49.126093 io_getevents(140159307776000, 1, 256, <unfinished …>[pid 1785] 09:27:49.126114 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.501105>[pid 1784] 09:27:49.126136 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.501109>[pid 1785] 09:27:49.126163 io_getevents(140159307788288, 1, 256, <unfinished …>[pid 1784] 09:27:49.126189 io_getevents(140159307800576, 1, 256, <unfinished …>[pid 1783] 09:27:49.126209 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.501111>[pid 1782] 09:27:49.126231 <… io_getevents resumed> {}{0, 500000000}) = 0 <0.501114>[pid 1783] 09:27:49.126258 io_getevents(140159308013568, 1, 256, <unfinished …>[pid 1782] 09:27:49.126284 io_getevents(140159308025856, 1, 256, <unfinished …>[pid 1822] 09:27:49.126423 pread(4, “L/\212\300\0\1\37\217\0\0\0\0\0\0\0\0\0\0\2\230\317\230\233P\0\2\0\0\0\0\0\0”…, 16384, 1206108160) = 16384 <0.010693>