New database creation takes about 60 seconds when >400 bases in PostgreSQL

[Sergei Musson]

Hello, everyone.

We have met a problem recently: CREATE DATABASE query takes up to 60-70 seconds.

This happens when the total number of databases exceeds the number of 400. And the more databases we add the bigger is the execution time of this command.

What can be the problem?

I doubt that PostgreSQL has a limitation of 400 DB per server and if more, it slows one query down (only CREATE DATABASE query gets slow).

The PostgreSQL server configuration file is default. So I think there is no need to inserted it here.

PS: no additional services(like replication etc.) are running. The PostgreSQL server CPU/RAM usage is not even close to 30% then the database is being created so it is not the reason.
The number of active connections has nothing to do with it too because the same behaviour can be reproduced on local server with the same configuration and databases number.

Please help.
Thank you.

[rski]

What system do you use?
If linux/unix you can check what tooks so long using strace option like that

strace -ttt createdb <base name>

or

strace -ttt psql -c "CREATE DATABASE <base name>"

Can you put here the result of that strace command.

If you use windows, I don't know any such tool (like strace) for windows, but it must be some.

[Sergei Musson]

Here is the strace output.

1289808932.840528 execve("/usr/bin/createdb", ["createdb", "-U", "ptm", "-p", "5433", "-h", "127.0.0.1", "speed2"], [/* 22 vars */]) = 0
1289808932.841098 brk(0)                = 0x191a4000
1289808932.841163 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae15826d000
1289808932.841224 uname({sys="Linux", node="*******.com", ...}) = 0
1289808932.841320 readlink("/proc/self/exe", "/home/PostgreSQL/bin/createdb"..., 4096) = 29
1289808932.841405 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
1289808932.841474 open("/home/PostgreSQL/bin/../lib/tls/x86_64/libpq.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.841540 stat("/home/PostgreSQL/bin/../lib/tls/x86_64", 0x7fff80903720) = -1 ENOENT (No such file or directory)
1289808932.841598 open("/home/PostgreSQL/bin/../lib/tls/libpq.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.841645 stat("/home/PostgreSQL/bin/../lib/tls", 0x7fff80903720) = -1 ENOENT (No such file or directory)
1289808932.841690 open("/home/PostgreSQL/bin/../lib/x86_64/libpq.so.5", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.841787 stat("/home/PostgreSQL/bin/../lib/x86_64", 0x7fff80903720) = -1 ENOENT (No such file or directory)
1289808932.841962 open("/home/PostgreSQL/bin/../lib/libpq.so.5", O_RDONLY) = 3
1289808932.842031 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220~\0\0\0\0\0\0"..., 832) = 832
1289808932.842096 fstat(3, {st_mode=S_IFREG|0755, st_size=168575, ...}) = 0
1289808932.842161 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae15826e000
1289808932.842208 mmap(NULL, 1191696, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2ae15826f000
1289808932.842249 mprotect(0x2ae158290000, 1048576, PROT_NONE) = 0
1289808932.842296 mmap(0x2ae158390000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x21000) = 0x2ae158390000
1289808932.842363 close(3)              = 0
1289808932.842417 open("/home/PostgreSQL/bin/../lib/libxslt.so.1", O_RDONLY) = 3
1289808932.842467 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340\236\0\0\0\0\0\0"..., 832) = 832
1289808932.842522 fstat(3, {st_mode=S_IFREG|0755, st_size=806996, ...}) = 0
1289808932.842581 mmap(NULL, 1274128, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2ae158392000
1289808932.842627 mprotect(0x2ae1583c8000, 1048576, PROT_NONE) = 0
1289808932.842685 mmap(0x2ae1584c8000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x36000) = 0x2ae1584c8000
1289808932.842946 close(3)              = 0
1289808932.842990 open("/home/PostgreSQL/bin/../lib/libxml2.so.2", O_RDONLY) = 3
1289808932.843039 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0p\261\2\0\0\0\0\0"..., 832) = 832
1289808932.843093 fstat(3, {st_mode=S_IFREG|0755, st_size=4115398, ...}) = 0
1289808932.843156 mmap(NULL, 2352216, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2ae1584ca000
1289808932.843215 mprotect(0x2ae1585ff000, 1044480, PROT_NONE) = 0
1289808932.843263 mmap(0x2ae1586fe000, 36864, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x134000) = 0x2ae1586fe000
1289808932.843314 mmap(0x2ae158707000, 5208, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x2ae158707000
1289808932.843361 close(3)              = 0
1289808932.843403 open("/home/PostgreSQL/bin/../lib/libpam.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.843461 open("/etc/ld.so.cache", O_RDONLY) = 3
1289808932.843521 fstat(3, {st_mode=S_IFREG|0644, st_size=42853, ...}) = 0
1289808932.843586 mmap(NULL, 42853, PROT_READ, MAP_PRIVATE, 3, 0) = 0x2ae158709000
1289808932.843625 close(3)              = 0
1289808932.843670 open("/lib64/libpam.so.0", O_RDONLY) = 3
1289808932.843719 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\300$ \27:\0\0\0"..., 832) = 832
1289808932.843950 fstat(3, {st_mode=S_IFREG|0755, st_size=46800, ...}) = 0
1289808932.844027 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae158714000
1289808932.844081 mmap(0x3a17200000, 2140040, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a17200000
1289808932.844138 mprotect(0x3a1720b000, 2093056, PROT_NONE) = 0
1289808932.844181 mmap(0x3a1740a000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xa000) = 0x3a1740a000
1289808932.844228 close(3)              = 0
1289808932.844276 open("/home/PostgreSQL/bin/../lib/libssl.so.4", O_RDONLY) = 3
1289808932.844342 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\260\343\320\1=\0\0\0"..., 832) = 832
1289808932.844402 fstat(3, {st_mode=S_IFREG|0755, st_size=244288, ...}) = 0
1289808932.844461 mmap(0x3d01d00000, 1291880, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3d01d00000
1289808932.844503 mprotect(0x3d01d36000, 1048576, PROT_NONE) = 0
1289808932.844545 mmap(0x3d01e36000, 20480, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x36000) = 0x3d01e36000
1289808932.844602 mmap(0x3d01e3b000, 1640, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3d01e3b000
1289808932.844664 close(3)              = 0
1289808932.844714 open("/home/PostgreSQL/bin/../lib/libcrypto.so.4", O_RDONLY) = 3
1289808932.844934 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220\311\244\1=\0\0\0"..., 832) = 832
1289808932.844988 fstat(3, {st_mode=S_IFREG|0755, st_size=1234328, ...}) = 0
1289808932.845048 mmap(0x3d01a00000, 2295992, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3d01a00000
1289808932.845094 mprotect(0x3d01b0f000, 1048576, PROT_NONE) = 0
1289808932.845155 mmap(0x3d01c0f000, 122880, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x10f000) = 0x3d01c0f000
1289808932.845212 mmap(0x3d01c2d000, 14520, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3d01c2d000
1289808932.845255 close(3)              = 0
1289808932.845298 open("/home/PostgreSQL/bin/../lib/libkrb5.so.3", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.845352 open("/usr/lib64/libkrb5.so.3", O_RDONLY) = 3
1289808932.845406 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`\221\241\30:\0\0\0"..., 832) = 832
1289808932.845478 fstat(3, {st_mode=S_IFREG|0755, st_size=613896, ...}) = 0
1289808932.845542 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae158715000
1289808932.845589 mmap(0x3a18a00000, 2706624, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a18a00000
1289808932.845630 mprotect(0x3a18a91000, 2097152, PROT_NONE) = 0
1289808932.845673 mmap(0x3a18c91000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x91000) = 0x3a18c91000
1289808932.845785 close(3)              = 0
1289808932.845976 open("/home/PostgreSQL/bin/../lib/libz.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.846037 open("/usr/lib64/libz.so.1", O_RDONLY) = 3
1289808932.846087 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\260\36\340\24:\0\0\0"..., 832) = 832
1289808932.846140 fstat(3, {st_mode=S_IFREG|0755, st_size=85608, ...}) = 0
1289808932.846199 mmap(0x3a14e00000, 2178600, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a14e00000
1289808932.846248 mprotect(0x3a14e14000, 2093056, PROT_NONE) = 0
1289808932.846309 mmap(0x3a15013000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x13000) = 0x3a15013000
1289808932.846363 close(3)              = 0
1289808932.846407 open("/home/PostgreSQL/bin/../lib/libreadline.so.4", O_RDONLY) = 3
1289808932.846458 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`'al=\0\0\0"..., 832) = 832
1289808932.846510 fstat(3, {st_mode=S_IFREG|0755, st_size=229816, ...}) = 0
1289808932.846573 mmap(0x3d6c600000, 1278864, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3d6c600000
1289808932.846633 mprotect(0x3d6c630000, 1048576, PROT_NONE) = 0
1289808932.846681 mmap(0x3d6c730000, 32768, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x30000) = 0x3d6c730000
1289808932.846945 mmap(0x3d6c738000, 912, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3d6c738000
1289808932.846990 close(3)              = 0
1289808932.847042 open("/home/PostgreSQL/bin/../lib/libtermcap.so.2", O_RDONLY) = 3
1289808932.847101 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\240\20\340m=\0\0\0"..., 832) = 832
1289808932.847173 fstat(3, {st_mode=S_IFREG|0755, st_size=15728, ...}) = 0
1289808932.847232 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae158716000
1289808932.847278 mmap(0x3d6de00000, 1060400, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3d6de00000
1289808932.847319 mprotect(0x3d6de03000, 1044480, PROT_NONE) = 0
1289808932.847361 mmap(0x3d6df02000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x3d6df02000
1289808932.847416 close(3)              = 0
1289808932.847476 open("/home/PostgreSQL/bin/../lib/libcrypt.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.847532 open("/lib64/libcrypt.so.1", O_RDONLY) = 3
1289808932.847582 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\360\t\240\25:\0\0\0"..., 832) = 832
1289808932.847635 fstat(3, {st_mode=S_IFREG|0755, st_size=48600, ...}) = 0
1289808932.847695 mmap(0x3a15a00000, 2322880, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a15a00000
1289808932.847945 mprotect(0x3a15a09000, 2093056, PROT_NONE) = 0
1289808932.848007 mmap(0x3a15c08000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x8000) = 0x3a15c08000
1289808932.848060 mmap(0x3a15c0a000, 184768, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3a15c0a000
1289808932.848105 close(3)              = 0
1289808932.848148 open("/home/PostgreSQL/bin/../lib/libdl.so.2", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.848203 open("/lib64/libdl.so.2", O_RDONLY) = 3
1289808932.848260 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\20\16`\23:\0\0\0"..., 832) = 832
1289808932.848331 fstat(3, {st_mode=S_IFREG|0755, st_size=23360, ...}) = 0
1289808932.848391 mmap(0x3a13600000, 2109696, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a13600000
1289808932.848432 mprotect(0x3a13602000, 2097152, PROT_NONE) = 0
1289808932.848475 mmap(0x3a13802000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x3a13802000
1289808932.848526 close(3)              = 0
1289808932.848577 open("/home/PostgreSQL/bin/../lib/libm.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.848648 open("/lib64/libm.so.6", O_RDONLY) = 3
1289808932.848696 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`>\340\23:\0\0\0"..., 832) = 832
1289808932.848792 fstat(3, {st_mode=S_IFREG|0755, st_size=615136, ...}) = 0
1289808932.849005 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae158717000
1289808932.849053 mmap(0x3a13e00000, 2629848, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a13e00000
1289808932.849102 mprotect(0x3a13e82000, 2093056, PROT_NONE) = 0
1289808932.849164 mmap(0x3a14081000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x81000) = 0x3a14081000
1289808932.849213 close(3)              = 0
1289808932.849259 open("/home/PostgreSQL/bin/../lib/libc.so.6", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.849311 open("/lib64/libc.so.6", O_RDONLY) = 3
1289808932.849356 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220\332!\23:\0\0\0"..., 832) = 832
1289808932.849414 fstat(3, {st_mode=S_IFREG|0755, st_size=1717800, ...}) = 0
1289808932.849494 mmap(0x3a13200000, 3498328, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a13200000
1289808932.849536 mprotect(0x3a1334d000, 2097152, PROT_NONE) = 0
1289808932.849578 mmap(0x3a1354d000, 20480, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x14d000) = 0x3a1354d000
1289808932.849630 mmap(0x3a13552000, 16728, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3a13552000
1289808932.849674 close(3)              = 0
1289808932.849791 open("/home/PostgreSQL/bin/../lib/libpthread.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.849994 open("/home/PostgreSQL/bin/../lib/libpthread.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.850062 open("/lib64/libpthread.so.0", O_RDONLY) = 3
1289808932.850109 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\240W\240\23:\0\0\0"..., 832) = 832
1289808932.850163 fstat(3, {st_mode=S_IFREG|0755, st_size=145824, ...}) = 0
1289808932.850223 mmap(0x3a13a00000, 2204528, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a13a00000
1289808932.850269 mprotect(0x3a13a16000, 2093056, PROT_NONE) = 0
1289808932.850329 mmap(0x3a13c15000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x15000) = 0x3a13c15000
1289808932.850387 mmap(0x3a13c17000, 13168, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3a13c17000
1289808932.850432 close(3)              = 0
1289808932.850484 open("/home/PostgreSQL/bin/../lib/libaudit.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.850538 open("/lib64/libaudit.so.0", O_RDONLY) = 3
1289808932.850588 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340&`\26:\0\0\0"..., 832) = 832
1289808932.850658 fstat(3, {st_mode=S_IFREG|0755, st_size=107112, ...}) = 0
1289808932.850722 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae158718000
1289808932.850946 mmap(0x3a16600000, 2200080, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a16600000
1289808932.850987 mprotect(0x3a16619000, 2093056, PROT_NONE) = 0
1289808932.851030 mmap(0x3a16818000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x18000) = 0x3a16818000
1289808932.851082 close(3)              = 0
1289808932.851147 open("/home/PostgreSQL/bin/../lib/libgssapi_krb5.so.2", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.851208 open("/usr/lib64/libgssapi_krb5.so.2", O_RDONLY) = 3
1289808932.851258 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\222 \26:\0\0\0"..., 832) = 832
1289808932.851310 fstat(3, {st_mode=S_IFREG|0755, st_size=190976, ...}) = 0
1289808932.851404 mmap(0x3a16200000, 2283888, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a16200000
1289808932.851504 mprotect(0x3a1622c000, 2097152, PROT_NONE) = 0
1289808932.851565 mmap(0x3a1642c000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2c000) = 0x3a1642c000
1289808932.851621 close(3)              = 0
1289808932.851667 open("/home/PostgreSQL/bin/../lib/libcom_err.so.2", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.851720 open("/lib64/libcom_err.so.2", O_RDONLY) = 3
1289808932.851942 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\n\240\27:\0\0\0"..., 832) = 832
1289808932.852003 fstat(3, {st_mode=S_IFREG|0755, st_size=10000, ...}) = 0
1289808932.852082 mmap(0x3a17a00000, 2103048, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a17a00000
1289808932.852124 mprotect(0x3a17a02000, 2093056, PROT_NONE) = 0
1289808932.852166 mmap(0x3a17c01000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1000) = 0x3a17c01000
1289808932.852214 close(3)              = 0
1289808932.852258 open("/home/PostgreSQL/bin/../lib/libk5crypto.so.3", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.852319 open("/usr/lib64/libk5crypto.so.3", O_RDONLY) = 3
1289808932.852385 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\260V\340\30:\0\0\0"..., 832) = 832
1289808932.852438 fstat(3, {st_mode=S_IFREG|0755, st_size=153464, ...}) = 0
1289808932.852498 mmap(0x3a18e00000, 2247272, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a18e00000
1289808932.852539 mprotect(0x3a18e24000, 2093056, PROT_NONE) = 0
1289808932.852581 mmap(0x3a19023000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x23000) = 0x3a19023000
1289808932.852639 close(3)              = 0
1289808932.852713 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae158719000
1289808932.853073 open("/home/PostgreSQL/bin/../lib/libkrb5support.so.0", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.853178 open("/usr/lib64/libkrb5support.so.0", O_RDONLY) = 3
1289808932.853241 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200\"`\31:\0\0\0"..., 832) = 832
1289808932.853295 fstat(3, {st_mode=S_IFREG|0755, st_size=35728, ...}) = 0
1289808932.853363 mmap(0x3a19600000, 2128592, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a19600000
1289808932.853424 mprotect(0x3a19608000, 2093056, PROT_NONE) = 0
1289808932.853467 mmap(0x3a19807000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x7000) = 0x3a19807000
1289808932.853515 close(3)              = 0
1289808932.853561 open("/home/PostgreSQL/bin/../lib/libkeyutils.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.853615 open("/lib64/libkeyutils.so.1", O_RDONLY) = 3
1289808932.853673 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`\n\240\26:\0\0\0"..., 832) = 832
1289808932.853961 fstat(3, {st_mode=S_IFREG|0755, st_size=9472, ...}) = 0
1289808932.854022 mmap(0x3a16a00000, 2102416, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a16a00000
1289808932.854064 mprotect(0x3a16a02000, 2093056, PROT_NONE) = 0
1289808932.854107 mmap(0x3a16c01000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1000) = 0x3a16c01000
1289808932.854155 close(3)              = 0
1289808932.854211 open("/home/PostgreSQL/bin/../lib/libresolv.so.2", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.854280 open("/lib64/libresolv.so.2", O_RDONLY) = 3
1289808932.854329 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\2402`\27:\0\0\0"..., 832) = 832
1289808932.854381 fstat(3, {st_mode=S_IFREG|0755, st_size=92736, ...}) = 0
1289808932.854441 mmap(0x3a17600000, 2181864, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a17600000
1289808932.854483 mprotect(0x3a17611000, 2097152, PROT_NONE) = 0
1289808932.854536 mmap(0x3a17811000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x11000) = 0x3a17811000
1289808932.854604 mmap(0x3a17813000, 6888, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3a17813000
1289808932.854648 close(3)              = 0
1289808932.854699 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae15871a000
1289808932.854964 open("/home/PostgreSQL/bin/../lib/libselinux.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.855018 open("/lib64/libselinux.so.1", O_RDONLY) = 3
1289808932.855073 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`E \24:\0\0\0"..., 832) = 832
1289808932.855141 fstat(3, {st_mode=S_IFREG|0755, st_size=95464, ...}) = 0
1289808932.855201 mmap(0x3a14200000, 2192784, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a14200000
1289808932.855243 mprotect(0x3a14215000, 2097152, PROT_NONE) = 0
1289808932.855286 mmap(0x3a14415000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x15000) = 0x3a14415000
1289808932.855337 mmap(0x3a14417000, 1424, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3a14417000
1289808932.855393 close(3)              = 0
1289808932.855460 open("/home/PostgreSQL/bin/../lib/libsepol.so.1", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.855514 open("/lib64/libsepol.so.1", O_RDONLY) = 3
1289808932.855559 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0=`\24:\0\0\0"..., 832) = 832
1289808932.855610 fstat(3, {st_mode=S_IFREG|0755, st_size=247496, ...}) = 0
1289808932.855670 mmap(0x3a14600000, 2383136, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3a14600000
1289808932.855785 mprotect(0x3a1463b000, 2097152, PROT_NONE) = 0
1289808932.855966 mmap(0x3a1483b000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3b000) = 0x3a1483b000
1289808932.856024 mmap(0x3a1483c000, 40224, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3a1483c000
1289808932.856066 close(3)              = 0
1289808932.856133 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae15871b000
1289808932.856219 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae15871c000
1289808932.856277 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae15871d000
1289808932.856339 arch_prctl(ARCH_SET_FS, 0x2ae15871ca30) = 0
1289808932.856521 mprotect(0x3a17811000, 4096, PROT_READ) = 0
1289808932.856961 mprotect(0x3a13c15000, 4096, PROT_READ) = 0
1289808932.857162 mprotect(0x3a1354d000, 16384, PROT_READ) = 0
1289808932.857228 mprotect(0x3a14081000, 4096, PROT_READ) = 0
1289808932.857306 mprotect(0x3a13802000, 4096, PROT_READ) = 0
1289808932.857380 mprotect(0x3a15c08000, 4096, PROT_READ) = 0
1289808932.858244 mprotect(0x3a1301b000, 4096, PROT_READ) = 0
1289808932.858289 munmap(0x2ae158709000, 42853) = 0
1289808932.858335 set_tid_address(0x2ae15871cac0) = 14273
1289808932.858375 set_robust_list(0x2ae15871cad0, 0x18) = 0
1289808932.858423 futex(0x7fff8090424c, FUTEX_WAKE_PRIVATE, 1) = 0
1289808932.858484 rt_sigaction(SIGRTMIN, {0x3a13a05380, [], SA_RESTORER|SA_SIGINFO, 0x3a13a0e930}, NULL, 8) = 0
1289808932.858541 rt_sigaction(SIGRT_1, {0x3a13a052b0, [], SA_RESTORER|SA_RESTART|SA_SIGINFO, 0x3a13a0e930}, NULL, 8) = 0
1289808932.858589 rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
1289808932.858639 getrlimit(RLIMIT_STACK, {rlim_cur=10240*1024, rlim_max=RLIM_INFINITY}) = 0
1289808932.858795 access("/etc/selinux/", F_OK) = 0
1289808932.859044 brk(0)                = 0x191a4000
1289808932.859089 brk(0x191c5000)       = 0x191c5000
1289808932.859142 open("/etc/selinux/config", O_RDONLY) = 3
1289808932.859204 fstat(3, {st_mode=S_IFREG|0644, st_size=511, ...}) = 0
1289808932.859267 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae158709000
1289808932.859312 read(3, "# This file controls the state o"..., 4096) = 511
1289808932.859404 read(3, "", 4096)     = 0
1289808932.859458 close(3)              = 0
1289808932.859501 munmap(0x2ae158709000, 4096) = 0
1289808932.859581 open("/proc/mounts", O_RDONLY) = 3
1289808932.859659 fstat(3, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
1289808932.859788 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae158709000
1289808932.859961 read(3, "rootfs / rootfs rw 0 0\n/dev/root"..., 4096) = 701
1289808932.860060 read(3, "", 4096)     = 0
1289808932.860104 close(3)              = 0
1289808932.860141 munmap(0x2ae158709000, 4096) = 0
1289808932.860268 open("/usr/lib/locale/locale-archive", O_RDONLY) = 3
1289808932.860321 fstat(3, {st_mode=S_IFREG|0644, st_size=56422016, ...}) = 0
1289808932.860391 mmap(NULL, 56422016, PROT_READ, MAP_PRIVATE, 3, 0) = 0x2ae15871e000
1289808932.860446 close(3)              = 0
1289808932.860525 getcwd("/home/pgsql-9.0.1/data"..., 1024) = 23
1289808932.860586 stat("/usr/kerberos/sbin/createdb", 0x7fff80902c70) = -1 ENOENT (No such file or directory)
1289808932.860636 stat("/usr/kerberos/bin/createdb", 0x7fff80902c70) = -1 ENOENT (No such file or directory)
1289808932.860686 stat("/usr/local/sbin/createdb", 0x7fff80902c70) = -1 ENOENT (No such file or directory)
1289808932.860797 stat("/usr/local/bin/createdb", 0x7fff80902c70) = -1 ENOENT (No such file or directory)
1289808932.860964 stat("/sbin/createdb", 0x7fff80902c70) = -1 ENOENT (No such file or directory)
1289808932.861013 stat("/bin/createdb", 0x7fff80902c70) = -1 ENOENT (No such file or directory)
1289808932.861056 stat("/usr/sbin/createdb", 0x7fff80902c70) = -1 ENOENT (No such file or directory)
1289808932.861101 stat("/usr/bin/createdb", {st_mode=S_IFREG|0755, st_size=54882, ...}) = 0
1289808932.861174 geteuid()             = 0
1289808932.861220 getcwd("/home/pgsql-9.0.1/data"..., 1024) = 23
1289808932.861272 chdir("/usr/bin")     = 0
1289808932.861323 lstat("createdb", {st_mode=S_IFLNK|0777, st_size=29, ...}) = 0
1289808932.861389 readlink("createdb", "/home/PostgreSQL/bin/createdb"..., 1024) = 29
1289808932.861439 chdir("/home/PostgreSQL/bin") = 0
1289808932.861483 lstat("createdb", {st_mode=S_IFREG|0755, st_size=54882, ...}) = 0
1289808932.861553 getcwd("/home/PostgreSQL/bin"..., 1024) = 21
1289808932.861602 chdir("/home/pgsql-9.0.1/data") = 0
1289808932.861789 geteuid()             = 0
1289808932.861978 socket(PF_FILE, SOCK_STREAM, 0) = 3
1289808932.862029 fcntl(3, F_SETFL, O_RDWR|O_NONBLOCK) = 0
1289808932.862071 connect(3, {sa_family=AF_FILE, path="/var/run/nscd/socket"...}, 110) = -1 ENOENT (No such file or directory)
1289808932.862148 close(3)              = 0
1289808932.862196 socket(PF_FILE, SOCK_STREAM, 0) = 3
1289808932.862241 fcntl(3, F_SETFL, O_RDWR|O_NONBLOCK) = 0
1289808932.862277 connect(3, {sa_family=AF_FILE, path="/var/run/nscd/socket"...}, 110) = -1 ENOENT (No such file or directory)
1289808932.862338 close(3)              = 0
1289808932.862390 open("/etc/nsswitch.conf", O_RDONLY) = 3
1289808932.862445 fstat(3, {st_mode=S_IFREG|0644, st_size=1696, ...}) = 0
1289808932.862511 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae15bced000
1289808932.862559 read(3, "#\n# /etc/nsswitch.conf\n#\n# An ex"..., 4096) = 1696
1289808932.862638 read(3, "", 4096)     = 0
1289808932.862676 close(3)              = 0
1289808932.862715 munmap(0x2ae15bced000, 4096) = 0
1289808932.862958 open("/home/PostgreSQL/bin/../lib/libnss_files.so.2", O_RDONLY) = -1 ENOENT (No such file or directory)
1289808932.863020 open("/etc/ld.so.cache", O_RDONLY) = 3
1289808932.863068 fstat(3, {st_mode=S_IFREG|0644, st_size=42853, ...}) = 0
1289808932.863127 mmap(NULL, 42853, PROT_READ, MAP_PRIVATE, 3, 0) = 0x2ae15bced000
1289808932.863166 close(3)              = 0
1289808932.863216 open("/lib64/libnss_files.so.2", O_RDONLY) = 3
1289808932.863274 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340\37\0\0\0\0\0\0"..., 832) = 832
1289808932.863336 fstat(3, {st_mode=S_IFREG|0755, st_size=53880, ...}) = 0
1289808932.863407 mmap(NULL, 2139432, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2ae15bcf8000
1289808932.863450 mprotect(0x2ae15bd02000, 2093056, PROT_NONE) = 0
1289808932.863495 mmap(0x2ae15bf01000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x9000) = 0x2ae15bf01000
1289808932.863552 close(3)              = 0
1289808932.863631 mprotect(0x2ae15bf01000, 4096, PROT_READ) = 0
1289808932.863685 munmap(0x2ae15bced000, 42853) = 0
1289808932.863939 open("/etc/passwd", O_RDONLY) = 3
1289808932.863995 fcntl(3, F_GETFD)     = 0
1289808932.864033 fcntl(3, F_SETFD, FD_CLOEXEC) = 0
1289808932.864077 fstat(3, {st_mode=S_IFREG|0644, st_size=1934, ...}) = 0
1289808932.864151 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae15bced000
1289808932.864202 read(3, "root:x:0:0:root:/root:/bin/bash\n"..., 4096) = 1934
1289808932.864267 close(3)              = 0
1289808932.864304 munmap(0x2ae15bced000, 4096) = 0
1289808932.864366 geteuid()             = 0
1289808932.864410 open("/etc/passwd", O_RDONLY) = 3
1289808932.864469 fcntl(3, F_GETFD)     = 0
1289808932.864514 fcntl(3, F_SETFD, FD_CLOEXEC) = 0
1289808932.864550 fstat(3, {st_mode=S_IFREG|0644, st_size=1934, ...}) = 0
1289808932.864609 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2ae15bced000
1289808932.864650 read(3, "root:x:0:0:root:/root:/bin/bash\n"..., 4096) = 1934
1289808932.864703 close(3)              = 0
1289808932.864950 munmap(0x2ae15bced000, 4096) = 0
1289808932.865008 stat("/root/.pgpass", 0x7fff80903c30) = -1 ENOENT (No such file or directory)
1289808932.865086 socket(PF_INET, SOCK_STREAM, IPPROTO_IP) = 3
1289808932.865140 setsockopt(3, SOL_TCP, TCP_NODELAY, [1], 4) = 0
1289808932.865189 fcntl(3, F_SETFL, O_RDONLY|O_NONBLOCK) = 0
1289808932.865230 fcntl(3, F_SETFD, FD_CLOEXEC) = 0
1289808932.865286 connect(3, {sa_family=AF_INET, sin_port=htons(5433), sin_addr=inet_addr("127.0.0.1")}, 16) = -1 EINPROGRESS (Operation now in progress)
1289808932.865467 poll([{fd=3, events=POLLOUT|POLLERR}], 1, -1) = 1 ([{fd=3, revents=POLLOUT}])
1289808932.865524 getsockopt(3, SOL_SOCKET, SO_ERROR, [17179869184], [4]) = 0
1289808932.865574 getsockname(3, {sa_family=AF_INET, sin_port=htons(41334), sin_addr=inet_addr("127.0.0.1")}, [16]) = 0
1289808932.865633 poll([{fd=3, events=POLLOUT|POLLERR}], 1, -1) = 1 ([{fd=3, revents=POLLOUT}])
1289808932.865699 rt_sigprocmask(SIG_BLOCK, [PIPE], [], 8) = 0
1289808932.865962 sendto(3, "\0\0\0\10\4\322\26/", 8, 0, NULL, 0) = 8
1289808932.866084 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
1289808932.866125 poll([{fd=3, events=POLLIN|POLLERR}], 1, -1) = 1 ([{fd=3, revents=POLLIN}])
1289808932.866176 recvfrom(3, "N", 16384, 0, NULL, NULL) = 1
1289808932.866222 poll([{fd=3, events=POLLOUT|POLLERR}], 1, -1) = 1 ([{fd=3, revents=POLLOUT}])
1289808932.866280 rt_sigprocmask(SIG_BLOCK, [PIPE], [], 8) = 0
1289808932.866342 sendto(3, "\0\0\0&\0\3\0\0user\0ptm\0database\0post"..., 38, 0, NULL, 0) = 38
1289808932.866462 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
1289808932.866503 poll([{fd=3, events=POLLIN|POLLERR}], 1, -1) = 1 ([{fd=3, revents=POLLIN}])
1289808932.868617 recvfrom(3, "R\0\0\0\10\0\0\0\0S\0\0\0\26application_name\0\0"..., 16384, 0, NULL, NULL) = 317
1289808932.868794 rt_sigprocmask(SIG_BLOCK, [PIPE], [], 8) = 0
1289808932.868977 sendto(3, "Q\0\0\0\35CREATE DATABASE speed2;\n\0", 30, 0, NULL, 0) = 30
1289808932.869096 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
1289808932.869137 poll([{fd=3, events=POLLIN|POLLERR}], 1, -1) = 1 ([{fd=3, revents=POLLIN}])
1289808970.642297 recvfrom(3, "C\0\0\0\24CREATE DATABASE\0Z\0\0\0\5I", 16384, 0, NULL, NULL) = 27
1289808970.642409 rt_sigprocmask(SIG_BLOCK, [PIPE], [], 8) = 0
1289808970.642467 sendto(3, "X\0\0\0\4", 5, 0, NULL, 0) = 5
1289808970.642526 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
1289808970.642571 close(3)              = 0
1289808970.642863 exit_group(0)         = ?