[MDEV-15441] mysqld started in duble ? (mariadb 10.1.26 + Debian 9.3) Created: 2018-02-28  Updated: 2018-07-02  Resolved: 2018-07-02

Status: Closed
Project: MariaDB Server
Component/s: Server
Affects Version/s: 10.1
Fix Version/s: N/A

Type: Bug Priority: Major
Reporter: Aurélien LEQUOY Assignee: Unassigned
Resolution: Incomplete Votes: 0
Labels: need_feedback


 Description   

2018-02-28  9:54:13 140093945811520 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2018-02-28  9:54:13 140093945811520 [Note] InnoDB: The InnoDB memory heap is disabled
2018-02-28  9:54:13 140093945811520 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2018-02-28  9:54:13 140093945811520 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2018-02-28  9:54:13 140093945811520 [Note] InnoDB: Compressed tables use zlib 1.2.8
2018-02-28  9:54:13 140093945811520 [Note] InnoDB: Using Linux native AIO
2018-02-28  9:54:13 140093945811520 [Note] InnoDB: Using SSE crc32 instructions
2018-02-28  9:54:13 140093945811520 [Note] InnoDB: Initializing buffer pool, size = 40.0G
2018-02-28  9:54:14 140093945811520 [Note] InnoDB: Completed initialization of buffer pool
2018-02-28  9:54:14 140093945811520 [Note] InnoDB: Highest supported file format is Barracuda.
2018-02-28  9:54:14 140093945811520 [Note] InnoDB: Starting crash recovery from checkpoint LSN=51522759208
2018-02-28  9:54:30 140093945811520 [Note] InnoDB: Processed 2178 .ibd/.isl files
2018-02-28  9:54:46 140093945811520 [Note] InnoDB: Processed 4521 .ibd/.isl files
2018-02-28  9:55:02 140093945811520 [Note] InnoDB: Processed 6897 .ibd/.isl files
...
2018-02-28 10:03:50 140093945811520 [Note] InnoDB: Processed 88209 .ibd/.isl files
2018-02-28 10:04:06 140093945811520 [Note] InnoDB: Processed 90530 .ibd/.isl files
2018-02-28 10:04:11 140544819712576 [ERROR] mysqld: Can't lock aria control file '/data/mysql/data/aria_log_control' for exclusive use, error: 11. Will retry for 30 seconds
2018-02-28 10:04:22 140093945811520 [Note] InnoDB: Processed 93016 .ibd/.isl files
2018-02-28 10:04:38 140093945811520 [Note] InnoDB: Processed 95205 .ibd/.isl files
2018-02-28 10:04:42 140544819712576 [ERROR] mysqld: Got error 'Could not get an exclusive lock; file is probably in use by another process' when trying to use aria control file '/data/mysql/data/aria_log_control'
2018-02-28 10:04:42 140544819712576 [ERROR] Plugin 'Aria' init function returned error.
2018-02-28 10:04:42 140544819712576 [ERROR] Plugin 'Aria' registration as a STORAGE ENGINE failed.
2018-02-28 10:04:42 140544819712576 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2018-02-28 10:04:42 140544819712576 [Note] InnoDB: The InnoDB memory heap is disabled
2018-02-28 10:04:42 140544819712576 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2018-02-28 10:04:42 140544819712576 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2018-02-28 10:04:42 140544819712576 [Note] InnoDB: Compressed tables use zlib 1.2.8
2018-02-28 10:04:42 140544819712576 [Note] InnoDB: Using Linux native AIO
2018-02-28 10:04:42 140544819712576 [Note] InnoDB: Using SSE crc32 instructions
2018-02-28 10:04:42 140544819712576 [Note] InnoDB: Initializing buffer pool, size = 40.0G
2018-02-28 10:04:43 140544819712576 [Note] InnoDB: Completed initialization of buffer pool
2018-02-28 10:04:43 140544819712576 [ERROR] InnoDB: Unable to lock ./ibdata1, error: 11
2018-02-28 10:04:43 140544819712576 [Note] InnoDB: Retrying to lock the first data file
2018-02-28 10:04:44 140544819712576 [ERROR] InnoDB: Unable to lock ./ibdata1, error: 11
2018-02-28 10:04:45 140544819712576 [ERROR] InnoDB: Unable to lock ./ibdata1, error: 11
2018-02-28 10:04:46 140544819712576 [ERROR] InnoDB: Unable to lock ./ibdata1, error: 11
.2018-02-28 10:05:09 140544819712576 [ERROR] InnoDB: Unable to lock ./ibdata1, error: 11
...
2018-02-28 10:05:10 140093945811520 [Note] InnoDB: Processed 99660 .ibd/.isl files
2018-02-28 10:05:10 140544819712576 [ERROR] InnoDB: Unable to lock ./ibdata1, error: 11
201
..
2018-02-28 10:06:23 140544819712576 [ERROR] InnoDB: Unable to lock ./ibdata1, error: 11
2018-02-28 10:06:23 140544819712576 [Note] InnoDB: Unable to open the first data file
2018-02-28 10:06:23 7fd3240dd240  InnoDB: Operating system error number 1 in a file operation.
InnoDB: Error number 1 means 'Operation not permitted'.
InnoDB: Some operating system error numbers are described at
InnoDB: http://dev.mysql.com/doc/refman/5.6/en/operating-system-error-codes.html
2018-02-28 10:06:23 140544819712576 [ERROR] InnoDB: Can't open './ibdata1'
2018-02-28 10:06:23 140544819712576 [ERROR] InnoDB: Could not open or create the system tablespace. If you tried to add new data files to the system tablespace, and it failed here, you should now edit innodb_data_file_path in my.cnf back to what it was, and remove the new ibdata files InnoDB created in this failed attempt. InnoDB only wrote those files full of zeros, but did not yet use them in any way. But be careful: do not remove old data files which contain your precious data!
2018-02-28 10:06:23 140544819712576 [ERROR] Plugin 'InnoDB' init function returned error.
2018-02-28 10:06:23 140544819712576 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
2018-02-28 10:06:23 140544819712576 [Note] Plugin 'FEEDBACK' is disabled.
2018-02-28 10:06:23 140544819712576 [ERROR] Unknown/unsupported storage engine: InnoDB
2018-02-28 10:06:23 140544819712576 [ERROR] Aborting
 
2018-02-28 10:06:30 140093945811520 [Note] InnoDB: Processed 112244 .ibd/.isl files
2018-02-28 10:06:46 140093945811520 [Note] InnoDB: Processed 114543 .ibd/.isl files
 
2018-02-28 10:10:14 140093945811520 [Note] InnoDB: Processed 146542 .ibd/.isl files
2018-02-28 10:10:30 140093945811520 [Note] InnoDB: Processed 148984 .ibd/.isl files
2018-02-28 10:10:46 140093945811520 [Note] InnoDB: Processed 151360 .ibd/.isl files
2018-02-28 10:12:38 140165176279616 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2018-02-28 10:12:38 140165176279616 [Note] InnoDB: The InnoDB memory heap is disabled
2018-02-28 10:12:38 140165176279616 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2018-02-28 10:12:38 140165176279616 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2018-02-28 10:12:38 140165176279616 [Note] InnoDB: Compressed tables use zlib 1.2.8
2018-02-28 10:12:38 140165176279616 [Note] InnoDB: Using Linux native AIO
2018-02-28 10:12:38 140165176279616 [Note] InnoDB: Using SSE crc32 instructions
2018-02-28 10:12:38 140165176279616 [Note] InnoDB: Initializing buffer pool, size = 40.0G
2018-02-28 10:12:38 140165176279616 [Note] InnoDB: Completed initialization of buffer pool
2018-02-28 10:12:39 140165176279616 [Note] InnoDB: Highest supported file format is Barracuda.
2018-02-28 10:12:39 140165176279616 [Note] InnoDB: Starting crash recovery from checkpoint LSN=51522759208
2018-02-28 10:12:55 140165176279616 [Note] InnoDB: Processed 2332 .ibd/.isl files
2018-02-28 10:13:11 140165176279616 [Note] InnoDB: Processed 4576 .ibd/.isl files
2018-02-28 10:13:27 140165176279616 [Note] InnoDB: Processed 6996 .ibd/.isl files
2018-02-28 10:13:43 140165176279616 [Note] InnoDB: Processed 9394 .ibd/.isl files
 
ok my client decided to reboot server that's was bad idea
^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@2018-02-28 10:17:27 140436623778368 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2018-02-28 10:17:27 140436623778368 [Note] InnoDB: The InnoDB memory heap is disabled
2018-02-28 10:17:27 140436623778368 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2018-02-28 10:17:27 140436623778368 [Note] InnoDB: GCC builtin __atomic_threa2018-02-28 10:51:51 140493708309056 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2018-02-28 10:51:51 140493708309056 [Note] InnoDB: The InnoDB memory heap is disabled
2018-02-28 10:51:51 140493708309056 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2018-02-28 10:51:51 140493708309056 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2018-02-28 10:51:51 140493708309056 [Note] InnoDB: Compressed tables use zlib 1.2.8
2018-02-28 10:51:51 140493708309056 [Note] InnoDB: Using Linux native AIO
2018-02-28 10:51:51 140493708309056 [Note] InnoDB: Using SSE crc32 instructions
2018-02-28 10:51:51 140493708309056 [Note] InnoDB: Initializing buffer pool, size = 40.0G
2018-02-28 10:51:52 140493708309056 [Note] InnoDB: Completed initialization of buffer pool
2018-02-28 10:51:52 140493708309056 [Note] InnoDB: Highest supported file format is Barracuda.
2018-02-28 10:51:52 140493708309056 [Note] InnoDB: Starting crash recovery from checkpoint LSN=51522759208
2018-02-28 10:52:08 140493708309056 [Note] InnoDB: Processed 2222 .ibd/.isl files
2018-02-28 10:52:24 140493708309056 [Note] InnoDB: Processed 4620 .ibd/.isl files
2018-02-28 10:52:40 140493708309056 [Note] InnoDB: Processed 7029 .ibd/.isl files
2018-02-28 10:52:56 140493708309056 [Note] InnoDB: Processed 9427 .ibd/.isl files
2018-02-28 10:53:12 140493708309056 [Note] InnoDB: Processed 11979 .ibd/.isl files
2018-02-28 10:53:28 140493708309056 [Note] InnoDB: Processed 14487 .ibd/.isl files
2018-02-28 10:53:44 140493708309056 [Note] InnoDB: Processed 17006 .ibd/.isl files
2018-02-28 10:54:00 140493708309056 [Note] InnoDB: Processed 19789 .ibd/.isl files
...
...
2018-02-28 11:23:27 140518379704896 [Note] InnoDB: Processed 220000 .ibd/.isl files
2018-02-28 11:23:42 140518379704896 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer...
2018-02-28 11:23:43 140518379704896 [Note] InnoDB: Read redo log up to LSN=51522890240
2018-02-28 11:23:43 140518379704896 [Note] InnoDB: Starting final batch to recover 13 pages from redo log
InnoDB: Last MySQL binlog file position 0 950671726, file name /data/mysql/binlog/mysql-bin.000022
2018-02-28 11:33:33 140699591979584 [ERROR] mysqld: Can't lock aria control file '/data/mysql/data/aria_log_control' for exclusive use, error: 11. Will retry for 30 seconds
2018-02-28 11:34:04 140699591979584 [ERROR] mysqld: Got error 'Could not get an exclusive lock; file is probably in use by another process' when trying to use aria control file '/data/mysql/data/aria_log_control'
2018-02-28 11:34:04 140699591979584 [ERROR] Plugin 'Aria' init function returned error.
2018-02-28 11:34:04 140699591979584 [ERROR] Plugin 'Aria' registration as a STORAGE ENGINE failed.
2018-02-28 11:34:04 140699591979584 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2018-02-28 11:34:04 140699591979584 [Note] InnoDB: The InnoDB memory heap is disabled
2018-02-28 11:34:04 140699591979584 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2018-02-28 11:34:04 140699591979584 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2018-02-28 11:34:04 140699591979584 [Note] InnoDB: Compressed tables use zlib 1.2.8
2018-02-28 11:34:04 140699591979584 [Note] InnoDB: Using Linux native AIO
2018-02-28 11:34:04 140699591979584 [Note] InnoDB: Using SSE crc32 instructions
2018-02-28 11:34:04 140699591979584 [Note] InnoDB: Initializing buffer pool, size = 40.0G
2018-02-28 11:34:05 140699591979584 [Note] InnoDB: Completed initialization of buffer pool
2018-02-28 11:34:05 140699591979584 [ERROR] InnoDB: Unable to lock ./ibdata1, error: 11
2018-02-28 11:34:05 140699591979584 [Note] InnoDB: Retrying to lock the first data file
2018-02-28 11:34:06 140699591979584 [ERROR] InnoDB: Unable to lock ./ibdata1, error: 11
2018-02-28 11:34:07 140699591979584 [ERROR] InnoDB: Unable to lock ./ibdata1, error: 11
2018-02-28 11:34:08 140699591979584 [ERROR] InnoDB: Unable to lock ./ibdata1, error: 1
 
...
2018-02-28 11:35:44 140699591979584 [ERROR] InnoDB: Unable to lock ./ibdata1, error: 11
2018-02-28 11:35:45 140699591979584 [ERROR] InnoDB: Unable to lock ./ibdata1, error: 11
2018-02-28 11:35:45 140699591979584 [Note] InnoDB: Unable to open the first data file
2018-02-28 11:35:45 7ff72d333240  InnoDB: Operating system error number 1 in a file operation.
InnoDB: Error number 1 means 'Operation not permitted'.
InnoDB: Some operating system error numbers are described at
InnoDB: http://dev.mysql.com/doc/refman/5.6/en/operating-system-error-codes.html
2018-02-28 11:35:45 140699591979584 [ERROR] InnoDB: Can't open './ibdata1'
2018-02-28 11:35:45 140699591979584 [ERROR] InnoDB: Could not open or create the system tablespace. If you tried to add new data files to the system tablespace, and it failed here, you should now edit innodb_data_file_path in my.cnf back to what it was, and remove the new ibdata files InnoDB created in this failed attempt. InnoDB only wrote those files full of zeros, but did not yet use them in any way. But be careful: do not remove old data files which contain your precious data!
2018-02-28 11:35:45 140699591979584 [ERROR] Plugin 'InnoDB' init function returned error.
2018-02-28 11:35:45 140699591979584 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
2018-02-28 11:35:45 140699591979584 [Note] Plugin 'FEEDBACK' is disabled.
2018-02-28 11:35:45 140699591979584 [ERROR] Unknown/unsupported storage engine: InnoDB
2018-02-28 11:35:45 140699591979584 [ERROR] Aborting
 
2018-02-28 11:35:58 140518379704896 [Note] InnoDB: 128 rollback segment(s) are active.
2018-02-28 11:35:58 140518379704896 [Note] InnoDB: Waiting for purge to start
2018-02-28 11:35:58 140518379704896 [Note] InnoDB:  Percona XtraDB (http://www.percona.com) 5.6.36-82.1 started; log sequence number 51522825808
2018-02-28 11:35:59 140470139193088 [Note] InnoDB: Dumping buffer pool(s) not yet started
2018-02-28 11:35:59 140518379704896 [Note] Plugin 'FEEDBACK' is disabled.
2018-02-28 11:35:59 140518379704896 [Note] Recovering after a crash using /data/mysql/binlog/mysql-bin
2018-02-28 11:36:05 140518379704896 [ERROR] Error in Log_event::read_log_event(): 'Event invalid', data_len: 0, event_type: 0
2018-02-28 11:36:05 140518379704896 [Note] Starting crash recovery...
2018-02-28 11:36:05 140518379704896 [Note] Crash recovery finished.
2018-02-28 11:36:05 140518379704896 [Note] Server socket created on IP: '::'.
2018-02-28 11:36:05 140518379083520 [Note] /usr/sbin/mysqld: Normal shutdown
 
2018-02-28 11:36:06 140518379704896 [Warning] 'user' entry 'pmacontrol@178.*' ignored in --skip-name-resolve mode.
2018-02-28 11:36:06 140518379704896 [Note] /usr/sbin/mysqld: ready for connections.
Version: '10.1.26-MariaDB-0+deb9u1'  socket: '/var/run/mysqld/mysqld.sock'  port: 3306  Debian 9.1
2018-02-28 11:36:06 140518379083520 [Note] Event Scheduler: Purging the queue. 0 events
2018-02-28 11:36:06 140470172763904 [Note] InnoDB: FTS optimize thread exiting.
2018-02-28 11:36:06 140518379083520 [Note] InnoDB: Starting shutdown...
2018-02-28 11:36:06 140518379083520 [Note] InnoDB: Waiting for page_cleaner to finish flushing of buffer pool
2018-02-28 11:36:09 140518379083520 [Note] InnoDB: Shutdown completed; log sequence number 51522825818
2018-02-28 11:36:09 140518379083520 [Note] /usr/sbin/mysqld: Shutdown complete
 
2018-02-28 11:36:53 139791898899008 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2018-02-28 11:36:53 139791898899008 [Note] InnoDB: The InnoDB memory heap is disabled
2018-02-28 11:36:53 139791898899008 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2018-02-28 11:36:53 139791898899008 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2018-02-28 11:36:53 139791898899008 [Note] InnoDB: Compressed tables use zlib 1.2.8
2018-02-28 11:36:53 139791898899008 [Note] InnoDB: Using Linux native AIO
2018-02-28 11:36:53 139791898899008 [Note] InnoDB: Using SSE crc32 instructions
2018-02-28 11:36:53 139791898899008 [Note] InnoDB: Initializing buffer pool, size = 40.0G
2018-02-28 11:36:53 139791898899008 [Note] InnoDB: Completed initialization of buffer pool
2018-02-28 11:36:54 139791898899008 [Note] InnoDB: Highest supported file format is Barracuda.
2018-02-28 11:37:01 139791898899008 [Note] InnoDB: 128 rollback segment(s) are active.
2018-02-28 11:37:01 139791898899008 [Note] InnoDB: Waiting for purge to start
2018-02-28 11:37:01 139791898899008 [Note] InnoDB:  Percona XtraDB (http://www.percona.com) 5.6.36-82.1 started; log sequence number 51522825818
2018-02-28 11:37:01 139791898899008 [Note] Plugin 'FEEDBACK' is disabled.
2018-02-28 11:37:01 139744054834944 [Note] InnoDB: Dumping buffer pool(s) not yet started
2018-02-28 11:37:02 139791898899008 [Note] Server socket created on IP: '::'.
2018-02-28 11:37:02 139791898899008 [Warning] 'user' entry 'pmacontrol@178.*' ignored in --skip-name-resolve mode.

and in syslog

Feb 28 09:53:59 dba systemd[1]: Started Create Static Device Nodes in /dev.
Feb 28 09:53:59 dba systemd-fsck[371]: /: clean, 170531/1281120 files, 2567037/5119728 blocks
Feb 28 09:53:59 dba systemd[1]: Starting udev Kernel Device Manager...
Feb 28 09:53:59 dba systemd[1]: Started File System Check on Root Device.
Feb 28 09:53:59 dba systemd[1]: Starting Remount Root and Kernel File Systems...
Feb 28 09:53:59 dba kernel: Linux version 4.9.33-mod-std-ipv6-64 (kernel@kernel.ovh.net) (gcc version 6.3.0 20170516 (Debian 6.3.0-18) ) #1 SMP Tue Jun 20 11:36:21 CEST 2017
Feb 28 09:53:59 dba kernel: Command line: BOOT_IMAGE=/bzImage-4.9.33-mod-std-ipv6-64 root=/dev/md3 ro rootdelay=10 noquiet nosplash net.ifnames=0 biosdevname=0
Feb 28 09:53:59 dba kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
Feb 28 09:53:59 dba kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
Feb 28 09:53:59 dba systemd-udevd[390]: Network interface NamePolicy= disabled on kernel command line, ignoring.
Feb 28 09:53:59 dba kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
Feb 28 09:53:59 dba kernel: x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers'
Feb 28 09:53:59 dba kernel: x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR'
Feb 28 09:53:59 dba kernel: x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
Feb 28 09:53:59 dba keyboard-setup.sh[370]: cannot open file /tmp/tmpkbd.OUbjnw
Feb 28 09:53:59 dba kernel: x86/fpu: xstate_offset[3]:  832, xstate_sizes[3]:   64
Feb 28 09:53:59 dba kernel: x86/fpu: xstate_offset[4]:  896, xstate_sizes[4]:   64
Feb 28 09:53:59 dba kernel: x86/fpu: Enabled xstate features 0x1f, context size is 960 bytes, using 'compacted' format.
Feb 28 09:53:59 dba kernel: x86/fpu: Using 'eager' FPU context switches.
Feb 28 09:53:59 dba systemd[1]: Started Remount Root and Kernel File Systems.
Feb 28 09:53:59 dba kernel: e820: BIOS-provided physical RAM map:
Feb 28 09:53:59 dba kernel: BIOS-e820: [mem 0x0000000000000000-0x0000000000057fff] usable
Feb 28 09:53:59 dba kernel: BIOS-e820: [mem 0x0000000000058000-0x0000000000058fff] reserved
Feb 28 09:53:59 dba systemd[1]: Starting Flush Journal to Persistent Storage...
Feb 28 09:53:59 dba kernel: BIOS-e820: [mem 0x0000000000059000-0x000000000009dfff] usable
Feb 28 09:53:59 dba kernel: BIOS-e820: [mem 0x000000000009e000-0x000000000009efff] reserved
Feb 28 09:53:59 dba kernel: BIOS-e820: [mem 0x000000000009f000-0x000000000009ffff] usable
Feb 28 09:53:59 dba systemd[1]: Starting Load/Save Random Seed...
Feb 28 09:53:59 dba kernel: BIOS-e820: [mem 0x00000000000a0000-0x00000000000fffff] reserved
Feb 28 09:53:59 dba systemd[1]: Starting udev Coldplug all Devices...
Feb 28 09:53:59 dba kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000881c5fff] usable
Feb 28 09:53:59 dba kernel: BIOS-e820: [mem 0x00000000881c6000-0x00000000881c6fff] ACPI NVS
Feb 28 09:53:59 dba systemd[1]: Started Load/Save Random Seed.
Feb 28 09:53:59 dba kernel: BIOS-e820: [mem 0x00000000881c7000-0x0000000088210fff] reserved
Feb 28 09:53:59 dba kernel: BIOS-e820: [mem 0x0000000088211000-0x0000000088282fff] usable
Feb 28 09:53:59 dba kernel: BIOS-e820: [mem 0x0000000088283000-0x0000000088302fff] type 20
Feb 28 09:53:59 dba kernel: BIOS-e820: [mem 0x0000000088303000-0x0000000089ec1fff] reserved
Feb 28 09:53:59 dba kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
Feb 28 09:53:59 dba kernel: e820: last_pfn = 0x1062000 max_arch_pfn = 0x400000000
Feb 28 09:53:59 dba kernel: MTRR default type: write-back
Feb 28 09:53:59 dba kernel: MTRR fixed ranges enabled:
Feb 28 09:53:59 dba systemd[1]: Found device /dev/md2.
Feb 28 09:53:59 dba kernel:  00000-9FFFF write-back
Feb 28 09:53:59 dba kernel:  A0000-BFFFF uncachable
Feb 28 09:53:59 dba kernel:  C0000-FFFFF write-protect
Feb 28 09:53:59 dba systemd[1]: Started MD array monitor.
Feb 28 09:53:59 dba kernel: MTRR variable ranges enabled:
Feb 28 09:53:59 dba kernel:  0 base 00C0000000 mask 7FC0000000 uncachable
Feb 28 09:53:59 dba kernel:  1 base 00A0000000 mask 7FE0000000 uncachable
Feb 28 09:53:59 dba systemd[1]: Found device HGST_HUS726020ALA610 swap-sdb5.
Feb 28 09:53:59 dba kernel:  2 base 009E000000 mask 7FFE000000 uncachable
Feb 28 09:53:59 dba kernel:  3 base 1080000000 mask 7FC0000000 uncachable
Feb 28 09:53:59 dba kernel:  4 disabled
Feb 28 09:53:59 dba systemd[1]: Activating swap /dev/sdb5...
Feb 28 09:53:59 dba kernel:  5 disabled
Feb 28 09:53:59 dba kernel:  6 disabled
Feb 28 09:53:59 dba kernel:  7 disabled
Feb 28 09:53:59 dba kernel:  8 disabled
Feb 28 09:53:59 dba systemd[1]: Found device /dev/md4.
Feb 28 09:53:59 dba kernel:  9 disabled
Feb 28 09:53:59 dba kernel: x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- WT
Feb 28 09:53:59 dba kernel: e820: last_pfn = 0x8a000 max_arch_pfn = 0x400000000
Feb 28 09:53:59 dba systemd[1]: Found device HGST_HUS726020ALA610 swap-sda5.
Feb 28 09:53:59 dba kernel: Base memory trampoline at [ffff880000094000] 94000 size 24576
Feb 28 09:53:59 dba kernel: Using GB pages for direct mapping
Feb 28 09:53:59 dba kernel: BRK [0x02ce3000, 0x02ce3fff] PGTABLE
Feb 28 09:53:59 dba systemd[1]: Activating swap /dev/sda5...
Feb 28 09:53:59 dba kernel: BRK [0x02ce4000, 0x02ce4fff] PGTABLE
Feb 28 09:53:59 dba kernel: BRK [0x02ce5000, 0x02ce5fff] PGTABLE
Feb 28 09:53:59 dba kernel: BRK [0x02ce6000, 0x02ce6fff] PGTABLE
Feb 28 09:53:59 dba kernel: BRK [0x02ce7000, 0x02ce7fff] PGTABLE
Feb 28 09:53:59 dba kernel: BRK [0x02ce8000, 0x02ce8fff] PGTABLE
Feb 28 09:53:59 dba systemd[1]: Activated swap /dev/sda5.
Feb 28 09:53:59 dba kernel: ACPI: Early table checksum verification disabled
Feb 28 09:53:59 dba kernel: ACPI: RSDP 0x0000000089FFE014 000024 (v02 INTEL )
Feb 28 09:53:59 dba kernel: ACPI: XSDT 0x0000000089FB7188 0000D4 (v01 INTEL  S1200SPL 00000000 INTL 01000013)
Feb 28 09:53:59 dba kernel: ACPI: FACP 0x0000000089FF6000 0000F4 (v05 INTEL  S1200SPL 00000000 INTL 20091013)
Feb 28 09:53:59 dba kernel: ACPI: DSDT 0x0000000089FC6000 029241 (v02 INTEL  S1200SPL 00000000 INTL 20091013)
Feb 28 09:53:59 dba kernel: ACPI: FACS 0x0000000089F7E000 000040
Feb 28 09:53:59 dba systemd[1]: Started udev Wait for Complete Device Initialization.
Feb 28 09:53:59 dba kernel: ACPI: HPET 0x0000000089FF5000 000038 (v01 INTEL  S1200SPL 00000001 INTL 20091013)
Feb 28 09:53:59 dba kernel: ACPI: APIC 0x0000000089FF4000 0000BC (v03 INTEL  S1200SPL 00000001 INTL 20091013)
Feb 28 09:53:59 dba kernel: ACPI: MCFG 0x0000000089FF3000 00003C (v01 INTEL  S1200SPL 00000001 INTL 20091013)
Feb 28 09:53:59 dba systemd[1]: Activated swap /dev/sdb5.
Feb 28 09:53:59 dba kernel: ACPI: SPMI 0x0000000089FF1000 000042 (v05 INTEL  S1200SPL 00000000 INTL 20091013)
Feb 28 09:53:59 dba kernel: ACPI: WDDT 0x0000000089FF0000 000040 (v01 INTEL  S1200SPL 00000000 INTL 20091013)
Feb 28 09:53:59 dba kernel: ACPI: SSDT 0x0000000089FC3000 002BAE (v02 INTEL  S1200SPL 00001000 INTL 20091013)
Feb 28 09:53:59 dba kernel: ACPI: SSDT 0x0000000089FC2000 000BE3 (v02 INTEL  S1200SPL 00001000 INTL 20091013)
Feb 28 09:53:59 dba kernel:  DMA zone: 64 pages used for memmap
Feb 28 09:53:59 dba kernel:  DMA zone: 1091 pages reserved
Feb 28 09:53:59 dba systemd[1]: Starting Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling...
Feb 28 09:53:59 dba kernel:  DMA zone: 3997 pages, LIFO batch:0
Feb 28 09:53:59 dba kernel:  DMA32 zone: 8649 pages used for memmap
Feb 28 09:53:59 dba kernel:  DMA32 zone: 553529 pages, LIFO batch:31
Feb 28 09:53:59 dba kernel:  Normal zone: 252032 pages used for memmap
Feb 28 09:53:59 dba systemd[1]: Started Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling.
Feb 28 09:53:59 dba kernel:  Normal zone: 16130048 pages, LIFO batch:31
Feb 28 09:53:59 dba kernel: ACPI: PM-Timer IO Port: 0x1808
Feb 28 09:53:59 dba kernel: ACPI: Local APIC address 0xfee00000
Feb 28 09:53:59 dba systemd[1]: Reached target Local File Systems (Pre).
Feb 28 09:53:59 dba kernel: ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
Feb 28 09:53:59 dba kernel: ACPI: LAPIC_NMI (acpi_id[0x02] high edge lint[0x1])
Feb 28 09:53:59 dba kernel: ACPI: LAPIC_NMI (acpi_id[0x03] high edge lint[0x1])
Feb 28 09:53:59 dba systemd[1]: Starting File System Check on /dev/md4...
Feb 28 09:53:59 dba kernel: ACPI: LAPIC_NMI (acpi_id[0x04] high edge lint[0x1])
Feb 28 09:53:59 dba kernel: ACPI: LAPIC_NMI (acpi_id[0x05] high edge lint[0x1])
Feb 28 09:53:59 dba kernel: ACPI: LAPIC_NMI (acpi_id[0x06] high edge lint[0x1])
Feb 28 09:53:59 dba systemd[1]: Starting File System Check on /dev/md2...
Feb 28 09:53:59 dba kernel: ACPI: LAPIC_NMI (acpi_id[0x07] high edge lint[0x1])
Feb 28 09:53:59 dba kernel: ACPI: LAPIC_NMI (acpi_id[0x08] high edge lint[0x1])
Feb 28 09:53:59 dba kernel: IOAPIC[0]: apic_id 2, version 32, address 0xfec00000, GSI 0-119
Feb 28 09:53:59 dba systemd-fsck[600]: /boot: recovering journal
Feb 28 09:53:59 dba kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
Feb 28 09:53:59 dba kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Feb 28 09:53:59 dba systemd-fsck[599]: /data: recovering journal
Feb 28 09:53:59 dba kernel: ACPI: IRQ0 used by override.
Feb 28 09:53:59 dba kernel: ACPI: IRQ9 used by override.
Feb 28 09:53:59 dba systemd-fsck[600]: /boot: clean, 603/131072 files, 52758/523200 blocks
Feb 28 09:53:59 dba systemd[1]: Started File System Check on /dev/md2.
Feb 28 09:53:59 dba systemd[1]: Mounting /boot...
Feb 28 09:53:59 dba systemd[1]: Mounted /boot.
Feb 28 09:53:59 dba systemd[1]: Mounting /boot/efi...
Feb 28 09:53:59 dba systemd[1]: Mounted /boot/efi.
Feb 28 09:53:59 dba systemd-fsck[599]: /data: clean, 454929/120717312 files, 17098739/482864368 blocks
Feb 28 09:53:59 dba systemd[1]: Started File System Check on /dev/md4.
Feb 28 09:53:59 dba systemd[1]: Mounting /data...
Feb 28 09:53:59 dba systemd[1]: Mounted /data.
Feb 28 09:53:59 dba systemd[1]: Reached target Local File Systems.
Feb 28 09:53:59 dba systemd[1]: Starting Raise network interfaces...
Feb 28 09:53:59 dba systemd[1]: Starting Set console font and keymap...
Feb 28 09:53:59 dba systemd[1]: Starting Create Volatile Files and Directories...
Feb 28 09:53:59 dba systemd[1]: Started Set console font and keymap.
Feb 28 09:53:59 dba kernel: Using ACPI (MADT) for SMP configuration information
Feb 28 09:53:59 dba systemd[1]: Started Create Volatile Files and Directories.
Feb 28 09:53:59 dba kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000
Feb 28 09:53:59 dba kernel: smpboot: Allowing 8 CPUs, 0 hotplug CPUs
Feb 28 09:53:59 dba kernel: e820: [mem 0x9e000000-0xe00f9fff] available for PCI devices
Feb 28 09:53:59 dba systemd[1]: Starting Update UTMP about System Boot/Shutdown...
eb 28 09:53:59 dba kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
Feb 28 09:53:59 dba kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:8 nr_node_ids:1
Feb 28 09:53:59 dba systemd[1]: Starting Network Time Synchronization...
Feb 28 09:53:59 dba kernel: percpu: Embedded 33 pages/cpu @ffff881061c00000 s97048 r8192 d29928 u262144
Feb 28 09:53:59 dba kernel: pcpu-alloc: s97048 r8192 d29928 u262144 alloc=1*2097152
Feb 28 09:53:59 dba systemd[1]: Started Update UTMP about System Boot/Shutdown.
Feb 28 09:53:59 dba kernel: pcpu-alloc: [0] 0 1 2 3 4 5 6 7
Feb 28 09:53:59 dba kernel: Built 1 zonelists in Node order, mobility grouping on.  Total pages: 16425738
Feb 28 09:53:59 dba systemd[1]: Started Network Time Synchronization.
Feb 28 09:53:59 dba kernel: Policy zone: Normal
Feb 28 09:53:59 dba kernel: Kernel command line: BOOT_IMAGE=/bzImage-4.9.33-mod-std-ipv6-64 root=/dev/md3 ro rootdelay=10 noquiet nosplash net.ifnames=0 biosdevname=0
Feb 28 09:53:59 dba systemd[1]: Reached target System Time Synchronized.
Feb 28 09:53:59 dba kernel: PID hash table entries: 4096 (order: 3, 32768 bytes)
Feb 28 09:53:59 dba kernel: Memory: 65527836K/66750296K available (16496K kernel code, 1024K rwdata, 4936K rodata, 1092K init, 2812K bss, 1222460K reserved, 0K cma-reserved)
Feb 28 09:53:59 dba kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1
Feb 28 09:53:59 dba systemd[1]: Reached target System Initialization.
Feb 28 09:53:59 dba kernel: Hierarchical RCU implementation.
Feb 28 09:53:59 dba kernel:     Build-time adjustment of leaf fanout to 64.
Feb 28 09:53:59 dba kernel:     RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=8.
Feb 28 09:53:59 dba systemd[1]: Listening on D-Bus System Message Bus Socket.
Feb 28 09:53:59 dba kernel: RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=8
Feb 28 09:53:59 dba systemd[1]: Reached target Sockets.
Feb 28 09:53:59 dba kernel: NR_IRQS:33024 nr_irqs:2048 16
Feb 28 09:53:59 dba kernel: Console: colour dummy device 80x25
Feb 28 09:53:59 dba kernel: console [tty0] enabled
Feb 28 09:53:59 dba systemd[1]: Reached target Basic System.
Feb 28 09:53:59 dba kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 79635855245 ns
Feb 28 09:53:59 dba kernel: hpet clockevent registered
Feb 28 09:53:59 dba systemd[1]: Started Regular background program processing daemon.
Feb 28 09:53:59 dba kernel: tsc: Detected 3800.000 MHz processor
Feb 28 09:53:59 dba kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 7584.00 BogoMIPS (lpj=15168000)
Feb 28 09:53:59 dba systemd-timesyncd[654]: Synchronized to time server 95.81.173.155:123 (0.debian.pool.ntp.org).
Feb 28 09:53:59 dba kernel: pid_max: default: 32768 minimum: 301
Feb 28 09:53:59 dba kernel: EXT4-fs (md3): mounted filesystem with ordered data mode. Opts: (null)
Feb 28 09:53:59 dba kernel: VFS: Mounted root (ext4 filesystem) readonly on device 9:3.
Feb 28 09:53:59 dba kernel: devtmpfs: mounted
Feb 28 09:53:59 dba kernel: Freeing unused kernel memory: 1092K (ffffffff82902000 - ffffffff82a13000)
Feb 28 09:53:59 dba kernel: Write protecting the kernel read-only data: 24576k
Feb 28 09:53:59 dba kernel: Freeing unused kernel memory: 1908K (ffff880002023000 - ffff880002200000)
Feb 28 09:53:59 dba kernel: Freeing unused kernel memory: 1208K (ffff8800026d2000 - ffff880002800000)
Feb 28 09:53:59 dba kernel: x86/mm: Checked W+X mappings: passed, no W+X pages found.
Feb 28 09:53:59 dba kernel: EXT4-fs (md3): re-mounted. Opts: errors=remount-ro
Feb 28 09:53:59 dba kernel: Adding 523260k swap on /dev/sda5.  Priority:-1 extents:1 across:523260k
Feb 28 09:53:59 dba kernel: Adding 523260k swap on /dev/sdb5.  Priority:-2 extents:1 across:523260k
Feb 28 09:53:59 dba kernel: EXT4-fs (md2): mounted filesystem with ordered data mode. Opts: errors=remount-ro
Feb 28 09:53:59 dba kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Feb 28 09:53:59 dba kernel: EXT4-fs (md4): barriers disabled
Feb 28 09:53:59 dba kernel: EXT4-fs (md4): mounted filesystem with ordered data mode. Opts: nobarrier,data=ordered
Feb 28 09:53:59 dba kernel: IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Feb 28 09:53:59 dba kernel: 8021q: adding VLAN 0 to HW filter on device eth0
Feb 28 09:53:59 dba kernel: igb 0000:02:00.0 eth0: igb: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
Feb 28 09:53:59 dba kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Feb 28 09:53:59 dba kernel: Process accounting resumed
Feb 28 09:53:59 dba systemd[1]: Started System Logging Service.
Feb 28 09:53:59 dba systemd[1]: Started Atop process accounting daemon.
Feb 28 09:53:59 dba smartd[714]: Device: /dev/sda [SAT], is SMART capable. Adding to "monitor" list.
Feb 28 09:53:59 dba systemd[1]: Started Login Service.
Feb 28 09:53:59 dba smartd[714]: Device: /dev/sda [SAT], state read from /var/lib/smartmontools/smartd.HGST_HUS726020ALA610-K5H88L5A.ata.state
Feb 28 09:53:59 dba systemd[1]: Started Atop advanced performance monitor.
Feb 28 09:53:59 dba smartd[714]: Device: /dev/sdb, type changed from 'scsi' to 'sat'
Feb 28 09:53:59 dba smartd[714]: Device: /dev/sdb [SAT], opened
Feb 28 09:53:59 dba smartd[714]: Device: /dev/sdb [SAT], HGST HUS726020ALA610, S/N:K5H88KVA, WWN:5-000cca-25ed1daaa, FW:A5GNT920, 2.00 TB
Feb 28 09:53:59 dba smartd[714]: Device: /dev/sdb [SAT], not found in smartd database.
Feb 28 09:53:59 dba smartd[714]: Device: /dev/sdb [SAT], is SMART capable. Adding to "monitor" list.
Feb 28 09:53:59 dba smartd[714]: Device: /dev/sdb [SAT], state read from /var/lib/smartmontools/smartd.HGST_HUS726020ALA610-K5H88KVA.ata.state
Feb 28 09:53:59 dba smartd[714]: Monitoring 2 ATA/SATA, 0 SCSI/SAS and 0 NVMe devices
Feb 28 09:53:59 dba smartd[714]: Device: /dev/sda [SAT], state written to /var/lib/smartmontools/smartd.HGST_HUS726020ALA610-K5H88L5A.ata.state
Feb 28 09:53:59 dba smartd[714]: Device: /dev/sdb [SAT], state written to /var/lib/smartmontools/smartd.HGST_HUS726020ALA610-K5H88KVA.ata.state
Feb 28 09:54:01 dba CRON[811]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 09:54:01 dba ifup[635]: Waiting for DAD... Done
Feb 28 09:54:08 dba systemd[1]: Time has been changed
Feb 28 09:54:08 dba ntpdate[710]: step time server 92.222.117.115 offset -0.013647 sec
Feb 28 09:54:08 dba systemd[1]: apt-daily-upgrade.timer: Adding 23min 32.397333s random time.
Feb 28 09:54:11 dba systemd[1]: Started Raise network interfaces.
Feb 28 09:54:11 dba systemd[1]: Reached target Network.
Feb 28 09:54:11 dba systemd[1]: Started BIND Domain Name Server.
Feb 28 09:54:11 dba systemd[1]: Reached target Host and Network Name Lookups.
Feb 28 09:54:11 dba systemd[1]: Starting Permit User Sessions...
Feb 28 09:54:11 dba systemd[1]: Starting MariaDB database server...
Feb 28 09:54:11 dba systemd[1]: Starting OpenBSD Secure Shell server...
Feb 28 09:54:11 dba systemd[1]: Reached target Network is Online.
Feb 28 09:54:11 dba systemd[1]: Starting Daily apt download activities...
Feb 28 09:54:11 dba systemd[1]: Starting LSB: disk temperature monitoring daemon...
Feb 28 09:54:11 dba systemd[1]: Started Permit User Sessions.
Feb 28 09:54:11 dba named[977]: starting BIND 9.10.3-P4-Debian <id:ebd72b3> -f -u bind
Feb 28 09:54:11 dba named[977]: built with '--prefix=/usr' '--mandir=/usr/share/man' '--libdir=/usr/lib/x86_64-linux-gnu' '--infodir=/usr/share/info' '--sysconfdir=/etc/bind' '--with-python=python3' '--localstatedir=/' '--enable-threads' '--enable-largefile' '--with-libtool' '--enable-shared' '--enable-static' '--with-gost=no' '--with-openssl=/usr' '--with-gssapi=/usr' '--with-gnu-ld' '--with-geoip=/usr' '--with-atf=no' '--enable-ipv6' '--enable-rrl' '--enable-filter-aaaa' '--enable-native-pkcs11' '--with-pkcs11=/usr/lib/x86_64-linux-gnu/softhsm/libsofthsm2.so' '--with-randomdev=/dev/urandom' 'CFLAGS=-g -O2 -fdebug-prefix-map=/build/bind9-VypbYM/bind9-9.10.3.dfsg.P4=. -fstack-protector-strong -Wformat -Werror=format-security -fno-strict-aliasing -fno-delete-null-pointer-checks -DNO_VERSION_DATE -DDIG_SIGCHASE' 'LDFLAGS=-Wl,-z,relro -Wl,-z,now' 'CPPFLAGS=-Wdate-time -D_FORTIFY_SOURCE=2'
Feb 28 09:54:11 dba named[977]: ----------------------------------------------------
Feb 28 09:54:11 dba named[977]: BIND 9 is maintained by Internet Systems Consortium,
Feb 28 09:54:11 dba named[977]: Inc. (ISC), a non-profit 501(c)(3) public-benefit
Feb 28 09:54:11 dba systemd[1]: Started Getty on tty1.
Feb 28 09:54:11 dba named[977]: corporation.  Support and training for BIND 9 are
Feb 28 09:54:11 dba named[977]: available at https://www.isc.org/support
Feb 28 09:54:11 dba named[977]: ----------------------------------------------------
Feb 28 09:54:11 dba named[977]: adjusted limit on open files from 4096 to 1048576
Feb 28 09:54:11 dba systemd[1]: Reached target Login Prompts.
Feb 28 09:54:11 dba named[977]: zone 255.in-addr.arpa/IN: loaded serial 1
Feb 28 09:54:11 dba named[977]: zone 127.in-addr.arpa/IN: loaded serial 1
Feb 28 09:54:11 dba named[977]: all zones loaded
Feb 28 09:54:11 dba named[977]: running
Feb 28 09:54:13 dba mysqld[1198]: 2018-02-28  9:54:13 140093945811520 [Note] Using unique option prefix 'log-slave-update' is error-prone and can break in the future. Please use the full name 'log_slave_updates' instead.
Feb 28 09:54:13 dba mysqld[1198]: 2018-02-28  9:54:13 140093945811520 [Note] /usr/sbin/mysqld (mysqld 10.1.26-MariaDB-0+deb9u1) starting as process 1198 ...
Feb 28 09:54:15 dba systemd[1]: Started Daily apt download activities.
Feb 28 09:54:15 dba systemd[1]: apt-daily.timer: Adding 8h 53min 48.228706s random time.
Feb 28 09:54:15 dba systemd[1]: apt-daily.timer: Adding 7h 1min 16.528137s random time.
Feb 28 09:54:21 dba systemd[1]: Time has been changed
Feb 28 09:54:21 dba systemd[1]: apt-daily-upgrade.timer: Adding 2min 47.694402s random time.
Feb 28 09:54:21 dba ntpdate[941]: step time server 5.196.192.58 offset 0.000021 sec
Feb 28 09:54:21 dba systemd[1]: apt-daily.timer: Adding 7h 5min 12.028169s random time.
Feb 28 09:55:00 dba CRON[1437]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 09:55:42 dba systemd[1]: mariadb.service: Start operation timed out. Terminating.
Feb 28 09:56:01 dba CRON[1536]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 09:57:01 dba CRON[1635]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 09:57:12 dba systemd[1]: mariadb.service: State 'stop-final-sigterm' timed out. Skipping SIGKILL. Entering failed mode.
Feb 28 09:57:12 dba systemd[1]: Failed to start MariaDB database server.
Feb 28 09:57:12 dba systemd[1]: mariadb.service: Unit entered failed state.
Feb 28 09:57:12 dba systemd[1]: mariadb.service: Failed with result 'timeout'.
Feb 28 09:57:12 dba systemd[1]: Reached target Multi-User System.
Feb 28 09:57:12 dba systemd[1]: Reached target Graphical Interface.
Feb 28 09:57:12 dba systemd[1]: Starting Update UTMP about System Runlevel Changes...
Feb 28 09:57:12 dba systemd[1]: Started Update UTMP about System Runlevel Changes.
Feb 28 09:57:12 dba systemd[1]: Startup finished in 17.191s (kernel) + 3min 17.756s (userspace) = 3min 34.947s.
Feb 28 09:58:01 dba CRON[1738]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 09:59:01 dba CRON[1837]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 10:00:01 dba CRON[1952]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 10:01:01 dba CRON[2051]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 10:02:01 dba CRON[2152]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 10:03:01 dba CRON[2251]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 10:04:01 dba CRON[2363]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 10:04:10 dba systemd[1]: Starting MariaDB database server...
Feb 28 10:04:11 dba mysqld[2687]: 2018-02-28 10:04:11 140544819712576 [Note] Using unique option prefix 'log-slave-update' is error-prone and can break in the future. Please use the full name 'log_slave_updates' instead.
Feb 28 10:04:11 dba mysqld[2687]: 2018-02-28 10:04:11 140544819712576 [Note] /usr/sbin/mysqld (mysqld 10.1.26-MariaDB-0+deb9u1) starting as process 2687 ...
Feb 28 10:05:01 dba CRON[2704]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 10:06:01 dba CRON[2826]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 10:06:23 dba systemd[1]: mariadb.service: Main process exited, code=exited, status=1/FAILURE
Feb 28 10:06:23 dba systemd[1]: Stopped MariaDB database server.
Feb 28 10:06:23 dba systemd[1]: mariadb.service: Unit entered failed state.
Feb 28 10:06:23 dba systemd[1]: mariadb.service: Failed with result 'exit-code'.
Feb 28 10:07:01 dba CRON[2927]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 10:08:01 dba CRON[3026]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 10:08:44 dba systemd[1]: Starting Cleanup of Temporary Directories...
Feb 28 10:08:44 dba systemd[1]: Started Cleanup of Temporary Directories.
Feb 28 10:09:01 dba CRON[3128]: (root) CMD (/usr/local/rtm/bin/rtm 2 > /dev/null 2> /dev/null)
Feb 28 10:09:20 dba systemd[1]: Stopped target Timers.
Feb 28 10:09:20 dba systemd[1]: Stopped target Graphical Interface.
Feb 28 10:09:20 dba systemd[1]: Stopped target Multi-User System.
Feb 28 10:09:20 dba systemd[1]: Stopping Login Service...
Feb 28 10:09:20 dba systemd[1]: Stopping irqbalance daemon...
 

look like some trouble and mariadb started 2 times (problem with systemctl ?)



 Comments   
Comment by Elena Stepanova [ 2018-05-28 ]

Well, there can be a number of reasons why server got started twice, we have no way of knowing what happened unless you'll describe the exact steps that led to this situation.

Generated at Thu Feb 08 08:21:19 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.