• Marko Mäkelä's avatar
    MDEV-29911 InnoDB recovery and mariadb-backup --prepare fail to report detailed progress · 2f9e2647
    Marko Mäkelä authored
    The progress reporting of InnoDB crash recovery was rather intermittent.
    Nothing was reported during the single-threaded log record parsing, which
    could consume minutes when parsing a large log. During log application,
    there only was progress reporting in background threads that would be
    invoked on data page read completion.
    
    The progress reporting here will be detailed like this:
    
    InnoDB: Starting crash recovery from checkpoint LSN=503549688
    InnoDB: Parsed redo log up to LSN=1990840177; to recover: 124806 pages
    InnoDB: Parsed redo log up to LSN=2729777071; to recover: 186123 pages
    InnoDB: Parsed redo log up to LSN=3488599173; to recover: 248397 pages
    InnoDB: Parsed redo log up to LSN=4177856618; to recover: 306469 pages
    InnoDB: Multi-batch recovery needed at LSN 4189599815
    InnoDB: End of log at LSN=4483551634
    InnoDB: To recover: LSN 4189599815/4483551634; 307490 pages
    InnoDB: To recover: LSN 4189599815/4483551634; 197159 pages
    InnoDB: To recover: LSN 4189599815/4483551634; 67623 pages
    InnoDB: Parsed redo log up to LSN=4353924218; to recover: 102083 pages
    ...
    InnoDB: log sequence number 4483551634 ...
    
    The previous messages "Starting a batch to recover" or
    "Starting a final batch to recover" will be replaced by
    "To recover: ... pages" messages.
    
    If a batch lasts longer than 15 seconds, then there will be
    progress reports every 15 seconds, showing the number of remaining pages.
    For the non-final batch, the "To recover:" message includes two end LSN:
    that of the batch, and of the recovered log. This is the primary measure
    of progress. The batch will end once the number of pages to recover
    reaches 0.
    
    If recovery is possible in a single batch, the output will look like this,
    with a shorter "To recover:" message that counts only the remaining pages:
    
    InnoDB: Starting crash recovery from checkpoint LSN=503549688
    InnoDB: Parsed redo log up to LSN=1998701027; to recover: 125560 pages
    InnoDB: Parsed redo log up to LSN=2734136874; to recover: 186446 pages
    InnoDB: Parsed redo log up to LSN=3499505504; to recover: 249378 pages
    InnoDB: Parsed redo log up to LSN=4183247844; to recover: 306964 pages
    InnoDB: End of log at LSN=4483551634
    ...
    InnoDB: To recover: 331797 pages
    ...
    InnoDB: log sequence number 4483551634 ...
    
    We will also speed up recovery by improving the memory management and
    implementing multi-threaded recovery of data pages that will not need
    to be read into the buffer pool ("fake read"). Log application in the
    "fake read" threads will be protected by an atomic being_recovered field
    and exclusive buf_page_t::latch.
    
    Recovery will reserve for data pages two thirds of the buffer pool,
    or 256 pages, whichever is smaller. Previously, we could only use at most
    one third of the buffer pool for buffered log records. This would typically
    mean that with large buffer pools, recovery unnecessary consisted of
    multiple batches.
    
    If recovery runs out of memory, it will "roll back" or "rewind" the current
    mini-transaction. The recv_sys.lsn and recv_sys.pages will correspond
    to the "out of memory LSN", at the end of the previous complete
    mini-transaction.
    
    If recovery runs out of memory while executing the final recovery batch,
    we can simply invoke recv_sys.apply(false) to make room, and resume
    parsing.
    
    If recovery runs out of memory before the final batch, we will scan
    the redo log to the end (recv_sys.scanned_lsn) and check for any missing
    or inconsistent files. If recv_init_crash_recovery_spaces() does not
    report any potentially missing tablespaces, we can make use of the
    already stored recv_sys.pages and only rewind to the "out of memory LSN".
    Else, we must keep parsing and invoking recv_validate_tablespace()
    until an error has been found or everything has been resolved, and
    ultimatily rewind to to the checkpoint LSN.
    
    recv_sys_t::pages_it: A cached iterator to recv_sys.pages
    
    recv_sys_t::parse_mtr(): Remove an ATTRIBUTE_NOINLINE that would
    prevent tail call optimization in recv_sys_t::parse_pmem().
    
    recv_sys_t::parse(), recv_sys_t::parse_mtr(), recv_sys_t::parse_pmem():
    Add template<bool store> parameter. Redo log record parsing
    (store=false) is better specialized from store=true
    (with bool if_exists) so that we can avoid some conditional branches
    in frequently invoked low-level code.
    
    recv_sys_t::is_memory_exhausted(): Remove. The special parse() status
    GOT_OOM will report out-of-memory situation at the low level.
    
    recv_sys_t::rewind(), page_recv_t::recs_t::rewind():
    Remove all log starting with a specific LSN.
    
    recv_scan_log(): Separate some code for only parsing, not storing log.
    In rewound_lsn, remember the LSN at which last_phase=false recovery
    ran out of memory. This is where the next call to recv_scan_log()
    will resume storing the log. This replaces recv_sys.last_stored_lsn.
    
    recv_sys_t::parse(): Evaluate the template parameter store in a few more
    cases, to allow dead code to be eliminated at compile time.
    
    recv_sys_t::scanned_lsn: The end of the log found by recv_scan_log().
    The special value 1 means that recv_sys has been initialized but
    no log has been parsed.
    
    IORequest::write_complete(), IORequest::read_complete():
    Replaces fil_aio_callback().
    
    read_io_callback(), write_io_callback(): Replaces io_callback().
    
    IORequest::fake_read_complete(), fake_io_callback(), os_fake_read():
    Process a "fake read" request for concurrent recovery.
    
    recv_sys_t::apply_batch(): Choose a number of successive pages
    for a recovery batch.
    
    recv_sys_t::erase(recv_sys_t::map::iterator): Remove log records for a
    page whose recovery is not in progress. Log application threads
    will not invoke this; they will only set being_recovered=-1 to indicate
    that the entry is no longer needed.
    
    recv_sys_t::garbage_collect(): Remove all being_recovered=-1 entries.
    
    recv_sys_t::wait_for_pool(): Wait for some space to become available
    in the buffer pool.
    
    mlog_init_t::mark_ibuf_exist(): Avoid calls to
    recv_sys::recover_low() via ibuf_page_exists() and buf_page_get_low().
    Such calls would lead to double locking of recv_sys.mutex, which
    depending on implementation could cause a deadlock. We will use
    lower-level calls to look up index pages.
    
    buf_LRU_block_remove_hashed(): Disable consistency checks for freed
    ROW_FORMAT=COMPRESSED pages. Their contents could be uninitialized garbage.
    This fixes an occasional failure of the test
    innodb.innodb_bulk_create_index_debug.
    
    Tested by: Matthias Leich
    2f9e2647
xtrabackup.cc 203 KB