Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.09.2021 13:38
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 16047116 kB
MemAvailable 30842488 kB
Buffers 0 kB
Cached 15684880 kB
SwapCached 668 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-21 13:37:07
DTS_END                         2021-09-21 13:37:07.9350
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52882
TRN_ID                          236553
ATT_ID                          695
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: 8977.67 21625 1077321
customer order: creation 170.01 426 20401
customer order: refuse 14.12 308 1694
order to supplier: creation 44.99 1430 5399
order to supplier: removal 3.42 105163 410
invoice (draft): creation 45.37 3844 5444
invoice (draft): removal 49.56 934 5947
invoice accept: apply 41.61 18751 4993
invoice accept: cancel 44.58 839 5349
customer reserve: creation 3864.05 184 463686
customer reserve: removal 4408.47 65 529016
realization accept: apply 82.90 186 9948
realization accept: cancel 82.51 124 9901
payment from customer: creation 58.43 113 7012
payment from customer: removal 5.43 78 651
payment to supplier: creation 48.26 143 5791
payment to supplier: removal 3.57 115 428
service: total inventory turnovers 3.92 3441 470
service: total monetary turnovers 5.68 589 682
service: aggregate perf. data 0.79 23030 95
service: refresh index statistics 0.03 272734 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-09-2021 13:38:50 to 21-09-2021 13:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1712
TEST_TIME 2 2510
TEST_TIME 3 3786
TEST_TIME 4 4284
TEST_TIME 5 4313
TEST_TIME 6 4921
TEST_TIME 7 4930
TEST_TIME 8 5396
TEST_TIME 9 5616
TEST_TIME 10 5744
TEST_TIME 11 5758
TEST_TIME 12 6167
TEST_TIME 13 6267
TEST_TIME 14 6489
TEST_TIME 15 6745
TEST_TIME 16 7303
TEST_TIME 17 7569
TEST_TIME 18 8069
TEST_TIME 19 8679
TEST_TIME 20 8877
TEST_TIME 21 8929
TEST_TIME 22 9456
TEST_TIME 23 9605
TEST_TIME 24 9764
TEST_TIME 25 10062
TEST_TIME 26 10490
TEST_TIME 27 11434
TEST_TIME 28 11197
TEST_TIME 29 10853
TEST_TIME 30 10836
TEST_TIME 31 10560
TEST_TIME 32 10382
TEST_TIME 33 10243
TEST_TIME 34 10085
TEST_TIME 35 10062
TEST_TIME 36 10067
TEST_TIME 37 10400
TEST_TIME 38 10273
TEST_TIME 39 10134
TEST_TIME 40 10076
TEST_TIME 41 9938
TEST_TIME 42 9868
TEST_TIME 43 9774
TEST_TIME 44 9813
TEST_TIME 45 9663
TEST_TIME 46 9615
TEST_TIME 47 9578
TEST_TIME 48 9536
TEST_TIME 49 9563
TEST_TIME 50 9618
TEST_TIME 51 9711
TEST_TIME 52 9680
TEST_TIME 53 9710
TEST_TIME 54 9747
TEST_TIME 55 9710
TEST_TIME 56 9721
TEST_TIME 57 9752
TEST_TIME 58 9750
TEST_TIME 59 9624
TEST_TIME 60 9594
TEST_TIME 61 9506
TEST_TIME 62 9458
TEST_TIME 63 9385
TEST_TIME 64 9349
TEST_TIME 65 9335
TEST_TIME 66 9386
TEST_TIME 67 9435
TEST_TIME 68 9453
TEST_TIME 69 9400
TEST_TIME 70 9421
TEST_TIME 71 9565
TEST_TIME 72 9604
TEST_TIME 73 9646
TEST_TIME 74 9663
TEST_TIME 75 9685
TEST_TIME 76 9591
TEST_TIME 77 9515
TEST_TIME 78 9429
TEST_TIME 79 9473
TEST_TIME 80 9432
TEST_TIME 81 9352
TEST_TIME 82 9328
TEST_TIME 83 9318
TEST_TIME 84 9266
TEST_TIME 85 9300
TEST_TIME 86 9283
TEST_TIME 87 9247
TEST_TIME 88 9324
TEST_TIME 89 9277
TEST_TIME 90 9333
TEST_TIME 91 9300
TEST_TIME 92 9294
TEST_TIME 93 9292
TEST_TIME 94 9353
TEST_TIME 95 9385
TEST_TIME 96 9397
TEST_TIME 97 9349
TEST_TIME 98 9311
TEST_TIME 99 9269
TEST_TIME 100 9225
TEST_TIME 101 9201
TEST_TIME 102 9165
TEST_TIME 103 9174
TEST_TIME 104 9144
TEST_TIME 105 9177
TEST_TIME 106 9174
TEST_TIME 107 9157
TEST_TIME 108 9201
TEST_TIME 109 9196
TEST_TIME 110 9181
TEST_TIME 111 9160
TEST_TIME 112 9178
TEST_TIME 113 9238
TEST_TIME 114 9244
TEST_TIME 115 9181
TEST_TIME 116 9119
TEST_TIME 117 9117
TEST_TIME 118 9060
TEST_TIME 119 9052
TEST_TIME 120 8978

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-09-2021 13:38:54 to 21-09-2021 13:38:57.

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 1505417 1505417 0 0.00 0 22421 7 0 0
doc_list_biud 1505417 1505417 0 0.00 0 66546 29 0 0
sp_add_invoice_to_stock 5080 4993 87 1.71 41 190284 18751 0 87
sp_cancel_adding_invoice 5349 5349 0 0.00 4 74378 840 0 0
sp_cancel_client_order 1694 1694 0 0.00 4 10549 309 0 0
sp_cancel_customer_reserve 529016 529016 0 0.00 1 41151 66 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 3 8191 79 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 5596 116 0 0
sp_cancel_supplier_invoice 5951 5947 4 0.06 2 66551 935 0 4
sp_cancel_supplier_order 410 410 0 0.00 8 1306596 105163 0 0
sp_cancel_write_off 9901 9901 0 0.00 1 11145 125 0 0
sp_client_order 20401 20401 0 0.00 4 31971 427 0 0
sp_customer_reserve 463686 463686 0 0.00 0 54283 184 0 0
sp_fill_shopping_cart 31214 31214 0 0.00 1 18090 47 0 0
sp_fill_shopping_cart_clo_res 463686 463686 0 0.00 0 31027 16 0 0
sp_get_clo_for_invoice 4991 4991 0 0.00 0 36127 417 0 0
sp_kill_cost_storno 16329 16329 0 0.00 0 11107 59 0 0
sp_kill_qstorno_ret_qs2qd 1074290 1074290 0 0.00 0 66545 36 0 0
sp_kill_qty_storno 567256 567256 0 0.00 0 66546 77 0 0
sp_lock_selected_doc 20652 20652 0 0.00 0 8179 6 0 0
sp_make_cost_storno 27742 27742 0 0.00 0 14839 53 0 0
sp_multiply_rows_for_pdistr 27742 27742 0 0.00 0 10152 34 0 0
sp_multiply_rows_for_qdistr 20401 20401 0 0.00 0 17920 223 0 0
sp_pay_from_customer 7012 7012 0 0.00 4 11993 114 0 0
sp_pay_to_supplier 5791 5791 0 0.00 5 14091 143 0 0
sp_payment_common 12803 12803 0 0.00 1 13940 30 0 0
sp_qd_handle_on_cancel_clo 1694 1694 0 0.00 0 10492 279 0 0
sp_qd_handle_on_invoice_upd_sts 10340 10340 0 0.00 0 34245 341 0 0
sp_qd_handle_on_reserve_upd_sts 9948 9948 0 0.00 0 18971 62 0 0
sp_reserve_write_off 9948 9948 0 0.00 6 19031 186 0 0
sp_supplier_invoice 5444 5444 0 0.00 7 102230 3845 0 0
sp_supplier_order 5399 5399 0 0.00 5 50248 1430 0 0
srv_aggregate_perf_data 95 95 0 0.00 2709 622039 23031 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2415 122368 50025 0 0
srv_find_qd_qs_mism 1029408 1029408 0 0.00 0 22420 6 0 0
srv_make_invnt_saldo 470 470 0 0.00 65 172209 3441 0 0
srv_make_money_saldo 682 682 0 0.00 1 16447 590 0 0
srv_recalc_idx_stat 4 4 0 0.00 191163 449984 272735 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4993 4993 0 0.00 0 42311 201 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 0 7504 115 0 0
t$perf-norm:sp_cancel_client_order 1694 1694 0 0.00 0 5816 15 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 485 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 3128 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 699 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 509 7 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 24014 1349 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 4072 9 0 0
t$perf-norm:sp_client_order 20401 20401 0 0.00 0 20192 17 0 0
t$perf-norm:sp_customer_reserve 1003 1003 0 0.00 0 6517 19 0 0
t$perf-norm:sp_pay_from_customer 7012 7012 0 0.00 0 17852 11 0 0
t$perf-norm:sp_pay_to_supplier 5791 5791 0 0.00 0 8927 10 0 0
t$perf-norm:sp_reserve_write_off 9948 9948 0 0.00 0 10816 9 0 0
t$perf-norm:sp_supplier_invoice 5444 5444 0 0.00 0 7852 17 0 0
t$perf-norm:sp_supplier_order 5399 5399 0 0.00 0 19026 20 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 30 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1064 22 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 455 6 0 0
t$perf-norm:srv_make_money_saldo 682 682 0 0.00 0 8156 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10156 9982 174 1.71 0 16162 20 0 174
v_all_customers 37568 37568 0 0.00 0 70 1 0 0
v_all_suppliers 30072 30072 0 0.00 0 12 1 0 0
v_all_wares 40802 40802 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 1074 9 0 0
v_cancel_client_order 3388 3388 0 0.00 0 6774 5 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 6790 10 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 361 3 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 2241 8 0 0
v_cancel_supplier_invoice 830 822 8 0.96 0 1641 15 0 8
v_cancel_supplier_order 820 820 0 0.00 0 3191 12 0 0
v_cancel_write_off 2022 2022 0 0.00 0 2074 13 0 0
v_max_id_clo_ord 5399 5399 0 0.00 0 5313 2 0 0
v_max_id_clo_res 1003 1003 0 0.00 0 1859 20 0 0
v_max_id_ord_sup 5411 5411 0 0.00 0 4371 5 0 0
v_max_non_paid_invoice 1564 1564 0 0.00 0 500 2 0 0
v_max_non_paid_realizn 3596 3596 0 0.00 0 1112 3 0 0
v_min_id_clo_ord 5399 5399 0 0.00 0 2338 5 0 0
v_min_id_clo_res 1003 1003 0 0.00 0 2637 8 0 0
v_min_id_ord_sup 5411 5411 0 0.00 0 6842 10 0 0
v_min_non_paid_invoice 5791 5791 0 0.00 0 13937 30 0 0
v_min_non_paid_realizn 7012 7012 0 0.00 0 10279 19 0 0
v_our_firm 10066 10066 0 0.00 0 18 1 0 0
v_reserve_write_off 19896 19896 0 0.00 0 8873 7 0 0
x_lock_depdocs_on_canc_invoice 10885 10885 0 0.00 0 8373 97 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 5818 322 0 0
x_make_qstorno_1000_1200 5399 5399 0 0.00 2 50228 1355 0 0
x_make_qstorno_1200_2000 5444 5444 0 0.00 3 102205 3649 0 0
x_make_qstorno_2100_3300 463686 463686 0 0.00 0 54281 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-09-2021 13:38:58 to 21-09-2021 13:39:02.

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
11:07:11 12 8192000000 8798449840 8809664512 4478816 0.054 2 1 2 0 4578208 63616 35776
11:08:12 33536 8192000000 9735704400 10036154368 807859376 8.976 100 91 182 0 838573024 8243248 22470400
11:09:49 18254 8192000000 9769261552 10076868608 840613776 9.306 100 86 172 0 874537584 13400848 20522960
11:11:10 20875 8192000000 9816590336 10134994944 872419392 9.624 100 84 168 1 918405184 26592576 19393216
11:12:36 13143 8192000000 9889016928 10222809088 926145792 10.157 100 81 162 0 995928560 51696096 18086672
11:13:52 9291 8192000000 9936597152 10280898560 957682000 10.466 100 82 164 2 1046690832 72056320 16952512
11:15:06 4666 8192000000 9965055888 10321412096 995951696 10.839 100 85 166 3 1074915040 61893744 17069600
11:16:14 2529 8192000000 10010399008 10375897088 1028333904 11.152 100 72 144 0 1127179472 84780880 14064688
11:17:18 1660 8192000000 10024484112 10393718784 1053120256 11.391 100 85 168 0 1133669856 62707984 17841616
11:18:22 10828 8192000000 10044988992 10416283648 1065010208 11.504 100 86 175 0 1149536592 67466784 17059600
11:19:35 1848 8192000000 10006308432 10385985536 1071979808 11.571 100 75 150 1 1118274608 29905664 16389136
11:20:38 9434 8192000000 9991575504 10395402240 1075747808 11.607 100 35 70 0 1108529536 25027792 7753936
11:21:50 3999 8192000000 10010357088 10399723520 1083963008 11.685 100 61 124 0 1127155824 29511424 13681392
11:22:56 23113 8192000000 10021890160 10405543936 1086451488 11.709 100 76 151 1 1117634128 14294896 16887744
11:24:20 5609 8192000000 10031198064 10446401536 1093243904 11.773 100 31 62 1 1148227664 48879376 6104384
11:25:27 3889 8192000000 10012842736 10407145472 1095687200 11.797 100 41 79 3 1114605600 9405776 9512624
11:26:31 11075 8192000000 9990699520 10397405184 1072231344 11.573 98 20 39 2 1102695072 26196720 4267008
11:27:50 30527 8192000000 9951579296 10356531200 955946768 10.449 88 21 42 1 982496048 22145824 4403456
11:29:20 4532 8192000000 9962225392 10359992320 1018697792 11.059 97 53 105 0 1059505200 29623200 11184208
11:30:29 8203 8192000000 9908929808 10295263232 980615376 10.690 97 34 68 0 1010211040 21835776 7759888
11:31:42 66607 8192000000 9935417744 10335854592 1016082288 11.034 98 28 55 2 1033102176 10760592 6259296
11:33:52 7342 8192000000 9964338320 10358091776 1049867792 11.359 100 41 83 0 1070519312 11292496 9359024
11:35:00 9024 8192000000 9979310752 10377711616 1063565008 11.491 100 46 92 0 1091179936 16332704 11282224
11:36:10 10290 8192000000 9979216944 10387505152 1068226160 11.535 100 28 56 1 1102691840 27572368 6893312
11:37:23 95705 8192000000 10007680464 10413182976 1086194112 11.706 100 35 69 1 1113833808 20393584 7246112
11:40:05 5080 8192000000 10060895856 10465492992 1112480016 11.956 100 52 105 0 1172468608 49068896 10919696
11:41:13 10457 8192000000 10098909344 10508206080 1135121536 12.170 100 34 68 1 1212271760 69395264 7754960
11:42:26 36063 8192000000 10171615440 10594017280 1148580000 12.296 100 43 86 1 1275073712 116927696 9566016
11:44:08 5140 8192000000 10183802912 10597646336 1158416928 12.388 100 50 99 1 1287856944 118976944 10463072
11:45:18 19611 8192000000 10226569360 10669318144 1171877920 12.514 100 46 92 0 1347710032 165053488 10778624
11:46:43 4038 8192000000 10253376624 10669752320 1173659456 12.531 100 62 124 0 1357713952 170344544 13709952
11:47:52 4038 8192000000 10288786080 10696589312 1163855408 12.439 99 66 130 0 1377161872 199231392 14075072
11:48:58 4636 8192000000 10348076832 10751959040 1169844576 12.495 100 89 177 2 1452041968 263591136 18606256
11:50:05 6027 8192000000 10333239904 10753761280 1170134432 12.498 100 59 118 0 1443236832 260149360 12953040
11:51:16 3165 8192000000 10360588384 10779521024 1148490432 12.295 99 63 126 1 1454484944 291341488 14653024
11:52:25 9184 8192000000 10384418192 10800566272 1144100592 12.254 99 47 94 2 1479719568 324143296 11475680
11:53:40 3091 8192000000 10444046816 10858029056 1163649136 12.437 100 76 150 0 1541767728 360535296 17583296
11:54:47 5315 8192000000 10377537920 10798731264 1140835984 12.223 99 59 116 0 1471404208 316319072 14249152
11:55:59 7267 8192000000 10334148320 10770235392 1134048496 12.160 99 56 112 1 1440053488 292101824 13903168
11:57:15 9690 8192000000 10311770112 10749685760 1133585776 12.155 100 40 80 1 1441448688 297283488 10579424
11:58:35 2414 8192000000 10376724992 10787627008 1155998688 12.366 100 66 131 1 1472333280 300357184 15977408
11:59:42 13815 8192000000 10358668272 10853851136 1156702448 12.372 100 38 74 1 1513931904 346958304 10271152
12:01:02 20710 8192000000 10305934592 10749673472 1112159664 11.953 98 30 60 0 1393923040 273885680 7877696
12:02:25 20291 8192000000 10260333600 10704125952 1123862496 12.063 100 19 38 3 1370525328 241499744 5163088
12:03:58 32707 8192000000 10110201136 10652045312 1102068128 11.857 99 11 22 0 1289187760 184202736 2916896
12:05:31 121922 8192000000 10057213632 10509938688 1060146208 11.458 95 34 67 2 1104626144 36667904 7812032
12:08:37 88681 8192000000 10104642544 10535165952 1108831008 11.921 98 31 62 1 1159630144 44589088 6210048
12:11:10 55882 8192000000 10230273632 10692804608 1148511872 12.296 100 33 64 0 1308596496 153139328 6945296
12:13:22 66043 8192000000 10068991088 10623913984 1126502416 12.088 99 9 18 1 1212191728 83866896 1822416
12:15:37 56825 8192000000 10099099648 10555162624 1132874096 12.148 99 6 12 1 1182312944 48107632 1331216
12:17:37 100993 8192000000 10082618256 10562805760 1126908128 12.092 99 19 36 0 1162138160 31345152 3884880
12:20:25 35530 8192000000 10111102192 10597355520 1149291168 12.303 100 14 28 0 1203974240 50977616 3705456
12:22:16 67190 8192000000 10097097248 10560278528 1129377392 12.115 98 12 24 0 1181813712 49567632 2868688
12:24:24 82746 8192000000 10117490256 10561806336 1144454064 12.257 99 21 42 0 1221320880 72373568 4493248
12:26:52 69863 8192000000 10105649824 10554040320 1101158576 11.849 95 18 36 1 1144610512 39709824 3742112
12:29:12 36798 8192000000 10083280192 10525204480 1107028048 11.904 97 38 75 1 1150599920 36019360 7552512
12:30:49 64806 8192000000 10083007600 10556997632 1098857504 11.827 96 19 38 0 1167078272 64254528 3966240
12:32:54 46484 8192000000 10080312672 10549477376 1115020256 11.980 98 20 40 0 1155134080 36261136 3852688
12:34:43 63815 8192000000 10089375040 10547585024 1095784768 11.798 96 36 72 0 1155351296 52380784 7185744
12:36:50 85689 8192000000 10061290304 10539569152 1145361008 12.266 100 40 81 0 1216149392 62096992 8691392
12:39:17 40146 8192000000 10065306800 10520764416 1121007184 12.037 98 9 18 0 1160693344 38172032 1514128
12:41:01 62875 8192000000 10109445168 10578305024 1131286640 12.133 99 7 14 0 1204606048 71892448 1426960
12:43:07 122368 8192000000 10107643200 10592538624 1107477104 11.909 96 16 32 0 1174683008 63505072 3700832
12:46:26 45380 8192000000 10131892896 10577915904 1107786320 11.911 97 29 58 0 1182975280 68862944 6326016
12:48:14 121172 8192000000 10107955280 10639351808 1109016848 11.923 97 9 18 0 1224937984 114169216 1751920
12:51:17 100733 8192000000 10083119360 10576191488 1113081808 11.962 98 5 10 0 1153574736 39693696 799232
12:53:58 75753 8192000000 10066901072 10504564736 1119278640 12.020 98 27 54 0 1143608528 18580736 5749152
12:56:23 60360 8192000000 10093636496 10592112640 1131427712 12.135 99 32 64 1 1253553984 115258128 6868144
12:58:31 67023 8192000000 10076989296 10534821888 1082172448 11.668 95 12 24 0 1141517824 56800528 2544848
13:00:38 61570 8192000000 10056174336 10505228288 1097565920 11.815 97 24 48 1 1118946160 16105504 5274736
13:02:40 60871 8192000000 10052816368 10528100352 1088408832 11.728 96 17 34 0 1163165056 70807728 3948496
13:04:55 78872 8192000000 10061451792 10531115008 1113086400 11.962 98 14 28 1 1152664384 36845392 2732592
13:07:16 48945 8192000000 10069896464 10553995264 1118495488 12.013 98 23 46 0 1186757600 63777648 4484464
13:09:06 23269 8192000000 10101065904 10551119872 1141733264 12.232 100 48 96 1 1206420416 55404192 9282960
13:10:34 51856 8192000000 10128523984 10630811648 1144213632 12.255 99 32 64 0 1224974576 74383360 6377584
13:12:29 105773 8192000000 10097942144 10566340608 1125890880 12.083 98 15 30 0 1162658224 33814144 2953200
13:15:41 60520 8192000000 10096351072 10555420672 1141961856 12.234 99 32 64 0 1191944848 43331472 6651520
13:17:42 57317 8192000000 10103036976 10566991872 1131553840 12.136 98 10 20 0 1192904992 59319488 2031664
13:19:39 88276 8192000000 10080778672 10556428288 1127168944 12.095 98 13 26 1 1194355744 64227536 2959264
13:22:12 78526 8192000000 10095623776 10558296064 1113688784 11.967 96 19 38 0 1171129872 53247504 4193584
13:24:36 68855 8192000000 10042915792 10514038784 1065776112 11.512 94 23 46 0 1117569696 46942016 4851568
13:26:50 29436 8192000000 10025290128 10464415744 1093990960 11.781 99 22 44 1 1135055696 36892256 4172480
13:28:28 46016 8192000000 10142763392 10593058816 1120398720 12.031 99 34 68 0 1220334240 92960560 6974960
13:30:14 96981 8192000000 10070060128 10505654272 1064674400 11.501 94 22 44 0 1114630384 45370176 4585808
13:33:00 63217 8192000000 10053202112 10473193472 1099279744 11.831 97 52 105 2 1127524384 17230224 11014416
13:35:04 50804 8192000000 10061976944 10524561408 1116114528 11.990 100 5 10 0 1168142976 50966112 1062336
13:36:56 22876 8192000000 9281087856 9615773696 412796560 4.797 38 2 4 0 435425696 22314336 314800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-09-2021 13:39:04 to 21-09-2021 13:39:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-09-2021 13:39:20 to 21-09-2021 13:39:22.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          236838
MON$OLDEST_ACTIVE               236839
MON$OLDEST_SNAPSHOT             236839
MON$NEXT_TRANSACTION            236839
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-30 07:30:08.6030 GMT
MON$PAGES                       2042336
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             807
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2613 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2613 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2613 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2613 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
--- end of diff output ---

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

21.09.21 13:39:23. There are 3 logs in /var/tmp/logs-oltp40 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-21 13:39:25 No severe PSQL-related problems occured
21.09.21 13:39:27. End of report.