[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