Startup time for INF-ONLINE 7.31.UC2
Posted in 1999
Topics: Storage & Space Management, Logging & Checkpoints
Hello
I have load on a INF-ONL database. After a immediate shutdown is the time
for shared memory init 108 sec. There are no rollbacks or commits in the
protocol.
A second test with the same load but a checkpoint (onstat -c) befor the
immediate shutdown has only 3 sec. for the shared memory init.
Now the question:
1, Why is the time for shared memory init so different ?
2, Why is no commit, rollback for transction when a immedite shutdown is
performed ?
3, Depends the time on the numbers of OFF_RECOVERY_THREADS ?
Today the system is a test configuration, but soon this configuration is
deliverd to the customer and i need infos to explain this situation. Or
infos in which documentation i can find the infos.
As appendix is the protocol from 3 tests (without load, with load no
checkpoint, with load with checkpoint).
I hope somebody can help me.
best regards
martin sambor
martin.sambor@siemens.at
Mon Nov 29 00:00:28 1999
00:00:28 Logical Log 399 Complete.
00:00:31 Logical Log 399 - Backup Started
00:01:13 Logical Log 399 - Backup Completed
00:16:27 Checkpoint Completed: duration was 0 seconds.
01:16:26 Checkpoint Completed: duration was 0 seconds.
02:16:26 Checkpoint Completed: duration was 0 seconds.
03:16:26 Checkpoint Completed: duration was 0 seconds.
04:16:26 Checkpoint Completed: duration was 0 seconds.
05:16:26 Checkpoint Completed: duration was 0 seconds.
06:16:26 Checkpoint Completed: duration was 0 seconds.
07:16:26 Checkpoint Completed: duration was 0 seconds.
08:16:26 Checkpoint Completed: duration was 0 seconds.
09:16:26 Checkpoint Completed: duration was 0 seconds.
10:16:26 Checkpoint Completed: duration was 0 seconds.
========================================================stop and start without load
========================================================
11:04:48 Shutdown Mode
11:04:50 Quiescent Mode
11:05:10 Checkpoint Completed: duration was 0 seconds.
11:05:14 Informix Dynamic Server Stopped.
Mon Nov 29 11:07:06 1999
11:07:06 Event alarms enabled. ALARMPROG =
'/opt/lib/informix/etc/log_full_smp.sh'
11:07:14 DR: DRAUTO is 0 (Off)
11:07:14 Requested shared memory segment size rounded from 2648KB to 2656KB
11:07:15 Informix Dynamic Server Version 7.31.UC2 Software Serial NumberAAC#J169024
|
3 sec
|
11:07:18 Informix Dynamic Server Initialized -- Shared Memory Initialized.
11:07:18 Physical Recovery Started.
11:07:18 Physical Recovery Complete: 0 Pages Restored.
11:07:18 Logical Recovery Started.
11:07:24 Logical Recovery Complete. 0 Committed, 0 Rolled Back, 0 Open, 0 Bad Locks
11:07:26 Dataskip is now OFF for all dbspaces
11:07:28 Quiescent Mode
11:07:28 Checkpoint Completed: duration was 0 seconds.
11:07:36 On-Line Mode
========================================================stop and start with load
no checkpoint before the stop of informix
========================================================
11:27:52 Logical Log 400 Complete.
11:27:54 Logical Log 400 - Backup Started
11:28:37 Logical Log 400 - Backup Completed
12:01:48 Shutdown Mode
12:01:50 Quiescent Mode
12:02:03 Checkpoint Completed: duration was 2 seconds.
12:02:06 Informix Dynamic Server Stopped.
Mon Nov 29 12:02:35 1999
12:02:35 Event alarms enabled. ALARMPROG =
'/opt/lib/informix/etc/log_full_smp.sh'
12:02:43 DR: DRAUTO is 0 (Off)
12:02:43 Requested shared memory segment size rounded from 2648KB to 2656KB
12:02:43 Informix Dynamic Server Version 7.31.UC2 Software Serial NumberAAC#J169024
|
108 sec
|
12:04:31 Informix Dynamic Server Initialized -- Shared Memory Initialized.
12:04:31 Physical Recovery Started.
12:04:31 Physical Recovery Complete: 0 Pages Restored.
12:04:31 Logical Recovery Started.
12:04:37 Logical Recovery Complete. 0 Committed, 0 Rolled Back, 0 Open, 0 Bad Locks
12:04:41 Dataskip is now OFF for all dbspaces
12:04:41 Quiescent Mode
12:19:15 Logical Log 401 Complete.
12:19:17 Logical Log 401 - Backup Started
12:20:29 Logical Log 401 - Backup Completed
12:20:42 Checkpoint Completed: duration was 0 seconds.
13:10:31 Logical Log 402 Complete.
13:10:32 Logical Log 402 - Backup Started
13:11:39 Logical Log 402 - Backup Completed
13:20:48 Checkpoint Completed: duration was 2 seconds.
13:23:08 Checkpoint Completed: duration was 0 seconds.
========================================================stop and start with load
Checkpoint before the stop of informix
========================================================
13:23:23 Shutdown Mode
13:23:27 Quiescent Mode
13:23:37 Checkpoint Completed: duration was 0 seconds.
13:23:39 Informix Dynamic Server Stopped.
Mon Nov 29 13:24:03 1999
13:24:03 Event alarms enabled. ALARMPROG =
'/opt/lib/informix/etc/log_full_smp.sh'
13:24:11 DR: DRAUTO is 0 (Off)
13:24:11 Requested shared memory segment size rounded from 2648KB to 2656KB
13:24:12 Informix Dynamic Server Version 7.31.UC2 Software Serial NumberAAC#J169024
|
3 sec
|
13:24:15 Informix Dynamic Server Initialized -- Shared Memory Initialized.
13:24:15 Physical Recovery Started.
13:24:15 Physical Recovery Complete: 0 Pages Restored.
13:24:15 Logical Recovery Started.
13:24:21 Logical Recovery Complete. 0 Committed, 0 Rolled Back, 0 Open, 0 Bad Locks
13:24:23 Dataskip is now OFF for all dbspaces
13:24:25 Quiescent Mode
13:24:27 Checkpoint Completed: duration was 2 seconds.
13:24:31 On-Line Mode
In article <81u1vk$82o$1@scesie13.sie.siemens.at>,
"Martin Sambor" <martin.sambor@siemens.at> wrote:
> Hello
>
> I have load on a INF-ONL database. After a immediate shutdown is the
time
> for shared memory init 108 sec. There are no rollbacks or commits in
the
> protocol.
> A second test with the same load but a checkpoint (onstat -c) befor
the
> immediate shutdown has only 3 sec. for the shared memory init.
>
> Now the question:
> 1, Why is the time for shared memory init so different ?
> 2, Why is no commit, rollback for transction when a immedite shutdown
is
> performed ?
> 3, Depends the time on the numbers of OFF_RECOVERY_THREADS ?
>
> Today the system is a test configuration, but soon this configuration
is
> deliverd to the customer and i need infos to explain this situation.
Or
> infos in which documentation i can find the infos.
>
> As appendix is the protocol from 3 tests (without load, with load no
> checkpoint, with load with checkpoint).
>
> I hope somebody can help me.
>
> best regards
> martin sambor
>
> martin.sambor@siemens.at
>
> Mon Nov 29 00:00:28 1999
>
> 00:00:28 Logical Log 399 Complete.
> 00:00:31 Logical Log 399 - Backup Started
> 00:01:13 Logical Log 399 - Backup Completed
> 00:16:27 Checkpoint Completed: duration was 0 seconds.
> 01:16:26 Checkpoint Completed: duration was 0 seconds.
> 02:16:26 Checkpoint Completed: duration was 0 seconds.
> 03:16:26 Checkpoint Completed: duration was 0 seconds.
> 04:16:26 Checkpoint Completed: duration was 0 seconds.
> 05:16:26 Checkpoint Completed: duration was 0 seconds.
> 06:16:26 Checkpoint Completed: duration was 0 seconds.
> 07:16:26 Checkpoint Completed: duration was 0 seconds.
> 08:16:26 Checkpoint Completed: duration was 0 seconds.
> 09:16:26 Checkpoint Completed: duration was 0 seconds.
> 10:16:26 Checkpoint Completed: duration was 0 seconds.
> ========================================================> stop and start without load
> ========================================================
> 11:04:48 Shutdown Mode
> 11:04:50 Quiescent Mode
>
> 11:05:10 Checkpoint Completed: duration was 0 seconds.
>
> 11:05:14 Informix Dynamic Server Stopped.>
> Mon Nov 29 11:07:06 1999
> 11:07:06 Event alarms enabled. ALARMPROG =
> '/opt/lib/informix/etc/log_full_smp.sh'
> 11:07:14 DR: DRAUTO is 0 (Off)
> 11:07:14 Requested shared memory segment size rounded from 2648KB to2656KB
> 11:07:15 Informix Dynamic Server Version 7.31.UC2 Software SerialNumber
> AAC#J169024
> |
> 3 sec
> |
> 11:07:18 Informix Dynamic Server Initialized -- Shared MemoryInitialized.
> 11:07:18 Physical Recovery Started.
> 11:07:18 Physical Recovery Complete: 0 Pages Restored.
> 11:07:18 Logical Recovery Started.
> 11:07:24 Logical Recovery Complete.> 0 Committed, 0 Rolled Back, 0 Open, 0 Bad Locks
> 11:07:26 Dataskip is now OFF for all dbspaces
> 11:07:28 Quiescent Mode
> 11:07:28 Checkpoint Completed: duration was 0 seconds.
> 11:07:36 On-Line Mode
> ========================================================> stop and start with load
> no checkpoint before the stop of informix
> ========================================================
> 11:27:52 Logical Log 400 Complete.
> 11:27:54 Logical Log 400 - Backup Started
> 11:28:37 Logical Log 400 - Backup Completed
> 12:01:48 Shutdown Mode
> 12:01:50 Quiescent Mode
> 12:02:03 Checkpoint Completed: duration was 2 seconds.
But, as you can see, the engine performs a checkpoint anyway because
this is a normal shutdown.
> 12:02:06 Informix Dynamic Server Stopped.>
> Mon Nov 29 12:02:35 1999
>
> 12:02:35 Event alarms enabled. ALARMPROG =
> '/opt/lib/informix/etc/log_full_smp.sh'
> 12:02:43 DR: DRAUTO is 0 (Off)
> 12:02:43 Requested shared memory segment size rounded from 2648KB to2656KB
> 12:02:43 Informix Dynamic Server Version 7.31.UC2 Software SerialNumber
> AAC#J169024
> |
> 108 sec
> |
> 12:04:31 Informix Dynamic Server Initialized -- Shared MemoryInitialized.
Just a coincidence, I believe. During this period nothing is being
done that would be affected by whether there was a checkpoint or not.
The engine is just requesting shared memory segments and semaphores and
initializing certain of the data structures, like the LRUS and BUFFERS,
to startup values. This would only be affected by load, memory
swapping, or OS level delays in shared memory access.
What platform and OS version? This is significant. For example on
DG/UX on Motorolla M88K systems our startup is from 8 to 15 minutes,
on DG/UX on Pentium Pro startup is 2-4 minutes and on Solaris 2.6 SPARK
startup is <1 minute!
On M88k we have verified that most of the time the oninits are waiting
for each other to connect to the shared memory segments as they start
up.
Art S. Kagel
Sent via Deja.com http://www.deja.com/
Before you buy.