Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.08.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 15427900 kB
MemAvailable 30937740 kB
Buffers 0 kB
Cached 16297228 kB
SwapCached 2516 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-08-13 10:37:18
DTS_END                         2022-08-13 10:37:18.7470
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60590
TRN_ID                          218732
ATT_ID                          589
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: 9025.37 29475 1083044
customer order: creation 166.54 446 19985
customer order: refuse 14.15 437 1698
order to supplier: creation 45.28 1374 5434
order to supplier: removal 3.33 183226 399
invoice (draft): creation 44.98 3092 5397
invoice (draft): removal 49.52 1078 5942
invoice accept: apply 41.13 27996 4935
invoice accept: cancel 44.68 1303 5361
customer reserve: creation 3929.98 260 471598
customer reserve: removal 4400.71 108 528085
realization accept: apply 81.68 282 9802
realization accept: cancel 76.50 184 9180
payment from customer: creation 57.68 193 6921
payment from customer: removal 5.32 143 638
payment to supplier: creation 47.79 204 5735
payment to supplier: removal 3.45 131 414
service: total inventory turnovers 3.96 4180 475
service: total monetary turnovers 7.70 426 924
service: aggregate perf. data 0.98 15969 117
service: refresh index statistics 0.03 348474 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-08-2022 10:39:19 to 13-08-2022 10:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3657
TEST_TIME 2 4788
TEST_TIME 3 5217
TEST_TIME 4 5235
TEST_TIME 5 5362
TEST_TIME 6 5505
TEST_TIME 7 5465
TEST_TIME 8 5680
TEST_TIME 9 5654
TEST_TIME 10 5715
TEST_TIME 11 5784
TEST_TIME 12 5929
TEST_TIME 13 6036
TEST_TIME 14 6002
TEST_TIME 15 6204
TEST_TIME 16 6429
TEST_TIME 17 6604
TEST_TIME 18 6811
TEST_TIME 19 6820
TEST_TIME 20 7225
TEST_TIME 21 7495
TEST_TIME 22 7418
TEST_TIME 23 7459
TEST_TIME 24 7627
TEST_TIME 25 7932
TEST_TIME 26 8549
TEST_TIME 27 8762
TEST_TIME 28 8758
TEST_TIME 29 8669
TEST_TIME 30 8853
TEST_TIME 31 9166
TEST_TIME 32 9752
TEST_TIME 33 9892
TEST_TIME 34 9751
TEST_TIME 35 9880
TEST_TIME 36 10257
TEST_TIME 37 10469
TEST_TIME 38 11035
TEST_TIME 39 11085
TEST_TIME 40 10862
TEST_TIME 41 10723
TEST_TIME 42 10702
TEST_TIME 43 10549
TEST_TIME 44 10436
TEST_TIME 45 10419
TEST_TIME 46 10386
TEST_TIME 47 10338
TEST_TIME 48 10182
TEST_TIME 49 10038
TEST_TIME 50 9991
TEST_TIME 51 9947
TEST_TIME 52 9948
TEST_TIME 53 9971
TEST_TIME 54 9870
TEST_TIME 55 9993
TEST_TIME 56 10004
TEST_TIME 57 9938
TEST_TIME 58 9968
TEST_TIME 59 9944
TEST_TIME 60 9877
TEST_TIME 61 9830
TEST_TIME 62 9854
TEST_TIME 63 9912
TEST_TIME 64 9834
TEST_TIME 65 9844
TEST_TIME 66 9910
TEST_TIME 67 9862
TEST_TIME 68 9777
TEST_TIME 69 9835
TEST_TIME 70 9855
TEST_TIME 71 9848
TEST_TIME 72 9885
TEST_TIME 73 9756
TEST_TIME 74 9642
TEST_TIME 75 9609
TEST_TIME 76 9607
TEST_TIME 77 9522
TEST_TIME 78 9460
TEST_TIME 79 9407
TEST_TIME 80 9358
TEST_TIME 81 9327
TEST_TIME 82 9390
TEST_TIME 83 9386
TEST_TIME 84 9384
TEST_TIME 85 9357
TEST_TIME 86 9336
TEST_TIME 87 9463
TEST_TIME 88 9447
TEST_TIME 89 9431
TEST_TIME 90 9412
TEST_TIME 91 9522
TEST_TIME 92 9467
TEST_TIME 93 9402
TEST_TIME 94 9407
TEST_TIME 95 9325
TEST_TIME 96 9275
TEST_TIME 97 9270
TEST_TIME 98 9245
TEST_TIME 99 9194
TEST_TIME 100 9194
TEST_TIME 101 9169
TEST_TIME 102 9150
TEST_TIME 103 9162
TEST_TIME 104 9199
TEST_TIME 105 9187
TEST_TIME 106 9197
TEST_TIME 107 9213
TEST_TIME 108 9199
TEST_TIME 109 9215
TEST_TIME 110 9191
TEST_TIME 111 9197
TEST_TIME 112 9257
TEST_TIME 113 9268
TEST_TIME 114 9205
TEST_TIME 115 9132
TEST_TIME 116 9157
TEST_TIME 117 9194
TEST_TIME 118 9136
TEST_TIME 119 9110
TEST_TIME 120 9043

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-08-2022 10:39:23 to 13-08-2022 10:39:26.

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 1515169 1515169 0 0.00 0 23724 14 0 0
doc_list_biud 1515160 1515160 0 0.00 0 44612 44 0 0
sp_add_invoice_to_stock 5026 4935 91 1.81 66 235992 27997 0 91
sp_cancel_adding_invoice 5361 5361 0 0.00 6 96801 1304 0 0
sp_cancel_client_order 1698 1698 0 0.00 6 13285 437 0 0
sp_cancel_customer_reserve 528085 528085 0 0.00 1 27735 108 0 0
sp_cancel_pay_from_customer 638 638 0 0.00 4 5363 144 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 7551 132 0 0
sp_cancel_supplier_invoice 5952 5942 10 0.16 2 30849 1079 0 10
sp_cancel_supplier_order 399 399 0 0.00 8 1438821 183227 0 0
sp_cancel_write_off 9180 9180 0 0.00 2 11672 185 0 0
sp_client_order 19985 19985 0 0.00 6 18258 447 0 0
sp_customer_reserve 471598 471598 0 0.00 0 90928 260 0 0
sp_fill_shopping_cart 30793 30793 0 0.00 1 11244 80 0 0
sp_fill_shopping_cart_clo_res 471584 471584 0 0.00 0 18552 19 0 0
sp_get_clo_for_invoice 4917 4917 0 0.00 0 33225 303 0 0
sp_kill_cost_storno 15593 15593 0 0.00 0 9074 82 0 0
sp_kill_qstorno_ret_qs2qd 1071691 1071691 0 0.00 0 44610 47 0 0
sp_kill_qty_storno 565383 565383 0 0.00 0 44611 108 0 0
sp_lock_selected_doc 20314 20314 0 0.00 0 7035 7 0 0
sp_make_cost_storno 27375 27375 0 0.00 0 13967 87 0 0
sp_multiply_rows_for_pdistr 27374 27374 0 0.00 0 23686 40 0 0
sp_multiply_rows_for_qdistr 19983 19983 0 0.00 0 13022 176 0 0
sp_pay_from_customer 6921 6921 0 0.00 5 15021 194 0 0
sp_pay_to_supplier 5735 5735 0 0.00 6 23731 205 0 0
sp_payment_common 12654 12654 0 0.00 2 12072 46 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 0 13268 364 0 0
sp_qd_handle_on_invoice_upd_sts 10277 10277 0 0.00 0 18471 566 0 0
sp_qd_handle_on_reserve_upd_sts 9802 9802 0 0.00 0 11988 62 0 0
sp_reserve_write_off 9802 9802 0 0.00 8 16507 282 0 0
sp_supplier_invoice 5397 5397 0 0.00 9 109246 3092 0 0
sp_supplier_order 5434 5434 0 0.00 8 28104 1375 0 0
srv_aggregate_perf_data 118 117 1 0.84 2067 187253 15969 0 1
srv_fill_mon_cache_memory 73 73 0 0.00 769 136436 30714 0 0
srv_find_qd_qs_mism 1033435 1033435 0 0.00 0 23305 11 0 0
srv_make_invnt_saldo 475 475 0 0.00 57 58083 4181 0 0
srv_make_money_saldo 924 924 0 0.00 1 20543 427 0 0
srv_recalc_idx_stat 4 4 0 0.00 185804 487155 348474 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4940 4940 0 0.00 0 20161 952 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 1 6698 471 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 7925 33 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 1395 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 638 638 0 0.00 0 3672 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 468 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 3440 51 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 108074 8441 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 5125 29 0 0
t$perf-norm:sp_client_order 19987 19987 0 0.00 0 7876 38 0 0
t$perf-norm:sp_customer_reserve 950 950 0 0.00 0 6113 32 0 0
t$perf-norm:sp_pay_from_customer 6921 6921 0 0.00 0 6340 22 0 0
t$perf-norm:sp_pay_to_supplier 5735 5735 0 0.00 0 9585 25 0 0
t$perf-norm:sp_reserve_write_off 9803 9803 0 0.00 0 6338 25 0 0
t$perf-norm:sp_supplier_invoice 5397 5397 0 0.00 0 8225 34 0 0
t$perf-norm:sp_supplier_order 5434 5434 0 0.00 0 5579 32 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 745 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 250 7 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 1482 20 0 0
t$perf-norm:srv_make_money_saldo 924 924 0 0.00 0 3159 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 21 202 81 0 0
v_add_invoice_to_stock 10000 9818 182 1.82 0 5905 26 0 182
v_all_customers 36512 36512 0 0.00 0 186 4 0 0
v_all_suppliers 30328 30328 0 0.00 0 164 4 0 0
v_all_wares 39954 39954 0 0.00 0 65 4 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 1776 21 0 0
v_cancel_client_order 3396 3396 0 0.00 0 6679 12 0 0
v_cancel_customer_prepayment 1276 1276 0 0.00 0 3800 18 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 1176 11 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 1233 13 0 0
v_cancel_supplier_invoice 766 746 20 2.61 0 457 25 0 20
v_cancel_supplier_order 798 798 0 0.00 0 1392 14 0 0
v_cancel_write_off 2040 2040 0 0.00 0 4103 25 0 0
v_max_id_clo_ord 5427 5427 0 0.00 0 4690 7 0 0
v_max_id_clo_res 950 950 0 0.00 0 1757 11 0 0
v_max_id_ord_sup 5383 5383 0 0.00 0 5184 7 0 0
v_max_non_paid_invoice 1378 1378 0 0.00 0 191 8 0 0
v_max_non_paid_realizn 3567 3567 0 0.00 0 996 8 0 0
v_min_id_clo_ord 5427 5427 0 0.00 0 4387 10 0 0
v_min_id_clo_res 950 950 0 0.00 0 4710 20 0 0
v_min_id_ord_sup 5383 5383 0 0.00 0 6674 11 0 0
v_min_non_paid_invoice 5733 5733 0 0.00 0 10771 14 0 0
v_min_non_paid_realizn 6921 6921 0 0.00 0 12069 19 0 0
v_our_firm 10150 10150 0 0.00 0 93 5 0 0
v_reserve_write_off 19604 19604 0 0.00 0 4242 11 0 0
x_lock_depdocs_on_canc_invoice 10930 10930 0 0.00 0 14766 141 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 5937 413 0 0
x_make_qstorno_1000_1200 5434 5434 0 0.00 3 28069 1203 0 0
x_make_qstorno_1200_2000 5397 5397 0 0.00 4 108259 2882 0 0
x_make_qstorno_2100_3300 471598 471598 0 0.00 0 90926 229 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-08-2022 10:39:27 to 13-08-2022 10:39:31.

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:22 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:23 24783 8192000000 9717061152 10014294016 801637456 8.913 100 88 176 0 830551904 7913200 21001248
08:09:56 16604 8192000000 9763937952 10073022464 836482848 9.264 100 85 170 0 869174432 12388608 20302976
08:11:20 16287 8192000000 9796968848 10115526656 864898448 9.549 100 92 182 1 906134864 19762240 21474176
08:12:50 8095 8192000000 9872204816 10205036544 914892304 10.046 100 86 170 0 979343440 45536704 18914432
08:14:01 6410 8192000000 9915593952 10256629760 944760864 10.340 100 86 172 0 1029282928 66073360 18448704
08:15:10 2363 8192000000 9946382576 10294329344 977592464 10.661 100 90 174 1 1061460880 65215536 18652880
08:16:14 1588 8192000000 9979217472 10339348480 1008498592 10.961 100 88 171 0 1091567376 65710912 17357872
08:17:18 909 8192000000 9995735696 10362331136 1026900368 11.139 100 84 155 2 1119964704 78299072 14765264
08:18:24 1143 8192000000 10027939648 10398593024 1046835744 11.330 100 91 183 1 1150147408 84306560 19005104
08:19:26 811 8192000000 10019094496 10396139520 1058941744 11.446 100 91 177 0 1141330992 65050432 17338816
08:20:28 3333 8192000000 10041048752 10433179648 1059786704 11.454 100 78 156 0 1160319840 84803088 15730048
08:21:37 2115 8192000000 10045009216 10425229312 1068858240 11.541 100 85 160 3 1165668496 81424784 15385472
08:22:41 1162 8192000000 10049935680 10434326528 1075388944 11.604 100 97 185 1 1174660112 80989568 18281600
08:23:43 1273 8192000000 10044229024 10428362752 1077695632 11.626 100 93 178 3 1165065920 69490640 17879648
08:24:45 1487 8192000000 10028825872 10416758784 1089340304 11.736 100 77 145 3 1151733760 47668096 14725360
08:25:47 5255 8192000000 10037863440 10435923968 1084779840 11.693 100 72 141 2 1174652416 74939136 14933440
08:26:53 895 8192000000 10052795728 10438803456 1097639680 11.815 100 85 153 1 1172961536 59447936 15873920
08:27:55 1086 8192000000 10047283744 10437279744 1098132528 11.820 100 82 154 2 1167119840 53277504 15709808
08:28:57 1288 8192000000 10045031376 10432081920 1082506080 11.671 99 79 152 0 1171017712 72632448 15879184
08:29:59 1062 8192000000 10044534016 10429595648 1086565744 11.710 100 92 184 0 1171377136 66120000 18691392
08:31:01 1156 8192000000 10059510304 10439385088 1066302208 11.517 99 92 178 0 1178893616 94748400 17843008
08:32:04 1224 8192000000 10045752160 10429857792 1081163728 11.659 100 83 163 1 1167797056 70360624 16272704
08:33:07 1991 8192000000 10004490944 10381778944 1042458016 11.288 100 88 168 0 1128144880 69854480 15832384
08:34:10 981 8192000000 9975289232 10347532288 1029829344 11.167 100 91 168 2 1096129680 49783952 16516384
08:35:12 981 8192000000 9998443712 10372911104 1050043616 11.361 100 96 187 0 1120541232 50075040 20422576
08:36:14 918 8192000000 10016714592 10396168192 1043911488 11.302 100 85 157 0 1137467984 77267680 16288816
08:37:16 2110 8192000000 9964249536 10345820160 1035018832 11.217 100 71 115 4 1090890736 44254208 11617696
08:38:25 4892 8192000000 9972645168 10351820800 1049473344 11.356 100 65 120 1 1095852112 35273344 11105424
08:39:37 1297 8192000000 10031677536 10415656960 1072052736 11.572 100 91 163 2 1151782688 62017920 17712032
08:40:44 19506 8192000000 10095906752 10491400192 1091936880 11.761 100 57 113 0 1211394144 107311488 12145776
08:42:13 3977 8192000000 10113646912 10507710464 1107326352 11.907 100 67 120 0 1234051824 113379856 13345616
08:43:21 6369 8192000000 10146001424 10554998784 1113545664 11.966 100 77 143 0 1255798896 127410144 14843088
08:44:52 3893 8192000000 10190599872 10595676160 1132033088 12.141 100 76 135 2 1301444176 154611456 14799632
08:46:01 7736 8192000000 10224416768 10625740800 1140559536 12.221 100 78 146 0 1339627584 184149040 14919008
08:47:21 1299 8192000000 10262395888 10661683200 1159549824 12.399 100 97 190 1 1376263040 196028976 20684240
08:48:28 1498 8192000000 10302042960 10701647872 1175403904 12.547 100 87 163 0 1415494768 222159248 17931616
08:49:35 2273 8192000000 10352977072 10755874816 1179891504 12.589 100 93 177 0 1469041184 269350224 19799456
08:50:41 1400 8192000000 10374555056 10783367168 1187655264 12.662 100 91 174 2 1476979392 270042448 19281680
08:51:47 941 8192000000 10420763152 10842533888 1192145104 12.703 100 91 176 1 1532330032 319481440 20703488
08:52:50 963 8192000000 10424863248 10858397696 1189136928 12.675 100 93 166 1 1532147168 323829504 19180736
08:53:57 765 8192000000 10447967648 10871951360 1207870352 12.849 100 95 184 1 1554711072 323599936 23240784
08:55:01 1396 8192000000 10477202496 10897408000 1200854512 12.784 100 96 183 0 1586535680 364858432 20822736
08:56:06 1068 8192000000 10494594848 10927165440 1206621184 12.838 100 95 184 1 1600699888 372138800 21939904
08:57:11 804 8192000000 10524263264 10975449088 1206746704 12.839 100 96 182 0 1630878256 402562544 21569008
08:58:14 10416 8192000000 10528208560 10967543808 1199812544 12.775 100 53 106 0 1631013872 417658128 13543200
08:59:28 3211 8192000000 10532644464 10946080768 1191256656 12.695 100 81 153 0 1645924880 436945184 17723040
09:00:35 5438 8192000000 10548419232 10982780928 1177377616 12.566 99 72 143 1 1661280304 465411856 18490832
09:01:46 3652 8192000000 10568896672 11023486976 1178689056 12.578 100 71 140 0 1680550976 483619520 18242400
09:02:58 5099 8192000000 10515186896 10995376128 1179173840 12.582 100 68 134 0 1637994832 442242176 16578816
09:04:09 17976 8192000000 10387235968 10876235776 1165889248 12.458 100 33 64 0 1530429792 356650528 7890016
09:05:51 8051 8192000000 10434728096 10867740672 1162281104 12.425 100 47 92 2 1534814576 361498416 11035056
09:07:04 2908 8192000000 10449575760 10902585344 1144060656 12.254 99 70 137 0 1543917328 383463680 16392992
09:08:09 2524 8192000000 10399443520 10903728128 1159054832 12.394 100 62 122 1 1495471088 321590640 14825616
09:09:13 16552 8192000000 10319185328 10816335872 1138576832 12.202 98 48 94 1 1400964128 251025360 11361936
09:10:36 28809 8192000000 10336017440 10810552320 1162670512 12.428 99 46 89 2 1419250576 245249344 11330720
09:12:11 24089 8192000000 10367582992 10849611776 1160705248 12.410 100 31 62 0 1485226576 316185600 8335728
09:13:35 62296 8192000000 10252135920 10743746560 1117969968 12.008 97 23 46 0 1364823168 240898560 5954640
09:16:10 58936 8192000000 10055228848 10520965120 1106024800 11.895 99 8 16 0 1177116224 69650288 1441136
09:18:25 59065 8192000000 10102498544 10540490752 1121313872 12.039 99 31 63 0 1181272160 53680976 6277312
09:20:30 20024 8192000000 10038523680 10486992896 1112726560 11.958 99 45 82 1 1139913296 19042272 8144464
09:22:03 136436 8192000000 10100873760 10549833728 1111472240 11.946 97 23 46 0 1164047360 47726832 4848288
09:25:23 92442 8192000000 10148936224 10590474240 1157700032 12.382 100 32 62 0 1243163616 78732848 6730736
09:28:00 2292 8192000000 10177039712 10598694912 1149838704 12.308 99 70 138 1 1275006064 109947424 15219936
09:29:05 2706 8192000000 10194189808 10612334592 1134743488 12.166 99 87 158 5 1289477856 135704144 19030224
09:30:09 11600 8192000000 10230543040 10654892032 1154333824 12.350 100 61 114 2 1341010272 173169936 13506512
09:31:26 58300 8192000000 10172541696 10694008832 1144850960 12.261 99 13 26 0 1288396464 140117664 3427840
09:34:29 85451 8192000000 10108821856 10580819968 1130869472 12.130 99 23 43 3 1226002912 89932000 5201440
09:36:59 10830 8192000000 10060205648 10497400832 1134322224 12.162 99 21 34 1 1161567872 23820544 3425104
09:38:14 73041 8192000000 10101729008 10569793536 1136352624 12.181 99 13 24 0 1195628464 56585120 2690720
09:40:28 45757 8192000000 10057603264 10493038592 1082111248 11.668 95 20 37 0 1109775648 24087760 3576640
09:42:20 22193 8192000000 10119239424 10547941376 1129523856 12.117 100 39 76 0 1201203872 63623216 8056800
09:43:44 53109 8192000000 10054800704 10500874240 1122495328 12.051 99 9 18 1 1161001344 36616800 1889216
09:45:38 26236 8192000000 10124823712 10543628288 1134869024 12.167 100 47 94 0 1211209712 66611664 9729024
09:47:09 57451 8192000000 10099401424 10531913728 1114784400 11.978 97 17 30 0 1158754016 40802432 3167184
09:49:19 83021 8192000000 10032136928 10492305408 1101265536 11.850 98 11 22 0 1140366224 37220688 1880000
09:51:44 19989 8192000000 10064979104 10490023936 1122919200 12.055 100 41 78 0 1164648048 33024816 8704032
09:53:05 61126 8192000000 10073726992 10563751936 1134640096 12.165 100 24 48 0 1195868096 56449920 4778080
09:55:07 38147 8192000000 10113971984 10563739648 1154528416 12.352 100 29 59 0 1221144128 59837952 6777760
09:56:54 46659 8192000000 10143465984 10606039040 1136046064 12.178 98 19 38 0 1239005296 97957808 5001424
09:59:41 43108 8192000000 10116038048 10582835200 1136784736 12.185 99 24 45 0 1203768256 61885568 5097952
10:01:32 27804 8192000000 10133304800 10568695808 1127809632 12.101 99 37 74 0 1195702944 59218768 8674544
10:03:06 42565 8192000000 10155500192 10593665024 1114114496 11.971 98 30 60 0 1242794752 122481472 6198784
10:04:52 18659 8192000000 10094899936 10544844800 1135994976 12.178 100 42 83 0 1181387088 37085744 8306368
10:06:11 10491 8192000000 10100840688 10524327936 1117534176 12.004 99 54 107 1 1188185408 60266864 10384368
10:07:23 17505 8192000000 10189372944 10612531200 1128755712 12.110 99 58 114 0 1251332608 111022896 11554000
10:08:43 28540 8192000000 10068087360 10498117632 1124357648 12.068 99 26 51 0 1162904400 32537904 6008848
10:10:27 77491 8192000000 10051031136 10525577216 1113481056 11.965 98 34 64 2 1164270400 44004832 6784512
10:12:47 66575 8192000000 10105757968 10559430656 1137287136 12.190 100 22 44 1 1203120288 60754144 5079008
10:15:01 66366 8192000000 10073205952 10590961664 1130797120 12.129 100 6 12 0 1171305696 39273792 1234784
10:17:11 43981 8192000000 10056178672 10535796736 1114688016 11.977 99 10 20 0 1186041888 69562080 1791792
10:18:58 15624 8192000000 10090030208 10524680192 1128362096 12.106 99 34 66 0 1178790000 43955856 6472048
10:20:18 31075 8192000000 10115584736 10557771776 1114973648 11.979 98 31 58 1 1177371760 55169376 7228736
10:21:58 34673 8192000000 10082720336 10547572736 1122189504 12.048 99 21 42 0 1181244976 54532080 4523392
10:23:42 57018 8192000000 10073034032 10520883200 1110115504 11.934 99 20 40 0 1168484448 54070608 4298336
10:25:40 24140 8192000000 10106600032 10541133824 1133026272 12.150 100 40 78 0 1191342960 50533920 7782768
10:27:33 13153 8192000000 10121399248 10564685824 1109096288 11.924 98 52 102 1 1208688688 89170272 10422128
10:28:53 104561 8192000000 10076494864 10543730688 1081209760 11.659 96 24 45 1 1134764832 48827328 4727744
10:31:39 105140 8192000000 10135553840 10587414528 1115849056 11.988 99 39 78 0 1230978256 107206512 7922688
10:34:41 63176 8192000000 10028330368 10536321024 1098238080 11.821 99 5 10 0 1135990576 36899168 853328
10:36:55 34313 8192000000 9053193136 9312649216 211567152 2.517 19 5 10 0 240812048 28357712 887184

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-08-2022 10:39:33 to 13-08-2022 10:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 91 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-08-2022 10:39:50 to 13-08-2022 10:39:52.

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          219084
MON$OLDEST_ACTIVE               219085
MON$OLDEST_SNAPSHOT             219085
MON$NEXT_TRANSACTION            219085
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                       2092544
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 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 ---
:211: 
:212: mscs-test-161.budzdorov.ru    Sat Aug 13 10:37:39 2022
:213:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:214: 
--- end of diff output ---

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

13.08.22 10:39:53. 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-08-13 10:39:56 No severe PSQL-related problems occured
13.08.22 10:39:57. End of report.