Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.07.2022 10: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 16101044 kB
MemAvailable 31079724 kB
Buffers 0 kB
Cached 15586188 kB
SwapCached 2520 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-07-13 10:37:19
DTS_END                         2022-07-13 10:37:19.4540
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/51524
TRN_ID                          223662
ATT_ID                          605
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: 8997.03 22591 1079644
customer order: creation 171.19 493 20543
customer order: refuse 14.23 411 1707
order to supplier: creation 45.28 1402 5433
order to supplier: removal 3.08 154045 370
invoice (draft): creation 45.30 3203 5436
invoice (draft): removal 48.67 926 5840
invoice accept: apply 42.17 25620 5060
invoice accept: cancel 44.25 1141 5310
customer reserve: creation 3905.93 244 468712
customer reserve: removal 4382.58 86 525910
realization accept: apply 83.49 276 10019
realization accept: cancel 81.63 143 9795
payment from customer: creation 58.43 176 7011
payment from customer: removal 5.75 140 690
payment to supplier: creation 49.55 206 5946
payment to supplier: removal 3.68 179 442
service: total inventory turnovers 4.00 3789 480
service: total monetary turnovers 6.83 377 820
service: aggregate perf. data 0.95 15636 114
service: refresh index statistics 0.05 243321 6

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11186
TEST_TIME 2 8325
TEST_TIME 3 6708
TEST_TIME 4 6662
TEST_TIME 5 7345
TEST_TIME 6 7363
TEST_TIME 7 7154
TEST_TIME 8 7238
TEST_TIME 9 7253
TEST_TIME 10 7182
TEST_TIME 11 7252
TEST_TIME 12 7280
TEST_TIME 13 7306
TEST_TIME 14 7410
TEST_TIME 15 7371
TEST_TIME 16 7478
TEST_TIME 17 7864
TEST_TIME 18 7967
TEST_TIME 19 7942
TEST_TIME 20 8019
TEST_TIME 21 8144
TEST_TIME 22 8269
TEST_TIME 23 8559
TEST_TIME 24 8833
TEST_TIME 25 9031
TEST_TIME 26 9057
TEST_TIME 27 9263
TEST_TIME 28 10003
TEST_TIME 29 10081
TEST_TIME 30 10430
TEST_TIME 31 10605
TEST_TIME 32 10634
TEST_TIME 33 10822
TEST_TIME 34 10634
TEST_TIME 35 10425
TEST_TIME 36 10253
TEST_TIME 37 9999
TEST_TIME 38 10280
TEST_TIME 39 10059
TEST_TIME 40 9965
TEST_TIME 41 9945
TEST_TIME 42 9839
TEST_TIME 43 9840
TEST_TIME 44 9758
TEST_TIME 45 9623
TEST_TIME 46 9471
TEST_TIME 47 9472
TEST_TIME 48 9391
TEST_TIME 49 9453
TEST_TIME 50 9825
TEST_TIME 51 9729
TEST_TIME 52 9706
TEST_TIME 53 9722
TEST_TIME 54 9714
TEST_TIME 55 9685
TEST_TIME 56 9836
TEST_TIME 57 9922
TEST_TIME 58 9857
TEST_TIME 59 9872
TEST_TIME 60 9880
TEST_TIME 61 9940
TEST_TIME 62 9815
TEST_TIME 63 9706
TEST_TIME 64 9732
TEST_TIME 65 9644
TEST_TIME 66 9631
TEST_TIME 67 9554
TEST_TIME 68 9503
TEST_TIME 69 9496
TEST_TIME 70 9480
TEST_TIME 71 9425
TEST_TIME 72 9368
TEST_TIME 73 9415
TEST_TIME 74 9408
TEST_TIME 75 9453
TEST_TIME 76 9441
TEST_TIME 77 9424
TEST_TIME 78 9435
TEST_TIME 79 9415
TEST_TIME 80 9477
TEST_TIME 81 9540
TEST_TIME 82 9490
TEST_TIME 83 9423
TEST_TIME 84 9387
TEST_TIME 85 9308
TEST_TIME 86 9276
TEST_TIME 87 9282
TEST_TIME 88 9248
TEST_TIME 89 9205
TEST_TIME 90 9131
TEST_TIME 91 9069
TEST_TIME 92 9081
TEST_TIME 93 9146
TEST_TIME 94 9179
TEST_TIME 95 9183
TEST_TIME 96 9167
TEST_TIME 97 9165
TEST_TIME 98 9173
TEST_TIME 99 9194
TEST_TIME 100 9286
TEST_TIME 101 9301
TEST_TIME 102 9299
TEST_TIME 103 9294
TEST_TIME 104 9338
TEST_TIME 105 9354
TEST_TIME 106 9293
TEST_TIME 107 9242
TEST_TIME 108 9224
TEST_TIME 109 9193
TEST_TIME 110 9144
TEST_TIME 111 9076
TEST_TIME 112 9082
TEST_TIME 113 9121
TEST_TIME 114 9086
TEST_TIME 115 9052
TEST_TIME 116 9080
TEST_TIME 117 9086
TEST_TIME 118 9062
TEST_TIME 119 9103
TEST_TIME 120 9032

Performance per minute, chart


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

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1510780 1510780 0 0.00 0 37625 13 0 0
doc_list_biud 1510767 1510767 0 0.00 0 35436 36 0 0
sp_add_invoice_to_stock 5208 5060 148 2.84 68 199460 25620 0 148
sp_cancel_adding_invoice 5310 5310 0 0.00 6 80135 1141 0 0
sp_cancel_client_order 1707 1707 0 0.00 6 12273 412 0 0
sp_cancel_customer_reserve 525910 525910 0 0.00 1 32626 86 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 4 7341 140 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 4 9387 180 0 0
sp_cancel_supplier_invoice 5853 5840 13 0.22 3 35443 927 0 13
sp_cancel_supplier_order 370 370 0 0.00 22 1169421 154046 0 0
sp_cancel_write_off 9795 9795 0 0.00 2 10737 143 0 0
sp_client_order 20543 20543 0 0.00 5 69612 494 0 0
sp_customer_reserve 468712 468712 0 0.00 0 94831 245 0 0
sp_fill_shopping_cart 31401 31401 0 0.00 1 33952 83 0 0
sp_fill_shopping_cart_clo_res 468678 468678 0 0.00 0 15165 19 0 0
sp_get_clo_for_invoice 5015 5015 0 0.00 0 16093 262 0 0
sp_kill_cost_storno 16237 16237 0 0.00 0 10730 61 0 0
sp_kill_qstorno_ret_qs2qd 1067825 1067825 0 0.00 0 35435 39 0 0
sp_kill_qty_storno 563962 563962 0 0.00 0 35436 89 0 0
sp_lock_selected_doc 20742 20742 0 0.00 0 9335 9 0 0
sp_make_cost_storno 27991 27991 0 0.00 0 37624 80 0 0
sp_multiply_rows_for_pdistr 27988 27988 0 0.00 0 11122 37 0 0
sp_multiply_rows_for_qdistr 20543 20543 0 0.00 0 69577 196 0 0
sp_pay_from_customer 7011 7011 0 0.00 5 11133 177 0 0
sp_pay_to_supplier 5946 5946 0 0.00 7 37631 207 0 0
sp_payment_common 12957 12957 0 0.00 2 8794 45 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 1 12140 338 0 0
sp_qd_handle_on_invoice_upd_sts 10321 10321 0 0.00 0 19079 473 0 0
sp_qd_handle_on_reserve_upd_sts 10019 10019 0 0.00 0 13862 65 0 0
sp_reserve_write_off 10019 10019 0 0.00 8 17662 276 0 0
sp_supplier_invoice 5436 5436 0 0.00 10 81380 3203 0 0
sp_supplier_order 5433 5433 0 0.00 8 38965 1403 0 0
srv_aggregate_perf_data 114 114 0 0.00 2324 150727 15636 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 787 150458 37537 0 0
srv_find_qd_qs_mism 1030337 1030337 0 0.00 0 26591 10 0 0
srv_make_invnt_saldo 480 480 0 0.00 58 96167 3789 0 0
srv_make_money_saldo 820 820 0 0.00 1 13580 377 0 0
srv_recalc_idx_stat 6 6 0 0.00 42892 619381 243322 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 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 5069 5069 0 0.00 1 13290 882 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 1 7876 472 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 1771 31 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 1799 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 986 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 167 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 724 35 0 0
t$perf-norm:sp_cancel_supplier_order 370 370 0 0.00 0 78075 5634 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 3769 30 0 0
t$perf-norm:sp_client_order 20544 20544 0 0.00 0 7800 38 0 0
t$perf-norm:sp_customer_reserve 996 996 0 0.00 0 1496 15 0 0
t$perf-norm:sp_pay_from_customer 7011 7011 0 0.00 0 6056 19 0 0
t$perf-norm:sp_pay_to_supplier 5946 5946 0 0.00 0 2395 18 0 0
t$perf-norm:sp_reserve_write_off 10020 10020 0 0.00 0 3637 22 0 0
t$perf-norm:sp_supplier_invoice 5436 5436 0 0.00 0 2877 27 0 0
t$perf-norm:sp_supplier_order 5433 5433 0 0.00 0 5319 31 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 974 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 55 4 0 0
t$perf-norm:srv_make_invnt_saldo 480 480 0 0.00 0 2001 23 0 0
t$perf-norm:srv_make_money_saldo 820 820 0 0.00 0 2236 7 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 4 197 66 0 0
v_add_invoice_to_stock 10312 10016 296 2.87 0 4573 26 0 296
v_all_customers 37140 37140 0 0.00 0 68 4 0 0
v_all_suppliers 30174 30174 0 0.00 0 69 4 0 0
v_all_wares 41077 41077 0 0.00 0 104 4 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 1176 17 0 0
v_cancel_client_order 3414 3414 0 0.00 0 2269 13 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 1556 11 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 1202 12 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 3657 21 0 0
v_cancel_supplier_invoice 858 832 26 3.03 0 3486 29 0 26
v_cancel_supplier_order 740 740 0 0.00 0 2219 17 0 0
v_cancel_write_off 1940 1940 0 0.00 0 5263 32 0 0
v_max_id_clo_ord 5428 5428 0 0.00 0 198 5 0 0
v_max_id_clo_res 996 996 0 0.00 0 2609 14 0 0
v_max_id_ord_sup 5433 5433 0 0.00 0 5712 7 0 0
v_max_non_paid_invoice 1720 1720 0 0.00 0 220 7 0 0
v_max_non_paid_realizn 3847 3847 0 0.00 0 5303 9 0 0
v_min_id_clo_ord 5428 5428 0 0.00 0 7579 11 0 0
v_min_id_clo_res 996 996 0 0.00 0 4856 18 0 0
v_min_id_ord_sup 5433 5433 0 0.00 0 7523 11 0 0
v_min_non_paid_invoice 5946 5946 0 0.00 0 6871 15 0 0
v_min_non_paid_realizn 7010 7010 0 0.00 0 5935 16 0 0
v_our_firm 10260 10260 0 0.00 0 71 5 0 0
v_reserve_write_off 20038 20038 0 0.00 0 5268 11 0 0
x_lock_depdocs_on_canc_invoice 10734 10734 0 0.00 0 13605 116 0 0
x_lock_depdocs_on_canc_sup_ord 370 370 0 0.00 0 24927 518 0 0
x_make_qstorno_1000_1200 5433 5433 0 0.00 2 29198 1238 0 0
x_make_qstorno_1200_2000 5436 5436 0 0.00 5 81242 2969 0 0
x_make_qstorno_2100_3300 468710 468710 0 0.00 0 94825 214 0 0

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

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:23 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:24 19746 8192000000 9724026176 10023768064 804305744 8.940 100 85 170 0 836914224 12208912 20399568
08:09:51 19317 8192000000 9768007280 10077745152 842429104 9.324 100 89 177 0 875266288 11467232 21369952
08:11:17 12489 8192000000 9817710768 10137780224 884799168 9.747 100 88 174 1 925833264 21118496 19915600
08:12:44 13084 8192000000 9873332832 10210156544 923968992 10.135 100 73 144 0 975847728 36312416 15566320
08:14:02 3521 8192000000 9918397552 10263261184 965250176 10.540 100 90 174 1 1038857776 55183168 18424432
08:15:08 2459 8192000000 9965742256 10317733888 995363920 10.834 100 90 168 1 1083811360 71481744 16965696
08:16:13 3070 8192000000 10000826336 10358906880 1014487712 11.019 100 89 173 1 1118561312 86750768 17322832
08:17:17 1017 8192000000 10007602512 10377711616 1045476064 11.317 100 96 182 1 1126603344 62994656 18132624
08:18:20 1165 8192000000 10018734832 10389151744 1064065440 11.495 100 94 179 2 1132216496 50113200 18037856
08:19:23 939 8192000000 10046660608 10426155008 1068491296 11.538 100 93 180 2 1172991568 85892800 18607472
08:20:25 1055 8192000000 10034845872 10415067136 1074345328 11.594 100 93 170 2 1157996400 65394480 18256592
08:21:27 2402 8192000000 10055908384 10443673600 1084291888 11.688 100 83 161 1 1178686544 79034576 15360080
08:22:31 1259 8192000000 10059830272 10448089088 1090933712 11.752 100 87 171 2 1187214144 78936368 17344064
08:23:33 846 8192000000 10049858944 10437976064 1095395216 11.794 100 79 156 3 1171144448 59263600 16485632
08:24:35 2535 8192000000 10048848992 10447085568 1100041072 11.838 100 73 133 2 1169212256 53562096 15609088
08:25:39 1204 8192000000 10050237200 10436362240 1105731888 11.892 100 96 187 0 1168773744 44872240 18169616
08:26:41 8432 8192000000 10053458576 10446147584 1100487296 11.842 100 51 98 1 1169292688 59082768 9722624
08:27:50 1429 8192000000 10041124192 10435239936 1104032720 11.876 100 84 159 3 1168011824 46534368 17444736
08:28:53 1128 8192000000 10049360976 10433441792 1093343408 11.774 100 89 159 2 1171263456 61743408 16176640
08:29:55 1858 8192000000 10043057904 10426019840 1069111104 11.544 99 83 163 4 1167438416 82267104 16060208
08:30:58 849 8192000000 10023689328 10404184064 1053646608 11.396 99 85 168 3 1146868528 76130608 17091312
08:32:01 1354 8192000000 10000071952 10372780032 1050615360 11.367 100 95 177 2 1125783600 57806720 17361520
08:33:03 1022 8192000000 9982678000 10350895104 1031969232 11.187 100 94 178 3 1102286432 51679312 18637888
08:34:05 4034 8192000000 9966766832 10344898560 1029386352 11.163 99 62 108 1 1090786368 49818880 11581136
08:35:10 1018 8192000000 9978848960 10352459776 1037846112 11.244 100 89 170 1 1100496912 44679152 17971648
08:36:12 1179 8192000000 10030838128 10410610688 1059137344 11.448 100 97 186 4 1151993728 74593728 18262656
08:37:14 1441 8192000000 10034752128 10415968256 1066007408 11.514 100 85 158 1 1153027760 70874640 16145712
08:38:22 1160 8192000000 10024360192 10402598912 1077187248 11.621 100 86 155 1 1138435456 44518656 16729552
08:39:44 4151 8192000000 10063800224 10451169280 1097055072 11.810 100 75 150 0 1179281328 67221568 15004688
08:40:53 3155 8192000000 10086579568 10472767488 1101022064 11.847 100 78 141 1 1205748304 90846688 13879552
08:41:59 1925 8192000000 10106444672 10504089600 1115570640 11.985 100 86 154 1 1225782640 93136176 17075824
08:43:06 5239 8192000000 10132695968 10528014336 1126426416 12.088 100 82 155 2 1249508256 106667024 16414816
08:44:15 8083 8192000000 10153065568 10552631296 1142728496 12.241 100 85 164 2 1268121584 107548704 17844384
08:45:28 1241 8192000000 10148566384 10555416576 1148446688 12.295 100 85 160 5 1260963488 96419520 16097280
08:46:32 5369 8192000000 10211811264 10621509632 1158038976 12.385 100 71 136 2 1323278544 150609184 14630384
08:47:49 1945 8192000000 10212400592 10617114624 1164765680 12.448 100 83 159 1 1327447008 145487488 17193840
08:48:54 1745 8192000000 10266960592 10671960064 1171672816 12.512 100 88 167 2 1385128176 194643696 18811664
08:50:00 1145 8192000000 10260323184 10672254976 1182069984 12.609 100 95 179 1 1375773888 173626336 20077568
08:51:05 1214 8192000000 10327701792 10730557440 1184953136 12.636 100 96 186 1 1437058704 231328384 20777184
08:52:10 1177 8192000000 10333478480 10734620672 1184061248 12.628 100 96 192 1 1438275312 231962976 22251088
08:53:17 1306 8192000000 10327686816 10748473344 1181776560 12.607 100 87 169 0 1441388752 239432432 20179760
08:54:20 1363 8192000000 10349471760 10779414528 1187048656 12.656 100 97 189 1 1456776128 247316480 22410992
08:55:24 2717 8192000000 10366134064 10776858624 1193808272 12.719 100 83 156 2 1460873168 248856848 18208048
08:56:30 778 8192000000 10404109808 10811801600 1194178816 12.722 100 95 184 2 1510423280 294543072 21701392
08:57:34 1546 8192000000 10421260560 10840305664 1187284416 12.658 100 90 168 1 1532282656 324282320 20715920
08:58:38 872 8192000000 10442081120 10878726144 1185671744 12.643 100 99 188 2 1543917088 335487248 22758096
08:59:41 1008 8192000000 10398736512 10845532160 1167811072 12.476 100 83 161 2 1504326352 317106032 19409248
09:00:44 2120 8192000000 10423746928 10847846400 1157410240 12.379 100 68 135 2 1526796208 353361008 16024960
09:01:52 6535 8192000000 10365678304 10812022784 1139887504 12.214 99 40 74 0 1468513024 318149856 10475664
09:03:02 25549 8192000000 10353003600 10827902976 1147549712 12.286 100 29 58 0 1480159088 324987184 7622192
09:04:28 9137 8192000000 10324511760 10766696448 1117209536 12.001 98 32 63 0 1427137264 302109568 7818160
09:05:46 23071 8192000000 10161284672 10629881856 1115362720 11.983 100 15 29 0 1294997696 176035728 3599248
09:07:14 48226 8192000000 10106878032 10613207040 1122642128 12.052 100 18 36 0 1245883152 119075488 4165536
09:09:03 7198 8192000000 10104704672 10520567808 1143458368 12.248 100 78 134 2 1197669824 40580480 13630976
09:10:11 79584 8192000000 10073310000 10516615168 1105602720 11.891 97 11 22 0 1174011248 66282848 2125680
09:12:31 150454 8192000000 10127713744 10606682112 1132726304 12.147 99 38 73 1 1239686320 98936032 8023984
09:16:04 73400 8192000000 10084861344 10508578816 1132962800 12.149 99 67 125 1 1166782816 20526912 13293104
09:18:19 12959 8192000000 10106293936 10528464896 1131308144 12.134 99 45 81 2 1185631104 46266832 8056128
09:19:33 18554 8192000000 10138070832 10573844480 1131149440 12.132 99 39 76 0 1234899280 95055696 8694144
09:20:53 59272 8192000000 10131878368 10577944576 1130994656 12.131 98 16 33 0 1231264000 96358096 3911248
09:22:56 21511 8192000000 10185868320 10635259904 1144463888 12.258 100 14 28 0 1286456816 138465728 3527200
09:24:24 62718 8192000000 10217603152 10670755840 1146090608 12.273 100 21 44 0 1329740784 178155856 5494320
09:26:27 63651 8192000000 10178791440 10654580736 1117371984 12.002 98 12 25 0 1280101216 159953264 2775968
09:28:33 79473 8192000000 10111581984 10580021248 1107534016 11.909 98 22 44 0 1165494944 53367552 4593376
09:31:15 10715 8192000000 10152182160 10582507520 1138074336 12.197 100 51 100 0 1248905328 100173696 10657296
09:32:29 49677 8192000000 10176636608 10625744896 1144653088 12.259 100 24 48 0 1280940224 130989392 5297744
09:34:58 36745 8192000000 10079316384 10522935296 1138118064 12.198 100 46 86 0 1189118432 40824144 10176224
09:36:43 56991 8192000000 10147913248 10597490688 1120776528 12.034 98 35 70 0 1199505024 71659680 7068816
09:38:41 118729 8192000000 10106527072 10545606656 1091182592 11.754 96 24 47 1 1170418336 74745296 4490448
09:41:40 29366 8192000000 10061604080 10518798336 1133092192 12.151 100 25 50 0 1171587312 33706864 4788256
09:43:10 93332 8192000000 10090966496 10551361536 1130293696 12.124 99 17 32 0 1190121152 56881744 2945712
09:45:43 65608 8192000000 10052514320 10536984576 1112152544 11.953 98 13 26 0 1168834704 53636944 3045216
09:47:53 44748 8192000000 10087858336 10538774528 1128891168 12.111 99 31 62 0 1153886928 18126112 6869648
09:49:44 36038 8192000000 10115554576 10570055680 1112892976 11.960 98 33 64 0 1206570736 86844400 6833360
09:51:21 49038 8192000000 10098142160 10536828928 1113414688 11.965 99 28 56 0 1199075392 79809936 5850768
09:53:13 59151 8192000000 10109244112 10548989952 1135346880 12.172 100 39 75 0 1198525968 55458176 7720912
09:55:14 29753 8192000000 10092274512 10534768640 1116298784 11.992 99 41 82 0 1190003392 65184624 8519984
09:56:46 14121 8192000000 10132412416 10557001728 1117329536 12.002 99 53 106 0 1213516944 85315424 10871984
09:58:01 53387 8192000000 10080489216 10571259904 1119172064 12.019 99 18 36 0 1178231248 55251392 3807792
10:00:04 89404 8192000000 10061336672 10521206784 1100018048 11.838 98 31 60 0 1140343520 33923440 6402032
10:02:40 15128 8192000000 10085247152 10505068544 1128466688 12.107 100 67 134 0 1189578368 47438768 13672912
10:04:00 49850 8192000000 10071989808 10525605888 1124540784 12.070 100 32 64 0 1214440096 83311744 6587568
10:05:50 45925 8192000000 10058240720 10527023104 1106887088 11.903 98 29 54 2 1147431216 34676928 5867200
10:07:39 73958 8192000000 10148596656 10595643392 1133236992 12.152 99 18 34 0 1231208896 94518224 3453680
10:09:54 75168 8192000000 10054825040 10560638976 1110326432 11.936 98 12 25 0 1193747776 80712928 2708416
10:12:09 36980 8192000000 10069451488 10512121856 1108918256 11.922 98 28 56 1 1156774096 42474448 5381392
10:13:47 51313 8192000000 10112889184 10568097792 1120055616 12.028 99 41 82 0 1204273136 75922144 8295376
10:15:39 58307 8192000000 10113167696 10582966272 1099655824 11.834 97 30 59 0 1192759536 86620864 6482848
10:17:42 94921 8192000000 10097381392 10528788480 1119602448 12.023 99 18 34 0 1165210240 42337488 3270304
10:20:34 52379 8192000000 10144252288 10587623424 1130321968 12.124 99 42 81 1 1202452160 63662320 8467872
10:22:57 122986 8192000000 10090175216 10556698624 1132954928 12.149 100 16 32 0 1193256640 57058304 3243408
10:26:01 51100 8192000000 10066159040 10540216320 1131641440 12.137 99 6 12 0 1183405072 50442416 1321216
10:27:52 74330 8192000000 10133088576 10593755136 1127787184 12.100 99 17 34 0 1199965344 68696944 3481216
10:30:32 70647 8192000000 10074307616 10518450176 1093521456 11.776 96 23 46 1 1158702928 60447856 4733616
10:32:45 35629 8192000000 10110887440 10574336000 1122262720 12.048 99 31 63 0 1212318336 83570608 6485008
10:34:25 55782 8192000000 10097063984 10526998528 1098414720 11.823 97 26 51 0 1161023632 57303248 5305664
10:36:56 35541 8192000000 9338294192 9709445120 453255776 5.242 40 9 18 0 506208144 51261824 1690544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 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 13-07-2022 10:38:58 to 13-07-2022 10:39:00.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          224802
MON$OLDEST_ACTIVE               224803
MON$OLDEST_SNAPSHOT             224803
MON$NEXT_TRANSACTION            224803
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                       2032736
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2798 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2798 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2798 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 ---
:139: 
:140: mscs-test-161.budzdorov.ru    Wed Jul 13 10:37:42 2022
:141:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:142: 
--- end of diff output ---

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

13.07.22 10:39:01. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-07-13 10:39:03 No severe PSQL-related problems occured
13.07.22 10:39:05. End of report.