Compare the first set of Informix log:
20:28:34 Checkpoint Completed: duration was 1 seconds.
20:28:34 Checkpoint loguniq 1, logpos 0xd40018, timestamp: 0xf638
20:28:34 Maximum server connections 1
20:28:35 IBM Informix Dynamic Server Stopped.
20:28:36 IBM Informix Dynamic Server Started.
Sat Oct 11 20:28:36 2008
20:28:36 Event alarms enabled. ALARMPROG = '/usr/informix/etc/log_full.sh'
20:28:36 Booting Languagefrom module <>
20:28:36 Loading Module
20:28:36 Booting Languagefrom module <>
20:28:36 Loading Module
20:28:41 DR: DRAUTO is 0 (Off)
20:28:41 IBM Informix Dynamic Server Version 10.00.UC7 Software Serial Number AAA#B000000
20:28:43 IBM Informix Dynamic Server Initialized -- Shared Memory Initialized.
20:28:43 Physical Recovery Started at Page (1:2283).
20:28:43 Physical Recovery Complete: 0 Pages Examined, 0 Pages Restored.
20:28:43 Logical Recovery Started.
20:28:43 10 recovery worker threads will be started.
20:28:46 Logical Recovery has reached the transaction cleanup phase.
20:28:46 Logical Recovery Complete.
0 Committed, 0 Rolled Back, 0 Open, 0 Bad Locks
20:28:46 Dataskip is now OFF for all dbspaces
20:28:47 Checkpoint Completed: duration was 0 seconds.
20:28:47 Checkpoint loguniq 1, logpos 0xd41018, timestamp: 0xf648
20:28:47 Maximum server connections 0
20:28:47 On-Line Mode
and second set of log:
19:11:22 Maximum server connections 39
19:16:22 Fuzzy Checkpoint Completed: duration was 0 seconds, 4 buffers not flushed.
19:16:22 Checkpoint loguniq 5, logpos 0x2c63080, timestamp: 0x4958f6
19:16:22 Maximum server connections 39
No stopped message listed. The engine did not shutdown cleanly.
19:23:37 IBM Informix Dynamic Server Started.
Tue Oct 21 19:23:39 2008
19:23:39 Event alarms enabled. ALARMPROG = '/usr/informix/etc/log_full.sh'
19:23:40 Booting Languagefrom module <>
19:23:40 Loading Module
19:23:40 Booting Languagefrom module <>
19:23:40 Loading Module
19:23:56 DR: DRAUTO is 0 (Off)
19:23:56 IBM Informix Dynamic Server Version 10.00.UC7 Software Serial Number AAA#B000000
19:23:59 IBM Informix Dynamic Server Initialized -- Shared Memory Initialized.
19:23:59 Physical Recovery Started at Page (1:4321).
19:23:59 Physical Recovery Complete: 0 Pages Examined, 0 Pages Restored.
19:23:59 Logical Recovery Started.
19:23:59 10 recovery worker threads will be started.
19:24:02 Logical Recovery has reached the transaction cleanup phase.
19:24:02 Logical Recovery Complete.
21 Committed, 0 Rolled Back, 0 Open, 0 Bad Locks
19:24:04 Dataskip is now OFF for all dbspaces
19:24:04 (11) connection rejected - no calls allowed for sqlexec
19:24:04 listener-thread: err = -27002: oserr = 0: errstr = : No connections are allowed in quiescent mode.
19:24:04 (13) connection rejected - no calls allowed for sqlexec
19:24:04 listener-thread: err = -27002: oserr = 0: errstr = : No connections are allowed in quiescent mode.
19:24:05 Checkpoint Completed: duration was 1 seconds.
19:24:05 Checkpoint loguniq 5, logpos 0x2c69018, timestamp: 0x4959d3
19:24:05 Maximum server connections 0
19:24:05 On-Line Mode
The first one came right to online mode in 11 seconds. If you look at the log you will see the engine was stopped right before restarting it. When the engine is stopped, all transactions are cleaned up. They are either rolled back or committed and the physical log is also cleared out. If you look at the second startup, there was no clean shutdown, This was due to someone killing the oninit process or perhaps the OS getting rebooted. Regardless the engine was still performing work. When the engine was restarted, it discovered that there were open transactions that needed to be dealt with . This explains the additional time it took to come online. The 27002 errors were due to users trying to connect before the engine was ready for them. The engine is working as designed.
0 comments:
Post a Comment