Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.10.2022 04:37
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 14921592 kB
MemAvailable 30450080 kB
Buffers 20 kB
Cached 16702636 kB
SwapCached 2184 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 2022-10-15 04:37:20
DTS_END                         2022-10-15 04:37:20.7120
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/35384
TRN_ID                          221396
ATT_ID                          627
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: 9372.58 22690 1124710
customer order: creation 169.49 466 20339
customer order: refuse 14.34 386 1721
order to supplier: creation 45.08 1413 5409
order to supplier: removal 3.51 154804 421
invoice (draft): creation 45.18 2988 5422
invoice (draft): removal 54.71 844 6565
invoice accept: apply 41.28 25044 4953
invoice accept: cancel 49.39 1067 5927
customer reserve: creation 3838.13 238 460575
customer reserve: removal 4811.42 88 577370
realization accept: apply 83.26 274 9991
realization accept: cancel 87.48 157 10498
payment from customer: creation 58.99 180 7079
payment from customer: removal 5.44 111 653
payment to supplier: creation 49.43 180 5931
payment to supplier: removal 3.21 138 385
service: total inventory turnovers 3.90 4108 468
service: total monetary turnovers 7.44 361 893
service: aggregate perf. data 0.88 17358 106
service: refresh index statistics 0.03 243586 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-10-2022 04:38:02 to 15-10-2022 04:38:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8004
TEST_TIME 2 6342
TEST_TIME 3 5430
TEST_TIME 4 5885
TEST_TIME 5 5947
TEST_TIME 6 6147
TEST_TIME 7 6490
TEST_TIME 8 6291
TEST_TIME 9 6495
TEST_TIME 10 6634
TEST_TIME 11 6659
TEST_TIME 12 6734
TEST_TIME 13 6840
TEST_TIME 14 6776
TEST_TIME 15 7036
TEST_TIME 16 7164
TEST_TIME 17 7240
TEST_TIME 18 7571
TEST_TIME 19 7644
TEST_TIME 20 7746
TEST_TIME 21 7980
TEST_TIME 22 7890
TEST_TIME 23 8102
TEST_TIME 24 8531
TEST_TIME 25 8983
TEST_TIME 26 9192
TEST_TIME 27 9056
TEST_TIME 28 9353
TEST_TIME 29 9446
TEST_TIME 30 9971
TEST_TIME 31 10210
TEST_TIME 32 11252
TEST_TIME 33 11423
TEST_TIME 34 11210
TEST_TIME 35 11058
TEST_TIME 36 11269
TEST_TIME 37 11335
TEST_TIME 38 11297
TEST_TIME 39 11188
TEST_TIME 40 10913
TEST_TIME 41 10802
TEST_TIME 42 10711
TEST_TIME 43 10669
TEST_TIME 44 10630
TEST_TIME 45 10621
TEST_TIME 46 10550
TEST_TIME 47 10394
TEST_TIME 48 10304
TEST_TIME 49 10280
TEST_TIME 50 10190
TEST_TIME 51 10154
TEST_TIME 52 10205
TEST_TIME 53 10244
TEST_TIME 54 10180
TEST_TIME 55 10126
TEST_TIME 56 10158
TEST_TIME 57 10162
TEST_TIME 58 10186
TEST_TIME 59 10173
TEST_TIME 60 10165
TEST_TIME 61 10118
TEST_TIME 62 10158
TEST_TIME 63 10221
TEST_TIME 64 10148
TEST_TIME 65 10160
TEST_TIME 66 10203
TEST_TIME 67 10185
TEST_TIME 68 10124
TEST_TIME 69 10115
TEST_TIME 70 10273
TEST_TIME 71 10147
TEST_TIME 72 10023
TEST_TIME 73 9950
TEST_TIME 74 9863
TEST_TIME 75 9871
TEST_TIME 76 9864
TEST_TIME 77 9806
TEST_TIME 78 9749
TEST_TIME 79 9703
TEST_TIME 80 9708
TEST_TIME 81 9685
TEST_TIME 82 9596
TEST_TIME 83 9647
TEST_TIME 84 9627
TEST_TIME 85 9672
TEST_TIME 86 9640
TEST_TIME 87 9625
TEST_TIME 88 9645
TEST_TIME 89 9618
TEST_TIME 90 9650
TEST_TIME 91 9680
TEST_TIME 92 9690
TEST_TIME 93 9704
TEST_TIME 94 9725
TEST_TIME 95 9688
TEST_TIME 96 9769
TEST_TIME 97 9782
TEST_TIME 98 9697
TEST_TIME 99 9687
TEST_TIME 100 9674
TEST_TIME 101 9618
TEST_TIME 102 9599
TEST_TIME 103 9584
TEST_TIME 104 9596
TEST_TIME 105 9559
TEST_TIME 106 9518
TEST_TIME 107 9463
TEST_TIME 108 9402
TEST_TIME 109 9350
TEST_TIME 110 9380
TEST_TIME 111 9383
TEST_TIME 112 9360
TEST_TIME 113 9389
TEST_TIME 114 9396
TEST_TIME 115 9384
TEST_TIME 116 9405
TEST_TIME 117 9386
TEST_TIME 118 9456
TEST_TIME 119 9467
TEST_TIME 120 9397

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-10-2022 04:38:06 to 15-10-2022 04:38:09.

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 1549531 1549531 0 0.00 0 14852 13 0 0
doc_list_biud 1549521 1549521 0 0.00 0 21581 38 0 0
sp_add_invoice_to_stock 5101 4953 148 2.90 41 210855 25045 0 148
sp_cancel_adding_invoice 5927 5927 0 0.00 7 87849 1068 0 0
sp_cancel_client_order 1721 1721 0 0.00 6 11511 387 0 0
sp_cancel_customer_reserve 577370 577370 0 0.00 1 18615 89 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 4 1759 112 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 4 7083 138 0 0
sp_cancel_supplier_invoice 6577 6565 12 0.18 2 21589 845 0 12
sp_cancel_supplier_order 421 421 0 0.00 7 1439109 154804 0 0
sp_cancel_write_off 10498 10498 0 0.00 2 15058 157 0 0
sp_client_order 20339 20339 0 0.00 6 64459 466 0 0
sp_customer_reserve 460575 460575 0 0.00 0 53606 239 0 0
sp_fill_shopping_cart 31167 31167 0 0.00 1 12949 79 0 0
sp_fill_shopping_cart_clo_res 460550 460550 0 0.00 0 13959 17 0 0
sp_get_clo_for_invoice 4914 4914 0 0.00 0 31214 294 0 0
sp_kill_cost_storno 17463 17463 0 0.00 0 8201 68 0 0
sp_kill_qstorno_ret_qs2qd 1172224 1172224 0 0.00 0 21577 38 0 0
sp_kill_qty_storno 617406 617406 0 0.00 0 21580 87 0 0
sp_lock_selected_doc 20573 20573 0 0.00 0 7077 8 0 0
sp_make_cost_storno 27915 27915 0 0.00 0 11396 82 0 0
sp_multiply_rows_for_pdistr 27913 27913 0 0.00 0 6740 39 0 0
sp_multiply_rows_for_qdistr 20339 20339 0 0.00 0 64370 181 0 0
sp_pay_from_customer 7079 7079 0 0.00 5 14403 181 0 0
sp_pay_to_supplier 5931 5931 0 0.00 7 15281 180 0 0
sp_payment_common 13008 13008 0 0.00 2 6513 45 0 0
sp_qd_handle_on_cancel_clo 1721 1721 0 0.00 0 11494 315 0 0
sp_qd_handle_on_invoice_upd_sts 10840 10840 0 0.00 0 21540 459 0 0
sp_qd_handle_on_reserve_upd_sts 9991 9991 0 0.00 0 12651 57 0 0
sp_reserve_write_off 9991 9991 0 0.00 8 17386 275 0 0
sp_supplier_invoice 5422 5422 0 0.00 10 93890 2988 0 0
sp_supplier_order 5409 5409 0 0.00 7 74480 1413 0 0
srv_aggregate_perf_data 107 106 1 0.93 2410 254887 17359 0 1
srv_fill_mon_cache_memory 70 70 0 0.00 715 124553 34652 0 0
srv_find_qd_qs_mism 1076452 1076452 0 0.00 0 14840 10 0 0
srv_make_invnt_saldo 468 468 0 0.00 89 209130 4109 0 0
srv_make_money_saldo 893 893 0 0.00 1 12857 361 0 0
srv_recalc_idx_stat 4 4 0 0.00 52886 488725 243586 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 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 4958 4958 0 0.00 1 15743 909 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 1 8121 473 0 0
t$perf-norm:sp_cancel_client_order 1721 1721 0 0.00 0 4999 34 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 1350 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 510 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 4072 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 2341 42 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 83549 6549 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 2189 22 0 0
t$perf-norm:sp_client_order 20339 20339 0 0.00 0 13652 35 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 4715 22 0 0
t$perf-norm:sp_pay_from_customer 7079 7079 0 0.00 0 9347 20 0 0
t$perf-norm:sp_pay_to_supplier 5931 5931 0 0.00 0 6708 21 0 0
t$perf-norm:sp_reserve_write_off 9991 9991 0 0.00 0 11476 27 0 0
t$perf-norm:sp_supplier_invoice 5422 5422 0 0.00 0 5853 30 0 0
t$perf-norm:sp_supplier_order 5409 5409 0 0.00 0 4489 29 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 594 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 117 6 0 0
t$perf-norm:srv_make_invnt_saldo 468 468 0 0.00 0 3383 25 0 0
t$perf-norm:srv_make_money_saldo 893 893 0 0.00 0 373 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 219 79 0 0
v_add_invoice_to_stock 10120 9824 296 2.92 0 3469 23 0 296
v_all_customers 37450 37450 0 0.00 0 76 4 0 0
v_all_suppliers 30800 30800 0 0.00 0 64 4 0 0
v_all_wares 40678 40678 0 0.00 0 104 4 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 2361 16 0 0
v_cancel_client_order 3442 3442 0 0.00 0 3756 12 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 1722 14 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 1 1727 11 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 339 10 0 0
v_cancel_supplier_invoice 824 800 24 2.91 0 4282 34 0 24
v_cancel_supplier_order 842 842 0 0.00 0 3412 13 0 0
v_cancel_write_off 1992 1992 0 0.00 0 5922 25 0 0
v_max_id_clo_ord 5409 5409 0 0.00 0 1152 5 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 6265 23 0 0
v_max_id_ord_sup 5419 5419 0 0.00 0 3209 7 0 0
v_max_non_paid_invoice 1358 1358 0 0.00 0 614 8 0 0
v_max_non_paid_realizn 3583 3583 0 0.00 0 3282 8 0 0
v_min_id_clo_ord 5409 5409 0 0.00 0 4668 10 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 3347 12 0 0
v_min_id_ord_sup 5419 5419 0 0.00 0 7714 12 0 0
v_min_non_paid_invoice 5931 5931 0 0.00 0 5024 16 0 0
v_min_non_paid_realizn 7077 7077 0 0.00 0 6105 21 0 0
v_our_firm 10216 10216 0 0.00 0 69 5 0 0
v_reserve_write_off 19982 19982 0 0.00 0 3966 10 0 0
x_lock_depdocs_on_canc_invoice 12092 12092 0 0.00 0 13939 110 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 4073 307 0 0
x_make_qstorno_1000_1200 5409 5409 0 0.00 2 74406 1258 0 0
x_make_qstorno_1200_2000 5422 5422 0 0.00 4 93598 2767 0 0
x_make_qstorno_2100_3300 460574 460574 0 0.00 0 53598 210 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-10-2022 04:38:10 to 15-10-2022 04:38:14.

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:24 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:25 26791 8192000000 9714516560 10009702400 786317920 8.757 100 87 172 0 815536928 7509184 21709824
02:09:56 20649 8192000000 9750386688 10058399744 829125536 9.190 100 91 182 0 862917568 11548848 22243184
02:11:21 13168 8192000000 9786844208 10105905152 863810240 9.538 100 83 166 0 900211680 17192304 19209136
02:12:39 12035 8192000000 9871004304 10205827072 919636816 10.092 100 76 148 1 978440528 43130080 15673632
02:13:57 2431 8192000000 9920103664 10260905984 954083888 10.431 100 89 173 1 1032205808 59890096 18231824
02:15:02 2579 8192000000 9964368784 10316881920 992540128 10.806 100 90 174 0 1087888080 77554672 17793280
02:16:07 1447 8192000000 9985436304 10348687360 1028729584 11.156 100 92 176 0 1111051456 64790256 17531616
02:17:10 4171 8192000000 10016408848 10387947520 1041614928 11.280 100 81 159 0 1137964016 80898704 15450384
02:18:15 1000 8192000000 10032389680 10404622336 1058753808 11.445 100 95 188 1 1155199104 77403840 19041456
02:19:17 4319 8192000000 10028293744 10403794944 1065425440 11.508 100 91 180 3 1147878256 65070080 17382736
02:20:23 1833 8192000000 10020181408 10404540416 1070452400 11.556 100 80 147 2 1144822016 57946912 16422704
02:21:25 1030 8192000000 10019910240 10403434496 1080685056 11.654 100 71 137 1 1146186368 52248464 13252848
02:22:27 1934 8192000000 10054000368 10447552512 1084681008 11.692 100 79 155 2 1172518368 72203872 15633488
02:23:31 1167 8192000000 10052731904 10442637312 1086258592 11.707 100 89 162 2 1169753648 66694176 16800880
02:24:34 930 8192000000 10041851264 10432139264 1090677920 11.749 100 94 184 1 1166836720 57288656 18870144
02:25:35 965 8192000000 10062602144 10452582400 1094623008 11.787 100 89 164 0 1187178848 74240336 18315504
02:26:37 2494 8192000000 10065666784 10461638656 1085980096 11.704 100 83 159 2 1192568688 89868416 16720176
02:27:41 1147 8192000000 10059155056 10450300928 1090598688 11.748 100 95 186 0 1180506624 70995808 18912128
02:28:43 1106 8192000000 10068427552 10456248320 1085463392 11.700 100 93 176 0 1189317168 85430368 18423408
02:29:46 2275 8192000000 10051713648 10438242304 1082209344 11.669 100 87 161 1 1175147664 76760816 16177504
02:30:49 1295 8192000000 10031693440 10407411712 1049508592 11.356 98 97 190 0 1154938592 85757808 19672192
02:31:52 6853 8192000000 9998159904 10393202688 1055995840 11.418 99 47 90 1 1107507520 42989264 8522416
02:32:59 1932 8192000000 10006952448 10394669056 1043072992 11.294 100 61 120 1 1128990432 73816432 12101008
02:34:02 1089 8192000000 9993339264 10369568768 1047409824 11.336 99 92 171 3 1112951680 49228896 16312960
02:35:04 1251 8192000000 9957759728 10330988544 1024397264 11.114 100 84 155 1 1082204400 43331328 14475808
02:36:07 992 8192000000 9952632176 10318962688 1009792944 10.973 98 95 175 2 1074141904 45780832 18568128
02:37:09 2017 8192000000 9983796400 10355068928 1041219952 11.276 99 79 135 5 1099614128 45246656 13147520
02:38:21 11782 8192000000 10028644944 10414182400 1071920128 11.570 100 65 127 0 1146787824 61833328 13034368
02:39:38 11618 8192000000 10036875616 10421071872 1087704272 11.721 100 80 143 3 1162636016 60371936 14559808
02:40:56 4935 8192000000 10068475488 10458091520 1102364544 11.860 100 83 143 2 1192823552 76510048 13948960
02:42:09 4517 8192000000 10128507408 10521731072 1113387968 11.964 100 77 144 2 1238769472 109749536 15631968
02:43:23 1332 8192000000 10135405680 10535264256 1120624688 12.033 100 89 161 0 1251002704 114160256 16217760
02:44:28 913 8192000000 10125915104 10524241920 1120767984 12.034 99 84 162 0 1239039680 101696400 16575296
02:45:34 2586 8192000000 10179593072 10578923520 1135686240 12.175 99 73 138 3 1295218256 144376832 15155184
02:46:42 3591 8192000000 10192890208 10594881536 1155033024 12.357 100 66 126 0 1306924736 137827488 14064224
02:47:49 1511 8192000000 10231934576 10632871936 1150713696 12.316 100 91 171 3 1355887200 185746032 19427472
02:48:53 1706 8192000000 10298709040 10699849728 1177738512 12.569 100 86 167 1 1410396752 214683600 17974640
02:49:57 815 8192000000 10334400768 10741628928 1177641024 12.568 100 98 195 2 1447678336 247572576 22464736
02:51:01 1406 8192000000 10359754384 10763476992 1184747936 12.634 100 92 173 0 1469905184 265525120 19632128
02:52:07 3072 8192000000 10387747904 10818580480 1185162672 12.638 100 92 184 0 1496862448 290750288 20949488
02:53:13 1511 8192000000 10431250736 10845085696 1194507152 12.725 100 90 174 2 1545799152 328858800 22433200
02:54:18 1810 8192000000 10468108128 10899894272 1193860960 12.719 100 89 174 0 1579058288 364348992 20848336
02:55:22 1314 8192000000 10472906736 10886262784 1190077200 12.684 100 97 191 1 1582556016 368010048 24468768
02:56:27 889 8192000000 10511944224 10934403072 1195393376 12.734 100 77 154 0 1618658592 404443696 18821520
02:57:32 5831 8192000000 10518093360 10956017664 1188885344 12.673 100 65 126 2 1621977824 416476112 16616368
02:58:41 4153 8192000000 10533339344 10971975680 1182529696 12.614 100 74 141 1 1634537312 434040448 17967168
02:59:47 2813 8192000000 10515523760 10990522368 1174498448 12.539 100 64 124 1 1632751424 442771344 15481632
03:00:54 700 8192000000 10520709744 11007787008 1175091488 12.544 100 96 189 0 1629566448 430669952 23805008
03:01:57 4564 8192000000 10531727808 10989608960 1177895792 12.571 100 64 126 0 1633358016 439833856 15628368
03:03:02 10935 8192000000 10464457680 10947858432 1184128752 12.629 100 42 84 0 1579193360 384159072 10905536
03:04:16 20439 8192000000 10530464736 10980892672 1177805696 12.570 99 38 75 0 1623924992 436395056 9724240
03:05:42 9011 8192000000 10513587216 10976309248 1177148864 12.564 100 32 64 1 1619636944 433720800 8767280
03:07:00 34537 8192000000 10398756448 10872176640 1148859552 12.299 99 24 48 0 1512716096 357392608 6463936
03:09:42 37559 8192000000 10092578752 10649411584 1106365296 11.898 97 9 18 0 1199474096 91242912 1865888
03:11:22 76500 8192000000 10120829888 10602319872 1109525920 11.928 97 8 16 0 1193895984 82822304 1547760
03:13:40 92946 8192000000 10072265696 10537574400 1054001024 11.399 92 6 12 0 1118288736 63353184 934528
03:16:24 76767 8192000000 10075583360 10534481920 1128984080 12.112 100 15 30 1 1192896864 60231328 3681456
03:18:53 63150 8192000000 10094823776 10552856576 1108760544 11.921 97 30 60 0 1158326512 43801328 5764640
03:21:02 81448 8192000000 10125617152 10599845888 1129369856 12.115 98 35 66 2 1241795376 104889600 7535920
03:23:30 35468 8192000000 10104985568 10548375552 1116309280 11.992 98 35 70 0 1182558512 58766400 7482832
03:25:08 2871 8192000000 10130504352 10557497344 1125945328 12.083 99 64 123 1 1216455120 77823152 12686640
03:26:13 21745 8192000000 10142918464 10590167040 1139124704 12.207 99 32 64 1 1257568176 110812528 7630944
03:27:35 17401 8192000000 10228863584 10662813696 1149038624 12.300 99 61 111 4 1302251232 142037408 11175200
03:29:03 69908 8192000000 10175188080 10679754752 1145150688 12.264 100 15 30 0 1320787168 171603904 4032576
03:31:21 43424 8192000000 10169834112 10645069824 1152071072 12.329 100 13 27 0 1270626800 115481840 3073888
03:33:05 33920 8192000000 10136342624 10606858240 1131197200 12.133 99 20 39 0 1233300016 97579360 4523456
03:34:40 59660 8192000000 10098494448 10588282880 1121027712 12.037 98 12 24 0 1201994336 78309680 2656944
03:37:00 56246 8192000000 10096216560 10538352640 1124780816 12.072 98 24 43 0 1180016416 50262528 4973072
03:38:58 42153 8192000000 10107880992 10597871616 1142852240 12.242 99 12 20 0 1208626512 63858608 1915664
03:41:24 52739 8192000000 10133762480 10581553152 1140981120 12.225 99 18 36 1 1194137456 49452656 3703680
03:43:20 101257 8192000000 10071150064 10494623744 1074580960 11.596 93 40 76 2 1111787824 29641984 7564880
03:46:02 19392 8192000000 10161563904 10610614272 1150342272 12.313 100 45 90 0 1245518912 86301008 8875632
03:47:24 86978 8192000000 10092119680 10593079296 1150823264 12.317 100 12 24 1 1208276448 54772096 2681088
03:50:33 52735 8192000000 10077843920 10505928704 1124335664 12.068 98 39 77 1 1155974720 23075408 8563648
03:52:28 45507 8192000000 10114867920 10611798016 1120468912 12.031 98 19 36 0 1221243040 96792512 3981616
03:54:19 55203 8192000000 10048734032 10479570944 1035582128 11.222 91 23 47 0 1085339712 45061296 4696288
03:56:17 64715 8192000000 10102854944 10581434368 1121201168 12.038 100 19 39 1 1219701792 93978384 4522240
03:58:22 77812 8192000000 10093649248 10552193024 1096303264 11.803 97 21 43 0 1169920256 68819744 4797248
04:02:05 64332 8192000000 10035827568 10479255552 1069418992 11.547 95 12 20 1 1097782064 26641856 1721216
04:04:10 15665 8192000000 10085966512 10523873280 1117055504 11.999 99 39 76 0 1165927600 41085792 7786304
04:05:29 32803 8192000000 10088518928 10540425216 1117003744 11.999 100 33 66 1 1210062944 86146912 6912288
04:07:03 10353 8192000000 10125267824 10552426496 1124767408 12.072 100 53 105 0 1207911248 72906944 10236896
04:08:32 21209 8192000000 10116581216 10562990080 1127321248 12.096 99 51 100 0 1207791680 70431824 10038608
04:09:58 51735 8192000000 10066468528 10505412608 1061723376 11.473 94 27 54 1 1100808736 33714352 5371008
04:11:50 87921 8192000000 10103213280 10551922688 1090350224 11.746 97 28 54 0 1176075424 80396224 5328976
04:14:20 124552 8192000000 10088547280 10530152448 1110326720 11.936 99 23 46 0 1176741968 61375632 5039616
04:17:49 42345 8192000000 10096540912 10551357440 1134049744 12.160 100 44 86 1 1182797712 39913840 8834128
04:19:41 84343 8192000000 10067166016 10582032384 1092413008 11.766 96 13 26 0 1176086992 80645424 3028560
04:22:10 56154 8192000000 10058333760 10519248896 1132497328 12.145 100 19 35 0 1163976192 27508656 3970208
04:24:07 69678 8192000000 10105505456 10562957312 1131280064 12.133 99 22 44 0 1188560304 52393632 4886608
04:26:33 39261 8192000000 10134649584 10615930880 1136993248 12.187 100 26 52 0 1226866048 84353184 5519616
04:28:14 57244 8192000000 10095026576 10552393728 1129812240 12.120 99 16 32 0 1177586016 44340656 3433120
04:30:25 25423 8192000000 10085754144 10532347904 1126389872 12.087 99 27 50 2 1182649536 50478624 5781040
04:31:54 35801 8192000000 10115422176 10567192576 1111744768 11.949 98 25 50 0 1191536224 75188768 4602688
04:33:32 24886 8192000000 10115179632 10545053696 1139223584 12.208 100 47 94 0 1214626400 65440384 9962432
04:34:57 50705 8192000000 10147742736 10621394944 1129071952 12.113 99 37 74 0 1232344064 95555200 7716912
04:37:13 15188 8192000000 9213988880 9499164672 350331168 4.101 31 4 8 0 366905632 15902000 672464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-10-2022 04:38:16 to 15-10-2022 04:38:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 148 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-10-2022 04:38:33 to 15-10-2022 04:38:35.

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          221771
MON$OLDEST_ACTIVE               221772
MON$OLDEST_SNAPSHOT             221772
MON$NEXT_TRANSACTION            221772
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                       2087200
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2859 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2859 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2859 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2859 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 ---
:21: 
:22: mscs-test-161.budzdorov.ru    Sat Oct 15 04:37:31 2022
:23:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:24: 
:25: 
:26: mscs-test-161.budzdorov.ru    Sat Oct 15 04:37:31 2022
:27:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:28: 
--- end of diff output ---

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

15.10.22 04:38:36. 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-10-15 04:38:39 No severe PSQL-related problems occured
15.10.22 04:38:40. End of report.