Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.05.2022 07:48
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 16298736 kB
MemAvailable 31433728 kB
Buffers 0 kB
Cached 15440976 kB
SwapCached 2544 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-25 07:46:47
DTS_END                         2022-05-25 07:46:47.5140
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/36120
TRN_ID                          215641
ATT_ID                          616
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: 9070.19 27179 1088423
customer order: creation 165.52 474 19862
customer order: refuse 13.33 383 1600
order to supplier: creation 43.75 1410 5250
order to supplier: removal 3.35 166473 402
invoice (draft): creation 43.15 3254 5178
invoice (draft): removal 52.02 921 6242
invoice accept: apply 40.74 27069 4889
invoice accept: cancel 47.18 1178 5662
customer reserve: creation 3771.63 258 452595
customer reserve: removal 4602.91 95 552349
realization accept: apply 81.08 280 9730
realization accept: cancel 82.16 161 9859
payment from customer: creation 56.56 181 6787
payment from customer: removal 5.17 131 620
payment to supplier: creation 46.11 201 5533
payment to supplier: removal 3.28 133 394
service: total inventory turnovers 3.85 4193 462
service: total monetary turnovers 7.53 344 903
service: aggregate perf. data 0.86 17113 103
service: refresh index statistics 0.03 319336 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-05-2022 07:48:48 to 25-05-2022 07:48:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3462
TEST_TIME 2 3622
TEST_TIME 3 5270
TEST_TIME 4 4532
TEST_TIME 5 4818
TEST_TIME 6 4702
TEST_TIME 7 4808
TEST_TIME 8 5383
TEST_TIME 9 6089
TEST_TIME 10 6081
TEST_TIME 11 6059
TEST_TIME 12 6115
TEST_TIME 13 6135
TEST_TIME 14 6276
TEST_TIME 15 6303
TEST_TIME 16 6506
TEST_TIME 17 6537
TEST_TIME 18 6481
TEST_TIME 19 6559
TEST_TIME 20 6781
TEST_TIME 21 6971
TEST_TIME 22 7126
TEST_TIME 23 7405
TEST_TIME 24 7720
TEST_TIME 25 7906
TEST_TIME 26 8088
TEST_TIME 27 8159
TEST_TIME 28 8942
TEST_TIME 29 9159
TEST_TIME 30 9512
TEST_TIME 31 9658
TEST_TIME 32 9911
TEST_TIME 33 10142
TEST_TIME 34 10512
TEST_TIME 35 10625
TEST_TIME 36 10757
TEST_TIME 37 10648
TEST_TIME 38 10482
TEST_TIME 39 10330
TEST_TIME 40 10084
TEST_TIME 41 10324
TEST_TIME 42 10196
TEST_TIME 43 10039
TEST_TIME 44 9988
TEST_TIME 45 9945
TEST_TIME 46 9878
TEST_TIME 47 9890
TEST_TIME 48 9811
TEST_TIME 49 9752
TEST_TIME 50 9712
TEST_TIME 51 9668
TEST_TIME 52 9627
TEST_TIME 53 9752
TEST_TIME 54 9722
TEST_TIME 55 9725
TEST_TIME 56 9723
TEST_TIME 57 9742
TEST_TIME 58 9753
TEST_TIME 59 9726
TEST_TIME 60 9750
TEST_TIME 61 9777
TEST_TIME 62 9840
TEST_TIME 63 9890
TEST_TIME 64 9926
TEST_TIME 65 9934
TEST_TIME 66 9892
TEST_TIME 67 9753
TEST_TIME 68 9716
TEST_TIME 69 9725
TEST_TIME 70 9701
TEST_TIME 71 9626
TEST_TIME 72 9641
TEST_TIME 73 9631
TEST_TIME 74 9640
TEST_TIME 75 9565
TEST_TIME 76 9499
TEST_TIME 77 9468
TEST_TIME 78 9418
TEST_TIME 79 9480
TEST_TIME 80 9509
TEST_TIME 81 9447
TEST_TIME 82 9535
TEST_TIME 83 9501
TEST_TIME 84 9532
TEST_TIME 85 9560
TEST_TIME 86 9521
TEST_TIME 87 9562
TEST_TIME 88 9612
TEST_TIME 89 9572
TEST_TIME 90 9594
TEST_TIME 91 9534
TEST_TIME 92 9476
TEST_TIME 93 9420
TEST_TIME 94 9413
TEST_TIME 95 9374
TEST_TIME 96 9320
TEST_TIME 97 9274
TEST_TIME 98 9295
TEST_TIME 99 9332
TEST_TIME 100 9302
TEST_TIME 101 9305
TEST_TIME 102 9276
TEST_TIME 103 9290
TEST_TIME 104 9290
TEST_TIME 105 9351
TEST_TIME 106 9331
TEST_TIME 107 9305
TEST_TIME 108 9400
TEST_TIME 109 9419
TEST_TIME 110 9466
TEST_TIME 111 9400
TEST_TIME 112 9329
TEST_TIME 113 9299
TEST_TIME 114 9296
TEST_TIME 115 9255
TEST_TIME 116 9237
TEST_TIME 117 9207
TEST_TIME 118 9166
TEST_TIME 119 9149
TEST_TIME 120 9088

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-05-2022 07:48:51 to 25-05-2022 07:48:55.

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 1505372 1505372 0 0.00 0 18238 13 0 0
doc_list_biud 1505362 1505362 0 0.00 0 24074 41 0 0
sp_add_invoice_to_stock 5040 4889 151 2.99 26 232247 27070 0 151
sp_cancel_adding_invoice 5662 5662 0 0.00 7 93836 1178 0 0
sp_cancel_client_order 1600 1600 0 0.00 5 6647 384 0 0
sp_cancel_customer_reserve 552349 552349 0 0.00 1 24096 96 0 0
sp_cancel_pay_from_customer 620 620 0 0.00 3 5145 131 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 4 5654 134 0 0
sp_cancel_supplier_invoice 6255 6242 13 0.20 3 17055 922 0 13
sp_cancel_supplier_order 402 402 0 0.00 9 1452314 166474 0 0
sp_cancel_write_off 9859 9859 0 0.00 2 9600 162 0 0
sp_client_order 19862 19862 0 0.00 6 33032 475 0 0
sp_customer_reserve 452595 452595 0 0.00 0 134953 259 0 0
sp_fill_shopping_cart 30277 30277 0 0.00 1 38114 83 0 0
sp_fill_shopping_cart_clo_res 452565 452565 0 0.00 0 17581 19 0 0
sp_get_clo_for_invoice 4845 4845 0 0.00 0 19570 284 0 0
sp_kill_cost_storno 16535 16535 0 0.00 0 8381 67 0 0
sp_kill_qstorno_ret_qs2qd 1121201 1121201 0 0.00 0 24050 42 0 0
sp_kill_qty_storno 590685 590685 0 0.00 0 24072 96 0 0
sp_lock_selected_doc 20019 20019 0 0.00 0 6426 8 0 0
sp_make_cost_storno 26893 26893 0 0.00 0 18226 83 0 0
sp_multiply_rows_for_pdistr 26891 26891 0 0.00 0 8916 40 0 0
sp_multiply_rows_for_qdistr 19862 19862 0 0.00 0 18137 172 0 0
sp_pay_from_customer 6787 6787 0 0.00 5 17427 181 0 0
sp_pay_to_supplier 5533 5533 0 0.00 6 13027 202 0 0
sp_payment_common 12319 12319 0 0.00 2 10167 46 0 0
sp_qd_handle_on_cancel_clo 1600 1600 0 0.00 0 6627 304 0 0
sp_qd_handle_on_invoice_upd_sts 10504 10504 0 0.00 0 20936 502 0 0
sp_qd_handle_on_reserve_upd_sts 9729 9729 0 0.00 0 16688 67 0 0
sp_reserve_write_off 9730 9730 0 0.00 9 16741 281 0 0
sp_supplier_invoice 5178 5178 0 0.00 9 124807 3255 0 0
sp_supplier_order 5250 5250 0 0.00 8 46456 1410 0 0
srv_aggregate_perf_data 103 103 0 0.00 2487 178495 17113 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 710 150209 38699 0 0
srv_find_qd_qs_mism 1041373 1041373 0 0.00 0 15666 10 0 0
srv_make_invnt_saldo 462 462 0 0.00 54 81628 4194 0 0
srv_make_money_saldo 903 903 0 0.00 2 12387 345 0 0
srv_recalc_idx_stat 3 3 0 0.00 191565 454558 319337 0 0
t$perf-abend:sp_add_invoice_to_stock 151 151 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 4889 4889 0 0.00 1 15961 949 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 1 4607 450 0 0
t$perf-norm:sp_cancel_client_order 1600 1600 0 0.00 0 11208 34 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 1259 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 620 620 0 0.00 0 1044 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 1737 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 2524 39 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 104504 7046 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 393 15 0 0
t$perf-norm:sp_client_order 19862 19862 0 0.00 0 5326 37 0 0
t$perf-norm:sp_customer_reserve 980 980 0 0.00 0 523 14 0 0
t$perf-norm:sp_pay_from_customer 6787 6787 0 0.00 0 7266 21 0 0
t$perf-norm:sp_pay_to_supplier 5533 5533 0 0.00 0 2986 19 0 0
t$perf-norm:sp_reserve_write_off 9730 9730 0 0.00 0 4969 23 0 0
t$perf-norm:sp_supplier_invoice 5178 5178 0 0.00 0 3067 32 0 0
t$perf-norm:sp_supplier_order 5250 5250 0 0.00 0 11210 36 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 814 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 449 18 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 1152 14 0 0
t$perf-norm:srv_make_money_saldo 903 903 0 0.00 0 735 5 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 10 56 27 0 0
v_add_invoice_to_stock 9984 9682 302 3.02 0 7050 28 0 302
v_all_customers 36156 36156 0 0.00 0 196 4 0 0
v_all_suppliers 28718 28718 0 0.00 0 85 4 0 0
v_all_wares 39720 39720 0 0.00 0 105 4 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 803 13 0 0
v_cancel_client_order 3200 3200 0 0.00 0 5143 14 0 0
v_cancel_customer_prepayment 1240 1240 0 0.00 0 3335 14 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 3280 16 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 1295 12 0 0
v_cancel_supplier_invoice 786 760 26 3.30 0 3285 27 0 26
v_cancel_supplier_order 804 804 0 0.00 0 837 10 0 0
v_cancel_write_off 1940 1940 0 0.00 0 7066 28 0 0
v_max_id_clo_ord 5247 5247 0 0.00 0 7835 7 0 0
v_max_id_clo_res 979 979 0 0.00 0 2302 17 0 0
v_max_id_ord_sup 5167 5167 0 0.00 0 4511 9 0 0
v_max_non_paid_invoice 1586 1586 0 0.00 0 59 7 0 0
v_max_non_paid_realizn 3596 3596 0 0.00 0 5661 9 0 0
v_min_id_clo_ord 5247 5247 0 0.00 0 2004 8 0 0
v_min_id_clo_res 979 979 0 0.00 0 2274 14 0 0
v_min_id_ord_sup 5167 5167 0 0.00 0 7650 8 0 0
v_min_non_paid_invoice 5532 5532 0 0.00 0 5189 15 0 0
v_min_non_paid_realizn 6784 6784 0 0.00 0 5671 17 0 0
v_our_firm 9932 9932 0 0.00 0 71 4 0 0
v_reserve_write_off 19455 19455 0 0.00 0 7059 11 0 0
x_lock_depdocs_on_canc_invoice 11524 11524 0 0.00 0 19704 137 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 7002 348 0 0
x_make_qstorno_1000_1200 5250 5250 0 0.00 3 46437 1228 0 0
x_make_qstorno_1200_2000 5178 5178 0 0.00 5 124045 3033 0 0
x_make_qstorno_2100_3300 452593 452593 0 0.00 0 134947 227 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-05-2022 07:48:56 to 25-05-2022 07:49:00.

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:16:51 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:17:51 21625 8192000000 9711320224 10008346624 791946880 8.815 100 83 167 0 819306720 7128720 20231120
05:19:16 22998 8192000000 9771931088 10080378880 838089280 9.281 100 83 166 0 869569568 11880976 19599312
05:20:48 23527 8192000000 9824416256 10142736384 880546176 9.705 100 89 176 3 920154224 19586160 20021888
05:22:18 12699 8192000000 9886415472 10221551616 927183600 10.167 100 79 160 1 991350544 46722288 17444656
05:23:38 5211 8192000000 9934078176 10277396480 973519552 10.621 100 84 165 1 1050597584 60027808 17050224
05:24:47 1613 8192000000 9986368752 10345091072 1008718080 10.963 100 93 179 3 1110898944 83450384 18730480
05:25:51 1538 8192000000 10032748064 10403315712 1039881360 11.264 100 92 179 1 1149628096 90507040 19239696
05:26:56 1114 8192000000 10030835360 10401423360 1059884928 11.455 100 92 182 1 1145518320 67128272 18505120
05:28:00 1417 8192000000 10039215136 10410811392 1066760080 11.521 100 90 170 0 1159199456 76040880 16398496
05:29:03 1268 8192000000 10052376576 10431315968 1076249936 11.612 100 95 183 0 1174318576 80138176 17930464
05:30:07 715 8192000000 10065408064 10447708160 1086910128 11.713 100 96 189 1 1187328320 80349472 20068720
05:31:09 1212 8192000000 10068497408 10449711104 1088677872 11.730 100 95 188 1 1187909904 80658464 18573568
05:32:12 1621 8192000000 10074005264 10460569600 1091065632 11.753 100 97 177 2 1193981392 85597472 17318288
05:33:15 1279 8192000000 10077543328 10467717120 1099446160 11.832 100 94 177 1 1201297728 83161296 18690272
05:34:18 1420 8192000000 10086097408 10476036096 1104248880 11.878 100 96 190 1 1212185792 88947184 18989728
05:35:20 1235 8192000000 10074565120 10464546816 1108291648 11.916 100 92 175 2 1196855648 70270112 18293888
05:36:23 1172 8192000000 10080427792 10467639296 1099286832 11.831 100 93 180 0 1200646800 83570752 17789216
05:37:25 1224 8192000000 10087803648 10479972352 1107634256 11.910 100 95 184 1 1208773360 82497744 18641360
05:38:28 1271 8192000000 10064265328 10451451904 1096546976 11.805 100 97 191 0 1186617888 70707344 19363568
05:39:30 1300 8192000000 10070221696 10455457792 1098789200 11.826 100 96 190 2 1191235024 73181568 19264256
05:40:33 1248 8192000000 10018139088 10393276416 1054909504 11.408 99 94 176 1 1141291488 68962784 17419200
05:41:35 1236 8192000000 10020733936 10400145408 1062767888 11.483 100 95 175 1 1144614304 62584560 19261856
05:42:37 1287 8192000000 10021872432 10399338496 1064631264 11.501 100 97 179 0 1143302896 60266464 18405168
05:43:40 1156 8192000000 10025101408 10405134336 1053263872 11.392 100 98 179 4 1153246752 81080496 18902384
05:44:42 1131 8192000000 10014878288 10390679552 1041515376 11.279 100 90 166 1 1133389216 75106752 16767088
05:45:44 3078 8192000000 9996768000 10385723392 1046680688 11.329 100 66 121 2 1119133648 59136816 13316144
05:46:49 1959 8192000000 10008272608 10390827008 1067580384 11.529 100 90 169 2 1129109280 44062544 17466352
05:47:56 1449 8192000000 10027674832 10406768640 1073514880 11.586 100 95 179 2 1153099728 62061296 17523552
05:49:06 1067 8192000000 10070812848 10457559040 1089435712 11.737 100 84 154 2 1195834832 91119536 15279584
05:50:11 4560 8192000000 10026827680 10422763520 1086700720 11.711 99 60 105 1 1145997456 48183328 11113408
05:51:22 1376 8192000000 10093957056 10489831424 1105164064 11.887 100 95 187 1 1219125216 92795664 21165488
05:52:25 2667 8192000000 10120021872 10513993728 1119514896 12.022 100 70 139 1 1240785008 106522176 14747936
05:53:33 791 8192000000 10164775792 10561765376 1134151616 12.160 100 88 174 1 1282348320 128983056 19213648
05:54:36 6715 8192000000 10162033536 10574401536 1125659200 12.080 100 81 153 0 1295541968 153919136 15963632
05:55:47 954 8192000000 10192139200 10593161216 1148107632 12.292 100 92 166 0 1308123888 141906896 18109360
05:56:52 5771 8192000000 10212266240 10618380288 1159533760 12.399 100 72 142 0 1320357760 145509760 15314240
05:58:04 2641 8192000000 10261448080 10663731200 1174311376 12.537 100 84 156 0 1372292880 181309088 16672416
05:59:12 1698 8192000000 10307856352 10711130112 1175111616 12.545 99 88 175 0 1413970528 218811504 20047408
06:00:16 1693 8192000000 10331327712 10733056000 1182382976 12.612 100 87 170 0 1443679568 241720512 19576080
06:01:21 701 8192000000 10372485376 10783272960 1187925152 12.664 100 99 193 0 1481996032 272055520 22015360
06:02:26 940 8192000000 10425399696 10836627456 1193810640 12.719 100 94 182 2 1535621904 321373200 20438064
06:03:31 1894 8192000000 10445288864 10852515840 1195966512 12.739 100 89 174 0 1553652752 336539232 21147008
06:04:38 1089 8192000000 10491056928 10896560128 1194511936 12.725 100 93 183 2 1595695408 379538880 21644592
06:05:42 1090 8192000000 10526431808 10946830336 1197063920 12.749 100 95 184 0 1635660512 415952080 22644512
06:06:49 891 8192000000 10531492400 10957991936 1195091600 12.731 100 91 175 0 1639854576 424090992 20671984
06:07:53 1654 8192000000 10513222416 10971852800 1186870096 12.654 99 92 180 1 1616619200 407740512 22008592
06:08:59 951 8192000000 10542660976 10970787840 1190126016 12.685 100 95 182 1 1645342368 433366192 21850160
06:10:01 3832 8192000000 10514881296 10981208064 1186437696 12.650 100 67 130 0 1622888080 419521360 16929024
06:11:08 2493 8192000000 10440750944 10929131520 1157501296 12.380 100 63 120 1 1545742864 372794160 15447408
06:12:20 2241 8192000000 10466819328 10932400128 1161851328 12.421 100 82 162 1 1566066272 383947120 20267824
06:13:28 853 8192000000 10460998720 10952257536 1171283472 12.509 100 82 164 0 1563880608 372300432 20296704
06:14:38 22766 8192000000 10369527648 10833977344 1142333424 12.237 99 35 70 2 1470148768 318371056 9444288
06:16:41 26681 8192000000 10307865696 10812280832 1155851888 12.364 100 27 54 2 1459353552 296639744 6861920
06:18:08 24895 8192000000 10253614528 10744893440 1116866848 11.997 98 27 51 1 1349349152 226011488 6470816
06:19:50 77865 8192000000 10092305232 10669023232 1117160032 12.000 99 9 18 0 1269748304 150297504 2290768
06:22:26 85504 8192000000 10062595616 10491957248 1098844944 11.827 96 20 40 1 1136418576 33549504 4024128
06:24:55 102825 8192000000 10127507632 10572783616 1149964336 12.309 100 24 48 2 1194735856 39576576 5194944
06:27:56 82950 8192000000 10080900688 10524819456 1135222432 12.171 99 22 44 0 1183605744 44248256 4135056
06:30:24 44643 8192000000 10063038816 10517704704 1086950128 11.714 95 36 72 0 1154821888 60654304 7217456
06:32:13 9010 8192000000 10092834496 10517381120 1130033440 12.122 100 71 135 2 1185138368 39772368 15332560
06:33:26 150208 8192000000 10125267792 10590892032 1093281872 11.774 96 20 40 0 1163568160 66065712 4220576
06:36:58 107059 8192000000 10142111072 10620563456 1139217456 12.208 100 19 38 0 1264645488 120897872 4530160
06:39:57 83667 8192000000 10054439488 10523885568 1089515360 11.738 96 14 28 2 1142669536 50182096 2972080
06:42:52 26193 8192000000 10091373104 10528960512 1135546288 12.174 100 37 74 2 1187067536 43671280 7849968
06:44:27 50492 8192000000 10082193696 10547191808 1125081936 12.075 98 22 44 1 1169111456 39551808 4477712
06:46:29 43950 8192000000 10162492784 10635272192 1142957616 12.243 100 47 95 0 1237569456 84840784 9771056
06:48:13 97695 8192000000 10108606816 10545963008 1138242816 12.199 99 16 32 0 1208812080 67318704 3250560
06:50:58 114026 8192000000 10064383840 10516017152 1059967664 11.456 93 20 40 0 1132946944 69102000 3877280
06:53:52 45639 8192000000 10122024608 10554003456 1097580992 11.815 96 38 76 1 1178466912 72805408 8080512
06:55:44 76325 8192000000 10119819728 10561433600 1131584096 12.136 100 43 84 3 1236546928 96254896 8707936
06:58:01 77411 8192000000 10097936832 10604580864 1118051648 12.009 98 22 44 1 1224073056 101247072 4774336
07:00:21 78218 8192000000 10085160736 10565869568 1131421040 12.135 99 12 25 1 1187616112 53508672 2686400
07:02:39 59503 8192000000 10117940608 10594279424 1147709680 12.288 100 17 34 3 1217526512 66376576 3440256
07:04:47 51374 8192000000 10149262144 10621644800 1143055648 12.244 100 12 24 2 1229753856 83665072 3033136
07:06:49 84126 8192000000 10098638000 10569613312 1110277040 11.935 98 14 28 1 1175063744 62103856 2682848
07:09:19 59818 8192000000 10103199168 10569740288 1089877200 11.741 96 28 56 2 1183858528 88143232 5838096
07:11:32 43837 8192000000 10088589104 10520444928 1122074000 12.047 99 26 51 1 1174872384 47229408 5568976
07:13:21 49868 8192000000 10134987504 10607460352 1120518496 12.032 99 31 62 0 1202533264 75715152 6299616
07:15:15 14443 8192000000 10091746304 10524647424 1118854368 12.016 99 34 67 1 1166867952 41311568 6702016
07:16:32 56755 8192000000 10098794288 10525618176 1126560688 12.089 99 54 106 0 1185032240 47914416 10557136
07:19:14 20638 8192000000 10070185360 10506924032 1145455280 12.267 100 37 67 3 1174787616 22986016 6346320
07:20:36 52358 8192000000 10096486672 10572763136 1124770736 12.072 98 21 42 0 1201324704 71903408 4650560
07:22:31 77567 8192000000 10131855328 10604519424 1126871696 12.092 98 25 48 0 1237333216 104889152 5572368
07:25:27 106044 8192000000 10105648912 10562981888 1114407744 11.974 97 24 49 1 1169532688 50553136 4571808
07:28:23 48774 8192000000 10152970944 10612600832 1140788496 12.223 98 27 54 1 1234380320 87653504 5938320
07:30:12 33223 8192000000 10154631840 10601586688 1142144032 12.236 98 27 54 2 1235737376 87665504 5927840
07:31:46 47288 8192000000 10157074736 10597670912 1151245664 12.321 99 32 63 1 1235640048 77655984 6738400
07:34:04 31034 8192000000 10160897248 10618347520 1132977344 12.149 98 47 94 1 1253136720 110694928 9464448
07:35:43 93513 8192000000 10041646256 10470895616 1112237616 11.954 98 13 24 0 1185566768 71033072 2296080
07:38:17 58170 8192000000 10084944336 10518360064 1111353440 11.945 98 51 98 0 1151742464 30730832 9658192
07:40:17 3638 8192000000 10138175168 10585001984 1148219312 12.293 100 49 94 1 1237438032 79911632 9307088
07:41:30 69731 8192000000 10055215344 10523910144 1120906256 12.036 98 13 26 0 1177811472 54290080 2615136
07:43:50 52041 8192000000 10065425456 10530541568 1126688928 12.090 99 22 44 0 1161053536 29619120 4745488
07:46:33 37480 8192000000 9271604832 9591402496 412639520 4.795 37 3 6 0 431163008 18414880 108608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-05-2022 07:49:02 to 25-05-2022 07:49:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 151 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 25-05-2022 07:49:18 to 25-05-2022 07:49:20.

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          216105
MON$OLDEST_ACTIVE               216106
MON$OLDEST_SNAPSHOT             216106
MON$NEXT_TRANSACTION            216106
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                       2057888
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             767
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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed May 25 07:47:15 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

25.05.22 07:49:21. 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-25 07:49:24 No severe PSQL-related problems occured
25.05.22 07:49:25. End of report.