Re: I/O read () chunk # Problem
Posted in 1997
In article <5pi778$kgn@cssun.mathcs.emory.edu>, "Croft, Evelyn"
<croft.evelyn@dorseylaw.com> writes
>Hello Everyone,
>
>Can anyone tell me why I would have two chunks go down within a few
>hours of one another. I don't see any errno =3D codes. Here are a few
>pieces of the log. Problem? Solution? Also how often should the
Problem is either new bad areas on the disk or something else e.g. a
filesystem overlapping the affected area of disk.
Solution, then
a) Force checkpoint (onmode -c)
b) Force hange to next logical log (onmode -l)
c) Backup current logical logs to tape.
d) Run level 0 archive to fresh tape - in case restore in next step
fails.
e) Run tool to find bad blocks on the disk (your manufacturer should
have one).
e) Restore from latest level 0 archive and try to roll forward the
logs.
>tbcheck be run? HPUX9.04 / Informix 5.0 Thx. Eve
>
Every night.
>10:01:21 Checkpoint Completed
>10:01:44 I/O read() chunk 3, pagenum 955906, pagecnt 1
>10:01:52 Process Aborted Abnormally: pid=3D12163 user=3D610 =>us=3Dc0021d98 flags=3D1
>10:03:17 I/O read() chunk 3, pagenum 903318, pagecnt 1
>10:03:17 I/O read() chunk 3, pagenum 903318, pagecnt 1
><<<snip>>>
>10:05:15 I/O read() chunk 3, pagenum 462611, pagecnt 1
>10:05:17 I/O read() chunk 3, pagenum 462611, pagecnt 1
>10:05:18 I/O read() chunk 3, pagenum 462611, pagecnt 1
>10:06:41 Checkpoint Completed
>10:08:48 Process Aborted Abnormally: pid=3D12409 user=3D743 =>us=3Dc0020c40 flags=3D1
>10:12:00 Checkpoint Completed
>10:14:40 Process Aborted Abnormally: pid=3D12534 user=3D743 =>us=3Dc001caec flags=3D1
>10:16:34 I/O read() chunk 3, pagenum 874896, pagecnt 1
>10:16:34 I/O read() chunk 3, pagenum 874896, pagecnt 1
>10:17:21 Checkpoint Completed
>10:29:04 Process Aborted Abnormally: pid=3D12699 user=3D0 us=3Dc001c240 =
>flags=3D1
>10:30:15 Shutdown Mode
>10:30:16 Checkpoint Completed
>10:30:16 Quiescent Mode
>10:30:21 INFORMIX-OnLine Stopped>
>Tue Jul 1 10:33:54 1997
>
>10:33:54 INFORMIX-OnLine Stopped>
>Tue Jul 1 11:07:23 1997
>
>11:07:23 INFORMIX-OnLine Initialized -- Shared Memory Initialized
>11:07:23 Physical Recovery Started
>11:07:23 Checkpoint Completed
>11:07:23 Physical Recovery Complete: 0 Pages Restored
>11:07:24 Checkpoint Completed
>11:07:24 Logical Recovery Complete> 0 Committed, 0 Rolled Back, 0 Open, 0 Bad Locks
>
>11:07:25 Dropping temporary tblspace 100001a, recovering 8 pages.
>11:07:25 Dropping temporary tblspace 100001c, recovering 8 pages.
>11:07:25 Dropping temporary tblspace 1000037, recovering 8 pages.
>11:07:25 Dropping temporary tblspace 1000039, recovering 8 pages.
>11:07:39 Quiescent Mode
>11:12:48 Checkpoint Completed
>11:17:05 On-Line Mode
>11:21:34 read_record: deleted rowid =3D 151525, partnum =3D 2000140
>11:21:34 read_record: invalid rowid =3D 151525, partnum =3D 2000140
>11:23:29 Checkpoint Completed
>11:24:28 read_record: deleted rowid =3D 151521, partnum =3D 2000140
>11:24:28 read_record: invalid rowid =3D 151521, partnum =3D 2000140
>11:24:40 read_record: deleted rowid =3D 151521, partnum =3D 2000140
>11:24:40 read_record: invalid rowid =3D 151521, partnum =3D 2000140
>11:24:56 read_record: deleted rowid =3D 151521, partnum =3D 2000140
>11:24:56 read_record: invalid rowid =3D 151521, partnum =3D 2000140
>11:25:11 read_record: deleted rowid =3D 151521, partnum =3D 2000140
>11:25:11 read_record: invalid rowid =3D 151521, partnum =3D 2000140
>11:28:49 Checkpoint Completed
>11:34:09 Checkpoint Completed
>11:39:31 Checkpoint Completed
>11:40:22 Logical Log 16383 Complete
>11:43:14 read_record: deleted rowid =3D 151524, partnum =3D 2000140
>11:43:14 read_record: invalid rowid =3D 151524, partnum =3D 2000140
>11:43:33 read_record: deleted rowid =3D 151524, partnum =3D 2000140
>11:43:33 read_record: invalid rowid =3D 15
>11:43:33 read_record: invalid rowid =3D 151524, partnum =3D 2000140
>11:44:56 read_record: deleted rowid =3D 151524, partnum =3D 2000140
>11:44:56 read_record: invalid rowid =3D 151524, partnum =3D 2000140
>11:44:59 Checkpoint Completed
>11:50:11 Checkpoint Completed
>11:52:16 Process Aborted Abnormally: pid=3D16917 user=3D604 =>us=3Dcc4b95e8 flags=3D1
>11:52:17 Process Completed Abnormally
> (rollback): tx=3Dcc4e2b00 flags=3D80403
>11:55:42 Checkpoint Completed
>12:01:07 Checkpoint Completed
>12:06:36 Checkpoint Completed
>12:12:20 Checkpoint Completed
>12:13:07 Logical Log 16384 Complete
><<<snip>>>
>14:03:27 Logical Log 16388 Backed Up
>14:06:52 Checkpoint Completed
>14:12:10 Checkpoint Completed
>14:17:27 Checkpoint Completed
>14:22:09 Logical Log 16389 Complete
>14:22:33 I/O read() chunk 8, pagenum 690364, pagecnt 1
>14:22:34 I/O read() chunk 8, pagenum 797539, pagecnt 1
>14:22:36 I/O read() chunk 8, pagenum 905256, pagecnt 1
>14:22:37 I/O read() chunk 8, pagenum 896689, pagecnt 1
>14:22:37 I/O read() chunk 8, pagenum 896689, pagecnt 1
><<<snip>>>
>15:08:33 INFORMIX-OnLine Stopped>
>Tue Jul 1 15:12:16 1997
>
>15:12:16 INFORMIX-OnLine Initialized -- Shared Memory Initialized
>15:12:16 Physical Recovery Started
>15:12:17 Checkpoint Completed
>15:12:17 Physical Recovery Complete: 0 Pages Restored
>15:12:17 Checkpoint Completed
>15:12:17 Logical Recovery Complete> 3 Committed, 0 Rolled Back, 0 Open, 0 Bad Locks
>
>15:12:18 Dropping temporary tblspace 1000009, recovering 8 pages.
>15:12:18 Dropping temporary tblspace 100000a, recovering 8 pages.
>15:12:20 Dropping temporary tblspace 10004c4, recovering 8 pages.
>15:12:20 Dropping temporary tblspace 10004d1, recovering 8 pages.
>15:12:20 Dropping temporary tblspace 10004e0, recovering 8 pages.
>15:12:27 Dropping temporary tblspace 200039c, recovering 8 pages.
>15:12:27 Dropping temporary tblspace 20003a4, recovering 8 pages.
>15:12:27 Dropping temporary tblspace 20003b0, recovering 8 pages.
>15:12:32 Quiescent Mode
>15:14:20 On-Line Mode
>15:17:37 Checkpoint Completed
>15:33:37 Checkpoint Completed
>15:39:00 Checkpoint Completed
>15:42:41 allocslot(no room) - partnum 0x2000582 tr_pagenum 0x7dbc6
>15:42:41 allocslot(no room) - maxslots 32 slotsize 58
>15:42:41 c1177518: 00000000 00000000 00000001 00000000 ........
><<<snip>>>
--
David Williams