Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.04.2022 13:39
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 16206568 kB
MemAvailable 31572740 kB
Buffers 0 kB
Cached 15526052 kB
SwapCached 2364 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-04-10 13:37:11
DTS_END                         2022-04-10 13:37:11.0250
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/32956
TRN_ID                          222050
ATT_ID                          655
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: 9090.82 23769 1090898
customer order: creation 170.14 474 20417
customer order: refuse 14.86 400 1783
order to supplier: creation 46.18 1412 5542
order to supplier: removal 3.44 158547 413
invoice (draft): creation 45.08 3461 5409
invoice (draft): removal 51.88 970 6225
invoice accept: apply 41.16 25120 4939
invoice accept: cancel 46.71 1139 5605
customer reserve: creation 3784.86 240 454183
customer reserve: removal 4594.57 92 551348
realization accept: apply 83.28 282 9993
realization accept: cancel 77.47 160 9296
payment from customer: creation 60.46 179 7255
payment from customer: removal 5.93 119 712
payment to supplier: creation 48.58 195 5830
payment to supplier: removal 3.87 130 464
service: total inventory turnovers 3.86 3922 463
service: total monetary turnovers 7.63 400 915
service: aggregate perf. data 0.83 20814 100
service: refresh index statistics 0.05 257322 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-04-2022 13:39:37 to 10-04-2022 13:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4896
TEST_TIME 2 4944
TEST_TIME 3 4380
TEST_TIME 4 4597
TEST_TIME 5 4937
TEST_TIME 6 4940
TEST_TIME 7 5764
TEST_TIME 8 5917
TEST_TIME 9 6162
TEST_TIME 10 6758
TEST_TIME 11 6899
TEST_TIME 12 6916
TEST_TIME 13 7134
TEST_TIME 14 7512
TEST_TIME 15 7628
TEST_TIME 16 7847
TEST_TIME 17 7608
TEST_TIME 18 7901
TEST_TIME 19 8071
TEST_TIME 20 8351
TEST_TIME 21 8698
TEST_TIME 22 8784
TEST_TIME 23 8791
TEST_TIME 24 9100
TEST_TIME 25 9103
TEST_TIME 26 9148
TEST_TIME 27 8997
TEST_TIME 28 9025
TEST_TIME 29 9412
TEST_TIME 30 9548
TEST_TIME 31 10280
TEST_TIME 32 11036
TEST_TIME 33 11132
TEST_TIME 34 10907
TEST_TIME 35 10700
TEST_TIME 36 10687
TEST_TIME 37 10732
TEST_TIME 38 10723
TEST_TIME 39 10563
TEST_TIME 40 10479
TEST_TIME 41 10329
TEST_TIME 42 10195
TEST_TIME 43 10222
TEST_TIME 44 10244
TEST_TIME 45 10152
TEST_TIME 46 10194
TEST_TIME 47 10146
TEST_TIME 48 10123
TEST_TIME 49 10038
TEST_TIME 50 10178
TEST_TIME 51 10100
TEST_TIME 52 10150
TEST_TIME 53 10001
TEST_TIME 54 9891
TEST_TIME 55 9899
TEST_TIME 56 9794
TEST_TIME 57 9690
TEST_TIME 58 9676
TEST_TIME 59 9598
TEST_TIME 60 9532
TEST_TIME 61 9568
TEST_TIME 62 9592
TEST_TIME 63 9509
TEST_TIME 64 9496
TEST_TIME 65 9463
TEST_TIME 66 9395
TEST_TIME 67 9499
TEST_TIME 68 9560
TEST_TIME 69 9560
TEST_TIME 70 9590
TEST_TIME 71 9590
TEST_TIME 72 9571
TEST_TIME 73 9597
TEST_TIME 74 9594
TEST_TIME 75 9665
TEST_TIME 76 9684
TEST_TIME 77 9699
TEST_TIME 78 9712
TEST_TIME 79 9753
TEST_TIME 80 9679
TEST_TIME 81 9751
TEST_TIME 82 9749
TEST_TIME 83 9666
TEST_TIME 84 9623
TEST_TIME 85 9546
TEST_TIME 86 9487
TEST_TIME 87 9512
TEST_TIME 88 9518
TEST_TIME 89 9463
TEST_TIME 90 9412
TEST_TIME 91 9375
TEST_TIME 92 9323
TEST_TIME 93 9320
TEST_TIME 94 9247
TEST_TIME 95 9338
TEST_TIME 96 9330
TEST_TIME 97 9288
TEST_TIME 98 9279
TEST_TIME 99 9287
TEST_TIME 100 9306
TEST_TIME 101 9326
TEST_TIME 102 9295
TEST_TIME 103 9377
TEST_TIME 104 9364
TEST_TIME 105 9344
TEST_TIME 106 9313
TEST_TIME 107 9384
TEST_TIME 108 9478
TEST_TIME 109 9443
TEST_TIME 110 9419
TEST_TIME 111 9351
TEST_TIME 112 9297
TEST_TIME 113 9290
TEST_TIME 114 9293
TEST_TIME 115 9238
TEST_TIME 116 9242
TEST_TIME 117 9230
TEST_TIME 118 9195
TEST_TIME 119 9184
TEST_TIME 120 9108

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-04-2022 13:39:41 to 10-04-2022 13:39:44.

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 1508457 1508457 0 0.00 0 15738 13 0 0
doc_list_biud 1508455 1508455 0 0.00 0 92791 40 0 0
sp_add_invoice_to_stock 5078 4939 139 2.73 34 217392 25120 0 139
sp_cancel_adding_invoice 5605 5605 0 0.00 6 73038 1140 0 0
sp_cancel_client_order 1783 1783 0 0.00 7 18620 401 0 0
sp_cancel_customer_reserve 551348 551348 0 0.00 1 29820 93 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 4 3465 119 0 0
sp_cancel_pay_to_supplier 464 464 0 0.00 4 8055 130 0 0
sp_cancel_supplier_invoice 6234 6225 9 0.14 3 92794 970 0 9
sp_cancel_supplier_order 413 413 0 0.00 13 1441248 158547 0 0
sp_cancel_write_off 9296 9296 0 0.00 2 12639 160 0 0
sp_client_order 20417 20417 0 0.00 6 15208 475 0 0
sp_customer_reserve 454183 454183 0 0.00 1 104881 240 0 0
sp_fill_shopping_cart 31367 31367 0 0.00 1 23647 92 0 0
sp_fill_shopping_cart_clo_res 454175 454175 0 0.00 0 27076 18 0 0
sp_get_clo_for_invoice 4913 4913 0 0.00 0 25977 284 0 0
sp_kill_cost_storno 16077 16077 0 0.00 0 12555 69 0 0
sp_kill_qstorno_ret_qs2qd 1118630 1118630 0 0.00 0 92790 41 0 0
sp_kill_qty_storno 589575 589575 0 0.00 0 92790 94 0 0
sp_lock_selected_doc 20776 20776 0 0.00 0 10258 8 0 0
sp_make_cost_storno 27990 27990 0 0.00 0 15736 75 0 0
sp_multiply_rows_for_pdistr 27990 27990 0 0.00 0 13245 42 0 0
sp_multiply_rows_for_qdistr 20417 20417 0 0.00 0 13311 180 0 0
sp_pay_from_customer 7255 7255 0 0.00 6 16138 180 0 0
sp_pay_to_supplier 5830 5830 0 0.00 7 8969 195 0 0
sp_payment_common 13085 13085 0 0.00 2 16052 47 0 0
sp_qd_handle_on_cancel_clo 1783 1783 0 0.00 0 18610 327 0 0
sp_qd_handle_on_invoice_upd_sts 10517 10517 0 0.00 0 35485 525 0 0
sp_qd_handle_on_reserve_upd_sts 9993 9993 0 0.00 0 20677 71 0 0
sp_reserve_write_off 9993 9993 0 0.00 9 20728 282 0 0
sp_supplier_invoice 5409 5409 0 0.00 9 92099 3461 0 0
sp_supplier_order 5542 5542 0 0.00 7 26684 1412 0 0
srv_aggregate_perf_data 101 100 1 0.99 2350 222373 20814 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 859 179155 42352 0 0
srv_find_qd_qs_mism 1041888 1041888 0 0.00 0 10981 9 0 0
srv_make_invnt_saldo 463 463 0 0.00 66 64774 3922 0 0
srv_make_money_saldo 915 915 0 0.00 2 23028 401 0 0
srv_recalc_idx_stat 6 6 0 0.00 128095 441521 257322 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 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 28126 958 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 6280 457 0 0
t$perf-norm:sp_cancel_client_order 1783 1783 0 0.00 0 3182 30 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 4001 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 6321 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 464 464 0 0.00 0 3087 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 10557 65 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 85404 6855 0 0
t$perf-norm:sp_cancel_write_off 1028 1028 0 0.00 0 4235 26 0 0
t$perf-norm:sp_client_order 20417 20417 0 0.00 0 9629 36 0 0
t$perf-norm:sp_customer_reserve 1039 1039 0 0.00 0 5139 24 0 0
t$perf-norm:sp_pay_from_customer 7255 7255 0 0.00 0 27189 23 0 0
t$perf-norm:sp_pay_to_supplier 5830 5830 0 0.00 0 5612 20 0 0
t$perf-norm:sp_reserve_write_off 9993 9993 0 0.00 0 17800 25 0 0
t$perf-norm:sp_supplier_invoice 5409 5409 0 0.00 0 2523 29 0 0
t$perf-norm:sp_supplier_order 5542 5542 0 0.00 0 3276 33 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 3177 45 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 829 17 0 0
t$perf-norm:srv_make_invnt_saldo 463 463 0 0.00 0 1073 18 0 0
t$perf-norm:srv_make_money_saldo 915 915 0 0.00 0 252 3 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 4 182 47 0 0
v_add_invoice_to_stock 10102 9824 278 2.75 0 4868 25 0 278
v_all_customers 37618 37618 0 0.00 0 244 4 0 0
v_all_suppliers 30278 30278 0 0.00 0 60 4 0 0
v_all_wares 40834 40834 0 0.00 0 58 4 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1514 14 0 0
v_cancel_client_order 3566 3566 0 0.00 0 3487 10 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 1277 10 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 272 9 0 0
v_cancel_payment_to_supplier 928 928 0 0.00 0 927 12 0 0
v_cancel_supplier_invoice 850 832 18 2.11 0 1478 27 0 18
v_cancel_supplier_order 826 826 0 0.00 0 1454 12 0 0
v_cancel_write_off 2056 2056 0 0.00 0 4377 29 0 0
v_max_id_clo_ord 5542 5542 0 0.00 0 324 5 0 0
v_max_id_clo_res 1039 1039 0 0.00 0 4204 17 0 0
v_max_id_ord_sup 5408 5408 0 0.00 0 5097 9 0 0
v_max_non_paid_invoice 1641 1641 0 0.00 0 834 8 0 0
v_max_non_paid_realizn 3706 3706 0 0.00 0 444 7 0 0
v_min_id_clo_ord 5542 5542 0 0.00 0 4531 10 0 0
v_min_id_clo_res 1039 1039 0 0.00 0 7521 17 0 0
v_min_id_ord_sup 5408 5408 0 0.00 0 8812 12 0 0
v_min_non_paid_invoice 5830 5830 0 0.00 0 8303 18 0 0
v_min_non_paid_realizn 7255 7255 0 0.00 0 16001 17 0 0
v_our_firm 10314 10314 0 0.00 0 63 5 0 0
v_reserve_write_off 19986 19986 0 0.00 0 3738 11 0 0
x_lock_depdocs_on_canc_invoice 11414 11414 0 0.00 0 31556 141 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 9840 379 0 0
x_make_qstorno_1000_1200 5542 5542 0 0.00 3 26670 1230 0 0
x_make_qstorno_1200_2000 5409 5409 0 0.00 4 87391 3210 0 0
x_make_qstorno_2100_3300 454183 454183 0 0.00 0 104877 210 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-04-2022 13:39:45 to 10-04-2022 13:39:49.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:14 34983 8192000000 9738810032 10037497856 811691936 9.015 100 88 176 0 839897712 7529072 20676704
11:09:54 21924 8192000000 9768844672 10078109696 846221040 9.362 100 93 186 0 880851984 12161664 22469280
11:11:27 17309 8192000000 9807265344 10126159872 871272720 9.613 100 82 164 0 912281408 22360656 18648032
11:12:46 9522 8192000000 9866714288 10203090944 925561200 10.151 100 77 154 1 977740832 35627760 16551872
11:14:02 5350 8192000000 9925204384 10270560256 967807392 10.565 100 85 165 0 1039052656 53852560 17392704
11:15:10 2051 8192000000 9977417024 10334859264 999750128 10.876 100 88 176 1 1094758784 77022640 17986016
11:16:14 1745 8192000000 10000783312 10363138048 1027272416 11.142 100 92 174 2 1120724096 76170400 17281280
11:17:17 1011 8192000000 10016008848 10390278144 1047284224 11.335 100 91 175 0 1136449856 71770928 17394704
11:18:19 1078 8192000000 10039770880 10420736000 1058552640 11.443 100 95 187 1 1161172448 83978256 18641552
11:19:21 943 8192000000 10025980448 10405605376 1066997296 11.523 100 94 183 0 1148564944 63149008 18418640
11:20:22 1220 8192000000 10035837872 10419367936 1074044240 11.591 100 96 192 0 1160639296 67124624 19470432
11:21:25 5760 8192000000 10052070816 10445959168 1075644160 11.606 100 53 97 0 1170714416 85324624 9745632
11:22:31 1024 8192000000 10040104528 10427736064 1092210352 11.764 100 92 175 1 1160363520 50120672 18032496
11:23:33 1143 8192000000 10070647360 10460221440 1099235920 11.830 100 97 188 0 1198899920 80224832 19439168
11:24:35 1379 8192000000 10088250144 10479775744 1102284928 11.859 100 96 184 1 1209507024 88069648 19152448
11:25:38 1454 8192000000 10074379888 10467860480 1103617552 11.872 100 93 172 3 1197326608 76558880 17150176
11:26:40 2063 8192000000 10067695632 10465165312 1095364240 11.794 100 99 193 2 1189575616 74453824 19757552
11:27:44 832 8192000000 10049465664 10437046272 1089099680 11.734 99 86 168 2 1171672432 65152864 17419888
11:28:47 3513 8192000000 10031586960 10421338112 1074293712 11.593 99 65 124 2 1148317136 61016672 13006752
11:29:51 2543 8192000000 10044662288 10426568704 1082864016 11.675 100 89 167 0 1166234720 66164112 17206592
11:30:55 951 8192000000 10041147600 10421878784 1067126416 11.525 100 90 166 1 1160916896 77121120 16669360
11:31:57 2009 8192000000 10017319776 10403434496 1066291392 11.517 99 89 171 0 1135002784 51402832 17308560
11:33:00 1243 8192000000 10025093536 10399948800 1055103632 11.410 100 98 191 2 1146118672 71769568 19245472
11:34:02 1073 8192000000 9999591888 10374905856 1036105664 11.227 100 94 177 1 1122258496 68108832 18044000
11:35:04 1054 8192000000 9998527520 10369482752 1030950224 11.178 100 93 177 0 1121752448 72445920 18356304
11:36:07 985 8192000000 9971254880 10341523456 1030190976 11.170 99 85 160 1 1095215872 49838992 15185904
11:37:08 39414 8192000000 9970783232 10351828992 1033610448 11.203 98 48 85 2 1079221120 36643232 8967440
11:38:55 3298 8192000000 10030430560 10410008576 1079197056 11.640 100 70 126 0 1149524128 59073616 11253456
11:40:03 16420 8192000000 10068041808 10459467776 1100165680 11.839 100 66 128 0 1182108016 69556960 12385376
11:41:27 3346 8192000000 10119147552 10506313728 1109140000 11.924 100 75 149 2 1232606272 107603680 15862592
11:42:36 1495 8192000000 10138366064 10526396416 1121120016 12.038 100 83 162 0 1258706560 120258064 17328480
11:43:42 2419 8192000000 10166965072 10567225344 1128313824 12.105 100 69 128 2 1283402752 141914144 13174784
11:44:46 2858 8192000000 10184694576 10579963904 1134591312 12.165 100 75 141 1 1297129776 148207408 14331056
11:45:55 1099 8192000000 10200464768 10597650432 1144821792 12.261 100 82 161 1 1316177328 153525296 17830240
11:47:00 1130 8192000000 10230355952 10642497536 1153373056 12.341 100 95 184 0 1345800032 171627088 20799888
11:48:04 854 8192000000 10248878384 10643955712 1161246480 12.415 100 98 190 0 1361695552 178008560 22440512
11:49:08 1118 8192000000 10293172512 10694971392 1168925088 12.487 100 96 184 1 1401724864 212048208 20751568
11:50:12 1448 8192000000 10317167248 10737078272 1174859120 12.542 100 93 184 0 1428975280 231719168 22396992
11:51:16 1548 8192000000 10316103536 10747920384 1174473200 12.539 100 85 164 1 1429792576 236826496 18492880
11:52:20 2785 8192000000 10335867776 10746961920 1169591392 12.493 100 70 137 1 1445844608 259091968 17161248
11:53:34 2199 8192000000 10297686720 10723397632 1173894720 12.533 100 70 122 1 1411584800 223968400 13721680
11:54:41 1590 8192000000 10371558000 10790019072 1175858480 12.552 100 88 172 0 1481425632 285332512 20234640
11:55:45 3466 8192000000 10409564496 10844016640 1184694208 12.634 100 63 121 1 1512050912 312414544 14942160
11:57:00 965 8192000000 10391892544 10819715072 1178202800 12.573 100 97 181 0 1490840112 291640336 20996976
11:58:04 1251 8192000000 10402492112 10839855104 1176915536 12.561 100 90 171 0 1504542624 307132144 20494944
11:59:08 1744 8192000000 10381936496 10813263872 1170580864 12.502 100 67 132 0 1491689696 306662976 14445856
12:00:17 2262 8192000000 10399028896 10833850368 1171368448 12.510 100 89 169 1 1498231312 307556960 19305904
12:01:20 979 8192000000 10414226912 10849992704 1178927584 12.580 100 97 189 0 1517925328 317691488 21306256
12:02:23 1530 8192000000 10415917200 10846466048 1178536544 12.577 100 87 171 1 1521307600 322823296 19947760
12:03:28 3189 8192000000 10430155440 10849730560 1182353200 12.612 100 65 117 3 1534086976 337626272 14107504
12:04:35 4014 8192000000 10478999392 10898382848 1178971760 12.581 100 71 134 3 1584736048 388519088 17245200
12:05:42 3824 8192000000 10523060400 10985406464 1181220400 12.602 100 64 127 0 1624936016 427500400 16215216
12:06:47 9638 8192000000 10481911296 10948820992 1173146128 12.526 100 43 86 0 1587719680 403439088 11134464
12:07:58 22125 8192000000 10356855184 10886250496 1137296736 12.190 99 28 56 0 1494769984 349181088 8292160
12:09:21 36739 8192000000 10111102032 10563526656 1128867280 12.111 99 15 29 0 1269866304 137207040 3791984
12:11:37 43436 8192000000 10109044400 10547011584 1154468432 12.351 100 25 50 1 1201299536 41898192 4932912
12:13:34 58841 8192000000 10084372288 10579234816 1109912896 11.932 98 7 14 1 1194519328 83099920 1506512
12:16:35 179154 8192000000 10095759168 10531307520 1107431152 11.908 98 19 37 2 1180745712 67765024 5549536
12:20:41 61335 8192000000 10069254048 10509451264 1084406448 11.689 97 28 56 0 1139119888 49141232 5572208
12:22:43 153120 8192000000 10080480720 10563244032 1075064992 11.600 96 17 34 0 1120775120 42359248 3350880
12:27:05 151222 8192000000 10107918864 10630791168 1101097024 11.848 97 10 20 0 1145509792 42490624 1922144
12:30:43 86759 8192000000 10067455472 10510299136 1115299184 11.983 98 25 50 1 1149041776 28001520 5741072
12:33:12 83781 8192000000 10080714880 10522193920 1113915088 11.969 98 26 52 1 1163843472 44473696 5454688
12:35:37 36742 8192000000 10111605616 10558562304 1118897808 12.017 98 21 39 0 1218309072 95236880 4174384
12:37:16 20185 8192000000 10169379040 10620219392 1152766640 12.335 100 40 80 1 1277194848 115300000 9128208
12:38:38 65067 8192000000 10152375264 10634608640 1120470880 12.031 97 22 44 0 1259184432 133709856 5003696
12:40:45 80379 8192000000 10159033456 10623774720 1134559760 12.164 99 26 48 0 1275133568 134783424 5790384
12:43:14 104987 8192000000 10143783536 10681159680 1139975840 12.215 99 16 32 1 1270627296 126838112 3813344
12:46:34 102888 8192000000 10089257200 10535337984 1100749616 11.845 96 17 34 0 1165911984 61860704 3301664
12:49:19 71491 8192000000 10159542800 10600427520 1103922256 11.875 96 17 34 0 1177392640 70257088 3213296
12:52:10 84888 8192000000 10072494032 10546692096 1046985680 11.332 91 20 40 0 1154701552 102761936 4953936
12:54:37 56846 8192000000 10090854336 10553544704 1123291680 12.058 100 28 55 2 1183639600 54815936 5531984
12:56:34 61657 8192000000 10121794960 10541682688 1128615904 12.108 99 39 74 4 1179482928 43066672 7800352
12:58:39 37978 8192000000 10113902432 10559803392 1112937792 11.960 98 23 46 2 1217352560 99421952 4992816
13:00:20 79401 8192000000 10068480624 10508292096 1118637728 12.014 99 26 52 0 1168764016 44802432 5323856
13:02:49 6769 8192000000 10079360064 10501328896 1123855296 12.063 100 45 90 0 1170864048 38023504 8985248
13:04:02 73204 8192000000 10099315408 10558930944 1107440000 11.908 98 30 60 0 1201785536 87947472 6398064
13:06:15 49606 8192000000 10079852624 10512121856 1117890672 12.007 99 35 69 0 1171389216 46610768 6887776
13:08:10 74290 8192000000 10126550624 10582515712 1112266912 11.954 98 19 38 1 1188545488 71819072 4459504
13:10:31 108187 8192000000 10162049824 10622414848 1125021600 12.074 99 31 59 1 1255660464 123335616 7303248
13:13:24 58542 8192000000 10052963504 10478575616 1093255008 11.774 97 17 32 1 1133980816 35909472 4816336
13:16:06 66653 8192000000 10097903568 10549354496 1087692256 11.721 96 29 58 2 1168906688 74737296 6477136
13:18:15 66005 8192000000 10096138592 10548129792 1096349664 11.803 98 19 38 0 1161927040 61865456 3711920
13:20:21 37528 8192000000 10084089952 10511589376 1128206560 12.104 100 46 89 1 1189279360 52331712 8741088
13:22:06 12265 8192000000 10137999232 10560835584 1137090304 12.188 100 40 80 1 1219844416 74893680 7860432
13:23:19 72263 8192000000 10170095408 10670829568 1130743184 12.128 99 48 90 2 1297839232 157337888 9758160
13:25:37 69001 8192000000 10095189072 10531807232 1120511840 12.032 98 25 49 0 1164349232 38531936 5305456
13:27:50 123047 8192000000 10121758832 10567225344 1151930080 12.328 100 33 66 0 1227527616 68509568 7087968
13:31:05 64644 8192000000 10105493904 10586591232 1120871168 12.035 97 16 32 1 1196474112 72170400 3432544
13:33:10 46111 8192000000 10113760992 10594508800 1150025824 12.310 100 25 51 0 1227750256 72435136 5289296
13:35:04 55716 8192000000 10083474944 10533126144 1126430192 12.088 99 20 38 1 1174190048 43395296 4364560
13:37:03 16290 8192000000 9356085680 9689870336 498659360 5.737 44 5 6 2 510832144 11586368 586416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-04-2022 13:39:51 to 10-04-2022 13:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 139 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

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

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          222325
MON$OLDEST_ACTIVE               222326
MON$OLDEST_SNAPSHOT             222326
MON$NEXT_TRANSACTION            222326
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                       2084896
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.2757 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2757 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2757 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2757 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 ---
:124: 
:125: mscs-test-161.budzdorov.ru    Sun Apr 10 13:37:26 2022
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Sun Apr 10 13:37:30 2022
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:131: 
:132: 
:133: mscs-test-161.budzdorov.ru    Sun Apr 10 13:37:30 2022
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:135: 
--- end of diff output ---

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

10.04.22 13:40:11. 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-04-10 13:40:13 No severe PSQL-related problems occured
10.04.22 13:40:14. End of report.