Re: BUG #6661: out-of-order XID insertion in KnownAssignedXids

Oh, that is not good, I am getting the same error after rebuilding
hot-standby from master.

2012-05-23 02:08:36.960 CEST,,,21080,,4fbc2a84.5258,1,,2012-05-23 02:08:36
CEST,,0,LOG,00000,"database system was interrupted while in recovery at log
time 2012-05-22 21:33:49 CEST",,"If this has occurred more than
once some data might be corrupted and you might need to choose an earlier
recovery target.",,,,,,,""
2012-05-23 02:08:36.967 CEST,,,21080,,4fbc2a84.5258,2,,2012-05-23 02:08:36
CEST,,0,LOG,00000,"entering standby mode",,,,,,,,,""
2012-05-23 02:08:36.987 CEST,,,21080,,4fbc2a84.5258,3,,2012-05-23 02:08:36
CEST,,0,LOG,00000,"restored log file ""0000000200001CA2000000BE"" from
archive",,,,,,,,,""
2012-05-23 02:08:36.987 CEST,,,21080,,4fbc2a84.5258,4,,2012-05-23 02:08:36
CEST,1/0,0,LOG,00000,"redo starts at 1CA2/BE38F0E0",,,,,,,,,""
2012-05-23 02:08:36.997 CEST,,,21080,,4fbc2a84.5258,5,,2012-05-23 02:08:36
CEST,1/0,0,LOG,00000,"file ""pg_multixact/offsets/0BF1"" doesn't exist,
reading as zeroes",,,,,"xlog redo create multixact 200389058 offset
473492754: 3675639270 3675639273",,,,""
2012-05-23 02:08:36.997 CEST,,,21080,,4fbc2a84.5258,6,,2012-05-23 02:08:36
CEST,1/0,0,LOG,00000,"file ""pg_multixact/members/1C38"" doesn't exist,
reading as zeroes",,,,,"xlog redo create multixact 200389058 offset
473492754: 3675639270 3675639273",,,,""
2012-05-23 02:08:37.096 CEST,,,21080,,4fbc2a84.5258,7,,2012-05-23 02:08:36
CEST,1/0,0,LOG,00000,"restored log file ""0000000200001CA2000000BF"" from
archive",,,,,,,,,""
2012-05-23 02:08:37.213 CEST,,,21080,,4fbc2a84.5258,8,,2012-05-23 02:08:36
CEST,1/0,0,LOG,00000,"restored log file ""0000000200001CA2000000C0"" from
archive",,,,,,,,,""
2012-05-23 02:08:37.393 CEST,,,21080,,4fbc2a84.5258,9,,2012-05-23 02:08:36
CEST,1/0,0,LOG,00000,"restored log file ""0000000200001CA2000000C1"" from
archive",,,,,,,,,""
2012-05-23 02:08:37.568 CEST,,,21080,,4fbc2a84.5258,10,,2012-05-23 02:08:36
CEST,1/0,0,LOG,00000,"restored log file ""0000000200001CA2000000C2"" from
archive",,,,,,,,,""
2012-05-23 02:08:37.932 CEST,,,21080,,4fbc2a84.5258,11,,2012-05-23 02:08:36
CEST,1/0,0,LOG,00000,"restored log file ""0000000200001CA2000000C3"" from
archive",,,,,,,,,""
2012-05-23 02:08:38.297 CEST,,,21080,,4fbc2a84.5258,12,,2012-05-23 02:08:36
CEST,1/0,0,LOG,00000,"restored log file ""0000000200001CA2000000C4"" from
archive",,,,,,,,,""

....

2012-05-23 02:08:41.617 CEST,,,21080,,4fbc2a84.5258,32,,2012-05-23 02:08:36
CEST,1/0,0,LOG,00000,"restored log file ""0000000200001CA2000000D8"" from
archive",,,,,,,,,""
2012-05-23 02:08:41.753 CEST,,,21080,,4fbc2a84.5258,33,,2012-05-23 02:08:36
CEST,1/0,0,LOG,00000,"restored log file ""0000000200001CA2000000D9"" from
archive",,,,,,,,,""
2012-05-23 02:08:41.881 CEST,,,21080,,4fbc2a84.5258,34,,2012-05-23 02:08:36
CEST,1/0,0,LOG,00000,"restored log file ""0000000200001CA2000000DA"" from
archive",,,,,,,,,""
2012-05-23 02:08:41.888 CEST,,,21080,,4fbc2a84.5258,35,,2012-05-23 02:08:36
CEST,1/0,0,LOG,00000,"4 KnownAssignedXids (num=4 tail=0 head=873)
[0]=3675654963 [1]=3675655620 [2]=3675655621 [3]=3675655814 ",,,,,"xlog
redo running xacts: nextXid 3675658265 latestCompletedXid 3675658264
oldestRunningXid 3675654963; 4 xacts: 3675655620 3675655621 3675655814
3675654963",,,,""
2012-05-23 02:08:41.888 CEST,,,21080,,4fbc2a84.5258,36,,2012-05-23 02:08:36
CEST,1/0,0,FATAL,XX000,"out-of-order XID insertion in
KnownAssignedXids",,,,,"xlog redo running xacts: nextXid 3675658265
latestCompletedXid 3675658264 oldestRunningXid 3675654963; 4 xacts:
3675655620 3675655621 3675655814 3675654963",,,,""
2012-05-23 02:08:41.909 CEST,,,21078,,4fbc2a84.5256,1,,2012-05-23 02:08:36
CEST,,0,LOG,00000,"startup process (PID 21080) exited with exit code
1",,,,,,,,,""
2012-05-23 02:08:41.909 CEST,,,21078,,4fbc2a84.5256,2,,2012-05-23 02:08:36
CEST,,0,LOG,00000,"terminating any other active server processes",,,,,,,,,""

On Tue, May 22, 2012 at 11:36 PM, <valgog(at)gmail(dot)com> wrote:

> The following bug has been logged on the website:
>
> Bug reference: 6661
> Logged by: Valentine Gogichashvili
> Email address: valgog(at)gmail(dot)com
> PostgreSQL version: 9.0.7
> Operating system: Linux version 2.6.32-5-amd64 (Debian 2.6.32-41sque
> Description:
>
> Hello,
>
> today when trying to restore a replication database I got the following
> error:
>
> ...
> 2012-05-22 21:20:24.823 CEST,,,23804,,4fbbe69e.5cfc,124,,2012-05-22
> 21:18:54
> CEST,1/0,0,LOG,00000,"restored log file ""0000000200001C9C0000001E"" from
> archive",,,,,,,,,""
> 2012-05-22 21:20:24.954 CEST,,,23804,,4fbbe69e.5cfc,125,,2012-05-22
> 21:18:54
> CEST,1/0,0,LOG,00000,"restored log file ""0000000200001C9C0000001F"" from
> archive",,,,,,,,,""
> 2012-05-22 21:20:26.374 CEST,,,23804,,4fbbe69e.5cfc,126,,2012-05-22
> 21:18:54
> CEST,1/0,0,LOG,00000,"restored log file ""0000000200001C9C00000020"" from
> archive",,,,,,,,,""
> 2012-05-22 21:20:27.238 CEST,,,23804,,4fbbe69e.5cfc,127,,2012-05-22
> 21:18:54
> CEST,1/0,0,LOG,00000,"restored log file ""0000000200001C9C00000021"" from
> archive",,,,,,,,,""
> 2012-05-22 21:20:27.443 CEST,,,23804,,4fbbe69e.5cfc,128,,2012-05-22
> 21:18:54
> CEST,1/0,0,LOG,00000,"consistent recovery state reached at
> 1C9C/21318C20",,,,,,,,,""
> 2012-05-22 21:20:27.868 CEST,,,23804,,4fbbe69e.5cfc,129,,2012-05-22
> 21:18:54
> CEST,1/0,0,LOG,00000,"4 KnownAssignedXids (num=4 tail=0 head=916)
> [0]=3674726497 [1]=3674727041 [2]=3674727042 [3]=3674727128 ",,,,,"xlog
> redo
> running xacts: nextXid 3674728633 latestCompletedXid 3674728632
> oldestRunningXid 3674726497; 4 xacts: 3674727041 3674727042 3674727128
> 3674726497",,,,""
> 2012-05-22 21:20:27.868 CEST,,,23804,,4fbbe69e.5cfc,130,,2012-05-22
> 21:18:54
> CEST,1/0,0,FATAL,XX000,"out-of-order XID insertion in
> KnownAssignedXids",,,,,"xlog redo running xacts: nextXid 3674728633
> latestCompletedXid 3674728632 oldestRunningXid 3674726497; 4 xacts:
> 3674727041 3674727042 3674727128 3674726497",,,,""
> 2012-05-22 21:20:27.939 CEST,,,23793,,4fbbe69e.5cf1,1,,2012-05-22 21:18:54
> CEST,,0,LOG,00000,"startup process (PID 23804) exited with exit code
> 1",,,,,,,,,""
> 2012-05-22 21:20:27.939 CEST,,,23793,,4fbbe69e.5cf1,2,,2012-05-22 21:18:54
> CEST,,0,LOG,00000,"terminating any other active server
> processes",,,,,,,,,""
>
>
>