Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.05.2022 07: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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 16188004 kB
MemAvailable 31332816 kB
Buffers 0 kB
Cached 15492044 kB
SwapCached 2440 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.2
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 2022-05-31 07:37:16
DTS_END                         2022-05-31 07:37:16.9670
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/43380
TRN_ID                          221543
ATT_ID                          606
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: 8920.63 21930 1070476
customer order: creation 170.79 466 20495
customer order: refuse 14.17 392 1700
order to supplier: creation 46.12 1365 5534
order to supplier: removal 3.29 146143 395
invoice (draft): creation 45.58 3230 5470
invoice (draft): removal 49.57 981 5948
invoice accept: apply 41.07 26635 4928
invoice accept: cancel 44.77 1150 5372
customer reserve: creation 3870.18 251 464421
customer reserve: removal 4340.00 86 520800
realization accept: apply 82.56 258 9907
realization accept: cancel 83.55 157 10026
payment from customer: creation 58.86 163 7063
payment from customer: removal 5.60 113 672
payment to supplier: creation 48.38 173 5805
payment to supplier: removal 3.50 128 420
service: total inventory turnovers 4.28 3519 513
service: total monetary turnovers 7.40 334 888
service: aggregate perf. data 0.96 15225 115
service: refresh index statistics 0.03 237839 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-05-2022 07:38:27 to 31-05-2022 07:38:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6029
TEST_TIME 2 5036
TEST_TIME 3 4855
TEST_TIME 4 4868
TEST_TIME 5 4913
TEST_TIME 6 5347
TEST_TIME 7 5467
TEST_TIME 8 5648
TEST_TIME 9 6098
TEST_TIME 10 6376
TEST_TIME 11 6644
TEST_TIME 12 6669
TEST_TIME 13 6781
TEST_TIME 14 6927
TEST_TIME 15 7309
TEST_TIME 16 7476
TEST_TIME 17 7776
TEST_TIME 18 7974
TEST_TIME 19 8006
TEST_TIME 20 8031
TEST_TIME 21 8442
TEST_TIME 22 8850
TEST_TIME 23 8867
TEST_TIME 24 9058
TEST_TIME 25 8899
TEST_TIME 26 9338
TEST_TIME 27 9436
TEST_TIME 28 9929
TEST_TIME 29 10126
TEST_TIME 30 10414
TEST_TIME 31 10380
TEST_TIME 32 10762
TEST_TIME 33 11032
TEST_TIME 34 11123
TEST_TIME 35 11035
TEST_TIME 36 10922
TEST_TIME 37 10711
TEST_TIME 38 10693
TEST_TIME 39 10825
TEST_TIME 40 10609
TEST_TIME 41 10532
TEST_TIME 42 10368
TEST_TIME 43 10276
TEST_TIME 44 10205
TEST_TIME 45 10159
TEST_TIME 46 10154
TEST_TIME 47 10118
TEST_TIME 48 10189
TEST_TIME 49 10051
TEST_TIME 50 10010
TEST_TIME 51 9891
TEST_TIME 52 9854
TEST_TIME 53 9833
TEST_TIME 54 9825
TEST_TIME 55 9804
TEST_TIME 56 9785
TEST_TIME 57 9725
TEST_TIME 58 9754
TEST_TIME 59 9807
TEST_TIME 60 9891
TEST_TIME 61 9880
TEST_TIME 62 9807
TEST_TIME 63 9762
TEST_TIME 64 9860
TEST_TIME 65 9835
TEST_TIME 66 9873
TEST_TIME 67 9839
TEST_TIME 68 9711
TEST_TIME 69 9709
TEST_TIME 70 9665
TEST_TIME 71 9572
TEST_TIME 72 9579
TEST_TIME 73 9564
TEST_TIME 74 9519
TEST_TIME 75 9417
TEST_TIME 76 9390
TEST_TIME 77 9396
TEST_TIME 78 9434
TEST_TIME 79 9395
TEST_TIME 80 9374
TEST_TIME 81 9407
TEST_TIME 82 9372
TEST_TIME 83 9465
TEST_TIME 84 9422
TEST_TIME 85 9506
TEST_TIME 86 9593
TEST_TIME 87 9517
TEST_TIME 88 9610
TEST_TIME 89 9524
TEST_TIME 90 9477
TEST_TIME 91 9440
TEST_TIME 92 9392
TEST_TIME 93 9361
TEST_TIME 94 9299
TEST_TIME 95 9283
TEST_TIME 96 9254
TEST_TIME 97 9213
TEST_TIME 98 9193
TEST_TIME 99 9186
TEST_TIME 100 9163
TEST_TIME 101 9166
TEST_TIME 102 9180
TEST_TIME 103 9207
TEST_TIME 104 9163
TEST_TIME 105 9201
TEST_TIME 106 9219
TEST_TIME 107 9186
TEST_TIME 108 9251
TEST_TIME 109 9213
TEST_TIME 110 9160
TEST_TIME 111 9145
TEST_TIME 112 9114
TEST_TIME 113 9072
TEST_TIME 114 9035
TEST_TIME 115 9045
TEST_TIME 116 9049
TEST_TIME 117 9024
TEST_TIME 118 9031
TEST_TIME 119 9008
TEST_TIME 120 8946

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-05-2022 07:38:30 to 31-05-2022 07:38:34.

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 1497978 1497978 0 0.00 0 27131 13 0 0
doc_list_biud 1497973 1497973 0 0.00 0 40979 36 0 0
sp_add_invoice_to_stock 5056 4928 128 2.53 34 266276 26636 0 128
sp_cancel_adding_invoice 5372 5372 0 0.00 7 87496 1151 0 0
sp_cancel_client_order 1700 1700 0 0.00 7 18898 393 0 0
sp_cancel_customer_reserve 520800 520800 0 0.00 1 23792 87 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 3 3228 114 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 5270 129 0 0
sp_cancel_supplier_invoice 5961 5948 13 0.21 4 40999 981 0 13
sp_cancel_supplier_order 395 395 0 0.00 14 1321199 146143 0 0
sp_cancel_write_off 10026 10026 0 0.00 2 11749 158 0 0
sp_client_order 20495 20495 0 0.00 6 15828 466 0 0
sp_customer_reserve 464421 464421 0 0.00 1 120424 252 0 0
sp_fill_shopping_cart 31494 31494 0 0.00 2 29389 81 0 0
sp_fill_shopping_cart_clo_res 464395 464395 0 0.00 0 16341 20 0 0
sp_get_clo_for_invoice 4883 4883 0 0.00 0 20264 278 0 0
sp_kill_cost_storno 16490 16490 0 0.00 0 5593 61 0 0
sp_kill_qstorno_ret_qs2qd 1057969 1057969 0 0.00 0 40976 39 0 0
sp_kill_qty_storno 559031 559031 0 0.00 0 40978 89 0 0
sp_lock_selected_doc 20470 20470 0 0.00 0 10405 8 0 0
sp_make_cost_storno 27658 27658 0 0.00 0 10838 71 0 0
sp_multiply_rows_for_pdistr 27658 27658 0 0.00 0 27125 34 0 0
sp_multiply_rows_for_qdistr 20495 20495 0 0.00 0 13592 185 0 0
sp_pay_from_customer 7063 7063 0 0.00 6 7604 163 0 0
sp_pay_to_supplier 5805 5805 0 0.00 7 27137 174 0 0
sp_payment_common 12868 12868 0 0.00 2 6297 40 0 0
sp_qd_handle_on_cancel_clo 1700 1700 0 0.00 0 18887 330 0 0
sp_qd_handle_on_invoice_upd_sts 10255 10255 0 0.00 0 20764 474 0 0
sp_qd_handle_on_reserve_upd_sts 9907 9907 0 0.00 0 20931 64 0 0
sp_reserve_write_off 9907 9907 0 0.00 8 20972 259 0 0
sp_supplier_invoice 5470 5470 0 0.00 10 109903 3231 0 0
sp_supplier_order 5534 5534 0 0.00 9 37136 1365 0 0
srv_aggregate_perf_data 115 115 0 0.00 2377 161353 15225 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 903 110366 37024 0 0
srv_find_qd_qs_mism 1021530 1021530 0 0.00 0 9317 11 0 0
srv_make_invnt_saldo 513 513 0 0.00 70 75402 3519 0 0
srv_make_money_saldo 888 888 0 0.00 2 18244 334 0 0
srv_recalc_idx_stat 4 4 0 0.00 117900 335881 237840 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4928 4928 0 0.00 1 14840 886 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 3 7343 451 0 0
t$perf-norm:sp_cancel_client_order 1700 1700 0 0.00 0 2714 25 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 4558 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 1352 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 207 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 7078 50 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 93968 6550 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 901 16 0 0
t$perf-norm:sp_client_order 20495 20495 0 0.00 0 9812 33 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 498 11 0 0
t$perf-norm:sp_pay_from_customer 7063 7063 0 0.00 0 5864 18 0 0
t$perf-norm:sp_pay_to_supplier 5805 5805 0 0.00 0 11128 21 0 0
t$perf-norm:sp_reserve_write_off 9907 9907 0 0.00 0 7028 25 0 0
t$perf-norm:sp_supplier_invoice 5470 5470 0 0.00 0 17176 34 0 0
t$perf-norm:sp_supplier_order 5534 5534 0 0.00 0 7215 31 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 1256 35 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 4356 67 0 0
t$perf-norm:srv_make_invnt_saldo 513 513 0 0.00 0 2274 16 0 0
t$perf-norm:srv_make_money_saldo 888 888 0 0.00 0 645 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 195 82 0 0
v_add_invoice_to_stock 10016 9760 256 2.55 0 4094 27 0 256
v_all_customers 37492 37492 0 0.00 0 106 3 0 0
v_all_suppliers 30634 30634 0 0.00 0 74 4 0 0
v_all_wares 40990 40990 0 0.00 0 73 4 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 2075 15 0 0
v_cancel_client_order 3400 3400 0 0.00 0 608 7 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 3192 13 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 2044 13 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 2195 17 0 0
v_cancel_supplier_invoice 788 762 26 3.29 0 3185 26 0 26
v_cancel_supplier_order 790 790 0 0.00 0 1588 14 0 0
v_cancel_write_off 1940 1940 0 0.00 0 4768 23 0 0
v_max_id_clo_ord 5533 5533 0 0.00 0 289 5 0 0
v_max_id_clo_res 987 987 0 0.00 0 6769 39 0 0
v_max_id_ord_sup 5465 5465 0 0.00 0 3133 6 0 0
v_max_non_paid_invoice 1486 1486 0 0.00 0 3597 10 0 0
v_max_non_paid_realizn 3593 3593 0 0.00 0 692 6 0 0
v_min_id_clo_ord 5533 5533 0 0.00 0 1477 9 0 0
v_min_id_clo_res 987 987 0 0.00 0 6986 24 0 0
v_min_id_ord_sup 5465 5465 0 0.00 0 5903 10 0 0
v_min_non_paid_invoice 5805 5805 0 0.00 0 5070 12 0 0
v_min_non_paid_realizn 7063 7063 0 0.00 0 5858 15 0 0
v_our_firm 10438 10438 0 0.00 0 48 4 0 0
v_reserve_write_off 19814 19814 0 0.00 0 6144 10 0 0
x_lock_depdocs_on_canc_invoice 10939 10939 0 0.00 0 15498 126 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 14428 370 0 0
x_make_qstorno_1000_1200 5534 5534 0 0.00 2 37120 1199 0 0
x_make_qstorno_1200_2000 5470 5470 0 0.00 5 109870 3011 0 0
x_make_qstorno_2100_3300 464420 464420 0 0.00 0 120414 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-05-2022 07:38:35 to 31-05-2022 07:38:39.

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
05:07:20 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:21 27144 8192000000 9710913744 10012205056 791848400 8.814 100 85 170 0 820114992 7352688 20913904
05:10:03 12901 8192000000 9760327568 10066190336 832230048 9.222 100 87 174 0 865493456 12068800 21194608
05:11:22 16365 8192000000 9812598432 10133479424 878503008 9.685 100 84 168 0 919352320 21759200 19090112
05:12:41 8693 8192000000 9854839360 10191634432 919755808 10.094 100 75 150 1 967634336 31430176 16448352
05:13:56 5794 8192000000 9923923152 10268880896 964698288 10.535 100 88 170 1 1037205296 54786096 17720912
05:15:04 2819 8192000000 9976606048 10329571328 993889088 10.819 100 94 189 0 1095451088 82618496 18943504
05:16:09 2296 8192000000 10019516368 10384396288 1016507680 11.038 100 90 179 1 1140239376 105564416 18167280
05:17:14 911 8192000000 10006889072 10374844416 1046236752 11.325 100 94 180 1 1124644496 60657296 17750448
05:18:16 1188 8192000000 10027371584 10400829440 1057765408 11.435 100 87 166 0 1150895440 75775472 17354560
05:19:18 1094 8192000000 10039745328 10418245632 1070415472 11.556 100 98 183 2 1159341264 70067424 18858368
05:20:21 1148 8192000000 10061658704 10443558912 1078346496 11.632 100 94 176 0 1185228992 88924064 17958432
05:21:24 3318 8192000000 10044021312 10428375040 1083802384 11.684 100 82 155 3 1162577648 63545904 15229360
05:22:28 1102 8192000000 10048009136 10432638976 1086763232 11.712 100 91 172 2 1166960080 61097376 19099472
05:23:30 5222 8192000000 10067827248 10457743360 1091762064 11.759 100 76 138 1 1186131680 79786208 14583408
05:24:37 1325 8192000000 10065242544 10455269376 1096676864 11.806 100 94 183 2 1185619536 69771776 19170896
05:25:39 1333 8192000000 10073790560 10465300480 1102957104 11.866 100 98 186 1 1194765072 72803280 19004688
05:26:41 742 8192000000 10054295776 10442928128 1102318112 11.860 100 94 176 2 1173191600 54906592 15966896
05:27:43 970 8192000000 10089318496 10480906240 1101269328 11.850 100 89 174 0 1212602752 91851664 19481760
05:28:45 3472 8192000000 10046264448 10447077376 1098876880 11.827 100 73 145 1 1170346368 56160880 15308608
05:29:50 1399 8192000000 10055100272 10440298496 1093723904 11.778 100 96 192 3 1178694384 64640960 20329520
05:30:53 912 8192000000 10034127504 10413641728 1065988864 11.514 100 95 180 1 1153857552 69291824 18576864
05:31:56 1120 8192000000 10023135920 10398228480 1054730672 11.406 99 96 183 1 1152201632 78424032 19046928
05:32:58 2357 8192000000 10022129968 10409029632 1045578992 11.318 100 68 134 0 1144998288 85428464 13990832
05:34:01 2533 8192000000 9964839760 10336374784 1020557136 11.077 98 73 133 2 1088379968 53909808 13913024
05:35:06 1180 8192000000 10000209184 10376118272 1036230768 11.228 100 84 156 2 1122976048 69485808 17259472
05:36:08 1123 8192000000 9987571520 10362679296 1041032368 11.275 99 83 163 0 1115343264 57147728 17163168
05:37:10 3843 8192000000 10014043152 10401824768 1053071232 11.390 100 76 152 0 1124478448 56952608 14454608
05:38:23 18006 8192000000 10034789024 10416156672 1072568336 11.577 100 73 142 1 1156125408 69484784 14072288
05:39:49 3319 8192000000 10066053632 10451927040 1085985872 11.704 100 92 170 1 1186494832 83384272 17124688
05:41:14 6231 8192000000 10088516272 10485264384 1101752608 11.854 100 51 102 1 1217988720 105414416 10821696
05:42:23 2688 8192000000 10140142608 10529550336 1111761808 11.949 100 80 149 1 1253097376 123511840 17823728
05:43:29 5744 8192000000 10182277280 10574110720 1125754496 12.081 100 80 149 2 1295376800 154676944 14945360
05:44:40 908 8192000000 10204994784 10601390080 1138349616 12.200 100 83 157 2 1314766384 159553824 16862944
05:45:44 1460 8192000000 10237056608 10634469376 1146209344 12.274 100 87 167 3 1350713008 186263440 18240224
05:46:49 899 8192000000 10291610736 10689359872 1159443248 12.398 100 96 188 1 1407568576 226747008 21378320
05:47:53 3502 8192000000 10297233744 10696663040 1160523232 12.408 100 83 153 0 1410622192 233651184 16447776
05:49:00 1464 8192000000 10334540368 10735611904 1181527728 12.604 100 95 186 1 1448219760 244932512 21759520
05:50:05 1292 8192000000 10397020048 10801901568 1184966848 12.636 100 93 178 1 1507935264 302542128 20426288
05:51:09 1006 8192000000 10402616736 10835288064 1180632624 12.596 100 95 187 1 1518823936 315042912 23148400
05:52:13 1125 8192000000 10405892672 10824790016 1177056608 12.563 100 94 183 1 1518320720 320453888 20810224
05:53:17 938 8192000000 10439487840 10852196352 1182732816 12.616 100 96 190 1 1550300048 344962016 22605216
05:54:20 1348 8192000000 10410754384 10849779712 1175819824 12.551 100 91 178 3 1520231184 324336176 20075184
05:55:24 905 8192000000 10438748848 10842423296 1166835376 12.467 100 97 195 2 1543296928 352544608 23916944
05:56:27 1396 8192000000 10472606800 10875289600 1166116480 12.461 100 92 176 1 1579318864 392597600 20604784
05:57:34 1533 8192000000 10434935936 10879864832 1155125584 12.358 100 93 174 0 1542199104 366657456 20416064
05:58:38 1240 8192000000 10410815712 10875838464 1152940560 12.337 99 92 177 1 1515782368 341342688 21499120
05:59:41 7805 8192000000 10415549344 10894512128 1166584768 12.465 100 45 90 0 1515296768 337419824 11292176
06:00:55 22140 8192000000 10414204160 10889486336 1157253232 12.378 100 33 66 0 1547635920 381870880 8511808
06:02:20 7870 8192000000 10407092672 10841612288 1146323184 12.275 100 35 70 0 1507488832 352074848 9090800
06:03:40 16856 8192000000 10390090752 10881536000 1151470192 12.323 100 39 74 1 1486241040 325166512 9604336
06:04:57 26207 8192000000 10311665264 10769469440 1151413488 12.323 100 36 73 0 1429790848 269954640 8422720
06:06:25 11100 8192000000 10269018736 10699579392 1136017840 12.178 100 32 61 0 1351889072 208394848 7476384
06:07:43 26243 8192000000 10248498800 10705735680 1140540800 12.221 99 28 55 0 1343363760 196231104 6591856
06:09:46 43234 8192000000 10218035472 10676170752 1140124192 12.217 99 14 28 4 1302539664 159237680 3177792
06:11:33 27410 8192000000 10078032784 10510110720 1113175984 11.962 97 34 67 0 1148795760 29226416 6393360
06:13:04 35852 8192000000 10149076112 10600755200 1149685120 12.307 100 19 36 2 1234899216 81316592 3897504
06:14:49 34003 8192000000 10134362320 10592788480 1142044848 12.235 100 50 101 3 1278915584 124462144 12408592
06:16:32 105379 8192000000 10119804624 10571890688 1147450816 12.286 100 29 58 0 1249630896 95729824 6450256
06:19:40 33568 8192000000 10087722256 10529943552 1139380768 12.210 100 31 59 0 1188730704 42887280 6462656
06:21:15 71059 8192000000 10141301680 10600398848 1111243536 11.944 96 23 46 0 1195190656 79205216 4741904
06:23:30 27596 8192000000 10142672528 10606432256 1146082368 12.273 100 36 68 1 1255596912 102052480 7462064
06:24:59 110366 8192000000 10102366640 10563493888 1136897168 12.186 99 21 42 0 1234840080 93034208 4908704
06:27:55 109481 8192000000 10082148848 10559315968 1130263456 12.124 98 27 54 1 1175818464 39380464 6174544
06:30:45 99073 8192000000 10096622944 10559868928 1124519696 12.070 97 11 22 1 1174443024 47894656 2028672
06:33:24 45451 8192000000 10108953744 10553470976 1135958304 12.177 99 32 61 1 1208387648 66485264 5944080
06:35:11 48738 8192000000 10128914016 10602422272 1121964768 12.046 99 23 46 0 1233602576 106525360 5112448
06:37:17 109617 8192000000 10073802864 10512396288 1094860208 11.789 96 13 26 0 1146354576 48988768 2505600
06:40:06 43953 8192000000 10141413360 10582446080 1112109744 11.952 98 32 64 1 1208491648 89951792 6430112
06:41:54 57434 8192000000 10096764864 10527309824 1080762192 11.655 95 33 64 1 1141816784 54583792 6470800
06:43:56 21483 8192000000 10075696816 10503385088 1116339344 11.992 99 36 71 0 1154854192 31317696 7197152
06:45:20 76854 8192000000 10127606448 10586820608 1137191280 12.189 100 53 106 0 1221508048 73379024 10937744
06:47:43 44429 8192000000 10066205824 10497515520 1104163776 11.877 97 33 62 2 1137454912 26317168 6973968
06:49:28 65853 8192000000 10066177744 10510393344 1119384816 12.021 99 12 23 1 1180786720 58544016 2857888
06:51:35 48923 8192000000 10058046592 10524372992 1090112016 11.744 97 9 18 1 1143071232 51025568 1933648
06:55:41 45715 8192000000 10090335520 10526597120 1096992512 11.809 97 21 42 1 1160152192 58406640 4753040
06:57:27 48385 8192000000 10142309904 10594529280 1088719184 11.730 97 28 56 3 1203875232 109192688 5963360
06:59:17 57515 8192000000 10129668880 10623012864 1114173232 11.972 99 20 40 1 1195361648 76780976 4407440
07:01:33 53282 8192000000 10147389712 10611474432 1099137920 11.829 97 34 68 0 1224254144 117884128 7232096
07:04:00 47834 8192000000 10096911824 10523922432 1111785424 11.949 99 30 56 1 1155915264 38424368 5705472
07:05:50 93379 8192000000 10088686304 10529021952 1132107312 12.141 100 18 36 1 1184625632 48989760 3528560
07:08:33 23582 8192000000 10059382896 10531442688 1119225472 12.020 99 16 32 0 1147078432 25038368 2814592
07:09:57 59706 8192000000 10048959184 10493132800 1088766176 11.731 96 21 42 0 1135958480 42614544 4577760
07:11:58 75558 8192000000 10068283072 10553225216 1137041904 12.188 100 7 14 0 1166083328 27453632 1587792
07:14:14 40123 8192000000 10081928224 10530275328 1143536512 12.249 100 23 42 0 1188449120 39600144 5312464
07:16:01 23064 8192000000 10101544752 10559184896 1140947600 12.224 100 53 105 0 1203340208 51275056 11117552
07:17:25 20100 8192000000 10134798512 10582654976 1147074496 12.282 100 36 72 1 1231848400 77325552 7448352
07:18:46 44783 8192000000 10129834096 10569216000 1113727840 11.968 97 42 84 1 1198173520 75542928 8902752
07:20:34 53638 8192000000 10125988768 10586132480 1135253088 12.171 99 22 44 0 1202165232 62385712 4526432
07:22:28 28609 8192000000 10125309424 10604609536 1154967168 12.356 100 46 92 4 1257681648 93049648 9664832
07:24:00 26306 8192000000 10140228416 10568896512 1130958496 12.130 99 47 94 1 1205922416 65928352 9035568
07:25:27 82772 8192000000 10110143472 10571407360 1105213088 11.887 97 36 71 2 1169933936 57059024 7661824
07:28:20 83953 8192000000 10064911408 10571554816 1083270960 11.679 96 11 22 0 1141060304 55646128 2143216
07:30:53 85009 8192000000 10111958832 10569670656 1119061152 12.018 99 14 28 0 1183268112 61378544 2828416
07:33:18 52860 8192000000 10072519520 10528350208 1131743248 12.138 100 19 39 1 1166230464 30566784 3920432
07:35:11 56645 8192000000 10094778320 10561024000 1110995088 11.942 97 25 50 1 1185559120 69184288 5379744
07:37:08 18617 8192000000 9315362048 9658109952 441342048 5.112 39 20 38 1 500807792 55432160 4033584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 31-05-2022 07:38:41 to 31-05-2022 07:38:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-05-2022 07:38:58 to 31-05-2022 07:39:00.

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          221413
MON$OLDEST_ACTIVE               222145
MON$OLDEST_SNAPSHOT             222145
MON$NEXT_TRANSACTION            222145
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                       2057952
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2770 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2770 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2770 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 ---
:258: 
:259: mscs-test-161.budzdorov.ru    Tue May 31 07:37:29 2022
:260:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:261: 
:262: 
:263: mscs-test-161.budzdorov.ru    Tue May 31 07:37:34 2022
:264:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:265: 
:266: 
:267: mscs-test-161.budzdorov.ru    Tue May 31 07:37:41 2022
:268:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:269: 
:270: 
:271: mscs-test-161.budzdorov.ru    Tue May 31 07:37:43 2022
:272:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:273: 
--- end of diff output ---

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

31.05.22 07:39:01. 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
2022-05-31 07:39:03 No severe PSQL-related problems occured
31.05.22 07:39:05. End of report.