Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.03.2023 04: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 15102824 kB
MemAvailable 30371000 kB
Buffers 20 kB
Cached 16516368 kB
SwapCached 3084 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-02 04:37:28
DTS_END                         2023-03-02 04:37:28.3190
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/39046
TRN_ID                          214226
ATT_ID                          596
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: 8841.50 32747 1060980
customer order: creation 156.40 522 18768
customer order: refuse 13.63 413 1636
order to supplier: creation 43.67 1394 5240
order to supplier: removal 3.29 192042 395
invoice (draft): creation 44.15 3274 5298
invoice (draft): removal 50.02 1108 6002
invoice accept: apply 40.14 29669 4817
invoice accept: cancel 45.02 1385 5402
customer reserve: creation 3759.21 282 451105
customer reserve: removal 4407.75 112 528930
realization accept: apply 78.71 294 9445
realization accept: cancel 77.12 181 9254
payment from customer: creation 56.14 178 6737
payment from customer: removal 5.49 137 659
payment to supplier: creation 45.13 194 5416
payment to supplier: removal 3.57 90 428
service: total inventory turnovers 3.93 4304 472
service: total monetary turnovers 7.24 388 869
service: aggregate perf. data 0.86 17690 103
service: refresh index statistics 0.03 401289 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-03-2023 04:38:41 to 02-03-2023 04:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4558
TEST_TIME 2 4149
TEST_TIME 3 4542
TEST_TIME 4 5391
TEST_TIME 5 5780
TEST_TIME 6 6081
TEST_TIME 7 6044
TEST_TIME 8 5685
TEST_TIME 9 5704
TEST_TIME 10 5929
TEST_TIME 11 6116
TEST_TIME 12 6235
TEST_TIME 13 6336
TEST_TIME 14 6402
TEST_TIME 15 6521
TEST_TIME 16 6495
TEST_TIME 17 6543
TEST_TIME 18 6414
TEST_TIME 19 6383
TEST_TIME 20 6613
TEST_TIME 21 6752
TEST_TIME 22 6906
TEST_TIME 23 7161
TEST_TIME 24 7599
TEST_TIME 25 7781
TEST_TIME 26 7757
TEST_TIME 27 8069
TEST_TIME 28 8080
TEST_TIME 29 8249
TEST_TIME 30 8824
TEST_TIME 31 8759
TEST_TIME 32 9212
TEST_TIME 33 9577
TEST_TIME 34 9976
TEST_TIME 35 10535
TEST_TIME 36 10366
TEST_TIME 37 10282
TEST_TIME 38 10131
TEST_TIME 39 10018
TEST_TIME 40 9932
TEST_TIME 41 9691
TEST_TIME 42 9594
TEST_TIME 43 9738
TEST_TIME 44 9589
TEST_TIME 45 9495
TEST_TIME 46 9517
TEST_TIME 47 9442
TEST_TIME 48 9418
TEST_TIME 49 9584
TEST_TIME 50 9565
TEST_TIME 51 9468
TEST_TIME 52 9435
TEST_TIME 53 9370
TEST_TIME 54 9334
TEST_TIME 55 9295
TEST_TIME 56 9250
TEST_TIME 57 9367
TEST_TIME 58 9402
TEST_TIME 59 9571
TEST_TIME 60 9511
TEST_TIME 61 9482
TEST_TIME 62 9484
TEST_TIME 63 9496
TEST_TIME 64 9620
TEST_TIME 65 9585
TEST_TIME 66 9520
TEST_TIME 67 9428
TEST_TIME 68 9433
TEST_TIME 69 9354
TEST_TIME 70 9351
TEST_TIME 71 9321
TEST_TIME 72 9224
TEST_TIME 73 9207
TEST_TIME 74 9138
TEST_TIME 75 9175
TEST_TIME 76 9193
TEST_TIME 77 9168
TEST_TIME 78 9192
TEST_TIME 79 9165
TEST_TIME 80 9147
TEST_TIME 81 9138
TEST_TIME 82 9117
TEST_TIME 83 9114
TEST_TIME 84 9199
TEST_TIME 85 9195
TEST_TIME 86 9229
TEST_TIME 87 9327
TEST_TIME 88 9277
TEST_TIME 89 9285
TEST_TIME 90 9217
TEST_TIME 91 9193
TEST_TIME 92 9113
TEST_TIME 93 9107
TEST_TIME 94 9093
TEST_TIME 95 9087
TEST_TIME 96 9045
TEST_TIME 97 8987
TEST_TIME 98 8947
TEST_TIME 99 8967
TEST_TIME 100 8971
TEST_TIME 101 8949
TEST_TIME 102 8949
TEST_TIME 103 8969
TEST_TIME 104 8937
TEST_TIME 105 8901
TEST_TIME 106 8987
TEST_TIME 107 9035
TEST_TIME 108 9026
TEST_TIME 109 9039
TEST_TIME 110 9059
TEST_TIME 111 9065
TEST_TIME 112 9047
TEST_TIME 113 9001
TEST_TIME 114 9001
TEST_TIME 115 8998
TEST_TIME 116 8976
TEST_TIME 117 8932
TEST_TIME 118 8930
TEST_TIME 119 8904
TEST_TIME 120 8863

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-03-2023 04:38:45 to 02-03-2023 04:38:48.

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 1475620 1475620 0 0.00 0 18469 15 0 0
doc_list_biud 1475608 1475608 0 0.00 0 91468 46 0 0
sp_add_invoice_to_stock 4876 4817 59 1.21 30 283074 29669 0 59
sp_cancel_adding_invoice 5402 5402 0 0.00 6 113435 1386 0 0
sp_cancel_client_order 1636 1636 0 0.00 5 9615 413 0 0
sp_cancel_customer_reserve 528930 528930 0 0.00 1 65873 112 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 4 4722 137 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 4 756 91 0 0
sp_cancel_supplier_invoice 6007 6002 5 0.08 3 91475 1109 0 5
sp_cancel_supplier_order 395 395 0 0.00 11 1438163 192043 0 0
sp_cancel_write_off 9254 9254 0 0.00 2 7246 182 0 0
sp_client_order 18768 18768 0 0.00 6 79827 523 0 0
sp_customer_reserve 451105 451105 0 0.00 0 142119 283 0 0
sp_fill_shopping_cart 29292 29292 0 0.00 1 23028 83 0 0
sp_fill_shopping_cart_clo_res 451074 451074 0 0.00 0 21757 23 0 0
sp_get_clo_for_invoice 4770 4770 0 0.00 0 26453 296 0 0
sp_kill_cost_storno 15743 15743 0 0.00 0 7275 82 0 0
sp_kill_qstorno_ret_qs2qd 1073511 1073511 0 0.00 0 91467 49 0 0
sp_kill_qty_storno 565831 565831 0 0.00 0 91468 112 0 0
sp_lock_selected_doc 19639 19639 0 0.00 0 10399 9 0 0
sp_make_cost_storno 26367 26367 0 0.00 0 14669 78 0 0
sp_multiply_rows_for_pdistr 26364 26364 0 0.00 0 9609 39 0 0
sp_multiply_rows_for_qdistr 18768 18768 0 0.00 0 15162 198 0 0
sp_pay_from_customer 6737 6737 0 0.00 5 15138 178 0 0
sp_pay_to_supplier 5416 5416 0 0.00 6 9879 195 0 0
sp_payment_common 12151 12151 0 0.00 2 7029 46 0 0
sp_qd_handle_on_cancel_clo 1636 1636 0 0.00 0 9505 339 0 0
sp_qd_handle_on_invoice_upd_sts 10170 10170 0 0.00 0 43749 561 0 0
sp_qd_handle_on_reserve_upd_sts 9444 9444 0 0.00 0 17788 70 0 0
sp_reserve_write_off 9445 9445 0 0.00 8 18478 295 0 0
sp_supplier_invoice 5298 5298 0 0.00 8 108822 3274 0 0
sp_supplier_order 5240 5240 0 0.00 7 57230 1394 0 0
srv_aggregate_perf_data 103 103 0 0.00 2676 161225 17690 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 777 159100 33139 0 0
srv_find_qd_qs_mism 1014117 1014117 0 0.00 0 15320 12 0 0
srv_make_invnt_saldo 472 472 0 0.00 64 106857 4304 0 0
srv_make_money_saldo 869 869 0 0.00 1 9923 388 0 0
srv_recalc_idx_stat 4 4 0 0.00 287695 483282 401290 0 0
t$perf-abend:sp_add_invoice_to_stock 59 59 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4819 4819 0 0.00 1 28073 913 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 9048 535 0 0
t$perf-norm:sp_cancel_client_order 1636 1636 0 0.00 0 757 25 0 0
t$perf-norm:sp_cancel_customer_reserve 639 639 0 0.00 0 1052 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 706 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 13022 42 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 774 39 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 107357 7731 0 0
t$perf-norm:sp_cancel_write_off 895 895 0 0.00 0 6092 32 0 0
t$perf-norm:sp_client_order 18769 18769 0 0.00 0 18204 39 0 0
t$perf-norm:sp_customer_reserve 961 961 0 0.00 0 362 13 0 0
t$perf-norm:sp_pay_from_customer 6737 6737 0 0.00 0 14395 20 0 0
t$perf-norm:sp_pay_to_supplier 5416 5416 0 0.00 0 8523 19 0 0
t$perf-norm:sp_reserve_write_off 9446 9446 0 0.00 0 7670 22 0 0
t$perf-norm:sp_supplier_invoice 5298 5298 0 0.00 0 3163 31 0 0
t$perf-norm:sp_supplier_order 5240 5240 0 0.00 0 15082 37 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 1119 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 18 3 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 1364 17 0 0
t$perf-norm:srv_make_money_saldo 869 869 0 0.00 0 541 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 206 70 0 0
v_add_invoice_to_stock 9640 9522 118 1.22 0 9890 25 0 118
v_all_customers 34584 34584 0 0.00 0 71 4 0 0
v_all_suppliers 29196 29196 0 0.00 0 122 4 0 0
v_all_wares 37524 37524 0 0.00 0 182 4 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 9088 25 0 0
v_cancel_client_order 3272 3272 0 0.00 0 2870 10 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 4278 17 0 0
v_cancel_customer_reserve 1278 1278 0 0.00 1 9742 18 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 588 10 0 0
v_cancel_supplier_invoice 776 766 10 1.28 0 7000 40 0 10
v_cancel_supplier_order 790 790 0 0.00 0 1681 12 0 0
v_cancel_write_off 1790 1790 0 0.00 0 5352 30 0 0
v_max_id_clo_ord 5237 5237 0 0.00 0 6020 6 0 0
v_max_id_clo_res 961 961 0 0.00 0 833 12 0 0
v_max_id_ord_sup 5292 5292 0 0.00 0 4658 10 0 0
v_max_non_paid_invoice 1346 1346 0 0.00 0 118 7 0 0
v_max_non_paid_realizn 3454 3454 0 0.00 0 4401 9 0 0
v_min_id_clo_ord 5237 5237 0 0.00 0 2068 8 0 0
v_min_id_clo_res 961 961 0 0.00 0 9355 29 0 0
v_min_id_ord_sup 5292 5292 0 0.00 0 8521 10 0 0
v_min_non_paid_invoice 5415 5415 0 0.00 0 5908 16 0 0
v_min_non_paid_realizn 6736 6736 0 0.00 0 4725 17 0 0
v_our_firm 9500 9500 0 0.00 0 78 4 0 0
v_reserve_write_off 18888 18888 0 0.00 0 7219 11 0 0
x_lock_depdocs_on_canc_invoice 11021 11021 0 0.00 0 26609 152 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 15504 418 0 0
x_make_qstorno_1000_1200 5240 5240 0 0.00 2 57224 1224 0 0
x_make_qstorno_1200_2000 5298 5298 0 0.00 4 99712 3048 0 0
x_make_qstorno_2100_3300 451105 451105 0 0.00 0 142117 247 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-03-2023 04:38:49 to 02-03-2023 04:38:53.

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
02:07:32 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:33 23914 8192000000 9724466880 10020425728 798490512 8.881 100 90 180 0 827629808 7697904 21441392
02:10:07 23706 8192000000 9765309168 10072444928 837595904 9.276 100 91 182 0 871220544 11807728 21816912
02:11:37 18822 8192000000 9799832880 10116419584 864523792 9.545 100 82 161 0 903177632 20113232 18540608
02:13:04 7675 8192000000 9843908352 10176897024 911637568 10.013 100 83 162 0 964881952 35710176 17534208
02:14:20 4603 8192000000 9929291088 10271719424 959392880 10.483 100 86 170 0 1038963296 62297872 17272544
02:15:27 2426 8192000000 9967572784 10317987840 985805632 10.741 100 93 185 0 1087148640 82397248 18945760
02:16:31 1458 8192000000 9999307856 10363846656 1013971824 11.014 100 95 188 1 1118100272 85266016 18862432
02:17:34 1302 8192000000 10007505152 10377011200 1044166320 11.305 100 93 176 2 1130657504 68751104 17740080
02:18:37 1009 8192000000 10042909552 10419245056 1054572720 11.405 100 97 192 2 1162293232 88051440 19669072
02:19:40 1550 8192000000 10038524448 10415345664 1070198272 11.554 100 92 169 0 1154802864 67332496 17272096
02:20:43 983 8192000000 10049096592 10433712128 1070959840 11.561 100 82 164 0 1168157632 80292064 16905728
02:21:51 1524 8192000000 10053750928 10438377472 1077898480 11.627 100 94 176 0 1175496784 79474224 18124080
02:22:54 1228 8192000000 10055993840 10440212480 1084529216 11.691 100 100 190 1 1179699488 76432768 18737504
02:23:57 1225 8192000000 10064308480 10451996672 1086930416 11.713 100 97 185 3 1187651840 82466816 18254608
02:24:59 1264 8192000000 10036383424 10427105280 1086672240 11.711 100 84 159 2 1159712016 56646912 16392864
02:26:01 1047 8192000000 10055464720 10447364096 1096345856 11.803 100 90 165 0 1180349328 66154448 17849024
02:27:03 842 8192000000 10067143072 10458013696 1097392768 11.813 100 87 162 0 1189494416 75374224 16727424
02:28:05 1049 8192000000 10057724768 10444075008 1099760992 11.835 100 96 178 1 1177385632 59296832 18327808
02:29:07 1275 8192000000 10044580272 10428469248 1081198192 11.659 99 94 173 2 1164180864 64081856 18900816
02:30:09 1045 8192000000 10039776480 10427244544 1089980432 11.742 99 90 174 1 1162855792 54976448 17898912
02:31:11 3451 8192000000 10024877696 10408800256 1088477312 11.728 100 70 126 3 1136764448 36102608 12184528
02:32:16 1165 8192000000 10007552080 10388475904 1069518880 11.547 100 93 177 0 1131216368 41669024 20028464
02:33:20 7748 8192000000 10017713568 10404413440 1039211168 11.257 98 64 126 1 1112877792 61806896 11859728
02:34:29 1100 8192000000 9956629888 10330021888 1021192256 11.084 99 83 158 2 1088376448 49781472 17402720
02:35:30 2565 8192000000 9970673040 10343772160 1036006640 11.226 100 86 149 2 1088279936 39621856 12651440
02:36:34 23457 8192000000 9952981888 10341965824 1039071840 11.256 100 43 84 0 1079963312 31175792 9715680
02:38:03 15219 8192000000 10011046720 10384166912 1044027568 11.303 100 83 143 3 1120962896 62579200 14356128
02:39:30 7709 8192000000 10024698608 10414800896 1051199504 11.372 100 54 105 1 1139141840 77420784 10521552
02:40:42 6181 8192000000 10048645616 10430476288 1071130704 11.563 100 79 151 1 1175700704 88590624 15979376
02:41:57 5993 8192000000 10071681728 10469822464 1081590672 11.663 100 65 121 1 1191358640 96815696 12952272
02:43:10 5114 8192000000 10125317808 10518519808 1105511968 11.890 100 82 162 0 1248610800 125876352 17222480
02:44:22 9689 8192000000 10117803392 10522836992 1115433680 11.984 100 53 106 0 1229838960 102802656 11602624
02:45:36 8837 8192000000 10183934304 10579197952 1132868736 12.148 100 71 140 0 1288503984 140744384 14890864
02:46:49 2306 8192000000 10228140816 10623746048 1136776048 12.185 100 82 161 0 1339771392 185503360 17491984
02:47:56 1542 8192000000 10257583424 10653724672 1159416944 12.398 100 84 163 1 1369897792 192828336 17652512
02:48:59 1078 8192000000 10295912176 10692206592 1167068752 12.469 100 95 187 1 1405947232 218530336 20348144
02:50:06 1008 8192000000 10326053008 10723442688 1172325440 12.519 100 92 176 0 1435275408 243048272 19901696
02:51:12 1211 8192000000 10359620672 10758623232 1179810576 12.588 100 96 183 3 1466166912 264595200 21761136
02:52:17 772 8192000000 10387389408 10800885760 1193389232 12.715 100 99 191 1 1494839488 279678496 21771760
02:53:23 1658 8192000000 10447187696 10849603584 1201087552 12.786 100 91 176 0 1552962064 331658928 20215584
02:54:30 1841 8192000000 10459827072 10873884672 1202223136 12.797 100 82 157 1 1567349184 346278864 18847184
02:55:36 1577 8192000000 10519681744 10925400064 1214357936 12.909 100 97 186 0 1625392000 388405728 22628336
02:56:40 1350 8192000000 10552805280 10986463232 1210417792 12.873 100 93 178 1 1656668720 424782576 21468352
02:57:46 910 8192000000 10556724368 10989109248 1210507040 12.874 100 97 190 2 1665934928 430580144 24847744
02:58:52 949 8192000000 10589321216 11029639168 1217805376 12.941 100 94 180 0 1691707072 450978560 22923136
02:59:57 1027 8192000000 10600615648 11064201216 1225391088 13.012 100 97 190 1 1702455760 453697824 23366848
03:01:01 1806 8192000000 10567193952 11058008064 1220268752 12.964 100 95 186 0 1672097856 428844464 22984640
03:02:07 1401 8192000000 10578864384 11017564160 1209815680 12.867 100 75 150 0 1679280624 451569728 17895216
03:03:18 10685 8192000000 10561190736 11019935744 1196815712 12.747 100 55 104 0 1677397376 466886864 13694800
03:04:34 8329 8192000000 10541578000 11015757824 1183604064 12.624 100 50 98 1 1649285648 452879840 12801744
03:05:54 12715 8192000000 10537200032 11023863808 1182264416 12.611 100 48 96 0 1651836208 457321792 12250000
03:07:08 39425 8192000000 10456187776 10957733888 1175513312 12.548 100 31 62 0 1584385200 400486496 8385392
03:09:04 31276 8192000000 10363238320 10857058304 1126951072 12.093 97 35 68 0 1506505776 370971968 8582736
03:10:39 43469 8192000000 10218582352 10748416000 1125701344 12.081 99 28 50 0 1364009488 232251648 6056496
03:12:24 56239 8192000000 10057024576 10495119360 1083721728 11.683 96 29 54 0 1128084944 38836912 5526304
03:14:21 61885 8192000000 10081106288 10519371776 1123207408 12.057 99 36 69 1 1165266752 34163808 7895536
03:16:32 159100 8192000000 10131729072 10593333248 1112142944 11.953 98 36 71 1 1198832352 78871232 7818176
03:20:16 48543 8192000000 10063241120 10492956672 1108867392 11.922 98 28 57 0 1150457536 35063456 6526688
03:22:10 10746 8192000000 10095954944 10514124800 1136418880 12.182 100 65 121 1 1180954352 32755008 11780464
03:23:21 16238 8192000000 10128738736 10562891776 1141294800 12.228 100 51 99 1 1242224304 89605936 11323568
03:24:41 5209 8192000000 10140620592 10560512000 1134735712 12.166 100 76 139 0 1232789344 81835328 16218304
03:25:58 37270 8192000000 10137182688 10622361600 1142576400 12.240 100 18 32 0 1272627488 126170048 3881040
03:27:42 132105 8192000000 10106277104 10581843968 1114402176 11.974 97 17 34 0 1185597504 67249232 3946096
03:33:04 38654 8192000000 10206139232 10668146688 1138651712 12.203 98 41 81 1 1285702560 138066624 8984224
03:35:03 67820 8192000000 10146984224 10624135168 1110550016 11.938 97 15 28 0 1204059680 89905248 3604416
03:37:16 25957 8192000000 10088933216 10521948160 1140108656 12.217 100 41 80 1 1186828032 37982816 8736560
03:38:43 23052 8192000000 10117702432 10567688192 1131961504 12.140 99 41 81 0 1225818464 85534544 8322416
03:40:09 70214 8192000000 10134338592 10658086912 1147567664 12.287 100 14 26 0 1281100272 130279968 3252640
03:42:20 16405 8192000000 10102111488 10530156544 1125649824 12.080 98 38 74 1 1172386352 39255456 7481072
03:43:39 144863 8192000000 10103884288 10564927488 1115811536 11.987 97 38 72 1 1185045728 60615120 8619072
03:47:13 26309 8192000000 10112921888 10576629760 1140469760 12.220 99 23 45 0 1218063840 73318864 4275216
03:48:41 73033 8192000000 10136127520 10613796864 1147455024 12.286 99 12 24 0 1212353600 62310880 2587696
03:51:29 54950 8192000000 10107250992 10572906496 1122540992 12.051 98 23 36 1 1210381168 83848416 3991760
03:53:24 57700 8192000000 10086139616 10530693120 1106391104 11.898 97 21 40 0 1151441024 40826816 4223104
03:55:22 29643 8192000000 10058424256 10528440320 1110162592 11.934 98 21 39 0 1171928720 57192128 4574000
03:56:54 13806 8192000000 10104829568 10540195840 1123842592 12.063 99 35 70 0 1197601168 66382320 7376256
03:58:10 26495 8192000000 10134272864 10562752512 1135999744 12.178 99 46 91 1 1211391120 66282112 9109264
03:59:40 18271 8192000000 10152885936 10588622848 1116677824 11.996 98 37 74 0 1233057424 108593664 7785936
04:01:09 69917 8192000000 10106079856 10548797440 1085821584 11.703 96 20 40 0 1212135792 121924960 4389248
04:03:23 109278 8192000000 10061218912 10523938816 1106931104 11.903 98 12 24 0 1170159680 61013344 2215232
04:06:13 12832 8192000000 10086971776 10523992064 1106205920 11.896 98 57 101 0 1152255904 36074544 9975440
04:07:26 115015 8192000000 10082126688 10527862784 1132349376 12.144 100 30 59 0 1197156272 58725360 6081536
04:10:23 9163 8192000000 10090930896 10517004288 1133310112 12.153 100 72 140 1 1188614432 40368672 14935648
04:11:34 53832 8192000000 10080208736 10533457920 1113916368 11.969 98 21 42 0 1174974928 56948288 4110272
04:13:33 41936 8192000000 10089733984 10539995136 1133212832 12.152 99 24 40 1 1181003536 43365200 4425504
04:15:24 38366 8192000000 10108827920 10577784832 1134099232 12.160 99 28 53 1 1203269648 63172736 5997680
04:17:17 39711 8192000000 10086397264 10538057728 1138277024 12.199 100 19 38 0 1205778832 63033504 4468304
04:18:58 43159 8192000000 10117601408 10583166976 1115229904 11.982 97 24 48 0 1202346784 82498816 4618064
04:20:55 33872 8192000000 10120838912 10559938560 1125652544 12.080 98 32 62 1 1212573136 80272400 6648192
04:22:35 1546 8192000000 10194759504 10620436480 1150969760 12.319 100 69 129 1 1289748720 126198768 12580192
04:23:44 64949 8192000000 10126262736 10595663872 1108361760 11.917 96 21 42 0 1212125952 99204208 4559984
04:25:51 19259 8192000000 10093299360 10541780992 1130222256 12.123 99 59 116 3 1194369392 52484320 11662816
04:27:11 59633 8192000000 10105408448 10572369920 1145857584 12.271 100 40 80 0 1236909056 83117088 7934384
04:29:19 4569 8192000000 10158845296 10575024128 1142041296 12.235 100 75 143 0 1251610592 94290512 15278784
04:30:25 107052 8192000000 10079954752 10575040512 1134299008 12.162 99 27 54 0 1215331168 75093376 5938784
04:33:22 55908 8192000000 10099292880 10617954304 1140546624 12.221 100 7 14 0 1203906640 61892880 1467136
04:35:18 32101 8192000000 10082860144 10559889408 1146352976 12.275 100 10 20 0 1190630880 42483344 1794560
04:36:59 34019 8192000000 9465081040 9818075136 570350800 6.509 50 20 40 0 611630320 37164560 4114960

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 02-03-2023 04:38:55 to 02-03-2023 04:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 59 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-03-2023 04:39:13 to 02-03-2023 04:39:15.

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          214567
MON$OLDEST_ACTIVE               214568
MON$OLDEST_SNAPSHOT             214568
MON$NEXT_TRANSACTION            214568
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                       2046496
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             760
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2904 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2904 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2904 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2904 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 ---
:81: 
:82: mscs-test-161.budzdorov.ru    Thu Mar  2 04:37:37 2023
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:84: 
--- end of diff output ---

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

02.03.23 04:39:16. 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-02 04:39:19 No severe PSQL-related problems occured
02.03.23 04:39:20. End of report.