[CRIU] p.haul: mtouch test "Pre-dump failed"
Andrew Vagin
avagin at parallels.com
Fri Mar 28 07:45:24 PDT 2014
On Fri, Mar 28, 2014 at 02:39:33PM +0100, Oscar Fernández wrote:
> Hello Andrew, thanks for your answer.
>
> I'm using the latest version of CRIU ( https://github.com/xemul/criu ).
>
> Find attached the logs you ask for.
>
> Thanks for your help.
Which kernel version do you use?
I found that you problem is reproduced, if a kernel is compiled w/o
following commit:
commit 9d05746e7b16d8565dddbe3200faa1e669d23bbf
Author: Linus Torvalds <torvalds at linux-foundation.org>
Date: Mon Sep 30 08:35:10 2013 -0700
vfs: allow O_PATH file descriptors for fstatfs()
$ git describe 9d05746e7b16d8565dddbe3200faa1e669d23bbf
v3.12-rc4-126-g9d05746
[root at localhost mtouch]# ../../../criu/criu pre-dump -t 689
Error (files.c:424): Can't fstatfs file: Bad file descriptor
Error (cr-dump.c:1356): Pre-dumping files failed (pid: 689)
Error (cr-dump.c:1687): Pre-dumping FAILED.
>
>
> 2014-03-28 9:41 GMT+01:00 Andrew Vagin <avagin at parallels.com>:
>
> On Thu, Mar 27, 2014 at 05:47:32PM +0100, Oscar Fernández wrote:
> > Hello and congratulations for your great work.
> >
> > I'm interested in p.haul and I wanted to see how it works. I found the
> mtouch
> > test as a process live migration example ( https://github.com/xemul/
> p.haul/blob
> > /master/test/mtouch/HOWTO ), I follow all the steps but I get an error
> when
> > executing the command
> >
> > # ./p.haul pid $test_pid $destination_node_ip
>
> Which version of CRIU do you use?
>
> Could you provide more info?
>
> $ lsof -p $test_pid > mem-touch.lsof
> $ strace -fo criu.predump.log ./criu pre-dump -v4 -o pd.log -D tmp -t 945
>
> and send criu.predump.log, mem-touch.lsof, tmp/pd.log to us.
>
> Thanks.
>
> >
> > at the source node.
> >
> > I'm trying to do the migration between two Ubuntu server 13.10 VMs,
> should I
> > try with other distro?
> >
> > The output at the SRC node is:
> >
> > Connecting to target host
> > Initialized shared FS hauler
> > Connecting to CRIU service
> > Preliminary FS migration
> > Starting iterations
> > * Iteration 0
> > making directory /var/local/p.haul-fs/14.03.27-15.35-bvrTFM/img/1
> > Issuing pre-dump command to service
> > Traceback (most recent call last):
> > File "p.haul/p.haul", line 39, in <module>
> > worker.start_migration()
> > File "/home/test/p.haul/p_haul_iters.py", line 100, in start_migration
> > raise Exception("Pre-dump failed")
> > Exception: Pre-dump failed
> >
> > The output at the DST node is:
> >
> > Connected
> > Selecting htype to ('pid', '1403')
> > Making directory /var/local/p.haul-fs/14.03.27-15.35-lI4_j3/img/1
> > Starting page server for iter 1
> > Sending criu rpc req
> > Page server started at 1613
> > Disconnected
> > Stopping page server 1613
> >
> > The criu service log file at SRC node is:
> >
> > (00.017306) The service socket is bound to /var/run/criu_service.socket
> > (00.018764) Waiting for connection...
> > (3032.36109 Connected.
> > (3032.36131 Waiting for connection...
> > (3032.37836 Restrict C/R with 0:0 uid
> > (3032.39072 Worker(pid 1505) exited with 1
> >
> > The criu service log file at DST node is:
> >
> > (00.012570) The service socket is bound to /var/run/criu_service.socket
> > (00.014230) Waiting for connection...
> > (2995.80278 Connected.
> > (2995.80293 Waiting for connection...
> > (2995.80455 Restrict C/R with 0:0 uid
> > (2995.80500 Worker(pid 1556) exited with 0
> >
> > I checked the source code and found that in p_haul_iters.py line 99, the
> value
> > of resp.success is False, so an Exception is raised. I tried to find the
> reason
> > of that failure without success. I'm running all comands in test as root
> and
> > the NFS has all permissions to everybody (777).
> >
> > Any idea of what can be the reason? What distro did you use to make the
> test
> > work?
> >
> > Thank you
> >
> > --
> > Óscar Fernández
> >
>
> > _______________________________________________
> > CRIU mailing list
> > CRIU at openvz.org
> > https://lists.openvz.org/mailman/listinfo/criu
>
>
>
>
>
> --
> Óscar Fernández
> 2471 execve("criu/criu", ["criu/criu", "pre-dump", "-v4", "-o", "pd.log", "tmp", "-t", "1592"], [/* 31 vars */]) = 0
> 2471 brk(0) = 0x13eb000
> 2471 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
> 2471 mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f9a4b11b000
> 2471 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
> 2471 open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
> 2471 fstat(3, {st_mode=S_IFREG|0644, st_size=22643, ...}) = 0
> 2471 mmap(NULL, 22643, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f9a4b115000
> 2471 close(3) = 0
> 2471 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
> 2471 open("/lib/x86_64-linux-gnu/libpthread.so.0", O_RDONLY|O_CLOEXEC) = 3
> 2471 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\360l\0\0\0\0\0\0"..., 832) = 832
> 2471 fstat(3, {st_mode=S_IFREG|0755, st_size=135757, ...}) = 0
> 2471 mmap(NULL, 2212936, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f9a4acde000
> 2471 mprotect(0x7f9a4acf5000, 2097152, PROT_NONE) = 0
> 2471 mmap(0x7f9a4aef5000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x17000) = 0x7f9a4aef5000
> 2471 mmap(0x7f9a4aef7000, 13384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f9a4aef7000
> 2471 close(3) = 0
> 2471 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
> 2471 open("/usr/lib/libprotobuf-c.so.0", O_RDONLY|O_CLOEXEC) = 3
> 2471 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
> 2471 fstat(3, {st_mode=S_IFREG|0644, st_size=68248, ...}) = 0
> 2471 mmap(NULL, 2163488, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f9a4aacd000
> 2471 mprotect(0x7f9a4aadd000, 2093056, PROT_NONE) = 0
> 2471 mmap(0x7f9a4acdc000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xf000) = 0x7f9a4acdc000
> 2471 close(3) = 0
> 2471 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
> 2471 open("/lib/x86_64-linux-gnu/libdl.so.2", O_RDONLY|O_CLOEXEC) = 3
> 2471 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\16\0\0\0\0\0\0"..., 832) = 832
> 2471 fstat(3, {st_mode=S_IFREG|0644, st_size=14664, ...}) = 0
> 2471 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f9a4b114000
> 2471 mmap(NULL, 2109736, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f9a4a8c9000
> 2471 mprotect(0x7f9a4a8cc000, 2093056, PROT_NONE) = 0
> 2471 mmap(0x7f9a4aacb000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7f9a4aacb000
> 2471 close(3) = 0
> 2471 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
> 2471 open("/lib/x86_64-linux-gnu/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
> 2471 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\360\36\2\0\0\0\0\0"..., 832) = 832
> 2471 fstat(3, {st_mode=S_IFREG|0755, st_size=1853400, ...}) = 0
> 2471 mmap(NULL, 3961912, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f9a4a501000
> 2471 mprotect(0x7f9a4a6be000, 2097152, PROT_NONE) = 0
> 2471 mmap(0x7f9a4a8be000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1bd000) = 0x7f9a4a8be000
> 2471 mmap(0x7f9a4a8c4000, 17464, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f9a4a8c4000
> 2471 close(3) = 0
> 2471 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f9a4b113000
> 2471 mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f9a4b111000
> 2471 arch_prctl(ARCH_SET_FS, 0x7f9a4b111740) = 0
> 2471 mprotect(0x7f9a4a8be000, 16384, PROT_READ) = 0
> 2471 mprotect(0x7f9a4aacb000, 4096, PROT_READ) = 0
> 2471 mprotect(0x7f9a4acdc000, 4096, PROT_READ) = 0
> 2471 mprotect(0x7f9a4aef5000, 4096, PROT_READ) = 0
> 2471 mprotect(0x68d000, 4096, PROT_READ) = 0
> 2471 mprotect(0x7f9a4b11d000, 4096, PROT_READ) = 0
> 2471 munmap(0x7f9a4b115000, 22643) = 0
> 2471 set_tid_address(0x7f9a4b111a10) = 2471
> 2471 set_robust_list(0x7f9a4b111a20, 24) = 0
> 2471 futex(0x7fff6a7a570c, FUTEX_WAIT_BITSET_PRIVATE|FUTEX_CLOCK_REALTIME, 1, NULL, 7f9a4b111740) = -1 EAGAIN (Resource temporarily unavailable)
> 2471 rt_sigaction(SIGRTMIN, {0x7f9a4ace47e0, [], SA_RESTORER|SA_SIGINFO, 0x7f9a4acedbb0}, NULL, 8) = 0
> 2471 rt_sigaction(SIGRT_1, {0x7f9a4ace4860, [], SA_RESTORER|SA_RESTART|SA_SIGINFO, 0x7f9a4acedbb0}, NULL, 8) = 0
> 2471 rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
> 2471 getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
> 2471 getgid() = 0
> 2471 getuid() = 0
> 2471 getrlimit(RLIMIT_NOFILE, {rlim_cur=1024, rlim_max=4*1024}) = 0
> 2471 open(".", O_RDONLY) = 3
> 2471 dup3(3, 1022, O_CLOEXEC) = 1022
> 2471 close(3) = 0
> 2471 chdir(".") = 0
> 2471 open("pd.log", O_WRONLY|O_CREAT|O_TRUNC|O_APPEND, 0600) = 3
> 2471 dup3(3, 1023, O_CLOEXEC) = 1023
> 2471 close(3) = 0
> 2471 write(1023, "(00.000085) Enforcing memory tra"..., 52) = 52
> 2471 write(1023, "(00.000128) Enforcing tasks run "..., 48) = 48
> 2471 brk(0) = 0x13eb000
> 2471 brk(0x140c000) = 0x140c000
> 2471 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_SHARED|MAP_ANONYMOUS, 0, 0) = 0x7f9a4b11a000
> 2471 stat("/proc/self/map_files/7f9a4b11a000-7f9a4b11b000", {st_mode=S_IFREG|0777, st_size=4096, ...}) = 0
> 2471 munmap(0x7f9a4b11a000, 4096) = 0
> 2471 write(1023, "(00.000353) Found anon-shmem dev"..., 41) = 41
> 2471 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, 0, 0) = 0x7f9a4b11a000
> 2471 write(1023, "(00.000402) Reset 2471's dirty t"..., 40) = 40
> 2471 open("/proc", O_RDONLY|O_DIRECTORY) = 3
> 2471 dup3(3, 1021, O_CLOEXEC) = 1021
> 2471 close(3) = 0
> 2471 openat(1021, "2471", O_RDONLY) = 3
> 2471 openat(3, "clear_refs", O_RDWR) = 4
> 2471 write(4, "4\0", 2) = 2
> 2471 close(4) = 0
> 2471 write(1023, "(00.000593) ... done\n", 22) = 22
> 2471 open("/proc/self/pagemap", O_RDONLY) = 4
> 2471 lseek(4, 274024728784, SEEK_SET) = 274024728784
> 2471 read(4, "\200J\2\0\0\0\200\200", 8) = 8
> 2471 close(4) = 0
> 2471 munmap(0x7f9a4b11a000, 4096) = 0
> 2471 write(1023, "(00.000990) Dirty track supporte"..., 44) = 44
> 2471 mmap(NULL, 4096, PROT_READ, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f9a4b11a000
> 2471 openat(3, "pagemap", O_RDONLY) = 4
> 2471 lseek(4, 274024728784, SEEK_SET) = 274024728784
> 2471 read(4, "m\36\0\0\0\0\0\200", 8) = 8
> 2471 close(4) = 0
> 2471 munmap(0x7f9a4b11a000, 4096) = 0
> 2471 write(1023, "(00.001136) irmap: Searching irm"..., 53) = 53
> 2471 openat(AT_FDCWD, "irmap-cache", O_RDONLY) = 4
> 2471 read(4, "Y@\0W", 4) = 4
> 2471 write(1023, "(00.001209) irmap: ... done\n", 28) = 28
> 2471 write(1023, "(00.001232) irmap: Loading irmap"..., 39) = 39
> 2471 read(4, "", 4) = 0
> 2471 close(4) = 0
> 2471 open("/proc/cpuinfo", O_RDONLY) = 4
> 2471 fstat(4, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
> 2471 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f9a4b11a000
> 2471 read(4, "processor\t: 0\nvendor_id\t: Genuin"..., 1024) = 555
> 2471 read(4, "", 1024) = 0
> 2471 close(4) = 0
> 2471 munmap(0x7f9a4b11a000, 4096) = 0
> 2471 write(1023, "(00.001486) cpu: fpu:1 fxsr:1 xs"..., 38) = 38
> 2471 open("/proc/self/maps", O_RDONLY) = 4
> 2471 fstat(4, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
> 2471 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f9a4b11a000
> 2471 read(4, "00400000-0048e000 r-xp 00000000 "..., 1024) = 1024
> 2471 read(4, "00003000 08:01 393395 "..., 1024) = 1024
> 2471 read(4, "6_64-linux-gnu/libpthread-2.17.s"..., 1024) = 858
> 2471 write(1023, "(00.001686) vdso: Parsing at 7ff"..., 55) = 55
> 2471 write(1023, "(00.001709) vdso: Base address f"..., 48) = 48
> 2471 close(4) = 0
> 2471 munmap(0x7f9a4b11a000, 4096) = 0
> 2471 openat(3, "pagemap", O_RDONLY) = 4
> 2471 lseek(4, 274873008112, SEEK_SET) = 274873008112
> 2471 read(4, "\3\34\0\0\0\0\0\240", 8) = 8
> 2471 close(4) = 0
> 2471 ptrace(PTRACE_SEIZE, 1592, 0, 0) = 0
> 2471 ptrace(PTRACE_INTERRUPT, 1592, 0, 0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_STOPPED, si_pid=1592, si_status=SIG_0, si_utime=0, si_stime=8} ---
> 2471 close(3) = 0
> 2471 openat(1021, "1592", O_RDONLY) = 3
> 2471 openat(3, "stat", O_RDONLY) = 4
> 2471 read(4, "1592 (mem-touch) t 1 1592 1592 0"..., 4096) = 329
> 2471 close(4) = 0
> 2471 wait4(1592, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP} | 0x800000], __WALL, NULL) = 1592
> 2471 ptrace(PTRACE_GETSIGINFO, 1592, 0, {si_signo=SIGTRAP, si_code=0xffff8005, si_pid=1, si_uid=0, si_value={int=1, ptr=0x1}}) = 0
> 2471 write(1023, "(00.002073) Seized task 1592, st"..., 38) = 38
> 2471 openat(3, "task", O_RDONLY) = 4
> 2471 fstat(4, {st_mode=S_IFDIR|0555, st_size=0, ...}) = 0
> 2471 fcntl(4, F_GETFL) = 0x8000 (flags O_RDONLY|O_LARGEFILE)
> 2471 fcntl(4, F_SETFD, FD_CLOEXEC) = 0
> 2471 getdents(4, /* 3 entries */, 32768) = 72
> 2471 getdents(4, /* 0 entries */, 32768) = 0
> 2471 close(4) = 0
> 2471 openat(3, "task", O_RDONLY) = 4
> 2471 fstat(4, {st_mode=S_IFDIR|0555, st_size=0, ...}) = 0
> 2471 fcntl(4, F_GETFL) = 0x8000 (flags O_RDONLY|O_LARGEFILE)
> 2471 fcntl(4, F_SETFD, FD_CLOEXEC) = 0
> 2471 getdents(4, /* 3 entries */, 32768) = 72
> 2471 openat(3, "task/1592/children", O_RDONLY) = 5
> 2471 fcntl(5, F_GETFL) = 0x8000 (flags O_RDONLY|O_LARGEFILE)
> 2471 fstat(5, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
> 2471 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f9a4b11a000
> 2471 lseek(5, 0, SEEK_CUR) = 0
> 2471 read(5, "", 1024) = 0
> 2471 close(5) = 0
> 2471 munmap(0x7f9a4b11a000, 4096) = 0
> 2471 getdents(4, /* 0 entries */, 32768) = 0
> 2471 close(4) = 0
> 2471 close(3) = 0
> 2471 write(1023, "(00.002553) Collected 1592 in 1 "..., 38) = 38
> 2471 openat(1021, "2471", O_RDONLY) = 3
> 2471 readlinkat(3, "ns/mnt", "mnt:[4026531840]", 32) = 16
> 2471 write(1023, "(00.002646) Collected 1.mnt name"..., 38) = 38
> 2471 close(3) = 0
> 2471 openat(1021, "1592", O_RDONLY) = 3
> 2471 readlinkat(3, "ns/mnt", "mnt:[4026531840]", 32) = 16
> 2471 write(1023, "(00.002733) NS mask generated: 0"..., 33) = 33
> 2471 write(1023, "(00.002756) Collecting mountinfo"..., 33) = 33
> 2471 open("/proc/1592/mountinfo", O_RDONLY) = 4
> 2471 fstat(4, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
> 2471 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f9a4b11a000
> 2471 read(4, "15 20 0:14 / /sys rw,nosuid,node"..., 1024) = 1024
> 2471 write(1023, "(00.002924) \ttype sysfs source s"..., 70) = 70
> 2471 write(1023, "(00.002954) \ttype proc source pr"..., 69) = 69
> 2471 write(1023, "(00.002984) \ttype devtmpfs sourc"..., 111) = 111
> 2471 write(1023, "(00.003013) \ttype devpts source "..., 104) = 104
> 2471 write(1023, "(00.003040) \ttype tmpfs source t"..., 92) = 92
> 2471 write(1023, "(00.003066) \ttype unsupported so"..., 158) = 158
> 2471 write(1023, "(00.003093) \ttype tmpfs source n"..., 97) = 97
> 2471 write(1023, "(00.003214) \ttype unsupported so"..., 96) = 96
> 2471 write(1023, "(00.003242) \ttype unsupported so"..., 88) = 88
> 2471 write(1023, "(00.003267) \ttype unsupported so"..., 91) = 91
> 2471 write(1023, "(00.003293) \ttype unsupported so"..., 117) = 117
> 2471 write(1023, "(00.003319) \ttype tmpfs source n"..., 86) = 86
> 2471 write(1023, "(00.003347) \ttype unsupported so"..., 96) = 96
> 2471 read(4, "0 19 0:29 / /run/shm rw,nosuid,n"..., 1024) = 960
> 2471 write(1023, "(00.003421) \ttype tmpfs source n"..., 74) = 74
> 2471 write(1023, "(00.003453) \ttype unsupported so"..., 104) = 104
> 2471 write(1023, "(00.003480) \ttype tmpfs source n"..., 97) = 97
> 2471 write(1023, "(00.003507) \ttype unsupported so"..., 86) = 86
> 2471 write(1023, "(00.003533) \ttype unsupported so"..., 102) = 102
> 2471 write(1023, "(00.003559) \ttype unsupported so"..., 104) = 104
> 2471 write(1023, "(00.003584) \ttype unsupported so"..., 104) = 104
> 2471 write(1023, "(00.003610) \ttype unsupported so"..., 100) = 100
> 2471 write(1023, "(00.003637) \ttype unsupported so"..., 110) = 110
> 2471 write(1023, "(00.003663) \ttype unsupported so"..., 104) = 104
> 2471 write(1023, "(00.003689) \ttype unsupported so"..., 93) = 93
> 2471 write(1023, "(00.003715) \ttype unsupported so"..., 110) = 110
> 2471 write(1023, "(00.003742) \ttype unsupported so"..., 85) = 85
> 2471 read(4, "", 1024) = 0
> 2471 close(4) = 0
> 2471 munmap(0x7f9a4b11a000, 4096) = 0
> 2471 write(1023, "(00.003824) Building mountpoints"..., 38) = 38
> 2471 write(1023, "(00.003846) \tBuilding plain moun"..., 39) = 39
> 2471 write(1023, "(00.003866) \t\tWorking on 62->16\n", 32) = 32
> 2471 write(1023, "(00.003888) \t\tWorking on 61->21\n", 32) = 32
> 2471 write(1023, "(00.003909) \t\tWorking on 60->19\n", 32) = 32
> 2471 write(1023, "(00.003929) \t\tWorking on 59->21\n", 32) = 32
> 2471 write(1023, "(00.003951) \t\tWorking on 58->21\n", 32) = 32
> 2471 write(1023, "(00.003972) \t\tWorking on 57->21\n", 32) = 32
> 2471 write(1023, "(00.003993) \t\tWorking on 56->21\n", 32) = 32
> 2471 write(1023, "(00.004013) \t\tWorking on 55->21\n", 32) = 32
> 2471 write(1023, "(00.004034) \t\tWorking on 54->21\n", 32) = 32
> 2471 write(1023, "(00.004055) \t\tWorking on 53->15\n", 32) = 32
> 2471 write(1023, "(00.004076) \t\tWorking on 52->19\n", 32) = 32
> 2471 write(1023, "(00.004097) \t\tWorking on 51->21\n", 32) = 32
> 2471 write(1023, "(00.004117) \t\tWorking on 50->19\n", 32) = 32
> 2471 write(1023, "(00.004138) \t\tWorking on 49->21\n", 32) = 32
> 2471 write(1023, "(00.004159) \t\tWorking on 48->19\n", 32) = 32
> 2471 write(1023, "(00.004180) \t\tWorking on 25->21\n", 32) = 32
> 2471 write(1023, "(00.004201) \t\tWorking on 24->15\n", 32) = 32
> 2471 write(1023, "(00.004222) \t\tWorking on 23->15\n", 32) = 32
> 2471 write(1023, "(00.004242) \t\tWorking on 22->15\n", 32) = 32
> 2471 write(1023, "(00.004263) \t\tWorking on 21->15\n", 32) = 32
> 2471 write(1023, "(00.004284) \t\tWorking on 20->1\n", 31) = 31
> 2471 write(1023, "(00.004305) \t\tWorking on 19->20\n", 32) = 32
> 2471 write(1023, "(00.004326) \t\tWorking on 18->17\n", 32) = 32
> 2471 write(1023, "(00.004346) \t\tWorking on 17->20\n", 32) = 32
> 2471 write(1023, "(00.004367) \t\tWorking on 16->20\n", 32) = 32
> 2471 write(1023, "(00.004393) \t\tWorking on 15->20\n", 32) = 32
> 2471 write(1023, "(00.004416) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004439) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004461) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004481) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004502) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004523) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004544) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004565) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004585) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004606) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004627) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004648) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004669) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004690) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004711) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004731) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004752) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004773) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004794) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004815) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004840) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004861) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004883) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004904) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004926) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004947) \tResorting siblings "..., 38) = 38
> 2471 write(1023, "(00.004970) Done:\n", 18) = 18
> 2471 write(1023, "(00.005013) [/](20->1)\n", 23) = 23
> 2471 write(1023, "(00.005044) [/run](19->20)\n", 28) = 28
> 2471 write(1023, "(00.005065) [/run/rpc_pipefs]("..., 40) = 40
> 2471 write(1023, "(00.005086) <--\n", 18) = 18
> 2471 write(1023, "(00.005107) [/run/lock](48->19"..., 34) = 34
> 2471 write(1023, "(00.005128) <--\n", 18) = 18
> 2471 write(1023, "(00.005148) [/run/shm](50->19)"..., 33) = 33
> 2471 write(1023, "(00.005169) <--\n", 18) = 18
> 2471 write(1023, "(00.005190) [/run/user](52->19"..., 34) = 34
> 2471 write(1023, "(00.005210) <--\n", 18) = 18
> 2471 write(1023, "(00.005231) <--\n", 17) = 17
> 2471 write(1023, "(00.005252) [/sys](15->20)\n", 28) = 28
> 2471 write(1023, "(00.005272) [/sys/fs/pstore](5"..., 39) = 39
> 2471 write(1023, "(00.005293) <--\n", 18) = 18
> 2471 write(1023, "(00.005314) [/sys/fs/cgroup](2"..., 39) = 39
> 2471 write(1023, "(00.005334) [/sys/fs/cgroup/s"..., 48) = 48
> 2471 write(1023, "(00.005355) <--\n", 19) = 19
> 2471 write(1023, "(00.005376) [/sys/fs/cgroup/c"..., 47) = 47
> 2471 write(1023, "(00.005397) <--\n", 19) = 19
> 2471 write(1023, "(00.005417) [/sys/fs/cgroup/c"..., 44) = 44
> 2471 write(1023, "(00.005438) <--\n", 19) = 19
> 2471 write(1023, "(00.005459) [/sys/fs/cgroup/c"..., 48) = 48
> 2471 write(1023, "(00.005480) <--\n", 19) = 19
> 2471 write(1023, "(00.005501) [/sys/fs/cgroup/m"..., 47) = 47
> 2471 write(1023, "(00.005521) <--\n", 19) = 19
> 2471 write(1023, "(00.005542) [/sys/fs/cgroup/d"..., 48) = 48
> 2471 write(1023, "(00.005563) <--\n", 19) = 19
> 2471 write(1023, "(00.005583) [/sys/fs/cgroup/f"..., 48) = 48
> 2471 write(1023, "(00.005604) <--\n", 19) = 19
> 2471 write(1023, "(00.005625) [/sys/fs/cgroup/b"..., 46) = 46
> 2471 write(1023, "(00.005645) <--\n", 19) = 19
> 2471 write(1023, "(00.005666) [/sys/fs/cgroup/p"..., 51) = 51
> 2471 write(1023, "(00.005687) <--\n", 19) = 19
> 2471 write(1023, "(00.005707) [/sys/fs/cgroup/h"..., 48) = 48
> 2471 write(1023, "(00.005728) <--\n", 19) = 19
> 2471 write(1023, "(00.005748) <--\n", 18) = 18
> 2471 write(1023, "(00.005769) [/sys/fs/fuse/conn"..., 49) = 49
> 2471 write(1023, "(00.005790) <--\n", 18) = 18
> 2471 write(1023, "(00.005811) [/sys/kernel/debug"..., 42) = 42
> 2471 write(1023, "(00.005832) <--\n", 18) = 18
> 2471 write(1023, "(00.005852) [/sys/kernel/secur"..., 45) = 45
> 2471 write(1023, "(00.005873) <--\n", 18) = 18
> 2471 write(1023, "(00.005894) <--\n", 17) = 17
> 2471 write(1023, "(00.005915) [/proc](16->20)\n", 29) = 29
> 2471 write(1023, "(00.005936) [/proc/fs/nfsd](62"..., 38) = 38
> 2471 write(1023, "(00.005957) <--\n", 18) = 18
> 2471 write(1023, "(00.005977) <--\n", 17) = 17
> 2471 write(1023, "(00.005998) [/dev](17->20)\n", 28) = 28
> 2471 write(1023, "(00.006019) [/dev/pts](18->17)"..., 33) = 33
> 2471 write(1023, "(00.006040) <--\n", 18) = 18
> 2471 write(1023, "(00.006061) <--\n", 17) = 17
> 2471 write(1023, "(00.006081) <--\n", 16) = 16
> 2471 open("/", O_RDONLY|O_DIRECTORY) = 4
> 2471 write(1023, "(00.006137) ===================="..., 53) = 53
> 2471 write(1023, "(00.006159) Pre-dumping task (pi"..., 41) = 41
> 2471 write(1023, "(00.006181) ===================="..., 53) = 53
> 2471 write(1023, "(00.006202) \n", 13) = 13
> 2471 write(1023, "(00.006223) Collecting mappings "..., 44) = 44
> 2471 write(1023, "(00.006253) --------------------"..., 53) = 53
> 2471 openat(3, "smaps", O_RDONLY) = 5
> 2471 fcntl(5, F_GETFL) = 0x8000 (flags O_RDONLY|O_LARGEFILE)
> 2471 fstat(5, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
> 2471 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f9a4b11a000
> 2471 lseek(5, 0, SEEK_CUR) = 0
> 2471 munmap(0x7f9a4b11a000, 4096) = 0
> 2471 openat(3, "map_files", O_RDONLY) = 6
> 2471 fstat(6, {st_mode=S_IFDIR|0500, st_size=0, ...}) = 0
> 2471 fcntl(6, F_GETFL) = 0x8000 (flags O_RDONLY|O_LARGEFILE)
> 2471 fcntl(6, F_SETFD, FD_CLOEXEC) = 0
> 2471 read(5, "00400000-00402000 r-xp 00000000 "..., 4096) = 4071
> 2471 openat(6, "400000-402000", O_RDONLY) = 7
> 2471 fstat(7, {st_mode=S_IFREG|0755, st_size=13826, ...}) = 0
> 2471 write(1023, "(00.009056) vma 601000 borrows v"..., 56) = 56
> 2471 write(1023, "(00.009085) vma 602000 borrows v"..., 56) = 56
> 2471 openat(6, "b87000-bb8000", O_RDONLY) = -1 ENOENT (No such file or directory)
> 2471 openat(6, "7f38e7dd7000-7f38e7f94000", O_RDONLY) = 8
> 2471 fstat(8, {st_mode=S_IFREG|0755, st_size=1853400, ...}) = 0
> 2471 write(1023, "(00.009203) vma 7f38e7f94000 bor"..., 68) = 68
> 2471 write(1023, "(00.009229) vma 7f38e8194000 bor"..., 68) = 68
> 2471 read(5, "7f38e8198000-7f38e819a000 rw-p 0"..., 4096) = 4019
> 2471 write(1023, "(00.009304) vma 7f38e8198000 bor"..., 68) = 68
> 2471 openat(6, "7f38e819a000-7f38e819f000", O_RDONLY) = -1 ENOENT (No such file or directory)
> 2471 openat(6, "7f38e819f000-7f38e81c2000", O_RDONLY) = 9
> 2471 fstat(9, {st_mode=S_IFREG|0755, st_size=149312, ...}) = 0
> 2471 openat(6, "7f38e83ac000-7f38e83bb000", O_RDONLY) = -1 ENOENT (No such file or directory)
> 2471 openat(6, "7f38e83c1000-7f38e83c2000", O_RDONLY) = 10
> 2471 fstat(10, {st_mode=S_IFREG|0755, st_size=149312, ...}) = 0
> 2471 write(1023, "(00.009490) vma 7f38e83c2000 bor"..., 68) = 68
> 2471 openat(6, "7fff99bd9000-7fff99bfa000", O_RDONLY) = -1 ENOENT (No such file or directory)
> 2471 read(5, "7fff99bfe000-7fff99c00000 r-xp 0"..., 4096) = 992
> 2471 read(5, "", 4096) = 0
> 2471 close(5) = 0
> 2471 close(6) = 0
> 2471 write(1023, "(00.009633) Collected, longest a"..., 57) = 57
> 2471 write(1023, "(00.009657) 0x400000-0x402000 (8"..., 77) = 77
> 2471 write(1023, "(00.009681) 0x601000-0x602000 (4"..., 82) = 82
> 2471 write(1023, "(00.009702) 0x602000-0x603000 (4"..., 82) = 82
> 2471 write(1023, "(00.009723) 0xb87000-0xbb8000 (1"..., 85) = 85
> 2471 write(1023, "(00.009745) 0x7f38d7dd7000-0x7f3"..., 95) = 95
> 2471 write(1023, "(00.009766) 0x7f38e7dd7000-0x7f3"..., 92) = 92
> 2471 write(1023, "(00.009788) 0x7f38e7f94000-0x7f3"..., 97) = 97
> 2471 write(1023, "(00.009809) 0x7f38e8194000-0x7f3"..., 97) = 97
> 2471 write(1023, "(00.009830) 0x7f38e8198000-0x7f3"..., 96) = 96
> 2471 write(1023, "(00.009851) 0x7f38e819a000-0x7f3"..., 91) = 91
> 2471 write(1023, "(00.009885) 0x7f38e819f000-0x7f3"..., 91) = 91
> 2471 write(1023, "(00.009907) 0x7f38e83ab000-0x7f3"..., 92) = 92
> 2471 write(1023, "(00.009928) 0x7f38e83bb000-0x7f3"..., 91) = 91
> 2471 write(1023, "(00.009949) 0x7f38e83c1000-0x7f3"..., 95) = 95
> 2471 write(1023, "(00.009970) 0x7f38e83c2000-0x7f3"..., 95) = 95
> 2471 write(1023, "(00.009991) 0x7fff99bd8000-0x7ff"..., 93) = 93
> 2471 write(1023, "(00.010013) 0x7fff99bfe000-0x7ff"..., 95) = 95
> 2471 write(1023, "(00.010042) 0xffffffffff600000-0"..., 99) = 99
> 2471 write(1023, "(00.010064) --------------------"..., 53) = 53
> 2471 ptrace(PTRACE_PEEKUSER, 1592, 8*CS, [0x33]) = 0
> 2471 ptrace(PTRACE_PEEKUSER, 1592, 8*SS + 24, [0]) = 0
> 2471 ptrace(0x420a /* PTRACE_??? */, 1592, 0x8, 0x13f1448) = 0
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x13f1450, 216}]) = 0
> 2471 ptrace(PTRACE_PEEKDATA, 1592, 0x400000, [0x10102464c457f]) = 0
> 2471 ptrace(PTRACE_POKEDATA, 1592, 0x400000, 0xcccccccccccc050f) = 0
> 2471 ptrace(0x420b /* PTRACE_??? */, 1592, 0x8, 0x7fff6a7a5370) = 0
> 2471 ptrace(PTRACE_SETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5410, 216}]) = 0
> 2471 ptrace(PTRACE_CONT, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(1592, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 ptrace(PTRACE_GETSIGINFO, 1592, 0, {si_signo=SIGTRAP, si_code=SI_KERNEL}) = 0
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5410, 216}]) = 0
> 2471 ptrace(PTRACE_SETREGSET, 1592, NT_PRSTATUS, [{0x13f1450, 216}]) = 0
> 2471 ptrace(0x420b /* PTRACE_??? */, 1592, 0x8, 0x13f1448) = 0
> 2471 ptrace(PTRACE_POKEDATA, 1592, 0x400000, 0x10102464c457f) = 0
> 2471 openat(3, "map_files/0x7f38e831f000-0x7f38e83ab000", O_RDWR) = 5
> 2471 mmap(NULL, 571968, PROT_READ|PROT_WRITE, MAP_SHARED, 5, 0) = 0x7f9a4b085000
> 2471 close(5) = 0
> 2471 write(1023, "(00.010484) Putting parasite blo"..., 70) = 70
> 2471 write(1023, "(00.010520) Dumping GP/FPU regis"..., 46) = 46
> 2471 ptrace(PTRACE_GETFPREGS, 1592, 0, 0x7fff6a7a5200) = 0
> 2471 write(1023, "(00.010562) xsave runtime struct"..., 36) = 36
> 2471 write(1023, "(00.010584) --------------------"..., 36) = 36
> 2471 write(1023, "(00.010605) cwd:37f swd:0 twd:0 "..., 65) = 65
> 2471 write(1023, "(00.010626) magic1:0 extended_si"..., 63) = 63
> 2471 write(1023, "(00.010647) xstate_bv: 0\n", 25) = 25
> 2471 write(1023, "(00.010668) --------------------"..., 36) = 36
> 2471 write(1023, "(00.010688) Putting tsock into p"..., 40) = 40
> 2471 write(1023, "(00.010711) Switching to 1592's "..., 55) = 55
> 2471 open("/proc/1592/ns/net", O_RDONLY) = 5
> 2471 open("/proc/self/ns/net", O_RDONLY) = 6
> 2471 setns(5, 1073741824) = 0
> 2471 close(5) = 0
> 2471 socket(PF_LOCAL, SOCK_SEQPACKET, 0) = 5
> 2471 setns(6, 1073741824) = 0
> 2471 close(6) = 0
> 2471 bind(5, {sa_family=AF_LOCAL, sun_path=@"/crtools-pr-2471"}, 19) = 0
> 2471 listen(5, 1) = 0
> 2471 rt_sigaction(SIGCHLD, {0x41ef80, [CHLD], SA_RESTORER|SA_RESTART|SA_SIGINFO, 0x7f9a4acedbb0}, NULL, 8) = 0
> 2471 ptrace(0x420b /* PTRACE_??? */, 1592, 0x8, 0x7fff6a7a5400) = 0
> 2471 ptrace(PTRACE_SETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5470, 216}]) = 0
> 2471 ptrace(PTRACE_CONT, 1592, 0, SIG_0) = 0
> 2471 accept(5, 0, NULL) = 6
> 2471 sendmsg(6, {msg_name(0)=NULL, msg_iov(1)=[{"\0", 1}], msg_controllen=20, {cmsg_len=20, cmsg_level=SOL_SOCKET, cmsg_type=SCM_RIGHTS, {1023}}, msg_flags=0}, 0) = 1
> 2471 write(1023, "(00.011182) Wait for parasite be"..., 50) = 50
> 2471 write(1023, "(00.011206) Wait for ack 2 on da"..., 44) = 44
> 2471 recvfrom(6, "\2\0\0\0\2\0\0\0\0\0\0\0", 12, MSG_WAITALL, NULL, NULL) = 12
> 2471 write(1023, "(00.011251) Fetched ack: 2 2 0\n", 31) = 31
> 2471 write(1023, "(00.011274) Parasite 1592 has be"..., 59) = 59
> 2471 openat(3, "pagemap", O_RDONLY) = 11
> 2471 sendto(6, "\21\0\0\0\0\0\0\0\0\0\0\0", 12, 0, NULL, 0) = 12
> 2471 write(1023, "(00.011357) Sent msg to daemon 1"..., 38) = 38
> 2471 write(1023, "(00.011379) Wait for ack 17 on d"..., 45) = 45
> 2471 recvfrom(6, "\21\0\0\0\21\0\0\0\0\0\0\0", 12, MSG_WAITALL, NULL, NULL) = 12
> 2471 write(1023, "(00.011420) Fetched ack: 17 17 0"..., 33) = 33
> 2471 pread(11, "\3\34\0\0\0\0\0\240", 8, 274874556400) = 8
> 2471 close(11) = 0
> 2471 sendto(6, "\f\0\0\0\0\0\0\0\0\0\0\0", 12, 0, NULL, 0) = 12
> 2471 write(1023, "(00.011527) Sent msg to daemon 1"..., 38) = 38
> 2471 write(1023, "(00.011558) Wait for ack 12 on d"..., 45) = 45
> 2471 recvfrom(6, "\f\0\0\0\f\0\0\0\0\0\0\0", 12, MSG_WAITALL, NULL, NULL) = 12
> 2471 write(1023, "(00.011599) Fetched ack: 12 12 0"..., 33) = 33
> 2471 write(1023, "(00.011632) Pre-dump fds for 159"..., 35) = 35
> 2471 openat(3, "fd", O_RDONLY) = 11
> 2471 fstat(11, {st_mode=S_IFDIR|0500, st_size=0, ...}) = 0
> 2471 fcntl(11, F_GETFL) = 0x8000 (flags O_RDONLY|O_LARGEFILE)
> 2471 fcntl(11, F_SETFD, FD_CLOEXEC) = 0
> 2471 getdents(11, /* 6 entries */, 32768) = 144
> 2471 openat(3, "fd/0", O_RDONLY|0x200000) = 12
> 2471 fstatfs(12, 0x7fff6a7a55b0) = -1 EBADF (Bad file descriptor)
> 2471 write(1023, "(00.011835) Error (files.c:424):"..., 73) = 73
> 2471 close(11) = 0
> 2471 write(1023, "(00.011894) Error (cr-dump.c:135"..., 73) = 73
> 2471 rt_sigaction(SIGCHLD, {SIG_DFL, [CHLD], SA_RESTORER|SA_RESTART|SA_SIGINFO, 0x7f9a4acedbb0}, NULL, 8) = 0
> 2471 ptrace(PTRACE_INTERRUPT, 1592, 0, 0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_STOPPED, si_pid=1592, si_status=SIG_0, si_utime=0, si_stime=8} ---
> 2471 write(1023, "(00.012003) Waiting for 1592 to "..., 37) = 37
> 2471 wait4(1592, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP} | 0x800000], __WALL, NULL) = 1592
> 2471 write(1023, "(00.012044) Daemon 1592 exited t"..., 40) = 40
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5570, 216}]) = 0
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 sendto(6, "\6\0\0\0\0\0\0\0\0\0\0\0", 12, 0, NULL, 0) = 12
> 2471 write(1023, "(00.012131) Sent msg to daemon 6"..., 37) = 37
> 2471 close(6) = 0
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.012191) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5440, 216}]) = 0
> 2471 write(1023, "(00.012230) 1592 is going to exe"..., 52) = 52
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.012297) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5440, 216}]) = 0
> 2471 write(1023, "(00.012337) 1592 is going to exe"..., 52) = 52
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.012402) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5440, 216}]) = 0
> 2471 write(1023, "(00.012441) 1592 is going to exe"..., 51) = 51
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.012508) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5440, 216}]) = 0
> 2471 write(1023, "(00.012546) 1592 is going to exe"..., 51) = 51
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.012614) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5440, 216}]) = 0
> 2471 write(1023, "(00.012653) 1592 is going to exe"..., 52) = 52
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.012718) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5440, 216}]) = 0
> 2471 write(1023, "(00.012757) 1592 is going to exe"..., 52) = 52
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.012823) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5440, 216}]) = 0
> 2471 write(1023, "(00.012862) 1592 is going to exe"..., 51) = 51
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.012938) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5440, 216}]) = 0
> 2471 write(1023, "(00.012978) 1592 is going to exe"..., 51) = 51
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.013043) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5440, 216}]) = 0
> 2471 write(1023, "(00.013082) 1592 is going to exe"..., 51) = 51
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.013151) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5440, 216}]) = 0
> 2471 write(1023, "(00.013190) 1592 is going to exe"..., 51) = 51
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.013257) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5440, 216}]) = 0
> 2471 write(1023, "(00.013296) 1592 is going to exe"..., 51) = 51
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.013361) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5440, 216}]) = 0
> 2471 write(1023, "(00.013400) 1592 is going to exe"..., 51) = 51
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.013465) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5440, 216}]) = 0
> 2471 write(1023, "(00.013504) 1592 is going to exe"..., 51) = 51
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(1592, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.013571) 1592 was stopped\n", 29) = 29
> 2471 ptrace(0x420b /* PTRACE_??? */, 1592, 0x8, 0x7fff6a7a5510) = 0
> 2471 ptrace(PTRACE_SETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5570, 216}]) = 0
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.013670) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5460, 216}]) = 0
> 2471 write(1023, "(00.013709) 1592 is going to exe"..., 51) = 51
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.013774) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5460, 216}]) = 0
> 2471 write(1023, "(00.013813) 1592 is going to exe"..., 51) = 51
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(-1, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.013883) 1592 was trapped\n", 29) = 29
> 2471 ptrace(PTRACE_GETREGSET, 1592, NT_PRSTATUS, [{0x7fff6a7a5460, 216}]) = 0
> 2471 write(1023, "(00.013923) 1592 is going to exe"..., 51) = 51
> 2471 ptrace(PTRACE_SYSCALL, 1592, 0, SIG_0) = 0
> 2471 --- SIGCHLD {si_signo=SIGCHLD, si_code=CLD_TRAPPED, si_pid=1592, si_status=SIGTRAP, si_utime=0, si_stime=8} ---
> 2471 wait4(1592, [{WIFSTOPPED(s) && WSTOPSIG(s) == SIGTRAP}], __WALL, NULL) = 1592
> 2471 write(1023, "(00.014029) 1592 was stopped\n", 29) = 29
> 2471 ptrace(PTRACE_SETREGSET, 1592, NT_PRSTATUS, [{0x13f1450, 216}]) = 0
> 2471 ptrace(0x420b /* PTRACE_??? */, 1592, 0x8, 0x13f1448) = 0
> 2471 munmap(0x7f9a4b085000, 573440) = 0
> 2471 close(7) = 0
> 2471 close(8) = 0
> 2471 close(9) = 0
> 2471 close(10) = 0
> 2471 write(1023, "(00.014205) Unfreezing tasks int"..., 36) = 36
> 2471 write(1023, "(00.014228) \tUnseizing 1592 into"..., 35) = 35
> 2471 ptrace(PTRACE_DETACH, 1592, 0, SIG_0) = 0
> 2471 write(1023, "(00.014284) Pre-dumping tasks' m"..., 38) = 38
> 2471 openat(AT_FDCWD, "irmap-cache", O_RDWR|O_CREAT|O_TRUNC, 0664) = 6
> 2471 write(6, "Y@\0W", 4) = 4
> 2471 write(1023, "(00.014384) irmap: Running irmap"..., 42) = 42
> 2471 close(6) = 0
> 2471 write(1023, "(00.014498) Error (cr-dump.c:168"..., 56) = 56
> 2471 exit_group(1) = ?
> 2471 +++ exited with 1 +++
> (00.000085) Enforcing memory tracking for pre-dump.
> (00.000128) Enforcing tasks run after pre-dump.
> (00.000353) Found anon-shmem device at 4
> (00.000402) Reset 2471's dirty tracking
> (00.000593) ... done
> (00.000990) Dirty track supported on kernel
> (00.001136) irmap: Searching irmap cache in work dir
> (00.001209) irmap: ... done
> (00.001232) irmap: Loading irmap cache
> (00.001486) cpu: fpu:1 fxsr:1 xsave:0
> (00.001686) vdso: Parsing at 7fff6a7fe000 7fff6a800000
> (00.001709) vdso: Base address ffffffffff700000
> (00.002073) Seized task 1592, state 1
> (00.002553) Collected 1592 in 1 state
> (00.002646) Collected 1.mnt namespace
> (00.002733) NS mask generated: 0
> (00.002756) Collecting mountinfo
> (00.002924) type sysfs source sysfs e / @ /sys flags 20000e options
> (00.002954) type proc source proc 3 / @ /proc flags 20000e options
> (00.002984) type devtmpfs source udev 5 / @ /dev flags 200000 options size=499080k,nr_inodes=124770,mode=755,
> (00.003013) type devpts source devpts b / @ /dev/pts flags 20000a options gid=5,mode=620,ptmxmode=000,
> (00.003040) type tmpfs source tmpfs f / @ /run flags 20000a options size=101832k,mode=755,
> (00.003066) type unsupported source /dev/disk/by-uuid/a5bbc48c-face-4184-8f89-4061c584b839 800001 / @ / flags 200000 options errors=remount-ro,data=ordered,
> (00.003093) type tmpfs source none 10 / @ /sys/fs/cgroup flags 200000 options size=4k,mode=755,
> (00.003214) type unsupported source none 11 / @ /sys/fs/fuse/connections flags 200000 options
> (00.003242) type unsupported source none 6 / @ /sys/kernel/debug flags 200000 options
> (00.003267) type unsupported source none a / @ /sys/kernel/security flags 200000 options
> (00.003293) type unsupported source cgroup 12 / @ /sys/fs/cgroup/cpuset flags 200000 options cpuset,clone_children,
> (00.003319) type tmpfs source none 1c / @ /run/lock flags 20000e options size=5120k,
> (00.003347) type unsupported source cgroup 14 / @ /sys/fs/cgroup/cpu flags 200000 options cpu,
> (00.003421) type tmpfs source none 1d / @ /run/shm flags 200006 options
> (00.003453) type unsupported source cgroup 15 / @ /sys/fs/cgroup/cpuacct flags 200000 options cpuacct,
> (00.003480) type tmpfs source none 1e / @ /run/user flags 20000e options size=102400k,mode=755,
> (00.003507) type unsupported source none 1f / @ /sys/fs/pstore flags 200000 options
> (00.003533) type unsupported source cgroup 16 / @ /sys/fs/cgroup/memory flags 200000 options memory,
> (00.003559) type unsupported source cgroup 17 / @ /sys/fs/cgroup/devices flags 200000 options devices,
> (00.003584) type unsupported source cgroup 18 / @ /sys/fs/cgroup/freezer flags 200000 options freezer,
> (00.003610) type unsupported source cgroup 19 / @ /sys/fs/cgroup/blkio flags 200000 options blkio,
> (00.003637) type unsupported source cgroup 1a / @ /sys/fs/cgroup/perf_event flags 200000 options perf_event,
> (00.003663) type unsupported source cgroup 1b / @ /sys/fs/cgroup/hugetlb flags 200000 options hugetlb,
> (00.003689) type unsupported source rpc_pipefs 20 / @ /run/rpc_pipefs flags 200000 options
> (00.003715) type unsupported source systemd 13 / @ /sys/fs/cgroup/systemd flags 20000e options name=systemd,
> (00.003742) type unsupported source nfsd 21 / @ /proc/fs/nfsd flags 200000 options
> (00.003824) Building mountpoints tree
> (00.003846) Building plain mount tree
> (00.003866) Working on 62->16
> (00.003888) Working on 61->21
> (00.003909) Working on 60->19
> (00.003929) Working on 59->21
> (00.003951) Working on 58->21
> (00.003972) Working on 57->21
> (00.003993) Working on 56->21
> (00.004013) Working on 55->21
> (00.004034) Working on 54->21
> (00.004055) Working on 53->15
> (00.004076) Working on 52->19
> (00.004097) Working on 51->21
> (00.004117) Working on 50->19
> (00.004138) Working on 49->21
> (00.004159) Working on 48->19
> (00.004180) Working on 25->21
> (00.004201) Working on 24->15
> (00.004222) Working on 23->15
> (00.004242) Working on 22->15
> (00.004263) Working on 21->15
> (00.004284) Working on 20->1
> (00.004305) Working on 19->20
> (00.004326) Working on 18->17
> (00.004346) Working on 17->20
> (00.004367) Working on 16->20
> (00.004393) Working on 15->20
> (00.004416) Resorting siblings on 20
> (00.004439) Resorting siblings on 19
> (00.004461) Resorting siblings on 60
> (00.004481) Resorting siblings on 52
> (00.004502) Resorting siblings on 50
> (00.004523) Resorting siblings on 48
> (00.004544) Resorting siblings on 17
> (00.004565) Resorting siblings on 18
> (00.004585) Resorting siblings on 16
> (00.004606) Resorting siblings on 62
> (00.004627) Resorting siblings on 15
> (00.004648) Resorting siblings on 53
> (00.004669) Resorting siblings on 24
> (00.004690) Resorting siblings on 23
> (00.004711) Resorting siblings on 22
> (00.004731) Resorting siblings on 21
> (00.004752) Resorting siblings on 61
> (00.004773) Resorting siblings on 59
> (00.004794) Resorting siblings on 58
> (00.004815) Resorting siblings on 57
> (00.004840) Resorting siblings on 56
> (00.004861) Resorting siblings on 55
> (00.004883) Resorting siblings on 54
> (00.004904) Resorting siblings on 51
> (00.004926) Resorting siblings on 49
> (00.004947) Resorting siblings on 25
> (00.004970) Done:
> (00.005013) [/](20->1)
> (00.005044) [/run](19->20)
> (00.005065) [/run/rpc_pipefs](60->19)
> (00.005086) <--
> (00.005107) [/run/lock](48->19)
> (00.005128) <--
> (00.005148) [/run/shm](50->19)
> (00.005169) <--
> (00.005190) [/run/user](52->19)
> (00.005210) <--
> (00.005231) <--
> (00.005252) [/sys](15->20)
> (00.005272) [/sys/fs/pstore](53->15)
> (00.005293) <--
> (00.005314) [/sys/fs/cgroup](21->15)
> (00.005334) [/sys/fs/cgroup/systemd](61->21)
> (00.005355) <--
> (00.005376) [/sys/fs/cgroup/cpuset](25->21)
> (00.005397) <--
> (00.005417) [/sys/fs/cgroup/cpu](49->21)
> (00.005438) <--
> (00.005459) [/sys/fs/cgroup/cpuacct](51->21)
> (00.005480) <--
> (00.005501) [/sys/fs/cgroup/memory](54->21)
> (00.005521) <--
> (00.005542) [/sys/fs/cgroup/devices](55->21)
> (00.005563) <--
> (00.005583) [/sys/fs/cgroup/freezer](56->21)
> (00.005604) <--
> (00.005625) [/sys/fs/cgroup/blkio](57->21)
> (00.005645) <--
> (00.005666) [/sys/fs/cgroup/perf_event](58->21)
> (00.005687) <--
> (00.005707) [/sys/fs/cgroup/hugetlb](59->21)
> (00.005728) <--
> (00.005748) <--
> (00.005769) [/sys/fs/fuse/connections](22->15)
> (00.005790) <--
> (00.005811) [/sys/kernel/debug](23->15)
> (00.005832) <--
> (00.005852) [/sys/kernel/security](24->15)
> (00.005873) <--
> (00.005894) <--
> (00.005915) [/proc](16->20)
> (00.005936) [/proc/fs/nfsd](62->16)
> (00.005957) <--
> (00.005977) <--
> (00.005998) [/dev](17->20)
> (00.006019) [/dev/pts](18->17)
> (00.006040) <--
> (00.006061) <--
> (00.006081) <--
> (00.006137) ========================================
> (00.006159) Pre-dumping task (pid: 1592)
> (00.006181) ========================================
> (00.006202)
> (00.006223) Collecting mappings (pid: 1592)
> (00.006253) ----------------------------------------
> (00.009056) vma 601000 borrows vfi from previous 400000
> (00.009085) vma 602000 borrows vfi from previous 601000
> (00.009203) vma 7f38e7f94000 borrows vfi from previous 7f38e7dd7000
> (00.009229) vma 7f38e8194000 borrows vfi from previous 7f38e7f94000
> (00.009304) vma 7f38e8198000 borrows vfi from previous 7f38e8194000
> (00.009490) vma 7f38e83c2000 borrows vfi from previous 7f38e83c1000
> (00.009633) Collected, longest area occupies 65536 pages
> (00.009657) 0x400000-0x402000 (8K) prot 0x5 flags 0x2 off 0 reg fp shmid: 0
> (00.009681) 0x601000-0x602000 (4K) prot 0x1 flags 0x2 off 0x1000 reg fp shmid: 0
> (00.009702) 0x602000-0x603000 (4K) prot 0x3 flags 0x2 off 0x2000 reg fp shmid: 0
> (00.009723) 0xb87000-0xbb8000 (196K) prot 0x3 flags 0x22 off 0 reg heap ap shmid: 0
> (00.009745) 0x7f38d7dd7000-0x7f38e7dd7000 (262144K) prot 0x3 flags 0x22 off 0 reg ap shmid: 0
> (00.009766) 0x7f38e7dd7000-0x7f38e7f94000 (1780K) prot 0x5 flags 0x2 off 0 reg fp shmid: 0
> (00.009788) 0x7f38e7f94000-0x7f38e8194000 (2048K) prot 0 flags 0x2 off 0x1bd000 reg fp shmid: 0
> (00.009809) 0x7f38e8194000-0x7f38e8198000 (16K) prot 0x1 flags 0x2 off 0x1bd000 reg fp shmid: 0
> (00.009830) 0x7f38e8198000-0x7f38e819a000 (8K) prot 0x3 flags 0x2 off 0x1c1000 reg fp shmid: 0
> (00.009851) 0x7f38e819a000-0x7f38e819f000 (20K) prot 0x3 flags 0x22 off 0 reg ap shmid: 0
> (00.009885) 0x7f38e819f000-0x7f38e81c2000 (140K) prot 0x5 flags 0x2 off 0 reg fp shmid: 0
> (00.009907) 0x7f38e83ab000-0x7f38e83bb000 (64K) prot 0x3 flags 0x122 off 0 reg ap shmid: 0
> (00.009928) 0x7f38e83bb000-0x7f38e83c1000 (24K) prot 0x3 flags 0x22 off 0 reg ap shmid: 0
> (00.009949) 0x7f38e83c1000-0x7f38e83c2000 (4K) prot 0x1 flags 0x2 off 0x22000 reg fp shmid: 0
> (00.009970) 0x7f38e83c2000-0x7f38e83c4000 (8K) prot 0x3 flags 0x2 off 0x23000 reg fp shmid: 0
> (00.009991) 0x7fff99bd8000-0x7fff99bfa000 (136K) prot 0x3 flags 0x122 off 0 reg ap shmid: 0
> (00.010013) 0x7fff99bfe000-0x7fff99c00000 (8K) prot 0x5 flags 0x22 off 0 reg vdso ap shmid: 0
> (00.010042) 0xffffffffff600000-0xffffffffff601000 (4K) prot 0x5 flags 0x22 off 0 vsys ap shmid: 0
> (00.010064) ----------------------------------------
> (00.010484) Putting parasite blob into 0x7f9a4b085000->0x7f38e831f000
> (00.010520) Dumping GP/FPU registers for 1592
> (00.010562) xsave runtime structure
> (00.010584) -----------------------
> (00.010605) cwd:37f swd:0 twd:0 fop:0 mxcsr:1f80 mxcsr_mask:ffff
> (00.010626) magic1:0 extended_size:0 xstate_bv:0 xstate_size:0
> (00.010647) xstate_bv: 0
> (00.010668) -----------------------
> (00.010688) Putting tsock into pid 1592
> (00.010711) Switching to 1592's net for tsock creation
> pie: Running daemon thread leader
> pie: __sent ack msg: 2 2 0
> pie: Daemon wais for command
> (00.011182) Wait for parasite being daemonized...
> (00.011206) Wait for ack 2 on daemon socket
> (00.011251) Fetched ack: 2 2 0
> (00.011274) Parasite 1592 has been switched to daemon mode
> pie: __fetched msg: 17 0 0
> pie: __sent ack msg: 17 17 0
> pie: Daemon wais for command
> (00.011357) Sent msg to daemon 17 0 0
> (00.011379) Wait for ack 17 on daemon socket
> (00.011420) Fetched ack: 17 17 0
> pie: __fetched msg: 12 0 0
> pie: __sent ack msg: 12 12 0
> pie: Daemon wais for command
> (00.011527) Sent msg to daemon 12 0 0
> (00.011558) Wait for ack 12 on daemon socket
> (00.011599) Fetched ack: 12 12 0
> (00.011632) Pre-dump fds for 1592)
> (00.011835) Error (files.c:424): Can't fstatfs file: Bad file descriptor
> (00.011894) Error (cr-dump.c:1356): Pre-dumping files failed (pid: 1592)
> (00.012003) Waiting for 1592 to trap
> (00.012044) Daemon 1592 exited trapping
> (00.012131) Sent msg to daemon 6 0 0
> (00.012191) 1592 was trapped
> (00.012230) 1592 is going to execute the syscall 2d
> (00.012297) 1592 was trapped
> (00.012337) 1592 is going to execute the syscall 2d
> (00.012402) 1592 was trapped
> (00.012441) 1592 is going to execute the syscall 1
> pie: __fetched msg: 6 0 0
> (00.012508) 1592 was trapped
> (00.012546) 1592 is going to execute the syscall 1
> (00.012614) 1592 was trapped
> (00.012653) 1592 is going to execute the syscall ba
> (00.012718) 1592 was trapped
> (00.012757) 1592 is going to execute the syscall ba
> (00.012823) 1592 was trapped
> (00.012862) 1592 is going to execute the syscall 1
> pie: 1: new_sp=0x7f38e83a6008 ip 0x7f38e7e9883e
> (00.012938) 1592 was trapped
> (00.012978) 1592 is going to execute the syscall 1
> (00.013043) 1592 was trapped
> (00.013082) 1592 is going to execute the syscall 3
> (00.013151) 1592 was trapped
> (00.013190) 1592 is going to execute the syscall 3
> (00.013257) 1592 was trapped
> (00.013296) 1592 is going to execute the syscall 3
> (00.013361) 1592 was trapped
> (00.013400) 1592 is going to execute the syscall 3
> (00.013465) 1592 was trapped
> (00.013504) 1592 is going to execute the syscall f
> (00.013571) 1592 was stopped
> (00.013670) 1592 was trapped
> (00.013709) 1592 is going to execute the syscall 1
> (00.013774) 1592 was trapped
> (00.013813) 1592 is going to execute the syscall 1
> (00.013883) 1592 was trapped
> (00.013923) 1592 is going to execute the syscall b
> (00.014029) 1592 was stopped
> (00.014205) Unfreezing tasks into 1
> (00.014228) Unseizing 1592 into 1
> (00.014284) Pre-dumping tasks' memory
> (00.014384) irmap: Running irmap pre-dump
> (00.014498) Error (cr-dump.c:1687): Pre-dumping FAILED.
More information about the CRIU
mailing list