1.1.9: test suite issue: axfer/test -> container-test loops forever

[kloczek]

Looks like it is some issue with axfer/test -> container-test.
This test cannot finishe and looks like it is looping and cannot finish.
Just in case: nothing is written on stdout/stderr or container-test.log. axfer/test/hoge file is constantly written and deleted.

[tkloczko@domek test]$ strace -f /bin/sh ../../test-driver --test-name container-test --log-file container-test.log --trs-file container-test.trs --color-tests yes --enable-hard-errors yes --expect-failure no -- ./container-test
execve("/bin/sh", ["/bin/sh", "../../test-driver", "--test-name", "container-test", "--log-file", "container-test.log", "--trs-file", "container-test.trs", "--color-tests", "yes", "--enable-hard-errors", "yes", "--expect-failure", "no", "--", "./container-test"], 0x7fff8aab0350 /* 67 vars */) = 0
brk(NULL)                               = 0x559a60017000
arch_prctl(0x3001 /* ARCH_??? */, 0x7ffce069bb50) = -1 EINVAL (Invalid argument)
access("/etc/ld.so.preload", R_OK)      = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=188179, ...}) = 0
mmap(NULL, 188179, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fd3e422c000
close(3)                                = 0
openat(AT_FDCWD, "/lib64/libtinfo.so.6", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\363\0\0\0\0\0\0"..., 832) = 832
lseek(3, 169216, SEEK_SET)              = 169216
read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32
fstat(3, {st_mode=S_IFREG|0755, st_size=285768, ...}) = 0
mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fd3e422a000
lseek(3, 169216, SEEK_SET)              = 169216
read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32
mmap(NULL, 190848, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fd3e41fb000
mmap(0x7fd3e4209000, 61440, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xe000) = 0x7fd3e4209000
mmap(0x7fd3e4218000, 53248, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1d000) = 0x7fd3e4218000
mmap(0x7fd3e4225000, 20480, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x29000) = 0x7fd3e4225000
close(3)                                = 0
openat(AT_FDCWD, "/lib64/libdl.so.2", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0p\"\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=56640, ...}) = 0
mmap(NULL, 24688, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fd3e41f4000
mmap(0x7fd3e41f6000, 8192, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7fd3e41f6000
mmap(0x7fd3e41f8000, 4096, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x4000) = 0x7fd3e41f8000
mmap(0x7fd3e41f9000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x4000) = 0x7fd3e41f9000
mmap(0x7fd3e41fa000, 112, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7fd3e41fa000
close(3)                                = 0
openat(AT_FDCWD, "/lib64/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200p\2\0\0\0\0\0"..., 832) = 832
lseek(3, 64, SEEK_SET)                  = 64
read(3, "\6\0\0\0\4\0\0\0@\0\0\0\0\0\0\0@\0\0\0\0\0\0\0@\0\0\0\0\0\0\0"..., 784) = 784
lseek(3, 848, SEEK_SET)                 = 848
read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32
lseek(3, 880, SEEK_SET)                 = 880
read(3, "\4\0\0\0\24\0\0\0\3\0\0\0GNU\0\0052`U\224\217\3<\204\4\231e\235\313\324 "..., 68) = 68
fstat(3, {st_mode=S_IFREG|0755, st_size=5577040, ...}) = 0
lseek(3, 64, SEEK_SET)                  = 64
read(3, "\6\0\0\0\4\0\0\0@\0\0\0\0\0\0\0@\0\0\0\0\0\0\0@\0\0\0\0\0\0\0"..., 784) = 784
lseek(3, 848, SEEK_SET)                 = 848
read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32
lseek(3, 880, SEEK_SET)                 = 880
read(3, "\4\0\0\0\24\0\0\0\3\0\0\0GNU\0\0052`U\224\217\3<\204\4\231e\235\313\324 "..., 68) = 68
mmap(NULL, 1852600, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fd3e402f000
mprotect(0x7fd3e4054000, 1662976, PROT_NONE) = 0
mmap(0x7fd3e4054000, 1359872, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x25000) = 0x7fd3e4054000
mmap(0x7fd3e41a0000, 299008, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x171000) = 0x7fd3e41a0000
mmap(0x7fd3e41ea000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1ba000) = 0x7fd3e41ea000
mmap(0x7fd3e41f0000, 13496, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7fd3e41f0000
close(3)                                = 0
mmap(NULL, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fd3e402c000
arch_prctl(ARCH_SET_FS, 0x7fd3e402c740) = 0
mprotect(0x7fd3e41ea000, 12288, PROT_READ) = 0
mprotect(0x7fd3e41f9000, 4096, PROT_READ) = 0
mprotect(0x7fd3e4225000, 16384, PROT_READ) = 0
mprotect(0x559a5eca6000, 16384, PROT_READ) = 0
mprotect(0x7fd3e4284000, 4096, PROT_READ) = 0
munmap(0x7fd3e422c000, 188179)          = 0
openat(AT_FDCWD, "/dev/tty", O_RDWR|O_NONBLOCK) = 3
close(3)                                = 0
brk(NULL)                               = 0x559a60017000
brk(0x559a60038000)                     = 0x559a60038000
brk(NULL)                               = 0x559a60038000
openat(AT_FDCWD, "/usr/lib/locale/locale-archive", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/share/locale/locale.alias", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=2997, ...}) = 0
read(3, "# Locale name alias data base.\n#"..., 4096) = 2997
read(3, "", 4096)                       = 0
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/locale/en_GB.UTF-8/LC_IDENTIFICATION", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/lib/locale/en_GB.utf8/LC_IDENTIFICATION", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=373, ...}) = 0
mmap(NULL, 373, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fd3e4283000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib64/gconv/gconv-modules.cache", O_RDONLY) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=26998, ...}) = 0
mmap(NULL, 26998, PROT_READ, MAP_SHARED, 3, 0) = 0x7fd3e4253000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/locale/en_GB.utf8/LC_MEASUREMENT", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=23, ...}) = 0
mmap(NULL, 23, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fd3e4252000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/locale/en_GB.UTF-8/LC_TELEPHONE", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/lib/locale/en_GB.utf8/LC_TELEPHONE", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=56, ...}) = 0
mmap(NULL, 56, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fd3e4251000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/locale/en_GB.UTF-8/LC_ADDRESS", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/lib/locale/en_GB.utf8/LC_ADDRESS", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=158, ...}) = 0
mmap(NULL, 158, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fd3e4250000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/locale/en_GB.UTF-8/LC_NAME", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/lib/locale/en_GB.utf8/LC_NAME", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=77, ...}) = 0
mmap(NULL, 77, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fd3e424f000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/locale/en_GB.utf8/LC_PAPER", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=34, ...}) = 0
mmap(NULL, 34, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fd3e424e000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/locale/en_GB.UTF-8/LC_MESSAGES", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/lib/locale/en_GB.utf8/LC_MESSAGES", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFDIR|0755, st_size=30, ...}) = 0
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/locale/en_GB.utf8/LC_MESSAGES/SYS_LC_MESSAGES", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=57, ...}) = 0
mmap(NULL, 57, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fd3e424d000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/locale/en_GB.utf8/LC_MONETARY", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=290, ...}) = 0
mmap(NULL, 290, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fd3e424c000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/locale/en_GB.UTF-8/LC_COLLATE", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/lib/locale/en_GB.utf8/LC_COLLATE", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=2586930, ...}) = 0
mmap(NULL, 2586930, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fd3e3db4000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/locale/en_GB.utf8/LC_TIME", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=3332, ...}) = 0
mmap(NULL, 3332, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fd3e424b000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/locale/en_GB.utf8/LC_NUMERIC", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=54, ...}) = 0
mmap(NULL, 54, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fd3e424a000
close(3)                                = 0
openat(AT_FDCWD, "/usr/lib/locale/en_GB.UTF-8/LC_CTYPE", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/lib/locale/en_GB.utf8/LC_CTYPE", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=340008, ...}) = 0
mmap(NULL, 340008, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fd3e3d60000
close(3)                                = 0
getuid()                                = 1000
getgid()                                = 1000
geteuid()                               = 1000
getegid()                               = 1000
rt_sigprocmask(SIG_BLOCK, NULL, [], 8)  = 0
ioctl(-1, TIOCGPGRP, 0x7ffce069b9b4)    = -1 EBADF (Bad file descriptor)
sysinfo({uptime=447376, loads=[64256, 115168, 149856], totalram=8272330752, freeram=130670592, sharedram=986529792, bufferram=106496, totalswap=0, freeswap=0, procs=1208, totalhigh=0, freehigh=0, mem_unit=1}) = 0
rt_sigaction(SIGCHLD, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0
rt_sigaction(SIGCHLD, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7fd3e4069f40}, 8) = 0
rt_sigaction(SIGINT, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0
rt_sigaction(SIGINT, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
rt_sigaction(SIGQUIT, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0
rt_sigaction(SIGQUIT, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
rt_sigaction(SIGTSTP, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0
rt_sigaction(SIGTSTP, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
rt_sigaction(SIGTTIN, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0
rt_sigaction(SIGTTIN, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
rt_sigaction(SIGTTOU, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0
rt_sigaction(SIGTTOU, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
rt_sigprocmask(SIG_BLOCK, NULL, [], 8)  = 0
rt_sigaction(SIGQUIT, {sa_handler=SIG_IGN, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
uname({sysname="Linux", nodename="domek", ...}) = 0
rt_sigprocmask(SIG_BLOCK, NULL, [], 8)  = 0
rt_sigprocmask(SIG_BLOCK, NULL, [], 8)  = 0
stat("/home/tkloczko/rpmbuild/BUILD/alsa-utils-1.1.9/axfer/test", {st_mode=S_IFDIR|0755, st_size=532, ...}) = 0
stat(".", {st_mode=S_IFDIR|0755, st_size=532, ...}) = 0
stat("/home", {st_mode=S_IFDIR|0755, st_size=16, ...}) = 0
stat("/home/tkloczko", {st_mode=S_IFDIR|0755, st_size=2148, ...}) = 0
stat("/home/tkloczko/rpmbuild", {st_mode=S_IFDIR|0755, st_size=1464, ...}) = 0
stat("/home/tkloczko/rpmbuild/BUILD", {st_mode=S_IFDIR|0755, st_size=2344, ...}) = 0
stat("/home/tkloczko/rpmbuild/BUILD/alsa-utils-1.1.9", {st_mode=S_IFDIR|0755, st_size=966, ...}) = 0
stat("/home/tkloczko/rpmbuild/BUILD/alsa-utils-1.1.9/axfer", {st_mode=S_IFDIR|0755, st_size=1634, ...}) = 0
stat("/home/tkloczko/rpmbuild/BUILD/alsa-utils-1.1.9/axfer/test", {st_mode=S_IFDIR|0755, st_size=532, ...}) = 0
stat("/home/tkloczko/rpmbuild/BUILD/alsa-utils-1.1.9/axfer", {st_mode=S_IFDIR|0755, st_size=1634, ...}) = 0
getpid()                                = 24893
getppid()                               = 24890
getpid()                                = 24893
getpgrp()                               = 24890
ioctl(2, TIOCGPGRP, [24890])            = 0
rt_sigaction(SIGCHLD, {sa_handler=0x559a5ebecd60, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7fd3e4069f40}, 8) = 0
prlimit64(0, RLIMIT_NPROC, NULL, {rlim_cur=31438, rlim_max=31438}) = 0
openat(AT_FDCWD, "/usr/lib/locale/en_GB.UTF-8/LC_MEASUREMENT", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/lib/locale/en_GB.UTF-8/LC_PAPER", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/lib/locale/en_GB.UTF-8/LC_MONETARY", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/lib/locale/en_GB.UTF-8/LC_TIME", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/usr/lib/locale/en_GB.UTF-8/LC_NUMERIC", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
rt_sigprocmask(SIG_BLOCK, NULL, [], 8)  = 0
openat(AT_FDCWD, "../../test-driver", O_RDONLY) = 3
stat("../../test-driver", {st_mode=S_IFREG|0755, st_size=4645, ...}) = 0
ioctl(3, TCGETS, 0x7ffce069b940)        = -1 ENOTTY (Inappropriate ioctl for device)
lseek(3, 0, SEEK_CUR)                   = 0
read(3, "#!/usr/bin/sh\n# test-driver - ba"..., 80) = 80
lseek(3, 0, SEEK_SET)                   = 0
prlimit64(0, RLIMIT_NOFILE, NULL, {rlim_cur=1024, rlim_max=512*1024}) = 0
fcntl(255, F_GETFD)                     = -1 EBADF (Bad file descriptor)
dup2(3, 255)                            = 255
close(3)                                = 0
fcntl(255, F_SETFD, FD_CLOEXEC)         = 0
fcntl(255, F_GETFL)                     = 0x8000 (flags O_RDONLY|O_LARGEFILE)
fstat(255, {st_mode=S_IFREG|0755, st_size=4645, ...}) = 0
lseek(255, 0, SEEK_CUR)                 = 0
read(255, "#!/usr/bin/sh\n# test-driver - ba"..., 4645) = 4645
rt_sigaction(SIGHUP, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0
rt_sigaction(SIGHUP, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
rt_sigprocmask(SIG_BLOCK, [HUP], [], 8) = 0
rt_sigaction(SIGHUP, {sa_handler=0x559a5ec06c40, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
rt_sigprocmask(SIG_BLOCK, [INT], [], 8) = 0
rt_sigaction(SIGINT, {sa_handler=0x559a5ec06c40, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
rt_sigaction(SIGPIPE, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0
rt_sigaction(SIGPIPE, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
rt_sigprocmask(SIG_BLOCK, [PIPE], [], 8) = 0
rt_sigaction(SIGPIPE, {sa_handler=0x559a5ec06c40, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
rt_sigaction(SIGTERM, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=0}, 8) = 0
rt_sigaction(SIGTERM, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
rt_sigprocmask(SIG_BLOCK, [TERM], [], 8) = 0
rt_sigaction(SIGTERM, {sa_handler=0x559a5ec06c40, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
rt_sigprocmask(SIG_BLOCK, [INT CHLD], [], 8) = 0
lseek(255, -1344, SEEK_CUR)             = 3301
clone(strace: Process 24894 attached
child_stack=NULL, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7fd3e402ca10) = 24894
[pid 24894] getpid( <unfinished ...>
[pid 24893] rt_sigprocmask(SIG_SETMASK, [],  <unfinished ...>
[pid 24894] <... getpid resumed>)       = 24894
[pid 24893] <... rt_sigprocmask resumed>NULL, 8) = 0
[pid 24894] close(255 <unfinished ...>
[pid 24893] rt_sigprocmask(SIG_BLOCK, [CHLD],  <unfinished ...>
[pid 24894] <... close resumed>)        = 0
[pid 24893] <... rt_sigprocmask resumed>[], 8) = 0
[pid 24894] rt_sigprocmask(SIG_SETMASK, [],  <unfinished ...>
[pid 24893] rt_sigprocmask(SIG_SETMASK, [],  <unfinished ...>
[pid 24894] <... rt_sigprocmask resumed>NULL, 8) = 0
[pid 24893] <... rt_sigprocmask resumed>NULL, 8) = 0
[pid 24894] rt_sigaction(SIGTSTP, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40},  <unfinished ...>
[pid 24893] rt_sigprocmask(SIG_BLOCK, [CHLD],  <unfinished ...>
[pid 24894] <... rt_sigaction resumed>{sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
[pid 24893] <... rt_sigprocmask resumed>[], 8) = 0
[pid 24894] rt_sigaction(SIGTTIN, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40},  <unfinished ...>
[pid 24893] rt_sigaction(SIGINT, {sa_handler=0x559a5ebe9810, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40},  <unfinished ...>
[pid 24894] <... rt_sigaction resumed>{sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
[pid 24893] <... rt_sigaction resumed>{sa_handler=0x559a5ec06c40, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
[pid 24894] rt_sigaction(SIGTTOU, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40},  <unfinished ...>
[pid 24893] wait4(-1,  <unfinished ...>
[pid 24894] <... rt_sigaction resumed>{sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
[pid 24894] rt_sigaction(SIGHUP, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=0x559a5ec06c40, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
[pid 24894] rt_sigaction(SIGINT, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=0x559a5ec06c40, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
[pid 24894] rt_sigaction(SIGQUIT, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=SIG_IGN, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
[pid 24894] rt_sigaction(SIGPIPE, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=0x559a5ec06c40, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
[pid 24894] rt_sigaction(SIGTERM, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, {sa_handler=0x559a5ec06c40, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x7fd3e4069f40}, 8) = 0
[pid 24894] rt_sigaction(SIGCHLD, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7fd3e4069f40}, {sa_handler=0x559a5ebecd60, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART, sa_restorer=0x7fd3e4069f40}, 8) = 0
[pid 24894] openat(AT_FDCWD, "container-test.log", O_WRONLY|O_CREAT|O_TRUNC, 0666) = 3
[pid 24894] dup2(3, 1)                  = 1
[pid 24894] close(3)                    = 0
[pid 24894] dup2(1, 2)                  = 2
[pid 24894] fcntl(1, F_GETFD)           = 0
[pid 24894] execve("./container-test", ["./container-test"], 0x559a60029510 /* 67 vars */) = 0
[pid 24894] brk(NULL)                   = 0x55711c860000
[pid 24894] arch_prctl(0x3001 /* ARCH_??? */, 0x7ffff5c380a0) = -1 EINVAL (Invalid argument)
[pid 24894] access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
[pid 24894] openat(AT_FDCWD, "/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
[pid 24894] fstat(3, {st_mode=S_IFREG|0644, st_size=188179, ...}) = 0
[pid 24894] mmap(NULL, 188179, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f302eae3000
[pid 24894] close(3)                    = 0
[pid 24894] openat(AT_FDCWD, "/lib64/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
[pid 24894] read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200p\2\0\0\0\0\0"..., 832) = 832
[pid 24894] lseek(3, 64, SEEK_SET)      = 64
[pid 24894] read(3, "\6\0\0\0\4\0\0\0@\0\0\0\0\0\0\0@\0\0\0\0\0\0\0@\0\0\0\0\0\0\0"..., 784) = 784
[pid 24894] lseek(3, 848, SEEK_SET)     = 848
[pid 24894] read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32
[pid 24894] lseek(3, 880, SEEK_SET)     = 880
[pid 24894] read(3, "\4\0\0\0\24\0\0\0\3\0\0\0GNU\0\0052`U\224\217\3<\204\4\231e\235\313\324 "..., 68) = 68
[pid 24894] fstat(3, {st_mode=S_IFREG|0755, st_size=5577040, ...}) = 0
[pid 24894] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f302eae1000
[pid 24894] lseek(3, 64, SEEK_SET)      = 64
[pid 24894] read(3, "\6\0\0\0\4\0\0\0@\0\0\0\0\0\0\0@\0\0\0\0\0\0\0@\0\0\0\0\0\0\0"..., 784) = 784
[pid 24894] lseek(3, 848, SEEK_SET)     = 848
[pid 24894] read(3, "\4\0\0\0\20\0\0\0\5\0\0\0GNU\0\2\0\0\300\4\0\0\0\3\0\0\0\0\0\0\0", 32) = 32
[pid 24894] lseek(3, 880, SEEK_SET)     = 880
[pid 24894] read(3, "\4\0\0\0\24\0\0\0\3\0\0\0GNU\0\0052`U\224\217\3<\204\4\231e\235\313\324 "..., 68) = 68
[pid 24894] mmap(NULL, 1852600, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f302e91c000
[pid 24894] mprotect(0x7f302e941000, 1662976, PROT_NONE) = 0
[pid 24894] mmap(0x7f302e941000, 1359872, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x25000) = 0x7f302e941000
[pid 24894] mmap(0x7f302ea8d000, 299008, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x171000) = 0x7f302ea8d000
[pid 24894] mmap(0x7f302ead7000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1ba000) = 0x7f302ead7000
[pid 24894] mmap(0x7f302eadd000, 13496, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f302eadd000
[pid 24894] close(3)                    = 0
[pid 24894] openat(AT_FDCWD, "/lib64/libasound.so.2", O_RDONLY|O_CLOEXEC) = 3
[pid 24894] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\260'\3\0\0\0\0\0"..., 832) = 832
[pid 24894] fstat(3, {st_mode=S_IFREG|0755, st_size=1157768, ...}) = 0
[pid 24894] mmap(NULL, 955152, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f302e832000
[pid 24894] mmap(0x7f302e85f000, 552960, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2d000) = 0x7f302e85f000
[pid 24894] mmap(0x7f302e8e6000, 184320, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xb4000) = 0x7f302e8e6000
[pid 24894] mmap(0x7f302e913000, 36864, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xe0000) = 0x7f302e913000
[pid 24894] close(3)                    = 0
[pid 24894] openat(AT_FDCWD, "/lib64/libm.so.6", O_RDONLY|O_CLOEXEC) = 3
[pid 24894] read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220\363\0\0\0\0\0\0"..., 832) = 832
[pid 24894] fstat(3, {st_mode=S_IFREG|0755, st_size=3315312, ...}) = 0
[pid 24894] mmap(NULL, 1331216, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f302e6ec000
[pid 24894] mmap(0x7f302e6fb000, 638976, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xf000) = 0x7f302e6fb000
[pid 24894] mmap(0x7f302e797000, 626688, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xab000) = 0x7f302e797000
[pid 24894] mmap(0x7f302e830000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x143000) = 0x7f302e830000
[pid 24894] close(3)                    = 0
[pid 24894] openat(AT_FDCWD, "/lib64/libdl.so.2", O_RDONLY|O_CLOEXEC) = 3
[pid 24894] read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0p\"\0\0\0\0\0\0"..., 832) = 832
[pid 24894] fstat(3, {st_mode=S_IFREG|0755, st_size=56640, ...}) = 0
[pid 24894] mmap(NULL, 24688, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f302e6e5000
[pid 24894] mmap(0x7f302e6e7000, 8192, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7f302e6e7000
[pid 24894] mmap(0x7f302e6e9000, 4096, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x4000) = 0x7f302e6e9000
[pid 24894] mmap(0x7f302e6ea000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x4000) = 0x7f302e6ea000
[pid 24894] mmap(0x7f302e6eb000, 112, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f302e6eb000
[pid 24894] close(3)                    = 0
[pid 24894] openat(AT_FDCWD, "/lib64/libpthread.so.0", O_RDONLY|O_CLOEXEC) = 3
[pid 24894] read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\202\0\0\0\0\0\0"..., 832) = 832
[pid 24894] lseek(3, 824, SEEK_SET)     = 824
[pid 24894] read(3, "\4\0\0\0\24\0\0\0\3\0\0\0GNU\0=K\6\363\272\306<\320\35\37vOsr\212\341"..., 68) = 68
[pid 24894] fstat(3, {st_mode=S_IFREG|0755, st_size=695360, ...}) = 0
[pid 24894] lseek(3, 824, SEEK_SET)     = 824
[pid 24894] read(3, "\4\0\0\0\24\0\0\0\3\0\0\0GNU\0=K\6\363\272\306<\320\35\37vOsr\212\341"..., 68) = 68
[pid 24894] mmap(NULL, 131552, PROT_READ, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f302e6c4000
[pid 24894] mmap(0x7f302e6cb000, 61440, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x7000) = 0x7f302e6cb000
[pid 24894] mmap(0x7f302e6da000, 20480, PROT_READ, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x16000) = 0x7f302e6da000
[pid 24894] mmap(0x7f302e6df000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1a000) = 0x7f302e6df000
[pid 24894] mmap(0x7f302e6e1000, 12768, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f302e6e1000
[pid 24894] close(3)                    = 0
[pid 24894] mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f302e6c2000
[pid 24894] arch_prctl(ARCH_SET_FS, 0x7f302e6c32c0) = 0
[pid 24894] mprotect(0x7f302ead7000, 12288, PROT_READ) = 0
[pid 24894] mprotect(0x7f302e6df000, 4096, PROT_READ) = 0
[pid 24894] mprotect(0x7f302e6ea000, 4096, PROT_READ) = 0
[pid 24894] mprotect(0x7f302e830000, 4096, PROT_READ) = 0
[pid 24894] mprotect(0x7f302e913000, 32768, PROT_READ) = 0
[pid 24894] mprotect(0x55711c760000, 4096, PROT_READ) = 0
[pid 24894] mprotect(0x7f302eb3b000, 4096, PROT_READ) = 0
[pid 24894] munmap(0x7f302eae3000, 188179) = 0
[pid 24894] set_tid_address(0x7f302e6c3590) = 24894
[pid 24894] set_robust_list(0x7f302e6c35a0, 24) = 0
[pid 24894] rt_sigaction(SIGRTMIN, {sa_handler=0x7f302e6cbc50, sa_mask=[], sa_flags=SA_RESTORER|SA_SIGINFO, sa_restorer=0x7f302e6d7d40}, NULL, 8) = 0
[pid 24894] rt_sigaction(SIGRT_1, {sa_handler=0x7f302e6cbcf0, sa_mask=[], sa_flags=SA_RESTORER|SA_RESTART|SA_SIGINFO, sa_restorer=0x7f302e6d7d40}, NULL, 8) = 0
[pid 24894] rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
[pid 24894] prlimit64(0, RLIMIT_STACK, NULL, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
[pid 24894] openat(AT_FDCWD, "/dev/urandom", O_RDONLY) = 3
[pid 24894] brk(NULL)                   = 0x55711c860000
[pid 24894] brk(0x55711c881000)         = 0x55711c881000
[pid 24894] brk(NULL)                   = 0x55711c881000
[pid 24894] read(3, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25\246\334W\337\222RK\262\200"..., 4500) = 4500
[pid 24894] unlink("hoge")              = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDWR|O_CREAT|O_TRUNC|O_NONBLOCK, 0644) = 4
[pid 24894] write(4, "RIFF\377\377\377\377", 8) = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0D\254\0\0D\254\0\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\333\377\377\377", 8) = 8
[pid 24894] write(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25", 23) = 23
[pid 24894] lseek(4, 0, SEEK_SET)       = 0
[pid 24894] write(4, "RIFF_\0\0\0", 8)  = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0D\254\0\0D\254\0\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\27\0\0\0", 8) = 8
[pid 24894] fsync(4)                    = 0
[pid 24894] close(4)                    = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDONLY|O_NONBLOCK) = 4
[pid 24894] read(4, "RIFF", 4)          = 4
[pid 24894] read(4, "_\0\0\0", 4)       = 4
[pid 24894] read(4, "WAVE", 4)          = 4
[pid 24894] read(4, "fmt \20\0\0\0", 8) = 8
[pid 24894] read(4, "\1\0\1\0D\254\0\0D\254\0\0\1\0\10\0", 16) = 16
[pid 24894] read(4, "data\27\0\0\0", 8) = 8
[pid 24894] read(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25", 23) = 23
[pid 24894] close(4)                    = 0
[pid 24894] unlink("hoge")              = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDWR|O_CREAT|O_TRUNC|O_NONBLOCK, 0644) = 4
[pid 24894] write(4, "RIFF\377\377\377\377", 8) = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\200\273\0\0\200\273\0\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\333\377\377\377", 8) = 8
[pid 24894] write(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25", 23) = 23
[pid 24894] lseek(4, 0, SEEK_SET)       = 0
[pid 24894] write(4, "RIFF_\0\0\0", 8)  = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\200\273\0\0\200\273\0\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\27\0\0\0", 8) = 8
[pid 24894] fsync(4)                    = 0
[pid 24894] close(4)                    = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDONLY|O_NONBLOCK) = 4
[pid 24894] read(4, "RIFF", 4)          = 4
[pid 24894] read(4, "_\0\0\0", 4)       = 4
[pid 24894] read(4, "WAVE", 4)          = 4
[pid 24894] read(4, "fmt \20\0\0\0", 8) = 8
[pid 24894] read(4, "\1\0\1\0\200\273\0\0\200\273\0\0\1\0\10\0", 16) = 16
[pid 24894] read(4, "data\27\0\0\0", 8) = 8
[pid 24894] read(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25", 23) = 23
[pid 24894] close(4)                    = 0
[pid 24894] unlink("hoge")              = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDWR|O_CREAT|O_TRUNC|O_NONBLOCK, 0644) = 4
[pid 24894] write(4, "RIFF\377\377\377\377", 8) = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\210X\1\0\210X\1\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\333\377\377\377", 8) = 8
[pid 24894] write(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25", 23) = 23
[pid 24894] lseek(4, 0, SEEK_SET)       = 0
[pid 24894] write(4, "RIFF_\0\0\0", 8)  = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\210X\1\0\210X\1\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\27\0\0\0", 8) = 8
[pid 24894] fsync(4)                    = 0
[pid 24894] close(4)                    = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDONLY|O_NONBLOCK) = 4
[pid 24894] read(4, "RIFF", 4)          = 4
[pid 24894] read(4, "_\0\0\0", 4)       = 4
[pid 24894] read(4, "WAVE", 4)          = 4
[pid 24894] read(4, "fmt \20\0\0\0", 8) = 8
[pid 24894] read(4, "\1\0\1\0\210X\1\0\210X\1\0\1\0\10\0", 16) = 16
[pid 24894] read(4, "data\27\0\0\0", 8) = 8
[pid 24894] read(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25", 23) = 23
[pid 24894] close(4)                    = 0
[pid 24894] unlink("hoge")              = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDWR|O_CREAT|O_TRUNC|O_NONBLOCK, 0644) = 4
[pid 24894] write(4, "RIFF\377\377\377\377", 8) = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\0w\1\0\0w\1\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\333\377\377\377", 8) = 8
[pid 24894] write(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25", 23) = 23
[pid 24894] lseek(4, 0, SEEK_SET)       = 0
[pid 24894] write(4, "RIFF_\0\0\0", 8)  = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\0w\1\0\0w\1\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\27\0\0\0", 8) = 8
[pid 24894] fsync(4)                    = 0
[pid 24894] close(4)                    = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDONLY|O_NONBLOCK) = 4
[pid 24894] read(4, "RIFF", 4)          = 4
[pid 24894] read(4, "_\0\0\0", 4)       = 4
[pid 24894] read(4, "WAVE", 4)          = 4
[pid 24894] read(4, "fmt \20\0\0\0", 8) = 8
[pid 24894] read(4, "\1\0\1\0\0w\1\0\0w\1\0\1\0\10\0", 16) = 16
[pid 24894] read(4, "data\27\0\0\0", 8) = 8
[pid 24894] read(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25", 23) = 23
[pid 24894] close(4)                    = 0
[pid 24894] unlink("hoge")              = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDWR|O_CREAT|O_TRUNC|O_NONBLOCK, 0644) = 4
[pid 24894] write(4, "RIFF\377\377\377\377", 8) = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\20\261\2\0\20\261\2\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\333\377\377\377", 8) = 8
[pid 24894] write(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25", 23) = 23
[pid 24894] lseek(4, 0, SEEK_SET)       = 0
[pid 24894] write(4, "RIFF_\0\0\0", 8)  = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\20\261\2\0\20\261\2\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\27\0\0\0", 8) = 8
[pid 24894] fsync(4)                    = 0
[pid 24894] close(4)                    = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDONLY|O_NONBLOCK) = 4
[pid 24894] read(4, "RIFF", 4)          = 4
[pid 24894] read(4, "_\0\0\0", 4)       = 4
[pid 24894] read(4, "WAVE", 4)          = 4
[pid 24894] read(4, "fmt \20\0\0\0", 8) = 8
[pid 24894] read(4, "\1\0\1\0\20\261\2\0\20\261\2\0\1\0\10\0", 16) = 16
[pid 24894] read(4, "data\27\0\0\0", 8) = 8
[pid 24894] read(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25", 23) = 23
[pid 24894] close(4)                    = 0
[pid 24894] unlink("hoge")              = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDWR|O_CREAT|O_TRUNC|O_NONBLOCK, 0644) = 4
[pid 24894] write(4, "RIFF\377\377\377\377", 8) = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\0\356\2\0\0\356\2\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\333\377\377\377", 8) = 8
[pid 24894] write(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25", 23) = 23
[pid 24894] lseek(4, 0, SEEK_SET)       = 0
[pid 24894] write(4, "RIFF_\0\0\0", 8)  = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\0\356\2\0\0\356\2\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\27\0\0\0", 8) = 8
[pid 24894] fsync(4)                    = 0
[pid 24894] close(4)                    = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDONLY|O_NONBLOCK) = 4
[pid 24894] read(4, "RIFF", 4)          = 4
[pid 24894] read(4, "_\0\0\0", 4)       = 4
[pid 24894] read(4, "WAVE", 4)          = 4
[pid 24894] read(4, "fmt \20\0\0\0", 8) = 8
[pid 24894] read(4, "\1\0\1\0\0\356\2\0\0\356\2\0\1\0\10\0", 16) = 16
[pid 24894] read(4, "data\27\0\0\0", 8) = 8
[pid 24894] read(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25", 23) = 23
[pid 24894] close(4)                    = 0
[pid 24894] unlink("hoge")              = 0
[pid 24894] unlink("hoge")              = -1 ENOENT (No such file or directory)
[pid 24894] openat(AT_FDCWD, "hoge", O_RDWR|O_CREAT|O_TRUNC|O_NONBLOCK, 0644) = 4
[pid 24894] write(4, "RIFF\377\377\377\377", 8) = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0D\254\0\0D\254\0\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\333\377\377\377", 8) = 8
[pid 24894] write(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25\246\334W\337\222RK\262\200"..., 1047) = 1047
[pid 24894] lseek(4, 0, SEEK_SET)       = 0
[pid 24894] write(4, "RIFF_\4\0\0", 8)  = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0D\254\0\0D\254\0\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\27\4\0\0", 8) = 8
[pid 24894] fsync(4)                    = 0
[pid 24894] close(4)                    = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDONLY|O_NONBLOCK) = 4
[pid 24894] read(4, "RIFF", 4)          = 4
[pid 24894] read(4, "_\4\0\0", 4)       = 4
[pid 24894] read(4, "WAVE", 4)          = 4
[pid 24894] read(4, "fmt \20\0\0\0", 8) = 8
[pid 24894] read(4, "\1\0\1\0D\254\0\0D\254\0\0\1\0\10\0", 16) = 16
[pid 24894] read(4, "data\27\4\0\0", 8) = 8
[pid 24894] read(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25\246\334W\337\222RK\262\200"..., 1047) = 1047
[pid 24894] close(4)                    = 0
[pid 24894] unlink("hoge")              = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDWR|O_CREAT|O_TRUNC|O_NONBLOCK, 0644) = 4
[pid 24894] write(4, "RIFF\377\377\377\377", 8) = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\200\273\0\0\200\273\0\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\333\377\377\377", 8) = 8
[pid 24894] write(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25\246\334W\337\222RK\262\200"..., 1047) = 1047
[pid 24894] lseek(4, 0, SEEK_SET)       = 0
[pid 24894] write(4, "RIFF_\4\0\0", 8)  = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\200\273\0\0\200\273\0\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\27\4\0\0", 8) = 8
[pid 24894] fsync(4)                    = 0
[pid 24894] close(4)                    = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDONLY|O_NONBLOCK) = 4
[pid 24894] read(4, "RIFF", 4)          = 4
[pid 24894] read(4, "_\4\0\0", 4)       = 4
[pid 24894] read(4, "WAVE", 4)          = 4
[pid 24894] read(4, "fmt \20\0\0\0", 8) = 8
[pid 24894] read(4, "\1\0\1\0\200\273\0\0\200\273\0\0\1\0\10\0", 16) = 16
[pid 24894] read(4, "data\27\4\0\0", 8) = 8
[pid 24894] read(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25\246\334W\337\222RK\262\200"..., 1047) = 1047
[pid 24894] close(4)                    = 0
[pid 24894] unlink("hoge")              = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDWR|O_CREAT|O_TRUNC|O_NONBLOCK, 0644) = 4
[pid 24894] write(4, "RIFF\377\377\377\377", 8) = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\210X\1\0\210X\1\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\333\377\377\377", 8) = 8
[pid 24894] write(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25\246\334W\337\222RK\262\200"..., 1047) = 1047
[pid 24894] lseek(4, 0, SEEK_SET)       = 0
[pid 24894] write(4, "RIFF_\4\0\0", 8)  = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\210X\1\0\210X\1\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\27\4\0\0", 8) = 8
[pid 24894] fsync(4)                    = 0
[pid 24894] close(4)                    = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDONLY|O_NONBLOCK) = 4
[pid 24894] read(4, "RIFF", 4)          = 4
[pid 24894] read(4, "_\4\0\0", 4)       = 4
[pid 24894] read(4, "WAVE", 4)          = 4
[pid 24894] read(4, "fmt \20\0\0\0", 8) = 8
[pid 24894] read(4, "\1\0\1\0\210X\1\0\210X\1\0\1\0\10\0", 16) = 16
[pid 24894] read(4, "data\27\4\0\0", 8) = 8
[pid 24894] read(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25\246\334W\337\222RK\262\200"..., 1047) = 1047
[pid 24894] close(4)                    = 0
[pid 24894] unlink("hoge")              = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDWR|O_CREAT|O_TRUNC|O_NONBLOCK, 0644) = 4
[pid 24894] write(4, "RIFF\377\377\377\377", 8) = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\0w\1\0\0w\1\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\333\377\377\377", 8) = 8
[pid 24894] write(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25\246\334W\337\222RK\262\200"..., 1047) = 1047
[pid 24894] lseek(4, 0, SEEK_SET)       = 0
[pid 24894] write(4, "RIFF_\4\0\0", 8)  = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\0w\1\0\0w\1\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\27\4\0\0", 8) = 8
[pid 24894] fsync(4)                    = 0
[pid 24894] close(4)                    = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDONLY|O_NONBLOCK) = 4
[pid 24894] read(4, "RIFF", 4)          = 4
[pid 24894] read(4, "_\4\0\0", 4)       = 4
[pid 24894] read(4, "WAVE", 4)          = 4
[pid 24894] read(4, "fmt \20\0\0\0", 8) = 8
[pid 24894] read(4, "\1\0\1\0\0w\1\0\0w\1\0\1\0\10\0", 16) = 16
[pid 24894] read(4, "data\27\4\0\0", 8) = 8
[pid 24894] read(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25\246\334W\337\222RK\262\200"..., 1047) = 1047
[pid 24894] close(4)                    = 0
[pid 24894] unlink("hoge")              = 0
[pid 24894] openat(AT_FDCWD, "hoge", O_RDWR|O_CREAT|O_TRUNC|O_NONBLOCK, 0644) = 4
[pid 24894] write(4, "RIFF\377\377\377\377", 8) = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\20\261\2\0\20\261\2\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\333\377\377\377", 8) = 8
[pid 24894] write(4, "\346\317xj\341.\354\2\213C9\370\240\320\216\3340=\366\27#\257\25\246\334W\337\222RK\262\200"..., 1047) = 1047
[pid 24894] lseek(4, 0, SEEK_SET)       = 0
[pid 24894] write(4, "RIFF_\4\0\0", 8)  = 8
[pid 24894] write(4, "WAVE", 4)         = 4
[pid 24894] write(4, "fmt \20\0\0\0\1\0\1\0\20\261\2\0\20\261\2\0\1\0\10\0", 24) = 24
[pid 24894] write(4, "data\27\4\0\0", 8) = 8
[pid 24894] fsync(4)                    = 0
[pid 24894] close(4)                    = 0

[kloczek]

Above was when I had installed alsa-lib 1.1.8.
When I've upgraded to 1.1.9 things a bit changed.
Previously it was only used one axfer/test/hoge file. Now container-test in the loop generates axfer/test/hoge{,0..31} and when hoge31 is generated everyhing goes to begin starting from hoge file.

If alsa-utils depends on exact version of the alsa-libs (using pkg-config) autoconf should fail not able to find exact version of the alsa-lib.

[takaswie]

@kloczek Against your expectation, these tests require a long time to finish. Please refer to this post.

[alsa-devel] alsa-utils 1.1.8: axfer tests take ages tu run.
https://mailman.alsa-project.org/pipermail/alsa-devel/2019-January/144767.html

Sorry for your inconvenience, but I'd like to leave them as is because at present I have no plan for further work, at least recent 6 months.

[kloczek]

I think that I know how to dramatically speedup that test :)
Just wrote some short wrapper which is executing container-test binary.
Here my resuls:

[tkloczko@barrel test]$ time ./container-test.sh 
/tmp/container-test.YrZP5

real	9m4.616s
user	0m19.684s
sys	8m35.583s

And here is the POC script:

[tkloczko@barrel test]$ cat ./container-test.sh
#!/bin/sh

tmp_dir=$(mktemp -d /tmp/container-test.XXXXX)
cur_dir=$(pwd)

echo ${tmp_dir}
cd ${tmp_dir}
${cur_dir}/container-test
cd ${cur_dir}
rm -rf ${tmp_dir}

All what it does is change current directory to tmpfs and then fire container-test :)
Will try to use this POC script to modify container-test.c.

[kloczek]

Sorry have no time now to finish this today (I must go :)).
Nevertheless I think that test should be modified to use

• mktemp() to obtain random directory name in /tmp
• create directory
• add directory name to *name
• fire tests
• remove temporary directory after all tests

It would be good as well print on the stdout some progress output showing how much already has been done :)

[takaswie]

@kloczek Any patch is always welcome ;)

[kloczek]

I'm still afk and will be busy in next week as Friday I just started new contract.
Nevertheless good to see that you are agree about general method about speedup that test :p

[takaswie]

I note that in some distributions; e.g. Debian/Ubuntu, tmpfs is not mounted to /tmp. /tmp is on usual filesystems and any system service removes the contents of /tmp at normal shutdown.

[takaswie]

Instead of usage of tmpfs, memfd is one of options for this issue but code change is required for axfer/container.c.

[perexg]

It's easier to do this setup in the shell script. I added run-test-in-tmpdir.sh (e1551de).

[takaswie]

@perexg Thanks for your care.

I note that /tmp is not any in-memory filesystem like tmpfs in some Linux distribution, at least Ubuntu.

[perexg]

It's easy to redefine this TMPDIR environment variable, so the script might receive an override. On Fedora:

# mount | grep /tmp
tmpfs on /tmp type tmpfs (rw,nosuid,nodev,seclabel,size=16303124k,nr_inodes=409600,inode64)

[takaswie]

The patchset to reduce test time by 1/28 is now applied. In detail, please refer to my post to alsa-devel:

https://mailman.alsa-project.org/pipermail/alsa-devel/2021-March/181947.html