Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.09.2021 22:40
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15899200 kB
MemAvailable 30776804 kB
Buffers 0 kB
Cached 15819556 kB
SwapCached 2944 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-09-30 22:37:49
DTS_END                         2021-09-30 22:37:49.5310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52540
TRN_ID                          244860
ATT_ID                          726
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9171.21 25955 1100545
customer order: creation 175.28 326 21033
customer order: refuse 14.74 373 1769
order to supplier: creation 46.58 1227 5589
order to supplier: removal 3.41 102472 409
invoice (draft): creation 47.13 3521 5656
invoice (draft): removal 50.03 812 6004
invoice accept: apply 43.41 18896 5209
invoice accept: cancel 45.84 840 5501
customer reserve: creation 3925.39 190 471047
customer reserve: removal 4517.06 60 542047
realization accept: apply 86.54 206 10385
realization accept: cancel 81.14 108 9737
payment from customer: creation 61.13 123 7336
payment from customer: removal 6.36 91 763
payment to supplier: creation 49.92 143 5990
payment to supplier: removal 3.56 65 427
service: total inventory turnovers 5.25 1105 630
service: total monetary turnovers 7.52 31 902
service: aggregate perf. data 0.89 20761 107
service: refresh index statistics 0.03 367752 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-09-2021 22:40:40 to 30-09-2021 22:40:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4333
TEST_TIME 2 3715
TEST_TIME 3 3803
TEST_TIME 4 5759
TEST_TIME 5 5813
TEST_TIME 6 6121
TEST_TIME 7 6231
TEST_TIME 8 6352
TEST_TIME 9 6086
TEST_TIME 10 6550
TEST_TIME 11 6261
TEST_TIME 12 6390
TEST_TIME 13 6282
TEST_TIME 14 6404
TEST_TIME 15 6619
TEST_TIME 16 6555
TEST_TIME 17 6526
TEST_TIME 18 6540
TEST_TIME 19 6529
TEST_TIME 20 6777
TEST_TIME 21 6825
TEST_TIME 22 6969
TEST_TIME 23 6872
TEST_TIME 24 7650
TEST_TIME 25 8216
TEST_TIME 26 8178
TEST_TIME 27 8516
TEST_TIME 28 8819
TEST_TIME 29 9046
TEST_TIME 30 9008
TEST_TIME 31 9526
TEST_TIME 32 10185
TEST_TIME 33 9989
TEST_TIME 34 9888
TEST_TIME 35 9919
TEST_TIME 36 9813
TEST_TIME 37 10039
TEST_TIME 38 9857
TEST_TIME 39 9788
TEST_TIME 40 9813
TEST_TIME 41 9693
TEST_TIME 42 9698
TEST_TIME 43 9624
TEST_TIME 44 9606
TEST_TIME 45 9605
TEST_TIME 46 9582
TEST_TIME 47 9681
TEST_TIME 48 9788
TEST_TIME 49 9762
TEST_TIME 50 9804
TEST_TIME 51 9715
TEST_TIME 52 9571
TEST_TIME 53 9440
TEST_TIME 54 9491
TEST_TIME 55 9428
TEST_TIME 56 9408
TEST_TIME 57 9311
TEST_TIME 58 9341
TEST_TIME 59 9464
TEST_TIME 60 9434
TEST_TIME 61 9373
TEST_TIME 62 9325
TEST_TIME 63 9303
TEST_TIME 64 9262
TEST_TIME 65 9332
TEST_TIME 66 9360
TEST_TIME 67 9349
TEST_TIME 68 9315
TEST_TIME 69 9413
TEST_TIME 70 9376
TEST_TIME 71 9373
TEST_TIME 72 9348
TEST_TIME 73 9416
TEST_TIME 74 9430
TEST_TIME 75 9426
TEST_TIME 76 9441
TEST_TIME 77 9420
TEST_TIME 78 9427
TEST_TIME 79 9501
TEST_TIME 80 9509
TEST_TIME 81 9419
TEST_TIME 82 9368
TEST_TIME 83 9353
TEST_TIME 84 9365
TEST_TIME 85 9339
TEST_TIME 86 9298
TEST_TIME 87 9291
TEST_TIME 88 9330
TEST_TIME 89 9280
TEST_TIME 90 9227
TEST_TIME 91 9167
TEST_TIME 92 9164
TEST_TIME 93 9148
TEST_TIME 94 9140
TEST_TIME 95 9152
TEST_TIME 96 9125
TEST_TIME 97 9223
TEST_TIME 98 9253
TEST_TIME 99 9245
TEST_TIME 100 9274
TEST_TIME 101 9287
TEST_TIME 102 9320
TEST_TIME 103 9351
TEST_TIME 104 9343
TEST_TIME 105 9326
TEST_TIME 106 9374
TEST_TIME 107 9377
TEST_TIME 108 9326
TEST_TIME 109 9293
TEST_TIME 110 9303
TEST_TIME 111 9272
TEST_TIME 112 9295
TEST_TIME 113 9270
TEST_TIME 114 9230
TEST_TIME 115 9256
TEST_TIME 116 9240
TEST_TIME 117 9228
TEST_TIME 118 9204
TEST_TIME 119 9204
TEST_TIME 120 9175

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-09-2021 22:40:44 to 30-09-2021 22:40:48.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1537262 1537262 0 0.00 0 19731 7 0 0
doc_list_biud 1537241 1537241 0 0.00 0 20309 27 0 0
sp_add_invoice_to_stock 5318 5209 109 2.04 27 173571 18896 0 109
sp_cancel_adding_invoice 5501 5501 0 0.00 4 79034 841 0 0
sp_cancel_client_order 1769 1769 0 0.00 4 9404 373 0 0
sp_cancel_customer_reserve 542047 542047 0 0.00 1 18053 61 0 0
sp_cancel_pay_from_customer 763 763 0 0.00 3 7936 92 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 5362 66 0 0
sp_cancel_supplier_invoice 6012 6004 8 0.13 1 20318 812 0 8
sp_cancel_supplier_order 409 409 0 0.00 8 1027357 102472 0 0
sp_cancel_write_off 9737 9737 0 0.00 1 14880 108 0 0
sp_client_order 21033 21033 0 0.00 4 11175 327 0 0
sp_customer_reserve 471047 471047 0 0.00 0 67153 191 0 0
sp_fill_shopping_cart 32277 32277 0 0.00 1 15895 42 0 0
sp_fill_shopping_cart_clo_res 471039 471039 0 0.00 0 24154 13 0 0
sp_get_clo_for_invoice 5201 5201 0 0.00 0 23345 268 0 0
sp_kill_cost_storno 16428 16428 0 0.00 0 10797 61 0 0
sp_kill_qstorno_ret_qs2qd 1100244 1100244 0 0.00 0 20305 33 0 0
sp_kill_qty_storno 581042 581042 0 0.00 0 20307 71 0 0
sp_lock_selected_doc 21511 21511 0 0.00 0 8495 5 0 0
sp_make_cost_storno 28912 28912 0 0.00 0 19064 72 0 0
sp_multiply_rows_for_pdistr 28893 28893 0 0.00 0 5981 28 0 0
sp_multiply_rows_for_qdistr 21033 21033 0 0.00 0 10657 120 0 0
sp_pay_from_customer 7336 7336 0 0.00 4 17116 124 0 0
sp_pay_to_supplier 5990 5990 0 0.00 5 15997 144 0 0
sp_payment_common 13318 13318 0 0.00 1 8362 30 0 0
sp_qd_handle_on_cancel_clo 1769 1769 0 0.00 0 9383 339 0 0
sp_qd_handle_on_invoice_upd_sts 10697 10697 0 0.00 0 19107 354 0 0
sp_qd_handle_on_reserve_upd_sts 10379 10379 0 0.00 0 20118 64 0 0
sp_reserve_write_off 10385 10385 0 0.00 6 20160 206 0 0
sp_supplier_invoice 5656 5656 0 0.00 8 84131 3522 0 0
sp_supplier_order 5589 5589 0 0.00 6 31824 1227 0 0
srv_aggregate_perf_data 108 107 1 0.92 2434 227697 20761 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 2212 170578 45918 0 0
srv_find_qd_qs_mism 1051890 1051890 0 0.00 0 16587 6 0 0
srv_make_invnt_saldo 630 630 0 0.00 45 85429 1106 0 0
srv_make_money_saldo 902 902 0 0.00 0 3152 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 83020 501163 367753 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5210 5210 0 0.00 0 11675 177 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 11121 174 0 0
t$perf-norm:sp_cancel_client_order 1769 1769 0 0.00 0 5890 19 0 0
t$perf-norm:sp_cancel_customer_reserve 723 723 0 0.00 0 1395 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 763 763 0 0.00 0 4439 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 825 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 1782 25 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 36624 1833 0 0
t$perf-norm:sp_cancel_write_off 1057 1057 0 0.00 0 2114 10 0 0
t$perf-norm:sp_client_order 21033 21033 0 0.00 0 10560 21 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 2381 8 0 0
t$perf-norm:sp_pay_from_customer 7336 7336 0 0.00 0 7461 13 0 0
t$perf-norm:sp_pay_to_supplier 5990 5990 0 0.00 0 5995 12 0 0
t$perf-norm:sp_reserve_write_off 10385 10385 0 0.00 0 7811 11 0 0
t$perf-norm:sp_supplier_invoice 5656 5656 0 0.00 0 6300 20 0 0
t$perf-norm:sp_supplier_order 5589 5589 0 0.00 0 6085 19 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 75 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 321 6 0 0
t$perf-norm:srv_make_invnt_saldo 630 630 0 0.00 0 1262 24 0 0
t$perf-norm:srv_make_money_saldo 902 902 0 0.00 0 969 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 10610 10392 218 2.05 0 3539 19 0 218
v_all_customers 38070 38070 0 0.00 0 166 1 0 0
v_all_suppliers 30834 30834 0 0.00 0 26 1 0 0
v_all_wares 42066 42066 0 0.00 0 94 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 463 6 0 0
v_cancel_client_order 3538 3538 0 0.00 0 2809 6 0 0
v_cancel_customer_prepayment 1526 1526 0 0.00 0 7919 16 0 0
v_cancel_customer_reserve 1446 1446 0 0.00 0 1387 4 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 5315 11 0 0
v_cancel_supplier_invoice 764 748 16 2.09 0 2966 20 0 16
v_cancel_supplier_order 818 818 0 0.00 0 8472 13 0 0
v_cancel_write_off 2114 2114 0 0.00 0 2819 17 0 0
v_max_id_clo_ord 5589 5589 0 0.00 0 805 2 0 0
v_max_id_clo_res 956 956 0 0.00 0 3416 16 0 0
v_max_id_ord_sup 5655 5655 0 0.00 0 6969 5 0 0
v_max_non_paid_invoice 1813 1813 0 0.00 0 502 3 0 0
v_max_non_paid_realizn 4108 4108 0 0.00 0 2264 5 0 0
v_min_id_clo_ord 5589 5589 0 0.00 0 1570 5 0 0
v_min_id_clo_res 956 956 0 0.00 0 2138 14 0 0
v_min_id_ord_sup 5655 5655 0 0.00 0 6792 11 0 0
v_min_non_paid_invoice 5986 5986 0 0.00 0 5317 20 0 0
v_min_non_paid_realizn 7332 7332 0 0.00 0 7121 17 0 0
v_our_firm 10444 10444 0 0.00 0 23 1 0 0
v_reserve_write_off 20758 20758 0 0.00 0 5853 7 0 0
x_lock_depdocs_on_canc_invoice 11131 11131 0 0.00 0 19319 138 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 13728 436 0 0
x_make_qstorno_1000_1200 5589 5589 0 0.00 2 31805 1140 0 0
x_make_qstorno_1200_2000 5656 5656 0 0.00 2 76724 3374 0 0
x_make_qstorno_2100_3300 471047 471047 0 0.00 0 67152 175 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 30-09-2021 22:40:48 to 30-09-2021 22:40:53.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:53 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:54 29548 8192000000 10387566368 10707431424 1422354272 14.794 100 86 172 0 1457045984 6195168 28496544
20:10:25 23203 8192000000 10445455504 10778124288 1496141552 15.443 100 86 174 1 1531880688 8279904 27459232
20:11:56 15436 8192000000 10500300384 10846093312 1556993248 15.970 100 80 160 0 1592700928 9998832 25708848
20:13:18 14767 8192000000 10573694704 10932006912 1631381424 16.607 100 80 160 0 1667236336 11494944 24359968
20:14:37 12376 8192000000 10628447632 11003609088 1684204304 17.053 100 65 130 0 1715317424 12299280 18813840
20:15:50 4837 8192000000 10698685744 11073925120 1754355776 17.638 100 75 149 0 1788306992 12917008 21034208
20:16:57 5946 8192000000 10724660592 11107586048 1791386144 17.943 100 67 135 1 1821347328 11254864 18706320
20:18:05 2291 8192000000 10787138112 11172446208 1850279264 18.424 100 91 182 2 1888303168 13118960 24904944
20:19:08 1735 8192000000 10798241648 11194884096 1866365600 18.555 100 86 172 3 1903389840 12990720 24033520
20:20:31 8052 8192000000 10802130992 11218137088 1880274080 18.667 100 52 104 0 1905412048 10690864 14447104
20:21:40 9346 8192000000 10800381456 11227590656 1890241264 18.748 100 26 51 6 1904332848 5849392 8242192
20:22:50 74011 8192000000 10816371376 11248697344 1900023936 18.826 100 24 46 1 1911764848 5257248 6483664
20:25:13 5125 8192000000 10836149104 11261317120 1915277056 18.949 100 33 66 1 1930572368 5986480 9308832
20:26:20 8468 8192000000 10837120448 11267710976 1918914112 18.978 100 22 44 1 1930583216 5469040 6200064
20:27:29 8438 8192000000 10797130784 11227394048 1817492544 18.157 95 27 53 2 1830908992 5782672 7633776
20:28:40 26630 8192000000 10793165408 11231989760 1843078256 18.366 97 35 69 2 1863236912 9658528 10500128
20:30:11 6906 8192000000 10739188000 11158786048 1786992944 17.907 97 33 66 1 1802643936 6403728 9247264
20:31:20 11958 8192000000 10701607856 11113541632 1728220464 17.421 95 15 30 1 1741903968 9263744 4419760
20:32:34 20761 8192000000 10744506848 11153477632 1777489232 17.829 97 34 68 1 1796396288 9544256 9362800
20:34:00 13777 8192000000 10710171424 11142971392 1795535840 17.977 98 14 28 0 1804356080 4904240 3916000
20:35:15 6164 8192000000 10764583312 11189555200 1851437520 18.434 100 33 66 1 1867318576 5463520 10417536
20:36:23 14896 8192000000 10774983824 11211108352 1867524688 18.564 100 24 49 1 1881048992 5819360 7704944
20:37:38 36313 8192000000 10837263104 11249029120 1905220224 18.868 100 34 66 1 1920435824 4988576 10227024
20:39:17 40783 8192000000 10832010896 11269689344 1917217056 18.965 100 23 46 0 1931016336 7348736 6450544
20:41:40 20178 8192000000 10880195680 11310190592 1949405072 19.222 100 40 80 3 1969067424 8042720 11619632
20:43:06 6436 8192000000 10890430192 11321749504 1960136784 19.307 100 47 93 1 1985998272 12296512 13564976
20:44:16 12095 8192000000 10931560816 11354734592 1982029248 19.481 100 67 135 2 2015601712 15803472 17768992
20:45:31 6953 8192000000 10936838560 11361533952 1986157040 19.513 100 53 104 0 2019930336 18954400 14818896
20:46:42 18478 8192000000 10934266560 11366240256 1990775904 19.550 100 55 110 0 2024903600 18432688 15695008
20:48:04 16857 8192000000 10923010800 11353858048 1973960688 19.417 99 60 120 2 2009267104 18029968 17276448
20:49:43 15312 8192000000 10910763120 11366494208 1979199760 19.458 100 23 46 0 2012140496 25046096 7894640
20:51:14 6268 8192000000 10928330816 11356766208 1962025056 19.322 99 77 155 1 2009852784 24551136 23276592
20:52:26 5653 8192000000 10921836480 11345989632 1965117280 19.347 100 69 138 1 2014989712 29832912 20039520
20:53:39 4236 8192000000 10935888448 11357728768 1971807616 19.400 100 60 120 1 2020906400 30725152 18373632
20:54:55 3168 8192000000 10938082432 11352801280 1946609792 19.199 99 72 142 3 2004914160 36621376 21682992
20:56:02 2209 8192000000 10922181296 11330228224 1928885872 19.058 99 80 158 0 1991059056 37218768 24954416
20:57:09 7987 8192000000 10924129744 11344068608 1949763680 19.225 100 62 120 2 2014663088 44965696 19933712
20:58:27 2241 8192000000 10956051200 11357450240 1961671776 19.319 100 86 173 4 2036851504 47946864 27232864
20:59:35 2696 8192000000 10969108832 11375820800 1964644400 19.343 100 86 172 2 2045696768 53274208 27778160
21:00:44 4611 8192000000 10985389264 11403771904 1980956480 19.472 100 79 158 2 2069853440 62486512 26410448
21:01:55 3945 8192000000 10995069312 11415351296 1997549552 19.603 100 73 146 2 2073244336 52487648 23207136
21:03:02 11891 8192000000 10928605328 11367895040 1947806128 19.209 99 37 74 0 2000740848 40238960 12695760
21:04:37 19274 8192000000 10953288880 11401416704 1979942032 19.464 100 32 64 1 2037132512 46927408 10263072
21:06:09 19377 8192000000 10927419904 11382140928 1946236096 19.196 99 22 44 1 1994561408 40843552 7481760
21:07:31 36229 8192000000 10918345872 11372630016 1937341168 19.126 99 15 30 0 1980319584 37277232 5701184
21:09:09 32499 8192000000 10899531040 11371933696 1948413968 19.214 100 15 30 0 1984810336 31433216 4963152
21:10:42 11808 8192000000 10925564320 11365302272 1980115840 19.466 100 59 116 4 2013196016 15630224 17449952
21:11:56 50591 8192000000 10906110896 11362492416 1951533616 19.239 99 32 64 1 1975786064 15265344 8987104
21:13:52 54414 8192000000 10903338608 11385139200 1972600560 19.406 100 8 16 1 1997139280 22163056 2375664
21:15:49 55839 8192000000 10931666736 11373842432 1983852720 19.495 100 46 88 0 2005610624 8746448 13011456
21:17:48 61935 8192000000 10909538336 11362553856 1938508464 19.135 98 16 32 0 1951816448 9002160 4305824
21:19:56 57160 8192000000 10914849872 11371286528 1958937840 19.298 99 25 50 0 1977136960 11148848 7050272
21:22:00 136760 8192000000 10934865456 11416756224 1990944368 19.551 100 10 20 0 2008411360 14798080 2668912
21:25:27 170577 8192000000 10921858208 11391557632 1956319136 19.277 98 10 20 1 1976941984 18330656 2292192
21:29:24 102712 8192000000 10938009760 11401838592 2000574272 19.627 100 34 68 1 2022975616 11889248 10512096
21:32:08 25746 8192000000 10931538976 11382837248 1962070032 19.322 98 51 103 0 1995457360 19055776 14331552
21:33:36 50946 8192000000 10933798272 11398758400 1968745904 19.375 98 26 52 2 1990634224 13323552 8564768
21:35:39 55772 8192000000 10904665760 11366604800 1922867840 19.010 97 22 41 0 1946040064 17425808 5746416
21:37:39 108299 8192000000 10890265712 11363188736 1938273920 19.133 97 14 28 0 1953046160 10683632 4088608
21:40:28 77790 8192000000 10885237024 11366944768 1934186192 19.100 98 23 46 1 1955656832 14935712 6534928
21:43:03 64390 8192000000 10868463856 11328106496 1869852928 18.583 95 9 17 1 1880194432 8390608 1950896
21:45:11 54553 8192000000 10896761504 11359879168 1927457968 19.047 98 26 52 3 1956284976 21477648 7349360
21:47:08 45920 8192000000 10883521056 11347890176 1895154736 18.787 96 17 34 0 1908024608 8142640 4727232
21:49:00 29984 8192000000 10880194048 11340795904 1940268576 19.149 100 22 43 1 1959794176 13526752 5998848
21:50:31 29652 8192000000 10878078256 11349225472 1935373088 19.110 99 30 60 0 1958429280 14632128 8424064
21:52:09 27329 8192000000 10878034448 11338842112 1930231776 19.069 99 18 36 1 1942820480 7929248 4659456
21:53:39 45497 8192000000 10887373472 11344322560 1903946992 18.858 97 36 71 1 1924794304 10617504 10229808
21:55:27 30663 8192000000 10870427680 11326492672 1901656016 18.840 98 36 72 0 1927372336 15734928 9981392
21:57:09 90242 8192000000 10845181648 11304325120 1861560928 18.516 96 13 26 0 1876127616 10451856 4114832
21:59:46 39711 8192000000 10884678448 11345604608 1950554768 19.231 100 32 64 2 1968585856 9031328 8999760
22:01:26 71492 8192000000 10882574432 11352293376 1958807152 19.297 100 20 40 0 1973252832 8988432 5457248
22:03:38 72098 8192000000 10876456912 11350093824 1910476672 18.910 97 16 32 1 1924732192 9611920 4643600
22:05:51 73264 8192000000 10858529440 11343396864 1925400912 19.030 99 12 24 0 1940336512 11212336 3723264
22:08:12 93532 8192000000 10867632512 11347095552 1887454336 18.725 96 11 20 1 1897439008 7641456 2343216
22:10:46 59633 8192000000 10883451040 11362082816 1955531984 19.271 99 13 26 0 1967467920 7999792 3936144
22:12:46 55266 8192000000 10924727056 11390259200 1961707904 19.320 98 25 50 3 1981763584 12797984 7257696
22:14:42 79576 8192000000 10901961680 11359289344 1948886416 19.218 98 16 33 1 1964299904 11138272 4275216
22:17:03 22936 8192000000 10922393024 11385335808 1955511248 19.270 98 53 104 0 1983250208 13074032 14664928
22:18:28 94241 8192000000 10920210112 11400138752 1930764976 19.073 97 17 34 0 1949804800 14124512 4915312
22:21:05 91507 8192000000 10934366800 11383529472 1885434592 18.709 94 29 58 0 1903849104 9743216 8671296
22:23:37 96465 8192000000 10894605344 11355402240 1882950640 18.689 95 8 16 0 1894913072 9507520 2454912
22:26:16 95221 8192000000 10904017168 11371155456 1949284048 19.221 99 22 44 0 1962979712 7881536 5814128
22:29:01 16895 8192000000 10892591440 11367972864 1959062496 19.299 100 24 47 1 1980415520 14906256 6446768
22:30:51 63073 8192000000 10881034000 11349147648 1872264992 18.603 95 16 32 0 1887997616 10718976 5013648
22:32:55 50334 8192000000 10866435728 11346870272 1911079264 18.915 98 8 16 0 1923732272 10480080 2172928
22:34:50 78396 8192000000 10886504704 11366764544 1945347504 19.189 99 17 34 2 1961934496 11041936 5545056
22:37:12 56696 8192000000 9989189936 10379206656 1105605616 11.891 57 8 16 0 1119506496 11812784 2088096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-09-2021 22:40:54 to 30-09-2021 22:41:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 109 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-09-2021 22:41:10 to 30-09-2021 22:41:12.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          245053
MON$OLDEST_ACTIVE               245054
MON$OLDEST_SNAPSHOT             245054
MON$NEXT_TRANSACTION            245054
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2047200
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33505 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33505 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33505 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33505 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:24: 
:25: mscs-test-161.budzdorov.ru    Thu Sep 30 22:38:26 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Thu Sep 30 22:38:44 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

30.09.21 22:41:13. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-09-30 22:41:15 No severe PSQL-related problems occured
30.09.21 22:41:17. End of report.