Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.03.2023 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 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 14870076 kB
MemAvailable 30414436 kB
Buffers 0 kB
Cached 16823372 kB
SwapCached 3028 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.3
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 2023-03-05 13:37:25
DTS_END                         2023-03-05 13:37:25.8540
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/34068
TRN_ID                          212647
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: 8596.45 33009 1031574
customer order: creation 156.11 516 18733
customer order: refuse 14.12 480 1694
order to supplier: creation 43.82 1382 5258
order to supplier: removal 3.02 208552 362
invoice (draft): creation 42.79 3761 5135
invoice (draft): removal 48.61 1151 5833
invoice accept: apply 37.85 28262 4542
invoice accept: cancel 43.78 1441 5254
customer reserve: creation 3605.28 260 432634
customer reserve: removal 4328.26 113 519391
realization accept: apply 77.53 293 9304
realization accept: cancel 75.22 179 9027
payment from customer: creation 55.00 197 6600
payment from customer: removal 5.19 129 623
payment to supplier: creation 44.73 195 5368
payment to supplier: removal 3.42 162 410
service: total inventory turnovers 3.48 4666 418
service: total monetary turnovers 7.33 337 880
service: aggregate perf. data 0.87 14272 104
service: refresh index statistics 0.03 393835 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-03-2023 13:38:50 to 05-03-2023 13:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5244
TEST_TIME 2 4573
TEST_TIME 3 5011
TEST_TIME 4 4776
TEST_TIME 5 4980
TEST_TIME 6 5173
TEST_TIME 7 5141
TEST_TIME 8 5353
TEST_TIME 9 5448
TEST_TIME 10 5609
TEST_TIME 11 5694
TEST_TIME 12 5932
TEST_TIME 13 6295
TEST_TIME 14 6287
TEST_TIME 15 6521
TEST_TIME 16 6545
TEST_TIME 17 6474
TEST_TIME 18 6448
TEST_TIME 19 6425
TEST_TIME 20 6482
TEST_TIME 21 6600
TEST_TIME 22 6830
TEST_TIME 23 7504
TEST_TIME 24 7581
TEST_TIME 25 7593
TEST_TIME 26 7728
TEST_TIME 27 7945
TEST_TIME 28 8184
TEST_TIME 29 8299
TEST_TIME 30 8866
TEST_TIME 31 9224
TEST_TIME 32 9327
TEST_TIME 33 9537
TEST_TIME 34 9774
TEST_TIME 35 9621
TEST_TIME 36 9856
TEST_TIME 37 10336
TEST_TIME 38 10811
TEST_TIME 39 10618
TEST_TIME 40 10473
TEST_TIME 41 10284
TEST_TIME 42 10216
TEST_TIME 43 10086
TEST_TIME 44 10028
TEST_TIME 45 10003
TEST_TIME 46 9977
TEST_TIME 47 9887
TEST_TIME 48 9766
TEST_TIME 49 9683
TEST_TIME 50 9694
TEST_TIME 51 9642
TEST_TIME 52 9539
TEST_TIME 53 9522
TEST_TIME 54 9359
TEST_TIME 55 9305
TEST_TIME 56 9202
TEST_TIME 57 9239
TEST_TIME 58 9205
TEST_TIME 59 9160
TEST_TIME 60 9067
TEST_TIME 61 8986
TEST_TIME 62 8929
TEST_TIME 63 8971
TEST_TIME 64 8908
TEST_TIME 65 8874
TEST_TIME 66 8839
TEST_TIME 67 8774
TEST_TIME 68 8740
TEST_TIME 69 8678
TEST_TIME 70 8675
TEST_TIME 71 8684
TEST_TIME 72 8674
TEST_TIME 73 8649
TEST_TIME 74 8818
TEST_TIME 75 8882
TEST_TIME 76 9000
TEST_TIME 77 8937
TEST_TIME 78 8965
TEST_TIME 79 9015
TEST_TIME 80 9021
TEST_TIME 81 9031
TEST_TIME 82 9087
TEST_TIME 83 9002
TEST_TIME 84 8934
TEST_TIME 85 8902
TEST_TIME 86 8877
TEST_TIME 87 8845
TEST_TIME 88 8798
TEST_TIME 89 8826
TEST_TIME 90 8844
TEST_TIME 91 8786
TEST_TIME 92 8783
TEST_TIME 93 8797
TEST_TIME 94 8756
TEST_TIME 95 8769
TEST_TIME 96 8791
TEST_TIME 97 8815
TEST_TIME 98 8790
TEST_TIME 99 8864
TEST_TIME 100 8889
TEST_TIME 101 8873
TEST_TIME 102 8897
TEST_TIME 103 8858
TEST_TIME 104 8900
TEST_TIME 105 8873
TEST_TIME 106 8875
TEST_TIME 107 8801
TEST_TIME 108 8809
TEST_TIME 109 8795
TEST_TIME 110 8757
TEST_TIME 111 8706
TEST_TIME 112 8694
TEST_TIME 113 8705
TEST_TIME 114 8702
TEST_TIME 115 8684
TEST_TIME 116 8718
TEST_TIME 117 8692
TEST_TIME 118 8673
TEST_TIME 119 8671
TEST_TIME 120 8621

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-03-2023 13:38:54 to 05-03-2023 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 1429413 1429413 0 0.00 0 24196 14 0 0
doc_list_biud 1429397 1429397 0 0.00 0 46628 48 0 0
sp_add_invoice_to_stock 4646 4542 104 2.23 68 204730 28262 0 104
sp_cancel_adding_invoice 5254 5254 0 0.00 6 97466 1441 0 0
sp_cancel_client_order 1694 1694 0 0.00 5 13946 480 0 0
sp_cancel_customer_reserve 519391 519391 0 0.00 1 34281 114 0 0
sp_cancel_pay_from_customer 623 623 0 0.00 4 3690 129 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 4 4953 163 0 0
sp_cancel_supplier_invoice 5843 5833 10 0.17 2 20587 1151 0 10
sp_cancel_supplier_order 362 362 0 0.00 15 1806748 208552 0 0
sp_cancel_write_off 9027 9027 0 0.00 2 9173 180 0 0
sp_client_order 18733 18733 0 0.00 5 26043 516 0 0
sp_customer_reserve 432634 432634 0 0.00 0 51852 260 0 0
sp_fill_shopping_cart 29115 29115 0 0.00 1 26343 94 0 0
sp_fill_shopping_cart_clo_res 432595 432595 0 0.00 0 18972 22 0 0
sp_get_clo_for_invoice 4494 4494 0 0.00 0 39131 348 0 0
sp_kill_cost_storno 15314 15314 0 0.00 0 7088 82 0 0
sp_kill_qstorno_ret_qs2qd 1054004 1054004 0 0.00 0 34145 49 0 0
sp_kill_qty_storno 555356 555356 0 0.00 0 46620 114 0 0
sp_lock_selected_doc 19271 19271 0 0.00 0 9142 7 0 0
sp_make_cost_storno 25765 25765 0 0.00 0 11185 79 0 0
sp_multiply_rows_for_pdistr 25763 25763 0 0.00 0 24186 42 0 0
sp_multiply_rows_for_qdistr 18732 18732 0 0.00 0 26010 206 0 0
sp_pay_from_customer 6600 6600 0 0.00 5 24201 197 0 0
sp_pay_to_supplier 5368 5368 0 0.00 7 7995 195 0 0
sp_payment_common 11968 11968 0 0.00 2 6979 47 0 0
sp_qd_handle_on_cancel_clo 1694 1694 0 0.00 1 13930 396 0 0
sp_qd_handle_on_invoice_upd_sts 9745 9745 0 0.00 0 46616 611 0 0
sp_qd_handle_on_reserve_upd_sts 9304 9304 0 0.00 0 20180 77 0 0
sp_reserve_write_off 9304 9304 0 0.00 8 20427 293 0 0
sp_supplier_invoice 5135 5135 0 0.00 10 117622 3761 0 0
sp_supplier_order 5258 5258 0 0.00 7 36036 1383 0 0
srv_aggregate_perf_data 104 104 0 0.00 2523 118261 14272 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 740 101164 35174 0 0
srv_find_qd_qs_mism 985895 985895 0 0.00 0 17695 10 0 0
srv_make_invnt_saldo 418 418 0 0.00 82 90109 4667 0 0
srv_make_money_saldo 880 880 0 0.00 1 7314 338 0 0
srv_recalc_idx_stat 4 4 0 0.00 173758 645267 393836 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4546 4546 0 0.00 0 47542 1015 0 0
t$perf-norm:sp_cancel_adding_invoice 355 355 0 0.00 2 7738 531 0 0
t$perf-norm:sp_cancel_client_order 1694 1694 0 0.00 0 3821 32 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 975 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 623 623 0 0.00 0 1025 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 1043 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 5128 49 0 0
t$perf-norm:sp_cancel_supplier_order 362 362 0 0.00 0 111696 9338 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 415 15 0 0
t$perf-norm:sp_client_order 18734 18734 0 0.00 0 7695 39 0 0
t$perf-norm:sp_customer_reserve 903 903 0 0.00 0 971 13 0 0
t$perf-norm:sp_pay_from_customer 6600 6600 0 0.00 0 10871 21 0 0
t$perf-norm:sp_pay_to_supplier 5368 5368 0 0.00 0 6251 21 0 0
t$perf-norm:sp_reserve_write_off 9304 9304 0 0.00 0 3288 23 0 0
t$perf-norm:sp_supplier_invoice 5135 5135 0 0.00 0 2647 30 0 0
t$perf-norm:sp_supplier_order 5258 5258 0 0.00 0 6773 32 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 540 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 364 17 0 0
t$perf-norm:srv_make_invnt_saldo 418 418 0 0.00 0 4176 27 0 0
t$perf-norm:srv_make_money_saldo 880 880 0 0.00 0 1053 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 602 178 0 0
v_add_invoice_to_stock 9178 8970 208 2.26 0 6815 29 0 208
v_all_customers 34616 34616 0 0.00 0 345 4 0 0
v_all_suppliers 28955 28955 0 0.00 0 84 5 0 0
v_all_wares 37460 37460 0 0.00 0 97 4 0 0
v_cancel_adding_invoice 710 710 0 0.00 0 3521 21 0 0
v_cancel_client_order 3388 3388 0 0.00 0 8222 16 0 0
v_cancel_customer_prepayment 1246 1246 0 0.00 0 1315 12 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 1 522 12 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 1557 14 0 0
v_cancel_supplier_invoice 818 798 20 2.44 0 2627 33 0 20
v_cancel_supplier_order 724 724 0 0.00 0 898 14 0 0
v_cancel_write_off 1926 1926 0 0.00 0 4680 25 0 0
v_max_id_clo_ord 5257 5257 0 0.00 0 372 5 0 0
v_max_id_clo_res 903 903 0 0.00 0 2574 22 0 0
v_max_id_ord_sup 5127 5127 0 0.00 0 9790 13 0 0
v_max_non_paid_invoice 1274 1274 0 0.00 0 125 8 0 0
v_max_non_paid_realizn 3351 3351 0 0.00 0 6382 11 0 0
v_min_id_clo_ord 5257 5257 0 0.00 0 5684 11 0 0
v_min_id_clo_res 903 903 0 0.00 0 2231 13 0 0
v_min_id_ord_sup 5127 5127 0 0.00 0 8744 11 0 0
v_min_non_paid_invoice 5368 5368 0 0.00 0 3609 14 0 0
v_min_non_paid_realizn 6600 6600 0 0.00 0 6969 19 0 0
v_our_firm 9340 9340 0 0.00 0 76 5 0 0
v_reserve_write_off 18608 18608 0 0.00 0 5371 13 0 0
x_lock_depdocs_on_canc_invoice 10688 10688 0 0.00 0 19191 148 0 0
x_lock_depdocs_on_canc_sup_ord 362 362 0 0.00 0 3639 446 0 0
x_make_qstorno_1000_1200 5258 5258 0 0.00 2 36023 1202 0 0
x_make_qstorno_1200_2000 5135 5135 0 0.00 6 105522 3481 0 0
x_make_qstorno_2100_3300 432631 432631 0 0.00 0 51815 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-03-2023 13:38:58 to 05-03-2023 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:29 3 8192000000 8800311440 8811962368 5977776 0.072 3 1 2 0 6079424 65872 35776
11:08:30 19260 8192000000 9726180064 10025975808 797463168 8.871 100 84 168 0 824653056 7650208 19539680
11:09:56 16715 8192000000 9763371904 10071281664 825842800 9.157 100 85 170 0 857961712 12134208 19984704
11:11:16 23659 8192000000 9790430592 10109943808 855445264 9.455 100 87 174 0 894945952 19682176 19818512
11:12:46 6566 8192000000 9851282288 10182627328 888710128 9.786 100 83 161 1 958215952 51768192 17737632
11:13:56 6914 8192000000 9893193328 10229112832 920215616 10.098 100 85 168 0 994594784 55590592 18788576
11:15:06 3976 8192000000 9944864160 10296115200 961621600 10.505 100 91 182 0 1053723088 72324608 19776880
11:16:14 2068 8192000000 9993719488 10351591424 995720336 10.837 100 96 182 0 1102856048 88746352 18389360
11:17:18 935 8192000000 9988664576 10355412992 1015223808 11.026 100 94 173 1 1094225344 61818400 17183136
11:18:21 1287 8192000000 10025892960 10399875072 1037689168 11.242 100 94 178 0 1133628192 77718304 18220720
11:19:23 981 8192000000 10038508576 10413015040 1054023776 11.399 100 97 189 1 1149622336 76413264 19185296
11:20:28 1237 8192000000 10045014128 10425761792 1061040352 11.466 100 93 178 1 1155408016 75859712 18507952
11:21:31 2811 8192000000 10065802240 10454372352 1061862160 11.474 100 76 141 4 1174408688 98801584 13744944
11:22:34 933 8192000000 10063425424 10449039360 1067992896 11.533 100 92 185 0 1173461376 87896736 17571744
11:23:36 1187 8192000000 10058058928 10446901248 1073261824 11.583 100 91 171 1 1175046176 83640000 18144352
11:24:38 1107 8192000000 10063543952 10452725760 1079326944 11.641 100 96 182 1 1178825424 80794976 18703504
11:25:41 738 8192000000 10069439552 10460577792 1083342016 11.679 100 97 189 4 1184663824 81326256 19995552
11:26:43 1151 8192000000 10078495840 10468220928 1088245120 11.726 100 95 184 1 1189337888 82620784 18471984
11:27:46 797 8192000000 10058854944 10456731648 1085733376 11.702 100 79 154 0 1168333360 66582544 16017440
11:28:49 1475 8192000000 10071960480 10465611776 1082521360 11.671 100 81 154 2 1182942480 85309856 15111264
11:29:52 1147 8192000000 10042470304 10433675264 1086906384 11.713 100 91 174 2 1152241664 48146352 17188928
11:30:54 1388 8192000000 10011854032 10404069376 1071028176 11.562 100 68 119 3 1124095552 40028448 13038928
11:31:57 1787 8192000000 10021260336 10410582016 1050702112 11.367 100 67 125 1 1132307328 69420656 12184560
11:33:00 1282 8192000000 10013362992 10386501632 1032847312 11.196 100 90 172 1 1123029344 72016976 18165056
11:34:02 1109 8192000000 10001509408 10379796480 1046973856 11.332 100 94 169 4 1112862176 47478448 18409872
11:35:06 1078 8192000000 9984133696 10352984064 1020038896 11.072 100 96 182 1 1094523856 56266288 18218672
11:36:09 1121 8192000000 10011955840 10389118976 1033423888 11.201 100 92 167 0 1124218368 74004608 16789872
11:37:11 1058 8192000000 9974697168 10351325184 1025412432 11.124 100 73 141 2 1082416672 42168912 14835328
11:38:20 27386 8192000000 10000422032 10382139392 1034292032 11.210 100 71 137 2 1116202480 68128912 13781536
11:39:56 8085 8192000000 10029349392 10426535936 1053754336 11.397 100 63 123 0 1147826944 80680144 13392464
11:41:10 3729 8192000000 10039871840 10422038528 1069547280 11.548 100 65 120 1 1157923088 76953184 11422624
11:42:23 1350 8192000000 10110849920 10488193024 1085625744 11.701 100 96 185 2 1220547120 115770608 19150768
11:43:28 3361 8192000000 10113791104 10507399168 1098769328 11.826 100 73 145 0 1227473600 112740240 15964032
11:44:37 4100 8192000000 10168649664 10563444736 1112250064 11.954 100 84 151 0 1276817664 148488352 16079248
11:45:58 2797 8192000000 10200165984 10596352000 1127866512 12.101 100 83 154 1 1315407760 172149776 15391472
11:47:05 3238 8192000000 10241021856 10634420224 1138982592 12.206 100 85 165 1 1358893104 202709088 17201424
11:48:12 1604 8192000000 10281185024 10676768768 1153710032 12.344 100 93 168 0 1396498560 223566992 19221536
11:49:18 1740 8192000000 10302851776 10703855616 1165444384 12.454 100 93 178 1 1417636624 232320928 19871312
11:50:23 1452 8192000000 10352047264 10761461760 1171061280 12.507 100 94 180 0 1464126336 273762512 19302544
11:51:30 1373 8192000000 10351466784 10751496192 1185835136 12.645 100 91 174 0 1464425728 258034080 20556512
11:52:34 2137 8192000000 10395919184 10803015680 1189265952 12.677 100 87 167 1 1505055392 297334912 18454528
11:53:40 735 8192000000 10468598624 10867982336 1198685184 12.764 100 97 187 1 1576202400 356389024 21128192
11:54:43 1527 8192000000 10491431936 10892869632 1186113264 12.647 100 93 182 0 1599491744 391666240 21712240
11:55:48 776 8192000000 10529589456 10933051392 1184686048 12.634 100 93 182 0 1635715616 429072160 21957408
11:56:54 1275 8192000000 10571339344 10987089920 1196731520 12.746 100 100 193 0 1677680416 458170672 22778224
11:57:57 1575 8192000000 10576796784 11012444160 1185058832 12.637 99 96 188 1 1681932528 473674912 23198784
11:59:03 1054 8192000000 10602496896 11061751808 1192383968 12.706 100 95 186 0 1708288608 492238960 23665680
12:00:09 5069 8192000000 10583300064 11051044864 1189416064 12.678 100 66 132 0 1688160752 482045184 16699504
12:01:19 11292 8192000000 10511730480 10973425664 1173811648 12.532 100 46 90 0 1619992608 433508288 12672672
12:02:40 804 8192000000 10584771776 11013787648 1183792160 12.626 100 95 188 0 1688017616 481493280 22732176
12:03:43 737 8192000000 10593958480 11049480192 1190509536 12.688 100 85 170 0 1699281856 488118816 20653504
12:04:48 15448 8192000000 10564797104 11040215040 1178630912 12.577 100 48 94 1 1676562272 484673168 13258192
12:06:12 4052 8192000000 10489849584 11032612864 1175757744 12.551 100 58 114 0 1611978224 421473520 14746960
12:07:20 941 8192000000 10468890096 10991128576 1185264976 12.639 100 90 171 0 1570871344 365476448 20129920
12:08:26 3515 8192000000 10457095760 10938650624 1176773728 12.560 100 98 193 3 1557514304 357367728 23372848
12:09:32 21456 8192000000 10429838256 10916270080 1183232656 12.620 100 51 100 0 1529151504 332962928 12955920
12:11:03 25879 8192000000 10418328144 10909925376 1166322400 12.462 100 29 56 0 1527921808 354101312 7498096
12:12:55 26170 8192000000 10365910928 10893422592 1141214512 12.227 99 21 43 0 1470471312 323445728 5811072
12:14:23 43884 8192000000 10168365200 10694684672 1129101728 12.113 99 29 58 1 1250467472 115069168 6296576
12:16:12 7511 8192000000 10118129200 10541568000 1145327648 12.266 100 71 137 0 1212284912 51573104 15384160
12:17:20 82692 8192000000 10146367168 10594689024 1126553184 12.089 99 60 101 2 1238666000 102598112 9514704
12:19:47 35392 8192000000 10153724688 10599735296 1145579312 12.268 99 31 55 2 1245794800 93868048 6347440
12:22:07 79959 8192000000 10106121088 10595684352 1137982960 12.197 99 18 36 0 1202077968 60235456 3859552
12:24:28 79736 8192000000 10108370096 10570379264 1139233168 12.208 99 21 42 0 1190208672 46374928 4600576
12:28:00 79764 8192000000 10115489760 10600529920 1133706896 12.156 99 20 40 0 1190258240 52008480 4542864
12:30:42 86132 8192000000 10060194320 10536321024 1092128608 11.763 96 24 47 0 1130580240 33964048 4487584
12:33:10 21560 8192000000 10100511600 10540732416 1130581808 12.127 99 55 104 2 1192908944 51263472 11063664
12:34:38 84304 8192000000 10099078224 10574807040 1119620496 12.023 98 22 44 0 1181663632 57045696 4997440
12:37:03 60436 8192000000 10076190128 10544275456 1102637968 11.863 98 14 28 0 1169873648 64419792 2815888
12:39:04 101132 8192000000 10075147968 10530959360 1126903536 12.092 100 20 40 0 1213388864 82244016 4241312
12:41:46 51460 8192000000 10076864768 10536783872 1132541680 12.145 100 12 25 0 1176630928 41628480 2460768
12:43:59 86043 8192000000 10121046400 10579533824 1125330320 12.077 98 13 26 1 1189472432 61721520 2420592
12:46:34 28952 8192000000 10131172640 10577133568 1120695296 12.034 97 27 52 0 1199479056 72669216 6114544
12:48:12 38680 8192000000 10137206896 10578333696 1121390320 12.040 98 32 64 0 1218831040 90533936 6906784
12:50:04 9172 8192000000 10158976768 10599018496 1134626272 12.165 100 45 87 0 1262469280 119075664 8767344
12:51:14 36817 8192000000 10164093696 10652884992 1126053328 12.084 99 38 76 0 1306546928 172366032 8127568
12:52:52 63263 8192000000 10069761040 10550571008 1113146432 11.962 99 19 36 1 1166891024 49831392 3913200
12:55:09 68532 8192000000 10122380336 10592493568 1091175440 11.754 97 32 63 0 1197155072 99425248 6554384
12:57:22 59502 8192000000 10102128992 10586075136 1126869008 12.092 100 17 34 0 1207792544 77266928 3656608
12:59:26 91258 8192000000 10043887600 10503168000 1111595904 11.948 98 21 44 0 1147605264 31431328 4578032
13:02:23 61215 8192000000 10139932704 10584719360 1134904448 12.168 100 39 78 0 1245382448 101916832 8561168
13:04:39 101164 8192000000 10131532576 10593800192 1119590480 12.023 98 20 39 0 1236250912 112302896 4357536
13:07:21 55853 8192000000 10103450624 10570706944 1129563984 12.117 99 13 26 0 1208556400 76065040 2927376
13:09:22 64762 8192000000 10100159856 10563411968 1105549776 11.890 98 12 24 0 1178497088 70332864 2614448
13:11:29 23905 8192000000 10100896352 10543992832 1127817920 12.101 99 34 65 0 1192262912 57806672 6638320
13:12:54 57162 8192000000 10090108000 10565001216 1085622400 11.701 96 29 57 0 1180389344 88336224 6430720
13:14:56 43203 8192000000 10104444528 10542243840 1117226592 12.001 99 34 66 1 1186659168 62827952 6604624
13:16:43 46530 8192000000 10085731136 10542374912 1111375616 11.945 99 25 44 0 1169662096 53819952 4466528
13:18:34 61644 8192000000 10066463632 10503917568 1087551856 11.719 96 29 56 0 1140882944 46028672 7302416
13:20:36 28227 8192000000 10110524784 10551136256 1122037392 12.046 99 41 80 0 1184582992 54525168 8020432
13:22:09 34145 8192000000 10120082000 10569908224 1119310016 12.020 99 47 94 0 1196525024 67891008 9324000
13:23:54 97804 8192000000 10096094736 10562420736 1124791840 12.072 99 38 73 1 1198111616 66095056 7224720
13:26:50 66418 8192000000 10091677744 10544410624 1127821552 12.101 100 18 37 0 1208693328 77190416 3681360
13:29:00 57432 8192000000 10091537136 10579091456 1123524976 12.060 99 18 36 1 1189997872 62243760 4229136
13:31:17 56428 8192000000 10072919888 10551824384 1098627664 11.825 97 22 44 0 1137432480 33987056 4817760
13:33:15 64480 8192000000 10051595360 10531250176 1117227664 12.001 99 16 32 0 1198958576 78206304 3524608
13:35:19 32681 8192000000 10117620960 10554634240 1124074160 12.065 99 34 68 0 1191191616 59865216 7252240
13:37:01 27986 8192000000 9818464080 10214187008 847120608 9.371 75 37 72 0 957949776 103204656 7624512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 05-03-2023 13:39:04 to 05-03-2023 13:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 05-03-2023 13:39:21 to 05-03-2023 13:39:24.

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          206659
MON$OLDEST_ACTIVE               212931
MON$OLDEST_SNAPSHOT             212931
MON$NEXT_TRANSACTION            212931
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                       2083424
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             762
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2906 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2906 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2906 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2906 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Sun Mar  5 13:37:41 2023
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Sun Mar  5 13:37:42 2023
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Sun Mar  5 13:37:50 2023
: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'

05.03.23 13:39:25. 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
2023-03-05 13:39:27 No severe PSQL-related problems occured
05.03.23 13:39:29. End of report.