Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.05.2022 13:39
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 15968004 kB
MemAvailable 31459652 kB
Buffers 0 kB
Cached 15763032 kB
SwapCached 2592 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-19 13:37:13
DTS_END                         2022-05-19 13:37:13.5740
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60940
TRN_ID                          222374
ATT_ID                          617
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: 9196.34 25800 1103561
customer order: creation 165.43 480 19851
customer order: refuse 14.75 415 1770
order to supplier: creation 44.63 1453 5355
order to supplier: removal 3.53 171309 424
invoice (draft): creation 43.94 3416 5273
invoice (draft): removal 52.36 977 6283
invoice accept: apply 40.95 25129 4914
invoice accept: cancel 47.51 1272 5701
customer reserve: creation 3850.98 234 462118
customer reserve: removal 4641.41 102 556969
realization accept: apply 82.64 288 9917
realization accept: cancel 81.58 168 9790
payment from customer: creation 57.88 178 6945
payment from customer: removal 5.53 156 663
payment to supplier: creation 47.63 205 5715
payment to supplier: removal 3.14 120 377
service: total inventory turnovers 3.79 4385 455
service: total monetary turnovers 7.67 354 920
service: aggregate perf. data 0.98 17319 117
service: refresh index statistics 0.03 288037 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-05-2022 13:39:37 to 19-05-2022 13:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6370
TEST_TIME 2 7667
TEST_TIME 3 6539
TEST_TIME 4 6717
TEST_TIME 5 6561
TEST_TIME 6 6440
TEST_TIME 7 6422
TEST_TIME 8 6357
TEST_TIME 9 6670
TEST_TIME 10 6566
TEST_TIME 11 6753
TEST_TIME 12 7003
TEST_TIME 13 7117
TEST_TIME 14 7461
TEST_TIME 15 7595
TEST_TIME 16 7626
TEST_TIME 17 7875
TEST_TIME 18 7761
TEST_TIME 19 7679
TEST_TIME 20 7682
TEST_TIME 21 7691
TEST_TIME 22 7766
TEST_TIME 23 7856
TEST_TIME 24 7942
TEST_TIME 25 8190
TEST_TIME 26 8253
TEST_TIME 27 8220
TEST_TIME 28 8823
TEST_TIME 29 9242
TEST_TIME 30 9235
TEST_TIME 31 9505
TEST_TIME 32 9981
TEST_TIME 33 10226
TEST_TIME 34 10541
TEST_TIME 35 10683
TEST_TIME 36 10684
TEST_TIME 37 11055
TEST_TIME 38 11260
TEST_TIME 39 11065
TEST_TIME 40 10969
TEST_TIME 41 10886
TEST_TIME 42 10816
TEST_TIME 43 10802
TEST_TIME 44 10664
TEST_TIME 45 10554
TEST_TIME 46 10796
TEST_TIME 47 10662
TEST_TIME 48 10708
TEST_TIME 49 10694
TEST_TIME 50 10674
TEST_TIME 51 10689
TEST_TIME 52 10517
TEST_TIME 53 10432
TEST_TIME 54 10294
TEST_TIME 55 10278
TEST_TIME 56 10240
TEST_TIME 57 10167
TEST_TIME 58 10095
TEST_TIME 59 10092
TEST_TIME 60 10053
TEST_TIME 61 9978
TEST_TIME 62 9991
TEST_TIME 63 9944
TEST_TIME 64 9899
TEST_TIME 65 9961
TEST_TIME 66 9923
TEST_TIME 67 9914
TEST_TIME 68 9856
TEST_TIME 69 9913
TEST_TIME 70 9915
TEST_TIME 71 9876
TEST_TIME 72 9918
TEST_TIME 73 9916
TEST_TIME 74 9868
TEST_TIME 75 9901
TEST_TIME 76 9952
TEST_TIME 77 9963
TEST_TIME 78 9924
TEST_TIME 79 10005
TEST_TIME 80 9985
TEST_TIME 81 10002
TEST_TIME 82 10060
TEST_TIME 83 10015
TEST_TIME 84 9943
TEST_TIME 85 9887
TEST_TIME 86 9873
TEST_TIME 87 9844
TEST_TIME 88 9772
TEST_TIME 89 9776
TEST_TIME 90 9756
TEST_TIME 91 9750
TEST_TIME 92 9732
TEST_TIME 93 9692
TEST_TIME 94 9677
TEST_TIME 95 9606
TEST_TIME 96 9534
TEST_TIME 97 9461
TEST_TIME 98 9523
TEST_TIME 99 9493
TEST_TIME 100 9476
TEST_TIME 101 9446
TEST_TIME 102 9435
TEST_TIME 103 9401
TEST_TIME 104 9446
TEST_TIME 105 9473
TEST_TIME 106 9488
TEST_TIME 107 9487
TEST_TIME 108 9512
TEST_TIME 109 9546
TEST_TIME 110 9551
TEST_TIME 111 9533
TEST_TIME 112 9555
TEST_TIME 113 9498
TEST_TIME 114 9477
TEST_TIME 115 9431
TEST_TIME 116 9423
TEST_TIME 117 9364
TEST_TIME 118 9318
TEST_TIME 119 9280
TEST_TIME 120 9217

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-05-2022 13:39:40 to 19-05-2022 13:39:44.

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 1529890 1529890 0 0.00 0 25663 13 0 0
doc_list_biud 1529880 1529880 0 0.00 0 27817 43 0 0
sp_add_invoice_to_stock 5130 4914 216 4.21 50 180568 25129 0 216
sp_cancel_adding_invoice 5701 5701 0 0.00 4 85106 1272 0 0
sp_cancel_client_order 1770 1770 0 0.00 5 14476 415 0 0
sp_cancel_customer_reserve 556969 556969 0 0.00 1 31300 103 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 4 8351 156 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 4 1565 120 0 0
sp_cancel_supplier_invoice 6303 6283 20 0.31 2 20691 977 0 20
sp_cancel_supplier_order 424 424 0 0.00 26 1417692 171310 0 0
sp_cancel_write_off 9790 9790 0 0.00 2 22854 169 0 0
sp_client_order 19851 19851 0 0.00 6 21153 481 0 0
sp_customer_reserve 462118 462118 0 0.00 0 107553 235 0 0
sp_fill_shopping_cart 30461 30461 0 0.00 2 29779 84 0 0
sp_fill_shopping_cart_clo_res 462084 462084 0 0.00 0 21632 17 0 0
sp_get_clo_for_invoice 4873 4873 0 0.00 0 27064 339 0 0
sp_kill_cost_storno 16531 16531 0 0.00 0 25652 79 0 0
sp_kill_qstorno_ret_qs2qd 1130435 1130435 0 0.00 0 27814 44 0 0
sp_kill_qty_storno 595726 595726 0 0.00 0 27816 101 0 0
sp_lock_selected_doc 20421 20421 0 0.00 0 7845 8 0 0
sp_make_cost_storno 27450 27450 0 0.00 0 21974 83 0 0
sp_multiply_rows_for_pdistr 27449 27449 0 0.00 0 10492 39 0 0
sp_multiply_rows_for_qdistr 19850 19850 0 0.00 0 17703 176 0 0
sp_pay_from_customer 6945 6945 0 0.00 5 16669 178 0 0
sp_pay_to_supplier 5715 5715 0 0.00 6 15532 206 0 0
sp_payment_common 12660 12660 0 0.00 2 13346 48 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 1 14461 343 0 0
sp_qd_handle_on_invoice_upd_sts 10574 10574 0 0.00 0 24232 545 0 0
sp_qd_handle_on_reserve_upd_sts 9916 9916 0 0.00 0 20928 72 0 0
sp_reserve_write_off 9917 9917 0 0.00 9 21035 288 0 0
sp_supplier_invoice 5273 5273 0 0.00 11 81697 3417 0 0
sp_supplier_order 5355 5355 0 0.00 8 35691 1454 0 0
srv_aggregate_perf_data 117 117 0 0.00 2760 192954 17320 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 836 154840 38920 0 0
srv_find_qd_qs_mism 1055971 1055971 0 0.00 0 12107 10 0 0
srv_make_invnt_saldo 455 455 0 0.00 58 71566 4385 0 0
srv_make_money_saldo 920 920 0 0.00 1 15111 355 0 0
srv_recalc_idx_stat 4 4 0 0.00 195353 425296 288037 0 0
t$perf-abend:sp_add_invoice_to_stock 216 216 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 20 20 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4918 4918 0 0.00 1 15349 947 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 1 5951 479 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 1932 29 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 914 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 3024 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 928 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 360 360 0 0.00 0 2642 47 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 70462 6904 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 5086 27 0 0
t$perf-norm:sp_client_order 19851 19851 0 0.00 0 8711 37 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 2642 16 0 0
t$perf-norm:sp_pay_from_customer 6945 6945 0 0.00 0 7326 24 0 0
t$perf-norm:sp_pay_to_supplier 5715 5715 0 0.00 0 12950 26 0 0
t$perf-norm:sp_reserve_write_off 9917 9917 0 0.00 0 11068 30 0 0
t$perf-norm:sp_supplier_invoice 5274 5274 0 0.00 0 8162 32 0 0
t$perf-norm:sp_supplier_order 5355 5355 0 0.00 0 7123 33 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 835 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 8424 125 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 1877 18 0 0
t$perf-norm:srv_make_money_saldo 920 920 0 0.00 0 1134 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 129 39 0 0
v_add_invoice_to_stock 10173 9741 432 4.24 0 7917 27 0 432
v_all_customers 36510 36510 0 0.00 0 131 4 0 0
v_all_suppliers 29893 29893 0 0.00 0 81 4 0 0
v_all_wares 39690 39690 0 0.00 0 69 4 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 599 16 0 0
v_cancel_client_order 3540 3540 0 0.00 0 2976 11 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 2226 15 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 1741 11 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 1323 13 0 0
v_cancel_supplier_invoice 760 720 40 5.26 0 6997 39 0 40
v_cancel_supplier_order 848 848 0 0.00 0 305 10 0 0
v_cancel_write_off 1988 1988 0 0.00 0 8997 26 0 0
v_max_id_clo_ord 5354 5354 0 0.00 0 748 5 0 0
v_max_id_clo_res 987 987 0 0.00 0 1217 13 0 0
v_max_id_ord_sup 5260 5260 0 0.00 0 4895 9 0 0
v_max_non_paid_invoice 1379 1379 0 0.00 0 58 8 0 0
v_max_non_paid_realizn 3562 3562 0 0.00 0 6329 8 0 0
v_min_id_clo_ord 5354 5354 0 0.00 0 5988 11 0 0
v_min_id_clo_res 987 987 0 0.00 0 1843 10 0 0
v_min_id_ord_sup 5260 5260 0 0.00 0 5968 12 0 0
v_min_non_paid_invoice 5714 5714 0 0.00 0 8420 19 0 0
v_min_non_paid_realizn 6945 6945 0 0.00 0 7101 18 0 0
v_our_firm 9940 9940 0 0.00 0 54 4 0 0
v_reserve_write_off 19828 19828 0 0.00 0 7171 10 0 0
x_lock_depdocs_on_canc_invoice 11624 11624 0 0.00 0 23819 132 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 3645 396 0 0
x_make_qstorno_1000_1200 5355 5355 0 0.00 2 35666 1269 0 0
x_make_qstorno_1200_2000 5273 5273 0 0.00 6 81513 3197 0 0
x_make_qstorno_2100_3300 462116 462116 0 0.00 0 107520 206 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-05-2022 13:39:45 to 19-05-2022 13:39:49.

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:17 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:17 44705 8192000000 9732721280 10031943680 805266224 8.950 100 90 180 0 835118864 8205328 21647312
11:10:09 22837 8192000000 9766774944 10076168192 840630992 9.306 100 89 178 0 877022016 15238576 21152448
11:11:37 10618 8192000000 9813693104 10139201536 876365152 9.663 100 83 166 0 928059824 32811184 18883488
11:12:53 10929 8192000000 9869601552 10206384128 917624880 10.073 100 76 150 1 982935792 49290000 16020912
11:14:07 5175 8192000000 9932832480 10278895616 960308544 10.492 100 85 169 0 1039321184 61420368 17592272
11:15:15 2099 8192000000 9978124944 10335055872 1007351200 10.950 100 94 185 2 1097228304 70906576 18970528
11:16:19 1058 8192000000 10010502816 10375036928 1032539040 11.193 100 96 182 4 1128701296 77040368 19121888
11:17:23 1165 8192000000 10026851968 10400972800 1054918240 11.408 100 93 182 2 1151703680 77836448 18948992
11:18:25 1768 8192000000 10056250032 10431848448 1065779728 11.512 100 95 186 0 1175328960 91166160 18383072
11:19:28 1436 8192000000 10057311648 10439802880 1076266448 11.612 100 97 191 1 1176860592 81402240 19191904
11:20:30 1089 8192000000 10048069408 10437201920 1083103904 11.677 100 87 169 1 1172530816 71278656 18148256
11:21:32 1261 8192000000 10066347232 10454937600 1089808784 11.741 100 91 175 0 1189276672 82901680 16566208
11:22:34 1226 8192000000 10072598608 10461392896 1093296864 11.774 100 96 187 2 1194637584 81540240 19800480
11:23:37 1171 8192000000 10067630320 10454798336 1099466704 11.833 100 97 184 0 1191089520 72759136 18863680
11:24:39 4319 8192000000 10056884864 10446966784 1101817312 11.855 100 69 134 1 1172452992 57884608 12751072
11:25:44 1354 8192000000 10068712768 10460286976 1100961504 11.847 100 89 166 2 1187508992 70359200 16188288
11:26:47 1020 8192000000 10077241008 10471677952 1100926496 11.846 100 94 183 1 1202061072 81727296 19407280
11:27:49 2828 8192000000 10054252464 10451648512 1100092864 11.839 100 70 134 0 1175087216 61075776 13918576
11:28:52 1136 8192000000 10075250416 10465013760 1104812000 11.883 100 97 188 0 1196410064 73133152 18464912
11:29:54 1208 8192000000 10028923728 10412322816 1072638048 11.577 99 90 172 0 1150861776 60473168 17750560
11:30:56 1068 8192000000 10037937840 10426892288 1074870992 11.599 99 87 165 2 1156878640 64969328 17038320
11:31:58 1289 8192000000 9982695024 10351214592 1031505200 11.183 98 87 161 1 1100185936 52992768 15687968
11:33:01 4195 8192000000 9971584752 10349920256 1023841392 11.109 98 68 128 2 1094449504 57354368 13253744
11:34:06 3560 8192000000 9983576704 10362671104 1033693328 11.204 100 73 141 0 1103654624 55656944 14304352
11:35:10 823 8192000000 9972433584 10348101632 1039391024 11.259 100 83 159 1 1092068736 35187696 17490016
11:36:11 1079 8192000000 9988125504 10364284928 1034946992 11.216 100 84 164 3 1109695600 58744784 16003824
11:37:13 1060 8192000000 9977952144 10356527104 1038485792 11.250 100 91 175 1 1103431216 45341008 19604416
11:38:23 2376 8192000000 10018835488 10391412736 1054295504 11.402 100 86 164 0 1131480000 60022624 17161872
11:39:32 1882 8192000000 10046173776 10429030400 1066312352 11.517 100 84 159 3 1162768304 80823728 15632224
11:40:40 16476 8192000000 10055716384 10446180352 1082426912 11.671 100 59 115 2 1169287536 75430320 11430304
11:42:00 913 8192000000 10063091568 10446565376 1093724144 11.778 100 96 184 1 1182401600 68971648 19705808
11:43:03 1779 8192000000 10120246544 10508173312 1109135728 11.924 100 82 160 1 1229686832 104451456 16099648
11:44:09 2513 8192000000 10143670176 10538405888 1119044672 12.018 100 76 151 1 1258017632 122649888 16323072
11:45:17 6726 8192000000 10157801872 10565251072 1134153696 12.161 100 66 121 3 1277922528 130969968 12798864
11:46:28 1773 8192000000 10223806432 10624294912 1156964976 12.375 100 92 178 2 1331489632 153840912 20683744
11:47:33 1525 8192000000 10240040064 10639548416 1165250784 12.452 100 89 176 0 1355917280 170000432 20666064
11:48:36 920 8192000000 10280225120 10690686976 1170459888 12.501 100 95 181 2 1391557152 199630736 21466528
11:49:39 5621 8192000000 10283499792 10690052096 1174053120 12.535 100 75 145 0 1401876368 211549888 16273360
11:50:49 3540 8192000000 10281748112 10704957440 1171113024 12.507 100 72 141 0 1397445616 210452576 15880016
11:51:59 1587 8192000000 10315708480 10732515328 1194342624 12.724 100 86 162 2 1422913792 210239904 18331264
11:53:02 1694 8192000000 10352406208 10766024704 1191583424 12.698 100 82 155 1 1460525520 251930496 17011600
11:54:09 1236 8192000000 10361919328 10790502400 1204137056 12.815 100 93 182 2 1470199376 244932128 21130192
11:55:14 1065 8192000000 10398549072 10800136192 1208252592 12.853 100 94 185 2 1501324256 272022320 21049344
11:56:17 3350 8192000000 10447077712 10856714240 1201917728 12.794 100 76 148 1 1551941952 331837680 18186544
11:57:28 2158 8192000000 10503433120 10916622336 1207253168 12.844 99 92 182 0 1601849376 372598688 21997520
11:58:34 1031 8192000000 10514979936 10919116800 1203208912 12.806 99 97 176 1 1620302016 394691840 22401264
11:59:38 1079 8192000000 10569073008 10985295872 1200025648 12.777 99 94 182 2 1676399792 453199776 23174368
12:00:42 832 8192000000 10564134816 10991247360 1178324704 12.575 100 94 180 1 1662890528 461290608 23275216
12:01:47 3212 8192000000 10576103856 11038105600 1198479696 12.762 100 75 144 1 1674458864 456634592 19344576
12:02:54 929 8192000000 10624552048 11057778688 1204502288 12.818 100 73 144 1 1726956496 504784368 17669840
12:04:02 4528 8192000000 10647074704 11067482112 1176064976 12.553 99 66 132 0 1734732432 540961376 17706080
12:05:09 4276 8192000000 10607001680 11111567360 1184707552 12.634 100 60 117 2 1714126816 514086496 15332768
12:06:15 18105 8192000000 10523365856 10982100992 1200240880 12.779 100 43 86 1 1648518784 437579008 10698896
12:07:34 18137 8192000000 10557229056 11012755456 1178235440 12.574 100 33 66 0 1645961488 458197616 9528432
12:09:04 20476 8192000000 10461855632 10961666048 1165464592 12.454 100 37 74 0 1582716112 408009824 9241696
12:10:27 19306 8192000000 10362811904 10859610112 1155634208 12.362 100 36 72 0 1485332592 319848064 9850320
12:12:02 59510 8192000000 10292484416 10764361728 1151967200 12.328 100 30 59 1 1411119168 251820080 7331888
12:14:04 30489 8192000000 10189468928 10802155520 1137037296 12.188 100 15 30 0 1317738944 177041904 3659744
12:15:36 76310 8192000000 10061334320 10486464512 1097899904 11.818 96 44 84 3 1121900352 15026704 8973744
12:17:54 67884 8192000000 10082877856 10515722240 1112277760 11.954 97 28 54 0 1149857648 32678304 4901584
12:20:48 49507 8192000000 10109769104 10545938432 1120309392 12.030 98 18 30 2 1176815904 53447520 3058992
12:22:41 79132 8192000000 10067411536 10548416512 1115912432 11.988 98 11 23 0 1210370240 91885088 2572720
12:25:07 154838 8192000000 10074020896 10542444544 1097686560 11.816 96 8 16 1 1155642352 56455792 1500000
12:28:54 54032 8192000000 10079309824 10541768704 1125981760 12.083 98 20 35 1 1161183104 31546336 3655008
12:31:04 85353 8192000000 10095048064 10545524736 1124109872 12.066 98 41 81 2 1146478800 14163232 8205696
12:33:29 114585 8192000000 10089940192 10530844672 1128243008 12.105 98 28 56 1 1170118544 36596272 5279264
12:36:25 13805 8192000000 10105606896 10549100544 1109845088 11.931 97 35 64 3 1192478688 75503376 7130224
12:37:40 43638 8192000000 10139041392 10573312000 1143089280 12.245 99 43 86 1 1213602944 60977264 9536400
12:39:26 17424 8192000000 10191961824 10655477760 1150597152 12.315 99 49 91 3 1284177472 123395888 10184432
12:41:01 44235 8192000000 10184045344 10649956352 1152835632 12.336 100 19 38 0 1260092464 102882048 4374784
12:42:50 55492 8192000000 10135348288 10611646464 1136231216 12.180 99 16 32 0 1244123488 104466640 3425632
12:44:55 79602 8192000000 10123841280 10607591424 1107832864 11.912 97 16 32 0 1196480816 84883408 3764544
12:47:20 57531 8192000000 10072177840 10523525120 1088129216 11.725 96 28 56 0 1129827952 36084512 5614224
12:49:21 59845 8192000000 10105776720 10545811456 1140225168 12.218 100 31 57 2 1189835152 44665312 4944672
12:51:24 32633 8192000000 10123911536 10561396736 1118648464 12.014 97 33 60 1 1167213936 41720624 6844848
12:53:03 107756 8192000000 10097858144 10576683008 1085010064 11.695 95 12 25 0 1152075120 64362016 2703040
12:55:51 88549 8192000000 10096322144 10574528512 1107650336 11.910 97 11 22 0 1169205472 59180544 2374592
12:58:24 90583 8192000000 10063349616 10569162752 1068538224 11.538 94 23 46 2 1159440272 86425872 4476176
13:00:55 118365 8192000000 10079016560 10528411648 1111266048 11.944 99 32 63 2 1190467472 71832016 7369408
13:03:54 82353 8192000000 10082439280 10543316992 1124136160 12.066 100 23 46 0 1179189680 50475440 4578080
13:06:17 38601 8192000000 10121999744 10597404672 1139051312 12.207 100 34 62 1 1238943328 93537920 6354096
13:08:38 68618 8192000000 10071323200 10532777984 1113706304 11.967 98 21 42 0 1146114576 28305904 4102368
13:10:47 60302 8192000000 10069725504 10530766848 1111739152 11.949 98 15 30 0 1155335408 40259456 3336800
13:12:48 113809 8192000000 10135264384 10585300992 1131567152 12.136 99 26 52 1 1222332288 85406416 5358720
13:16:03 55995 8192000000 10072983072 10540318720 1121727760 12.043 98 11 22 0 1167580880 43796352 2056768
13:17:59 53798 8192000000 10061339552 10501451776 1095930560 11.799 96 24 48 1 1134760976 34178080 4652336
13:20:16 21755 8192000000 10105135344 10541101056 1117191440 12.000 99 37 73 1 1189559312 65071696 7296176
13:21:43 59261 8192000000 10101740720 10583732224 1131468976 12.135 99 30 59 1 1218998608 81659824 5869808
13:23:47 29831 8192000000 10136237712 10570252288 1139967648 12.215 100 40 80 1 1226391104 78452112 7971344
13:25:17 87839 8192000000 10139038672 10626383872 1115301936 11.983 97 32 62 1 1185233056 63794496 6136624
13:27:47 26124 8192000000 10088643712 10535346176 1105013600 11.885 97 37 74 3 1158138704 46206240 6918864
13:29:16 61439 8192000000 10101407024 10568507392 1128172320 12.104 99 35 70 1 1208097664 72698224 7227120
13:31:51 28720 8192000000 10074488832 10547560448 1141483856 12.229 100 35 69 2 1185232320 36627648 7120816
13:33:20 69853 8192000000 10064281536 10539745280 1106662624 11.901 97 10 20 0 1147307776 38513280 2131872
13:35:45 95716 8192000000 9802795280 10249547776 878433840 9.684 77 16 32 0 947418336 65478880 3505616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-05-2022 13:39:51 to 19-05-2022 13:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 216 v_add_invoice_to_stock 335544517
except 20 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-05-2022 13:40:07 to 19-05-2022 13:40:09.

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          222601
MON$OLDEST_ACTIVE               222602
MON$OLDEST_SNAPSHOT             222602
MON$NEXT_TRANSACTION            222602
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                       2097536
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2766 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2766 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2766 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2766 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 ---
:44: 
:45: mscs-test-161.budzdorov.ru    Thu May 19 13:37:40 2022
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Thu May 19 13:38:09 2022
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
--- end of diff output ---

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

19.05.22 13:40:10. 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-19 13:40:13 No severe PSQL-related problems occured
19.05.22 13:40:14. End of report.