-
-
Save erthalion/cdc9357f7437171192348239eb4db764 to your computer and use it in GitHub Desktop.
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| # primary | |
| $ ./bin/pgbench -s 100 -i test | |
| NOTICE: table "pgbench_history" does not exist, skipping | |
| NOTICE: table "pgbench_tellers" does not exist, skipping | |
| NOTICE: table "pgbench_accounts" does not exist, skipping | |
| NOTICE: table "pgbench_branches" does not exist, skipping | |
| creating tables... | |
| 100000 of 10000000 tuples (1%) done (elapsed 0.11 s, remaining 10.50 s) | |
| 200000 of 10000000 tuples (2%) done (elapsed 1.06 s, remaining 52.00 s) | |
| 300000 of 10000000 tuples (3%) done (elapsed 1.88 s, remaining 60.87 s) | |
| 2017-09-30 15:47:26.884 CEST [6035] LOG: revoking synchronous replay lease for standby "walreceiver"... | |
| 2017-09-30 15:47:26.900 CEST [6035] LOG: standby "walreceiver" is no longer available for synchronous replay | |
| 2017-09-30 15:47:26.903 CEST [6197] LOG: revoking synchronous replay lease for standby "walreceiver"... | |
| 400000 of 10000000 tuples (4%) done (elapsed 2.44 s, remaining 58.62 s) | |
| 2017-09-30 15:47:27.979 CEST [6197] LOG: standby "walreceiver" is no longer available for synchronous replay | |
| 500000 of 10000000 tuples (5%) done (elapsed 4.23 s, remaining 80.44 s) | |
| 600000 of 10000000 tuples (6%) done (elapsed 4.96 s, remaining 77.67 s) | |
| 700000 of 10000000 tuples (7%) done (elapsed 6.34 s, remaining 84.26 s) | |
| 800000 of 10000000 tuples (8%) done (elapsed 6.82 s, remaining 78.39 s) | |
| 900000 of 10000000 tuples (9%) done (elapsed 8.98 s, remaining 90.82 s) | |
| 1000000 of 10000000 tuples (10%) done (elapsed 17.66 s, remaining 158.98 s) | |
| 1100000 of 10000000 tuples (11%) done (elapsed 20.88 s, remaining 168.94 s) | |
| 2017-09-30 15:47:46.136 CEST [6035] LOG: terminating walsender process due to replication timeout | |
| 2017-09-30 15:47:47.948 CEST [6197] LOG: terminating walsender process due to replication timeout | |
| 2017-09-30 15:47:55.404 CEST [10510] LOG: standby "walreceiver" joining synchronous replay set... | |
| 1200000 of 10000000 tuples (12%) done (elapsed 31.34 s, remaining 229.82 s) | |
| 2017-09-30 15:47:57.874 CEST [10510] LOG: standby "walreceiver" is no longer available for synchronous replay | |
| 1300000 of 10000000 tuples (13%) done (elapsed 36.83 s, remaining 246.50 s) | |
| 1400000 of 10000000 tuples (14%) done (elapsed 37.40 s, remaining 229.76 s) | |
| 2017-09-30 15:48:09.600 CEST [10510] LOG: terminating walsender process due to replication timeout | |
| 1500000 of 10000000 tuples (15%) done (elapsed 45.12 s, remaining 255.66 s) | |
| 2017-09-30 15:48:09.955 CEST [10576] LOG: standby "walreceiver" joining synchronous replay set... | |
| 1600000 of 10000000 tuples (16%) done (elapsed 46.25 s, remaining 242.84 s) | |
| 2017-09-30 15:48:11.394 CEST [10576] LOG: standby "walreceiver" is no longer available for synchronous replay | |
| 2017-09-30 15:48:17.460 CEST [10576] LOG: terminating walsender process due to replication timeout | |
| 1700000 of 10000000 tuples (17%) done (elapsed 54.18 s, remaining 264.53 s) | |
| 1800000 of 10000000 tuples (18%) done (elapsed 56.83 s, remaining 258.91 s) | |
| 1900000 of 10000000 tuples (19%) done (elapsed 58.66 s, remaining 250.08 s) | |
| 2000000 of 10000000 tuples (20%) done (elapsed 60.88 s, remaining 243.51 s) | |
| 2100000 of 10000000 tuples (21%) done (elapsed 64.23 s, remaining 241.63 s) | |
| 2200000 of 10000000 tuples (22%) done (elapsed 65.21 s, remaining 231.21 s) | |
| 2300000 of 10000000 tuples (23%) done (elapsed 65.31 s, remaining 218.65 s) | |
| 2400000 of 10000000 tuples (24%) done (elapsed 66.96 s, remaining 212.06 s) | |
| 2500000 of 10000000 tuples (25%) done (elapsed 67.08 s, remaining 201.24 s) | |
| 2600000 of 10000000 tuples (26%) done (elapsed 67.44 s, remaining 191.95 s) | |
| 2700000 of 10000000 tuples (27%) done (elapsed 68.24 s, remaining 184.50 s) | |
| 2800000 of 10000000 tuples (28%) done (elapsed 68.35 s, remaining 175.76 s) | |
| 2900000 of 10000000 tuples (29%) done (elapsed 69.07 s, remaining 169.11 s) | |
| 3000000 of 10000000 tuples (30%) done (elapsed 69.28 s, remaining 161.66 s) | |
| 3100000 of 10000000 tuples (31%) done (elapsed 70.07 s, remaining 155.96 s) | |
| 3200000 of 10000000 tuples (32%) done (elapsed 70.63 s, remaining 150.09 s) | |
| 3300000 of 10000000 tuples (33%) done (elapsed 70.93 s, remaining 144.00 s) | |
| 3400000 of 10000000 tuples (34%) done (elapsed 71.47 s, remaining 138.74 s) | |
| 3500000 of 10000000 tuples (35%) done (elapsed 72.62 s, remaining 134.87 s) | |
| 3600000 of 10000000 tuples (36%) done (elapsed 72.97 s, remaining 129.72 s) | |
| 3700000 of 10000000 tuples (37%) done (elapsed 73.51 s, remaining 125.17 s) | |
| 3800000 of 10000000 tuples (38%) done (elapsed 74.18 s, remaining 121.03 s) | |
| 3900000 of 10000000 tuples (39%) done (elapsed 74.34 s, remaining 116.28 s) | |
| 4000000 of 10000000 tuples (40%) done (elapsed 75.16 s, remaining 112.75 s) | |
| 4100000 of 10000000 tuples (41%) done (elapsed 76.20 s, remaining 109.65 s) | |
| 4200000 of 10000000 tuples (42%) done (elapsed 76.44 s, remaining 105.56 s) | |
| 4300000 of 10000000 tuples (43%) done (elapsed 76.96 s, remaining 102.01 s) | |
| ^A24400000 of 10000000 tuples (44%) done (elapsed 77.91 s, remaining 99.15 s) | |
| 4500000 of 10000000 tuples (45%) done (elapsed 78.02 s, remaining 95.36 s) | |
| 4600000 of 10000000 tuples (46%) done (elapsed 78.67 s, remaining 92.35 s) | |
| 4700000 of 10000000 tuples (47%) done (elapsed 79.63 s, remaining 89.80 s) | |
| 4800000 of 10000000 tuples (48%) done (elapsed 79.73 s, remaining 86.38 s) | |
| 4900000 of 10000000 tuples (49%) done (elapsed 80.46 s, remaining 83.74 s) | |
| 5000000 of 10000000 tuples (50%) done (elapsed 80.56 s, remaining 80.56 s) | |
| 5100000 of 10000000 tuples (51%) done (elapsed 81.57 s, remaining 78.37 s) | |
| 5200000 of 10000000 tuples (52%) done (elapsed 83.67 s, remaining 77.24 s) | |
| 5300000 of 10000000 tuples (53%) done (elapsed 83.78 s, remaining 74.29 s) | |
| 5400000 of 10000000 tuples (54%) done (elapsed 84.39 s, remaining 71.88 s) | |
| 5500000 of 10000000 tuples (55%) done (elapsed 84.73 s, remaining 69.33 s) | |
| 5600000 of 10000000 tuples (56%) done (elapsed 86.10 s, remaining 67.65 s) | |
| 5700000 of 10000000 tuples (57%) done (elapsed 86.94 s, remaining 65.58 s) | |
| 5800000 of 10000000 tuples (58%) done (elapsed 87.09 s, remaining 63.06 s) | |
| 5900000 of 10000000 tuples (59%) done (elapsed 87.97 s, remaining 61.13 s) | |
| 6000000 of 10000000 tuples (60%) done (elapsed 89.29 s, remaining 59.53 s) | |
| 6100000 of 10000000 tuples (61%) done (elapsed 89.56 s, remaining 57.26 s) | |
| 6200000 of 10000000 tuples (62%) done (elapsed 90.16 s, remaining 55.26 s) | |
| 6300000 of 10000000 tuples (63%) done (elapsed 90.76 s, remaining 53.31 s) | |
| 6400000 of 10000000 tuples (64%) done (elapsed 91.36 s, remaining 51.39 s) | |
| 6500000 of 10000000 tuples (65%) done (elapsed 92.18 s, remaining 49.64 s) | |
| 6600000 of 10000000 tuples (66%) done (elapsed 93.04 s, remaining 47.93 s) | |
| 6700000 of 10000000 tuples (67%) done (elapsed 93.56 s, remaining 46.08 s) | |
| 6800000 of 10000000 tuples (68%) done (elapsed 95.08 s, remaining 44.74 s) | |
| 6900000 of 10000000 tuples (69%) done (elapsed 95.85 s, remaining 43.06 s) | |
| 7000000 of 10000000 tuples (70%) done (elapsed 95.96 s, remaining 41.13 s) | |
| 7100000 of 10000000 tuples (71%) done (elapsed 96.99 s, remaining 39.61 s) | |
| 7200000 of 10000000 tuples (72%) done (elapsed 97.77 s, remaining 38.02 s) | |
| 7300000 of 10000000 tuples (73%) done (elapsed 97.88 s, remaining 36.20 s) | |
| 7400000 of 10000000 tuples (74%) done (elapsed 99.68 s, remaining 35.02 s) | |
| 7500000 of 10000000 tuples (75%) done (elapsed 100.16 s, remaining 33.39 s) | |
| 7600000 of 10000000 tuples (76%) done (elapsed 100.54 s, remaining 31.75 s) | |
| 7700000 of 10000000 tuples (77%) done (elapsed 102.27 s, remaining 30.55 s) | |
| 7800000 of 10000000 tuples (78%) done (elapsed 102.53 s, remaining 28.92 s) | |
| 2017-09-30 15:49:07.301 CEST [5942] LOG: checkpoints are occurring too frequently (28 seconds apart) | |
| 2017-09-30 15:49:07.301 CEST [5942] HINT: Consider increasing the configuration parameter "max_wal_size". | |
| 7900000 of 10000000 tuples (79%) done (elapsed 103.26 s, remaining 27.45 s) | |
| 8000000 of 10000000 tuples (80%) done (elapsed 104.43 s, remaining 26.11 s) | |
| 8100000 of 10000000 tuples (81%) done (elapsed 104.67 s, remaining 24.55 s) | |
| 8200000 of 10000000 tuples (82%) done (elapsed 105.25 s, remaining 23.10 s) | |
| 8300000 of 10000000 tuples (83%) done (elapsed 105.61 s, remaining 21.63 s) | |
| 8400000 of 10000000 tuples (84%) done (elapsed 105.72 s, remaining 20.14 s) | |
| 8500000 of 10000000 tuples (85%) done (elapsed 106.47 s, remaining 18.79 s) | |
| 8600000 of 10000000 tuples (86%) done (elapsed 106.57 s, remaining 17.35 s) | |
| 8700000 of 10000000 tuples (87%) done (elapsed 107.12 s, remaining 16.01 s) | |
| 8800000 of 10000000 tuples (88%) done (elapsed 107.69 s, remaining 14.69 s) | |
| 8900000 of 10000000 tuples (89%) done (elapsed 107.80 s, remaining 13.32 s) | |
| 9000000 of 10000000 tuples (90%) done (elapsed 108.31 s, remaining 12.03 s) | |
| 9100000 of 10000000 tuples (91%) done (elapsed 108.64 s, remaining 10.74 s) | |
| 9200000 of 10000000 tuples (92%) done (elapsed 109.31 s, remaining 9.51 s) | |
| 9300000 of 10000000 tuples (93%) done (elapsed 110.34 s, remaining 8.31 s) | |
| 9400000 of 10000000 tuples (94%) done (elapsed 110.58 s, remaining 7.06 s) | |
| 9500000 of 10000000 tuples (95%) done (elapsed 110.90 s, remaining 5.84 s) | |
| 9600000 of 10000000 tuples (96%) done (elapsed 111.29 s, remaining 4.64 s) | |
| 9700000 of 10000000 tuples (97%) done (elapsed 111.65 s, remaining 3.45 s) | |
| 9800000 of 10000000 tuples (98%) done (elapsed 112.28 s, remaining 2.29 s) | |
| 9900000 of 10000000 tuples (99%) done (elapsed 113.21 s, remaining 1.14 s) | |
| 10000000 of 10000000 tuples (100%) done (elapsed 113.49 s, remaining 0.00 s) | |
| vacuum... | |
| 2017-09-30 15:49:24.262 CEST [10866] LOG: standby "walreceiver" joining synchronous replay set... | |
| 2017-09-30 15:49:24.833 CEST [10866] LOG: standby "walreceiver" is no longer available for synchronous replay | |
| 2017-09-30 15:49:34.218 CEST [10866] LOG: terminating walsender process due to replication timeout | |
| 2017-09-30 15:49:37.215 CEST [11147] LOG: standby "walreceiver" joining synchronous replay set... | |
| 2017-09-30 15:49:40.936 CEST [11147] LOG: standby "walreceiver" is no longer available for synchronous replay | |
| 2017-09-30 15:49:54.416 CEST [11147] LOG: terminating walsender process due to replication timeout | |
| 2017-09-30 15:50:09.077 CEST [11461] LOG: standby "walreceiver" joining synchronous replay set... | |
| 2017-09-30 15:50:10.439 CEST [11461] LOG: standby "walreceiver" is no longer available for synchronous replay | |
| 2017-09-30 15:50:27.121 CEST [11461] LOG: terminating walsender process due to replication timeout | |
| 2017-09-30 15:50:28.585 CEST [11498] LOG: standby "walreceiver" joining synchronous replay set... | |
| # replica 1 (replay) | |
| 2017-09-30 15:47:51.802 CEST [6034] FATAL: could not receive data from WAL stream: server closed the connection unexpectedly | |
| This probably means the server terminated abnormally | |
| before or while processing the request. | |
| 2017-09-30 15:47:55.154 CEST [6030] LOG: invalid magic number 0000 in log segment 000000010000000000000020, offset 10092544 | |
| 2017-09-30 15:47:55.257 CEST [10508] LOG: started streaming WAL from primary at 0/20000000 on timeline 1 | |
| 2017-09-30 15:48:09.622 CEST [10508] FATAL: could not receive data from WAL stream: server closed the connection unexpectedly | |
| This probably means the server terminated abnormally | |
| before or while processing the request. | |
| 2017-09-30 15:48:09.951 CEST [6030] LOG: invalid magic number 0000 in log segment 000000010000000000000022, offset 10092544 | |
| 2017-09-30 15:48:09.954 CEST [10575] LOG: started streaming WAL from primary at 0/22000000 on timeline 1 | |
| 2017-09-30 15:48:18.765 CEST [10575] FATAL: could not receive data from WAL stream: server closed the connection unexpectedly | |
| This probably means the server terminated abnormally | |
| before or while processing the request. | |
| 2017-09-30 15:48:18.909 CEST [6030] LOG: invalid magic number 0000 in log segment 000000010000000000000023, offset 10223616 | |
| 2017-09-30 15:49:23.818 CEST [10619] LOG: started streaming WAL from primary at 0/23000000 on timeline 1 | |
| 2017-09-30 15:49:34.227 CEST [10619] FATAL: could not send data to WAL stream: server closed the connection unexpectedly | |
| This probably means the server terminated abnormally | |
| before or while processing the request. | |
| 2017-09-30 15:49:37.196 CEST [6030] LOG: invalid magic number 0000 in log segment 000000010000000000000026, offset 10223616 | |
| 2017-09-30 15:49:37.212 CEST [11143] LOG: started streaming WAL from primary at 0/26000000 on timeline 1 | |
| 2017-09-30 15:49:54.886 CEST [11143] FATAL: could not receive data from WAL stream: server closed the connection unexpectedly | |
| This probably means the server terminated abnormally | |
| before or while processing the request. | |
| 2017-09-30 15:49:56.266 CEST [6030] LOG: invalid magic number 0000 in log segment 000000010000000000000029, offset 10223616 | |
| 2017-09-30 15:50:28.457 CEST [11393] LOG: started streaming WAL from primary at 0/29000000 on timeline 1 | |
| 2017-09-30 15:50:50.376 CEST [11393] FATAL: could not receive data from WAL stream: server closed the connection unexpectedly | |
| This probably means the server terminated abnormally | |
| before or while processing the request. | |
| 2017-09-30 15:50:50.445 CEST [6030] LOG: invalid magic number 0000 in log segment 00000001000000000000002C, offset 6029312 | |
| # replica 2 (no replay) | |
| 2017-09-30 15:47:55.132 CEST [6196] FATAL: could not send data to WAL stream: server closed the connection unexpectedly | |
| This probably means the server terminated abnormally | |
| before or while processing the request. | |
| 2017-09-30 15:47:55.437 CEST [6192] LOG: invalid magic number 0000 in log segment 000000010000000000000020, offset 9437184 | |
| 2017-09-30 15:50:08.616 CEST [10511] FATAL: could not connect to the primary server: could not connect to server: Connection timed out | |
| Is the server running on host "localhost" (127.0.0.1) and accepting | |
| TCP/IP connections on port 5432? | |
| 2017-09-30 15:50:09.076 CEST [11460] LOG: started streaming WAL from primary at 0/20000000 on timeline 1 | |
| 2017-09-30 15:50:28.555 CEST [11460] FATAL: could not receive data from WAL stream: server closed the connection unexpectedly | |
| This probably means the server terminated abnormally | |
| before or while processing the request. | |
| 2017-09-30 15:50:31.848 CEST [6192] LOG: invalid magic number 0000 in log segment 000000010000000000000023, offset 10223616 | |
| 2017-09-30 15:51:36.939 CEST [11506] LOG: started streaming WAL from primary at 0/23000000 on timeline 1 | |
| 2017-09-30 15:51:45.729 CEST [11506] FATAL: could not send data to WAL stream: server closed the connection unexpectedly | |
| This probably means the server terminated abnormally | |
| before or while processing the request. | |
| 2017-09-30 15:51:46.173 CEST [6192] LOG: invalid magic number 0000 in log segment 000000010000000000000024, offset 10354688 | |
| 2017-09-30 15:51:46.180 CEST [11890] LOG: started streaming WAL from primary at 0/24000000 on timeline 1 |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment