[Pvfs2-users] Problems with pvfs-2.1.6 and suse-10.x

Murali Vilayannur murali.vilayannur at gmail.com
Thu Jan 4 17:21:38 EST 2007


Hi Trach,
Thanks a lot for the prompt response!  Your logs were immensely useful
to track this down,
Attached patch should fix this bug!
Do let us know if this fixes the problem.
Thanks,
Murali

On 1/4/07, Trach-Minh Tran <trach-minh.tran at epfl.ch> wrote:
> On 01/04/2007 09:17 PM, Murali Vilayannur wrote:
> > Hi,
> > Thanks for the test program and the report!
> > I am not able to trigger this on my machine with your program..
> > can you tell me the command line that causes it to reliably fail?
> > Also would it be possible to get an strace output of the failing program?
> > strace -f a.out
> > Sam and I had checked in a few fixes to straighten out some size_t and
> > ssize_t mixups
> > recently. However, those were fixes only for ppc64 and should not have
> > affected x86/32 bit archs..but something could have regressed..
> > Can you also do this prior to running the program and send the
> > relevant kernel logs?
> > Edit the src/kernel/linux-2.6/Makefile and uncomment the EXTRA_CFLAGS
> > += -DPVFS2_KERNEL_DEBUG and recompile..
> > then after loading the kernel module.. run
> > sudo sh -c "echo 1028 > /proc/sys/pvfs2/debug"
> >
> > then run your program and send me the kernel logs.. (if it is too
> > large, send it to me alone)
> > thanks again for the report!
> > Murali
> >
>
> Hi Murali,
>
> Thanks for your quick response!
>
> I did what you asked and attached are the input file "in" I used
> to run the command
>
>  strace -f a.out < /tmp/in 2>&1 | tee /tmp/strace_ifort.out
>
> together with the output of this command "strace_ifort.out"
> and the kernel messages.
>
> Thanks again for your help,
>
> -Minh.
>
> --
> Dr. Trach-Minh Tran
> CRPP/SB/EPFL http://crppwww.epfl.ch
> Swiss Federal Institute of Technology
> CH-1015 Lausanne
> Office: http://plan.epfl.ch/?room=PPB111
> Tel: +41 21 693 4569
> Fax: +41 21 693 5176
>
>
> 16 1
>
>
> execve("/home/ttran/Benchs/io/t_ifort", ["/home/ttran/Benchs/io/t_ifort"], [/* 87 vars */]) = 0
> brk(0)                                  = 0x667000
> mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee6806000
> uname({sys="Linux", node="a1", ...})    = 0
> access("/etc/ld.so.preload", R_OK)      = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/fce/9.1/lib/tls/x86_64/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/intel/fce/9.1/lib/tls/x86_64", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/fce/9.1/lib/tls/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/intel/fce/9.1/lib/tls", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/fce/9.1/lib/x86_64/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/intel/fce/9.1/lib/x86_64", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/fce/9.1/lib/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/intel/fce/9.1/lib", {st_mode=S_IFDIR|0755, st_size=1512, ...}) = 0
> open("/usr/local/intel/cce/9.1/lib/tls/x86_64/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/intel/cce/9.1/lib/tls/x86_64", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/cce/9.1/lib/tls/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/intel/cce/9.1/lib/tls", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/cce/9.1/lib/x86_64/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/intel/cce/9.1/lib/x86_64", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/cce/9.1/lib/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/intel/cce/9.1/lib", {st_mode=S_IFDIR|0755, st_size=1144, ...}) = 0
> open("/usr/local/hdf5_serial-1.6.5/lib/tls/x86_64/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/hdf5_serial-1.6.5/lib/tls/x86_64", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/hdf5_serial-1.6.5/lib/tls/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/hdf5_serial-1.6.5/lib/tls", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/hdf5_serial-1.6.5/lib/x86_64/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/hdf5_serial-1.6.5/lib/x86_64", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/hdf5_serial-1.6.5/lib/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/hdf5_serial-1.6.5/lib", {st_mode=S_IFDIR|0755, st_size=960, ...}) = 0
> open("/home/ttran/cmake/lib/tls/x86_64/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/home/ttran/cmake/lib/tls/x86_64", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/home/ttran/cmake/lib/tls/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/home/ttran/cmake/lib/tls", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/home/ttran/cmake/lib/x86_64/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/home/ttran/cmake/lib/x86_64", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/home/ttran/cmake/lib/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/home/ttran/cmake/lib", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/cmkl/8.1/lib/em64t/tls/x86_64/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/intel/cmkl/8.1/lib/em64t/tls/x86_64", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/cmkl/8.1/lib/em64t/tls/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/intel/cmkl/8.1/lib/em64t/tls", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/cmkl/8.1/lib/em64t/x86_64/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/intel/cmkl/8.1/lib/em64t/x86_64", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/cmkl/8.1/lib/em64t/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/intel/cmkl/8.1/lib/em64t", {st_mode=S_IFDIR|0755, st_size=920, ...}) = 0
> open("/usr/local/mpich2-1.0.4-intel9.1/lib/tls/x86_64/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/mpich2-1.0.4-intel9.1/lib/tls/x86_64", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/mpich2-1.0.4-intel9.1/lib/tls/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/mpich2-1.0.4-intel9.1/lib/tls", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/mpich2-1.0.4-intel9.1/lib/x86_64/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/mpich2-1.0.4-intel9.1/lib/x86_64", 0x7fffc42baf00) = -1 ENOENT (No such file or directory)
> open("/usr/local/mpich2-1.0.4-intel9.1/lib/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> stat("/usr/local/mpich2-1.0.4-intel9.1/lib", {st_mode=S_IFDIR|0755, st_size=1160, ...}) = 0
> open("/etc/ld.so.cache", O_RDONLY)      = 3
> fstat(3, {st_mode=S_IFREG|0644, st_size=128514, ...}) = 0
> mmap(NULL, 128514, PROT_READ, MAP_PRIVATE, 3, 0) = 0x2b6ee6807000
> close(3)                                = 0
> open("/lib64/libm.so.6", O_RDONLY)      = 3
> read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\20@\0\0"..., 832) = 832
> fstat(3, {st_mode=S_IFREG|0755, st_size=409065, ...}) = 0
> mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee6827000
> mmap(NULL, 2445512, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2b6ee6a08000
> fadvise64(3, 0, 2445512, POSIX_FADV_WILLNEED) = 0
> mprotect(0x2b6ee6a5d000, 2093056, PROT_NONE) = 0
> mmap(0x2b6ee6c5c000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x54000) = 0x2b6ee6c5c000
> close(3)                                = 0
> open("/usr/local/intel/fce/9.1/lib/libdl.so.2", O_RDONLY) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/cce/9.1/lib/libdl.so.2", O_RDONLY) = -1 ENOENT (No such file or directory)
> open("/usr/local/hdf5_serial-1.6.5/lib/libdl.so.2", O_RDONLY) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/cmkl/8.1/lib/em64t/libdl.so.2", O_RDONLY) = -1 ENOENT (No such file or directory)
> open("/usr/local/mpich2-1.0.4-intel9.1/lib/libdl.so.2", O_RDONLY) = -1 ENOENT (No such file or directory)
> open("/lib64/libdl.so.2", O_RDONLY)     = 3
> read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220\17\0"..., 832) = 832
> fstat(3, {st_mode=S_IFREG|0755, st_size=19896, ...}) = 0
> mmap(NULL, 2109728, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2b6ee6c5e000
> fadvise64(3, 0, 2109728, POSIX_FADV_WILLNEED) = 0
> mprotect(0x2b6ee6c60000, 2097152, PROT_NONE) = 0
> mmap(0x2b6ee6e60000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x2b6ee6e60000
> close(3)                                = 0
> open("/usr/local/intel/fce/9.1/lib/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/cce/9.1/lib/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> open("/usr/local/hdf5_serial-1.6.5/lib/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/cmkl/8.1/lib/em64t/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> open("/usr/local/mpich2-1.0.4-intel9.1/lib/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
> open("/lib64/libc.so.6", O_RDONLY)      = 3
> read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340\333"..., 832) = 832
> fstat(3, {st_mode=S_IFREG|0755, st_size=1595305, ...}) = 0
> mmap(NULL, 3412216, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2b6ee6e62000
> fadvise64(3, 0, 3412216, POSIX_FADV_WILLNEED) = 0
> mprotect(0x2b6ee6f9b000, 2093056, PROT_NONE) = 0
> mmap(0x2b6ee719a000, 20480, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x138000) = 0x2b6ee719a000
> mmap(0x2b6ee719f000, 16632, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x2b6ee719f000
> close(3)                                = 0
> open("/usr/local/intel/fce/9.1/lib/libgcc_s.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/cce/9.1/lib/libgcc_s.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
> open("/usr/local/hdf5_serial-1.6.5/lib/libgcc_s.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
> open("/usr/local/intel/cmkl/8.1/lib/em64t/libgcc_s.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
> open("/usr/local/mpich2-1.0.4-intel9.1/lib/libgcc_s.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
> open("/lib64/libgcc_s.so.1", O_RDONLY)  = 3
> read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220\36\0"..., 832) = 832
> fstat(3, {st_mode=S_IFREG|0755, st_size=59536, ...}) = 0
> mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee71a4000
> mmap(NULL, 2151240, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2b6ee71a5000
> fadvise64(3, 0, 2151240, POSIX_FADV_WILLNEED) = 0
> mprotect(0x2b6ee71b2000, 2093056, PROT_NONE) = 0
> mmap(0x2b6ee73b1000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xc000) = 0x2b6ee73b1000
> close(3)                                = 0
> mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee73b3000
> arch_prctl(ARCH_SET_FS, 0x2b6ee73b36f0) = 0
> mprotect(0x2b6ee719a000, 12288, PROT_READ) = 0
> mprotect(0x657000, 28672, PROT_READ)    = 0
> munmap(0x2b6ee6807000, 128514)          = 0
> brk(0)                                  = 0x667000
> brk(0x688000)                           = 0x688000
> rt_sigaction(SIGFPE, {0x405464, [], SA_RESTORER|SA_RESTART|SA_NOMASK|SA_SIGINFO, 0x2b6ee6e925b0}, NULL, 8) = 0
> rt_sigaction(SIGILL, {0x405464, [], SA_RESTORER|SA_RESTART|SA_NOMASK|SA_SIGINFO, 0x2b6ee6e925b0}, NULL, 8) = 0
> rt_sigaction(SIGSEGV, {0x405464, [], SA_RESTORER|SA_RESTART|SA_NOMASK|SA_SIGINFO, 0x2b6ee6e925b0}, NULL, 8) = 0
> rt_sigaction(SIGABRT, {0x405464, [], SA_RESTORER|SA_RESTART|SA_NOMASK, 0x2b6ee6e925b0}, NULL, 8) = 0
> rt_sigaction(SIGTERM, {0x405464, [], SA_RESTORER|SA_RESTART|SA_NOMASK, 0x2b6ee6e925b0}, NULL, 8) = 0
> rt_sigaction(SIGQUIT, {0x405464, [], SA_RESTORER|SA_RESTART|SA_NOMASK, 0x2b6ee6e925b0}, {SIG_DFL}, 8) = 0
> rt_sigaction(SIGINT, {0x405464, [], SA_RESTORER|SA_RESTART|SA_NOMASK, 0x2b6ee6e925b0}, {SIG_DFL}, 8) = 0
> ioctl(1, SNDCTL_TMR_TIMEBASE or TCGETS, 0x7fffc42b6070) = -1 EINVAL (Invalid argument)
> ioctl(1, SNDCTL_TMR_TIMEBASE or TCGETS, 0x7fffc42ba290) = -1 EINVAL (Invalid argument)
> fstat(1, {st_mode=S_IFIFO|0600, st_size=0, ...}) = 0
> write(1, " Enter size of record (MB) and n"..., 36 Enter size of record (MB) and nits
> ) = 36
> ioctl(0, SNDCTL_TMR_TIMEBASE or TCGETS, 0x7fffc42b6080) = -1 ENOTTY (Inappropriate ioctl for device)
> ioctl(0, SNDCTL_TMR_TIMEBASE or TCGETS, 0x7fffc42ba2a0) = -1 ENOTTY (Inappropriate ioctl for device)
> fstat(0, {st_mode=S_IFREG|0644, st_size=5, ...}) = 0
> ioctl(0, SNDCTL_TMR_TIMEBASE or TCGETS, 0x7fffc42ba2a0) = -1 ENOTTY (Inappropriate ioctl for device)
> read(0, "16 1\n", 134)                  = 5
> mmap(NULL, 16781312, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee73b4000
> getcwd("/scratch/ttran", 4096)          = 15
> open("/scratch/ttran/test.out", O_RDWR|O_CREAT, 0666) = 3
> fstat(3, {st_mode=S_IFREG|0644, st_size=12582920, ...}) = 0
> ioctl(3, SNDCTL_TMR_TIMEBASE or TCGETS, 0x7fffc42ba2c0) = -1 ENOTTY (Inappropriate ioctl for device)
> mmap(NULL, 4198400, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee83b5000
> lseek(3, 0, SEEK_CUR)                   = 0
> write(3, "\0\0\0\0", 4)                 = 4
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?"..., 524288) = 524288
> write(3, "\0\0\0\1", 4)                 = 4
> lseek(3, 0, SEEK_CUR)                   = 16777224
> lseek(3, 0, SEEK_SET)                   = 0
> write(3, "\0\0\0\1", 4)                 = 4
> lseek(3, 16777224, SEEK_SET)            = 16777224
> lseek(3, 0, SEEK_CUR)                   = 16777224
> ftruncate(3, 16777224)                  = 0
> close(3)                                = 0
> munmap(0x2b6ee83b5000, 4198400)         = 0
> write(1, " writing ok!\n", 13 writing ok!
> )          = 13
> getcwd("/scratch/ttran", 4096)          = 15
> open("/scratch/ttran/test.out", O_RDWR|O_CREAT, 0666) = 3
> fstat(3, {st_mode=S_IFREG|0644, st_size=16777224, ...}) = 0
> ioctl(3, SNDCTL_TMR_TIMEBASE or TCGETS, 0x7fffc42ba2c0) = -1 ENOTTY (Inappropriate ioctl for device)
> brk(0xa8a000)                           = 0xa8a000
> read(3, "\0\0\0\1\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0\0\200?\0"..., 4194304) = 4194304
> mmap(NULL, 16781312, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee83b5000
> read(3, 0x2b6ee87b5010, 12582924)       = -1 ENOMEM (Cannot allocate memory)
> dup(2)                                  = 4
> fcntl(4, F_GETFL)                       = 0x1 (flags O_WRONLY)
> close(4)                                = 0
> write(2, "forrtl: Cannot allocate memory\n", 31forrtl: Cannot allocate memory
> ) = 31
> rt_sigaction(SIGSEGV, {0x43d8fa, [], SA_RESTORER|SA_NOMASK|SA_SIGINFO, 0x2b6ee6e925b0}, {0x405464, [], SA_RESTORER|SA_RESTART|SA_NOMASK|SA_SIGINFO, 0x2b6ee6e925b0}, 8) = 0
> rt_sigaction(SIGILL, {0x43d8fa, [], SA_RESTORER|SA_NOMASK|SA_SIGINFO, 0x2b6ee6e925b0}, {0x405464, [], SA_RESTORER|SA_RESTART|SA_NOMASK|SA_SIGINFO, 0x2b6ee6e925b0}, 8) = 0
> rt_sigaction(SIGBUS, {0x43d8fa, [], SA_RESTORER|SA_NOMASK|SA_SIGINFO, 0x2b6ee6e925b0}, {SIG_DFL}, 8) = 0
> rt_sigaction(SIGFPE, {0x43d8fa, [], SA_RESTORER|SA_NOMASK|SA_SIGINFO, 0x2b6ee6e925b0}, {0x405464, [], SA_RESTORER|SA_RESTART|SA_NOMASK|SA_SIGINFO, 0x2b6ee6e925b0}, 8) = 0
> rt_sigprocmask(SIG_BLOCK, NULL, [], 8)  = 0
> rt_sigprocmask(SIG_BLOCK, NULL, [], 8)  = 0
> getpid()                                = 18940
> open("/proc/18940/exe", O_RDONLY)       = 4
> fstat(4, {st_mode=S_IFREG|0755, st_size=473465, ...}) = 0
> mmap(NULL, 32768, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee93b6000
> read(4, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\2\0>\0\1\0\0\0\300%@\0"..., 32768) = 32768
> lseek(4, 393216, SEEK_SET)              = 393216
> read(4, "\0\0\0\0\0\0\0\0T\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 32768) = 32768
> lseek(4, 425984, SEEK_SET)              = 425984
> close(4)                                = 0
> munmap(0x2b6ee93b6000, 32768)           = 0
> open("/proc/18940/exe", O_RDONLY)       = 4
> fstat(4, {st_mode=S_IFREG|0755, st_size=473465, ...}) = 0
> mmap(NULL, 32768, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee93b6000
> read(4, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\2\0>\0\1\0\0\0\300%@\0"..., 32768) = 32768
> lseek(4, 393216, SEEK_SET)              = 393216
> read(4, "\0\0\0\0\0\0\0\0T\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 32768) = 32768
> lseek(4, 425984, SEEK_SET)              = 425984
> close(4)                                = 0
> munmap(0x2b6ee93b6000, 32768)           = 0
> open("/proc/18940/exe", O_RDONLY)       = 4
> fstat(4, {st_mode=S_IFREG|0755, st_size=473465, ...}) = 0
> mmap(NULL, 32768, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee93b6000
> read(4, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\2\0>\0\1\0\0\0\300%@\0"..., 32768) = 32768
> lseek(4, 393216, SEEK_SET)              = 393216
> read(4, "\0\0\0\0\0\0\0\0T\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 32768) = 32768
> lseek(4, 425984, SEEK_SET)              = 425984
> close(4)                                = 0
> munmap(0x2b6ee93b6000, 32768)           = 0
> open("/proc/18940/exe", O_RDONLY)       = 4
> fstat(4, {st_mode=S_IFREG|0755, st_size=473465, ...}) = 0
> mmap(NULL, 32768, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee93b6000
> read(4, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\2\0>\0\1\0\0\0\300%@\0"..., 32768) = 32768
> lseek(4, 393216, SEEK_SET)              = 393216
> read(4, "\0\0\0\0\0\0\0\0T\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 32768) = 32768
> lseek(4, 425984, SEEK_SET)              = 425984
> close(4)                                = 0
> munmap(0x2b6ee93b6000, 32768)           = 0
> open("/proc/18940/exe", O_RDONLY)       = 4
> fstat(4, {st_mode=S_IFREG|0755, st_size=473465, ...}) = 0
> mmap(NULL, 32768, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee93b6000
> read(4, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\2\0>\0\1\0\0\0\300%@\0"..., 32768) = 32768
> lseek(4, 393216, SEEK_SET)              = 393216
> read(4, "\0\0\0\0\0\0\0\0T\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 32768) = 32768
> lseek(4, 425984, SEEK_SET)              = 425984
> close(4)                                = 0
> munmap(0x2b6ee93b6000, 32768)           = 0
> open("/proc/18940/exe", O_RDONLY)       = 4
> fstat(4, {st_mode=S_IFREG|0755, st_size=473465, ...}) = 0
> mmap(NULL, 32768, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee93b6000
> read(4, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\2\0>\0\1\0\0\0\300%@\0"..., 32768) = 32768
> lseek(4, 393216, SEEK_SET)              = 393216
> read(4, "\0\0\0\0\0\0\0\0T\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 32768) = 32768
> lseek(4, 425984, SEEK_SET)              = 425984
> close(4)                                = 0
> munmap(0x2b6ee93b6000, 32768)           = 0
> open("/proc/18940/exe", O_RDONLY)       = 4
> fstat(4, {st_mode=S_IFREG|0755, st_size=473465, ...}) = 0
> mmap(NULL, 32768, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee93b6000
> read(4, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\2\0>\0\1\0\0\0\300%@\0"..., 32768) = 32768
> lseek(4, 393216, SEEK_SET)              = 393216
> read(4, "\0\0\0\0\0\0\0\0T\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 32768) = 32768
> lseek(4, 425984, SEEK_SET)              = 425984
> close(4)                                = 0
> munmap(0x2b6ee93b6000, 32768)           = 0
> open("/proc/18940/exe", O_RDONLY)       = 4
> fstat(4, {st_mode=S_IFREG|0755, st_size=473465, ...}) = 0
> mmap(NULL, 32768, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee93b6000
> read(4, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\2\0>\0\1\0\0\0\300%@\0"..., 32768) = 32768
> lseek(4, 393216, SEEK_SET)              = 393216
> read(4, "\0\0\0\0\0\0\0\0T\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 32768) = 32768
> lseek(4, 425984, SEEK_SET)              = 425984
> close(4)                                = 0
> munmap(0x2b6ee93b6000, 32768)           = 0
> open("/proc/18940/exe", O_RDONLY)       = 4
> fstat(4, {st_mode=S_IFREG|0755, st_size=473465, ...}) = 0
> mmap(NULL, 32768, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee93b6000
> read(4, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\2\0>\0\1\0\0\0\300%@\0"..., 32768) = 32768
> lseek(4, 393216, SEEK_SET)              = 393216
> read(4, "\0\0\0\0\0\0\0\0T\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 32768) = 32768
> lseek(4, 425984, SEEK_SET)              = 425984
> close(4)                                = 0
> munmap(0x2b6ee93b6000, 32768)           = 0
> open("/proc/18940/exe", O_RDONLY)       = 4
> fstat(4, {st_mode=S_IFREG|0755, st_size=473465, ...}) = 0
> mmap(NULL, 32768, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b6ee93b6000
> read(4, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\2\0>\0\1\0\0\0\300%@\0"..., 32768) = 32768
> lseek(4, 393216, SEEK_SET)              = 393216
> read(4, "\0\0\0\0\0\0\0\0T\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 32768) = 32768
> lseek(4, 425984, SEEK_SET)              = 425984
> close(4)                                = 0
> munmap(0x2b6ee93b6000, 32768)           = 0
> rt_sigaction(SIGSEGV, {0x405464, [], SA_RESTORER|SA_RESTART|SA_NOMASK|SA_SIGINFO, 0x2b6ee6e925b0}, NULL, 8) = 0
> rt_sigaction(SIGILL, {0x405464, [], SA_RESTORER|SA_RESTART|SA_NOMASK|SA_SIGINFO, 0x2b6ee6e925b0}, NULL, 8) = 0
> rt_sigaction(SIGBUS, {SIG_DFL}, NULL, 8) = 0
> rt_sigaction(SIGFPE, {0x405464, [], SA_RESTORER|SA_RESTART|SA_NOMASK|SA_SIGINFO, 0x2b6ee6e925b0}, NULL, 8) = 0
> write(2, "forrtl: severe (39): error durin"..., 924forrtl: severe (39): error during read, unit 99, file /scratch/ttran/test.out
> Image              PC                Routine            Line        Source
> t_ifort            000000000043E6B3  Unknown               Unknown  Unknown
> t_ifort            000000000043CCDE  Unknown               Unknown  Unknown
> t_ifort            000000000042519A  Unknown               Unknown  Unknown
> t_ifort            000000000040481A  Unknown               Unknown  Unknown
> t_ifort            000000000040447B  Unknown               Unknown  Unknown
> t_ifort            000000000041191C  Unknown               Unknown  Unknown
> t_ifort            0000000000402CEE  MAIN__                     22  t.f90
> t_ifort            00000000004026AA  Unknown               Unknown  Unknown
> libc.so.6          00002B6EE6E7FAE4  Unknown               Unknown  Unknown
> t_ifort            00000000004025E9  Unknown               Unknown  Unknown
> ) = 924
> close(3)                                = 0
> munmap(0x2b6ee83b5000, 16781312)        = 0
> exit_group(39)                          = ?
> Process 18940 detached
>
>
> Jan  4 22:30:15 a1 kernel: pvfs2: module version 2.6.1 loaded
> Jan  4 22:31:34 a1 kernel: pvfs2_file_open: called on / (inode is 1048576)
> Jan  4 22:31:34 a1 kernel: pvfs2_file_open returning normally: 0
> Jan  4 22:31:34 a1 kernel: pvfs2_file_release: called on /
> Jan  4 22:31:37 a1 kernel: pvfs2_file_open: called on in (inode is 1048598)
> Jan  4 22:31:37 a1 kernel: pvfs2_file_open returning normally: 0
> Jan  4 22:31:37 a1 kernel: pvfs2_file_read 4194304 at 0
> Jan  4 22:31:37 a1 kernel: pvfs2_file_read: new_nr_segs: 1, seg_count: 1
> Jan  4 22:31:37 a1 kernel: pvfs2_file_read: 1) 00002b0bbb396000 to 00002b0bbb796000 [4194304 bytes]
> Jan  4 22:31:37 a1 kernel: pvfs2_file_read: 1) 1
> Jan  4 22:31:37 a1 kernel: GET op ffff8101b0d94138 -> buffer_index 0
> Jan  4 22:31:37 a1 kernel: pvfs2_file_read: copy_to_user 1 nr_segs 1, offset: 0 total_size: 4194304
> Jan  4 22:31:37 a1 kernel: pvfs_bufmap_copy_to_user_iovec: index 0, size 5
> Jan  4 22:31:37 a1 kernel: PUT buffer_index 0
> Jan  4 22:31:37 a1 kernel: pvfs2_file_read 4194304 at 5
> Jan  4 22:31:37 a1 kernel: pvfs2_file_read: new_nr_segs: 1, seg_count: 1
> Jan  4 22:31:37 a1 kernel: pvfs2_file_read: 1) 00002b0bbb396000 to 00002b0bbb796000 [4194304 bytes]
> Jan  4 22:31:37 a1 kernel: pvfs2_file_read: 1) 1
> Jan  4 22:31:37 a1 kernel: GET op ffff8101378ae178 -> buffer_index 0
> Jan  4 22:31:37 a1 kernel: pvfs2_file_read: copy_to_user 1 nr_segs 1, offset: 5 total_size: 4194304
> Jan  4 22:31:37 a1 kernel: PUT buffer_index 0
> Jan  4 22:31:37 a1 kernel: pvfs2_file_release: called on in
> Jan  4 22:32:17 a1 kernel: pvfs2_file_open: called on in (inode is 1048598)
> Jan  4 22:32:17 a1 kernel: pvfs2_file_open returning normally: 0
> Jan  4 22:32:17 a1 kernel: pvfs2_file_read 4194304 at 0
> Jan  4 22:32:17 a1 kernel: pvfs2_file_read: new_nr_segs: 1, seg_count: 1
> Jan  4 22:32:17 a1 kernel: pvfs2_file_read: 1) 00002b1983660000 to 00002b1983a60000 [4194304 bytes]
> Jan  4 22:32:17 a1 kernel: pvfs2_file_read: 1) 1
> Jan  4 22:32:17 a1 kernel: GET op ffff81018f7ec238 -> buffer_index 0
> Jan  4 22:32:17 a1 kernel: pvfs2_file_read: copy_to_user 1 nr_segs 1, offset: 0 total_size: 4194304
> Jan  4 22:32:17 a1 kernel: pvfs_bufmap_copy_to_user_iovec: index 0, size 5
> Jan  4 22:32:17 a1 kernel: PUT buffer_index 0
> Jan  4 22:32:17 a1 kernel: pvfs2_file_release: called on in
> Jan  4 22:33:12 a1 kernel: pvfs2_file_open: called on test.out (inode is 1048597)
> Jan  4 22:33:12 a1 kernel: pvfs2_file_open returning normally: 0
> Jan  4 22:33:12 a1 kernel: pvfs2_ioctl: called with cmd 21505
> Jan  4 22:33:12 a1 kernel: pvfs2_file_llseek: offset is 0 | origin is 1 | inode size is 12582920
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 0, size 4
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 4 at 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee83b5010 to 00002b6ee83b5014 [4 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81018f7ec2f8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 0 total_size: 4
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 4
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 4, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 4
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee73b4010 to 00002b6ee7434010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81016ddaa338 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 4 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 524292, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 524292
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7434010 to 00002b6ee74b4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81020dfee378 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 524292 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 1048580, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 1048580
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee74b4010 to 00002b6ee7534010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff810102ec43b8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 1048580 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 1572868, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 1572868
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7534010 to 00002b6ee75b4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81018952e3f8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 1572868 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 2097156, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 2097156
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee75b4010 to 00002b6ee7634010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff8101a63ac438 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 2097156 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 2621444, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 2621444
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7634010 to 00002b6ee76b4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff8101b81ca478 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 2621444 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 3145732, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 3145732
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee76b4010 to 00002b6ee7734010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff8101890704b8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 3145732 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 3670020, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 3670020
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7734010 to 00002b6ee77b4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff810172bde2b8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 3670020 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 4194308, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 4194308
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee77b4010 to 00002b6ee7834010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81018f7ec2f8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 4194308 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 4718596, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 4718596
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7834010 to 00002b6ee78b4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81016ddaa338 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 4718596 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 5242884, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 5242884
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee78b4010 to 00002b6ee7934010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81020dfee378 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 5242884 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 5767172, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 5767172
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7934010 to 00002b6ee79b4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff810102ec43b8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 5767172 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 6291460, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 6291460
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee79b4010 to 00002b6ee7a34010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81018952e3f8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 6291460 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 6815748, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 6815748
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7a34010 to 00002b6ee7ab4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff8101a63ac438 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 6815748 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 7340036, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 7340036
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7ab4010 to 00002b6ee7b34010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff8101b81ca478 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 7340036 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 7864324, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 7864324
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7b34010 to 00002b6ee7bb4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff8101890704b8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 7864324 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 8388612, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 8388612
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7bb4010 to 00002b6ee7c34010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff810172bde2b8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 8388612 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 8912900, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 8912900
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7c34010 to 00002b6ee7cb4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81018f7ec2f8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 8912900 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 9437188, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 9437188
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7cb4010 to 00002b6ee7d34010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81016ddaa338 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 9437188 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 9961476, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 9961476
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7d34010 to 00002b6ee7db4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81020dfee378 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 9961476 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 10485764, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 10485764
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7db4010 to 00002b6ee7e34010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff810102ec43b8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 10485764 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 11010052, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 11010052
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7e34010 to 00002b6ee7eb4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81018952e3f8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 11010052 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 11534340, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 11534340
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7eb4010 to 00002b6ee7f34010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff8101a63ac438 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 11534340 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 12058628, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 12058628
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7f34010 to 00002b6ee7fb4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff8101b81ca478 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 12058628 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 12582916, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 12582916
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee7fb4010 to 00002b6ee8034010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff8101890704b8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 12582916 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 13107204, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 13107204
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee8034010 to 00002b6ee80b4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff810172bde2b8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 13107204 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 13631492, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 13631492
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee80b4010 to 00002b6ee8134010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81018f7ec2f8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 13631492 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 14155780, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 14155780
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee8134010 to 00002b6ee81b4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81016ddaa338 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 14155780 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 14680068, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 14680068
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee81b4010 to 00002b6ee8234010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81020dfee378 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 14680068 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 15204356, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 15204356
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee8234010 to 00002b6ee82b4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff810102ec43b8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 15204356 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 15728644, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 15728644
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee82b4010 to 00002b6ee8334010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff81018952e3f8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 15728644 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 16252932, size 524288
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 524288 at 16252932
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00002b6ee8334010 to 00002b6ee83b4010 [524288 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff8101a63ac438 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 16252932 total_size: 524288
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 524288
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 16777220, size 4
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 4 at 16777220
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00007fffc42bb328 to 00007fffc42bb32c [4 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff8101b81ca478 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 16777220 total_size: 4
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 4
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_llseek: offset is 0 | origin is 1 | inode size is 16777220
> Jan  4 22:33:12 a1 kernel: pvfs2_file_llseek: offset is 0 | origin is 0 | inode size is 16777220
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: proceeding with offset : 0, size 4
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write 4 at 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 00007fffc42bb328 to 00007fffc42bb32c [4 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff8101890704b8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_write: copy_to_user 1 nr_segs 1, offset: 0 total_size: 4
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_iovec_from_user: index 0, size 4
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_llseek: offset is 16777224 | origin is 0 | inode size is 16777220
> Jan  4 22:33:12 a1 kernel: pvfs2_file_llseek: offset is 0 | origin is 1 | inode size is 16777220
> Jan  4 22:33:12 a1 kernel: pvfs2_file_release: called on test.out
> Jan  4 22:33:12 a1 kernel: pvfs2_file_open: called on test.out (inode is 1048597)
> Jan  4 22:33:12 a1 kernel: pvfs2_file_open returning normally: 0
> Jan  4 22:33:12 a1 kernel: pvfs2_ioctl: called with cmd 21505
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read 4194304 at 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: new_nr_segs: 1, seg_count: 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: 1) 0000000000669510 to 0000000000a69510 [4194304 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: 1) 1
> Jan  4 22:33:12 a1 kernel: GET op ffff810172bde2b8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: copy_to_user 1 nr_segs 1, offset: 0 total_size: 4194304
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_to_user_iovec: index 0, size 4194304
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: Splitting iovecs from 1 to 4 [max_new 4]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read 12582924 at 4194304
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: new_nr_segs: 4, seg_count: 4
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: 1) 00002b6ee87b5010 to 00002b6ee8bb5010 [4194304 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: 2) 00002b6ee8bb5010 to 00002b6ee8fb5010 [4194304 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: 3) 00002b6ee8fb5010 to 00002b6ee93b5010 [4194304 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: 4) 00002b6ee93b5010 to 00002b6ee93b501c [12 bytes]
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: 1) 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: 2) 1
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: 3) 18446604444960509873
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: 4) 18446604444960509873
> Jan  4 22:33:12 a1 kernel: GET op ffff81018f7ec2f8 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: copy_to_user 1 nr_segs 1, offset: 4194304 total_size: 4194304
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_to_user_iovec: index 0, size 4194304
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: GET op ffff81016ddaa338 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: copy_to_user 1 nr_segs 1, offset: 8388608 total_size: 4194304
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_to_user_iovec: index 0, size 4194304
> Jan  4 22:33:12 a1 kernel: PUT buffer_index 0
> Jan  4 22:33:12 a1 kernel: GET op ffff81020dfee378 -> buffer_index 0
> Jan  4 22:33:12 a1 kernel: pvfs2_file_read: copy_to_user 1 nr_segs 18446604444960509873, offset: 12582912 total_size: 4194304
> Jan  4 22:33:12 a1 kernel: pvfs_bufmap_copy_to_user_iovec: index 0, size 4194304
> Jan  4 22:33:12 a1 kernel: pvfs2_bufmap_copy_to_user_iovec: failed allocating memory
> Jan  4 22:33:12 a1 kernel: /usr/local/src/pvfs2/pvfs-2.6.1/src/kernel/linux-2.6/file.c line 278: pvfs2_file_read: Failed to copy-out buffers.  Please make sure that the pvfs2-client is running (-12)
> Jan  4 22:33:12 a1 kernel: pvfs2_file_release: called on test.out
>
>
>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: misc-bug.patch
Type: application/octet-stream
Size: 1052 bytes
Desc: not available
Url : http://www.beowulf-underground.org/pipermail/pvfs2-users/attachments/20070104/0c0cf24f/misc-bug-0001.obj


More information about the Pvfs2-users mailing list