xfs: fix incorrect log_flushed on fsync
authorAmir Goldstein <amir73il@gmail.com>
Wed, 30 Aug 2017 16:23:12 +0000 (09:23 -0700)
committerDarrick J. Wong <darrick.wong@oracle.com>
Fri, 1 Sep 2017 20:08:26 +0000 (13:08 -0700)
When calling into _xfs_log_force{,_lsn}() with a pointer
to log_flushed variable, log_flushed will be set to 1 if:
1. xlog_sync() is called to flush the active log buffer
AND/OR
2. xlog_wait() is called to wait on a syncing log buffers

xfs_file_fsync() checks the value of log_flushed after
_xfs_log_force_lsn() call to optimize away an explicit
PREFLUSH request to the data block device after writing
out all the file's pages to disk.

This optimization is incorrect in the following sequence of events:

 Task A                    Task B
 -------------------------------------------------------
 xfs_file_fsync()
   _xfs_log_force_lsn()
     xlog_sync()
        [submit PREFLUSH]
                           xfs_file_fsync()
                             file_write_and_wait_range()
                               [submit WRITE X]
                               [endio  WRITE X]
                             _xfs_log_force_lsn()
                               xlog_wait()
        [endio  PREFLUSH]

The write X is not guarantied to be on persistent storage
when PREFLUSH request in completed, because write A was submitted
after the PREFLUSH request, but xfs_file_fsync() of task A will
be notified of log_flushed=1 and will skip explicit flush.

If the system crashes after fsync of task A, write X may not be
present on disk after reboot.

This bug was discovered and demonstrated using Josef Bacik's
dm-log-writes target, which can be used to record block io operations
and then replay a subset of these operations onto the target device.
The test goes something like this:
- Use fsx to execute ops of a file and record ops on log device
- Every now and then fsync the file, store md5 of file and mark
  the location in the log
- Then replay log onto device for each mark, mount fs and compare
  md5 of file to stored value

Cc: Christoph Hellwig <hch@lst.de>
Cc: Josef Bacik <jbacik@fb.com>
Cc: <stable@vger.kernel.org>
Signed-off-by: Amir Goldstein <amir73il@gmail.com>
Reviewed-by: Darrick J. Wong <darrick.wong@oracle.com>
Signed-off-by: Darrick J. Wong <darrick.wong@oracle.com>
fs/xfs/xfs_log.c

index bcb2f860e508dc8567d5310713ec9587bf862afc..c5107c7bc4bf8cfaad37050babd60e9a4f17e109 100644 (file)
@@ -3375,8 +3375,6 @@ maybe_sleep:
                 */
                if (iclog->ic_state & XLOG_STATE_IOERROR)
                        return -EIO;
-               if (log_flushed)
-                       *log_flushed = 1;
        } else {
 
 no_sleep:
@@ -3480,8 +3478,6 @@ try_again:
 
                                xlog_wait(&iclog->ic_prev->ic_write_wait,
                                                        &log->l_icloglock);
-                               if (log_flushed)
-                                       *log_flushed = 1;
                                already_slept = 1;
                                goto try_again;
                        }
@@ -3515,9 +3511,6 @@ try_again:
                         */
                        if (iclog->ic_state & XLOG_STATE_IOERROR)
                                return -EIO;
-
-                       if (log_flushed)
-                               *log_flushed = 1;
                } else {                /* just return */
                        spin_unlock(&log->l_icloglock);
                }