test: flaky vinyl/gh-3395-read-prepared-uncommitted.test.lua test

[avtikhon]

Tarantool version:
Tarantool 2.6.0-7-g5a856023e8
Target: Linux-x86_64-Debug
Build options: cmake . -DCMAKE_INSTALL_PREFIX=/usr/local -DENABLE_BACKTRACE=ON
Compiler: /usr/bin/cc /usr/bin/c++
C_FLAGS: -fexceptions -funwind-tables -fno-omit-frame-pointer -fno-stack-protector -fno-common -fopenmp -msse2 -fprofile-arcs -ftest-coverage -std=c11 -Wall -Wextra -Wno-strict-aliasing -Wno-char-subscripts -Wno-format-truncation -Wno-gnu-alignof-expression -fno-gnu89-inline -Wno-cast-function-type -Werror
CXX_FLAGS: -fexceptions -funwind-tables -fno-omit-frame-pointer -fno-stack-protector -fno-common -fopenmp -msse2 -fprofile-arcs -ftest-coverage -std=c++11 -Wall -Wextra -Wno-strict-aliasing -Wno-char-subscripts -Wno-format-truncation -Wno-invalid-offsetof -Wno-gnu-alignof-expression -Wno-cast-function-type -Werror

OS version:
Linux (Debian 9)

Bug description:
1.
https://gitlab.com/tarantool/tarantool/-/jobs/647911034#L4541
https://gitlab.com/tarantool/tarantool/-/jobs/645672722#L4467
https://gitlab.com/tarantool/tarantool/-/jobs/759156959#L4849

results file checksum: 82156b1f64522ca82685c56e4803a3f7

[050] --- vinyl/gh-3395-read-prepared-uncommitted.result	Fri Jul 17 18:02:18 2020
[050] +++ vinyl/gh-3395-read-prepared-uncommitted.reject	Tue Jul 21 03:35:29 2020
[050] @@ -215,7 +215,8 @@
[050]  --
[050]  gen(param, state)
[050]   | ---
[050] - | - error: The read view is aborted
[050] + | - <iterator state>
[050] + | - [4, 20]
[050]   | ...
[050]  
[050]  fiber.sleep(0.1)
[050] @@ -224,6 +225,7 @@
[050]  sk:select{20}
[050]   | ---
[050]   | - - [2, 20]
[050] + |   - [3, 20]
[050]   |   - [4, 20]
[050]   | ...
[050]  
[050] 
[050] Last 15 lines of Tarantool Log file [Instance "vinyl"][/builds/WHBm2_Sk/0/tarantool/tarantool/test/var/050_vinyl/vinyl.log]:
[050] 2020-07-21 03:35:27.446 [1349] main/108/vinyl.scheduler I> 515/1: dump completed
[050] 2020-07-21 03:35:27.446 [1349] main/108/vinyl.scheduler I> 515/0: dump started
[050] 2020-07-21 03:35:27.451 [1349] vinyl.dump.0/105/task I> writing `/builds/WHBm2_Sk/0/tarantool/tarantool/test/var/050_vinyl/vinyl/515/0/00000000000000000064.run'
[050] 2020-07-21 03:35:27.704 [1349] vinyl.dump.0/105/task I> writing `/builds/WHBm2_Sk/0/tarantool/tarantool/test/var/050_vinyl/vinyl/515/0/00000000000000000064.index'
[050] 2020-07-21 03:35:28.145 [1349] main/108/vinyl.scheduler I> 515/0: dump completed
[050] 2020-07-21 03:35:28.145 [1349] main/108/vinyl.scheduler I> dumped 196847 bytes in 1.4 s, rate 0.1 MB/s
[050] 2020-07-21 03:35:28.145 [1349] main/124/console/unix/: I> vinyl checkpoint completed
[050] 2020-07-21 03:35:28.962 [1349] main I> removed /builds/WHBm2_Sk/0/tarantool/tarantool/test/var/050_vinyl/vinyl/00000000000000000000.snap
[050] 2020-07-21 03:35:28.966 [1349] wal I> removed /builds/WHBm2_Sk/0/tarantool/tarantool/test/var/050_vinyl/vinyl/00000000000000000582.xlog
[050] 2020-07-21 03:35:28.993 [1349] wal/101/main xlog.c:1216 E> ER_INJECTION: Error injection 'xlog write injection'
[050] 2020-07-21 03:35:28.993 [1349] main/133/lua txn.c:876 E> ER_WAL_IO: Failed to write to disk
[050] 2020-07-21 03:35:28.993 [1349] main/133/lua txn.c:876 E> ER_WAL_IO: Failed to write to disk
[050] 2020-07-21 03:35:28.993 [1349] main/109/vinyl.vylog_flusher xlog.c:1216 E> ER_INJECTION: Error injection 'xlog write injection'
[050] 2020-07-21 03:35:28.993 [1349] main/109/vinyl.vylog_flusher vy_log.c:913 E> failed to flush vylog
[050] 2020-07-21 03:35:28.995 [1349] wal I> removed /builds/WHBm2_Sk/0/tarantool/tarantool/test/var/050_vinyl/vinyl/00000000000000000000.xlog

2. https://gitlab.com/tarantool/tarantool/-/jobs/758902931#L4570

artifacts.zip

results file checksum: 6ab639ce38b94231c6f0be9a8380d2ff

[087] --- vinyl/gh-3395-read-prepared-uncommitted.result	Thu Sep 24 23:25:31 2020
[087] +++ vinyl/gh-3395-read-prepared-uncommitted.reject	Sat Sep 26 11:47:18 2020
[087] @@ -117,8 +117,7 @@
[087]  --
[087]  read_prepared_with_delay(true)
[087]   | ---
[087] - | - - [1, 2]
[087] - |   - [2, 2]
[087] + | - - [2, 2]
[087]   |   - [3, 2]
[087]   | ...
[087]  
[087] @@ -204,7 +203,7 @@
[087]  --
[087]  read_prepared_with_delay(true)
[087]   | ---
[087] - | - [3, 20]
[087] + | - [4, 20]
[087]   | ...
[087]  -- Give WAL thread time to catch up.
[087]  --
[087] 
[087] Last 15 lines of Tarantool Log file [Instance "vinyl"][/builds/4BUsapPU/0/tarantool/tarantool/test/var/087_vinyl/vinyl.log]:
[087] 2020-09-26 11:47:17.765 [29939] main/105/gc I> removed /builds/4BUsapPU/0/tarantool/tarantool/test/var/087_vinyl/vinyl/525/0/00000000000000000128.index
[087] 2020-09-26 11:47:17.765 [29939] main/105/gc I> removed /builds/4BUsapPU/0/tarantool/tarantool/test/var/087_vinyl/vinyl/525/0/00000000000000000128.run
[087] 2020-09-26 11:47:17.776 [29939] main/105/gc I> removed /builds/4BUsapPU/0/tarantool/tarantool/test/var/087_vinyl/vinyl/525/0/00000000000000000124.index
[087] 2020-09-26 11:47:17.776 [29939] main/105/gc I> removed /builds/4BUsapPU/0/tarantool/tarantool/test/var/087_vinyl/vinyl/525/0/00000000000000000124.run
[087] 2020-09-26 11:47:17.777 [29939] main/105/gc I> removed /builds/4BUsapPU/0/tarantool/tarantool/test/var/087_vinyl/vinyl/525/0/00000000000000000126.index
[087] 2020-09-26 11:47:17.778 [29939] main/105/gc I> removed /builds/4BUsapPU/0/tarantool/tarantool/test/var/087_vinyl/vinyl/525/0/00000000000000000126.run
[087] 2020-09-26 11:47:17.778 [29939] wal I> removed /builds/4BUsapPU/0/tarantool/tarantool/test/var/087_vinyl/vinyl/00000000000000000522.xlog
[087] 2020-09-26 11:47:17.783 [29939] wal/101/main xlog.c:1232 E> ER_INJECTION: Error injection 'xlog write injection'
[087] 2020-09-26 11:47:17.783 [29939] main/109/vinyl.vylog_flusher xlog.c:1232 E> ER_INJECTION: Error injection 'xlog write injection'
[087] 2020-09-26 11:47:17.783 [29939] main/109/vinyl.vylog_flusher vy_log.c:913 E> failed to flush vylog
[087] 2020-09-26 11:47:17.783 [29939] main/249/lua txn.c:688 E> ER_WAL_IO: Failed to write to disk
[087] 2020-09-26 11:47:17.783 [29939] main/249/lua txn.c:688 E> ER_WAL_IO: Failed to write to disk
[087] 2020-09-26 11:47:17.960 [29939] wal/101/main xlog.c:1232 E> ER_INJECTION: Error injection 'xlog write injection'
[087] 2020-09-26 11:47:17.986 [29939] main/251/lua txn.c:688 E> ER_WAL_IO: Failed to write to disk
[087] 2020-09-26 11:47:17.986 [29939] main/251/lua txn.c:688 E> ER_WAL_IO: Failed to write to disk

Steps to reproduce:

Optional (but very desirable):

• coredump
• backtrace
• netstat

[avtikhon]

Not reproduced on MCS with SSD neither in Github Actions.