Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.07.2022 13: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 15835308 kB
MemAvailable 31094752 kB
Buffers 0 kB
Cached 15824076 kB
SwapCached 2540 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-07 13:37:18
DTS_END                         2022-07-07 13:37:18.1390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48528
TRN_ID                          210184
ATT_ID                          580
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: 8877.34 24115 1065281
customer order: creation 157.17 495 18860
customer order: refuse 12.85 410 1542
order to supplier: creation 40.74 1407 4889
order to supplier: removal 3.25 195251 390
invoice (draft): creation 42.44 3575 5093
invoice (draft): removal 51.65 932 6198
invoice accept: apply 38.19 25126 4583
invoice accept: cancel 47.25 1193 5670
customer reserve: creation 3568.38 234 428206
customer reserve: removal 4634.41 109 556129
realization accept: apply 76.10 276 9132
realization accept: cancel 85.72 162 10286
payment from customer: creation 53.89 169 6467
payment from customer: removal 5.24 148 629
payment to supplier: creation 44.72 202 5366
payment to supplier: removal 3.26 113 391
service: total inventory turnovers 3.97 4222 476
service: total monetary turnovers 7.23 393 867
service: aggregate perf. data 0.86 15714 103
service: refresh index statistics 0.03 232174 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-07-2022 13:38:04 to 07-07-2022 13:38:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7069
TEST_TIME 2 5674
TEST_TIME 3 5970
TEST_TIME 4 6054
TEST_TIME 5 5837
TEST_TIME 6 6052
TEST_TIME 7 6215
TEST_TIME 8 6318
TEST_TIME 9 6339
TEST_TIME 10 6624
TEST_TIME 11 6762
TEST_TIME 12 6954
TEST_TIME 13 6889
TEST_TIME 14 6882
TEST_TIME 15 7139
TEST_TIME 16 7380
TEST_TIME 17 7422
TEST_TIME 18 7329
TEST_TIME 19 7459
TEST_TIME 20 7726
TEST_TIME 21 7782
TEST_TIME 22 7781
TEST_TIME 23 8233
TEST_TIME 24 8287
TEST_TIME 25 8070
TEST_TIME 26 8266
TEST_TIME 27 8949
TEST_TIME 28 9048
TEST_TIME 29 9282
TEST_TIME 30 9447
TEST_TIME 31 9981
TEST_TIME 32 10839
TEST_TIME 33 11194
TEST_TIME 34 11072
TEST_TIME 35 10884
TEST_TIME 36 10784
TEST_TIME 37 10675
TEST_TIME 38 10461
TEST_TIME 39 10470
TEST_TIME 40 10348
TEST_TIME 41 10230
TEST_TIME 42 10217
TEST_TIME 43 10101
TEST_TIME 44 9940
TEST_TIME 45 9835
TEST_TIME 46 9839
TEST_TIME 47 9704
TEST_TIME 48 9550
TEST_TIME 49 9536
TEST_TIME 50 9517
TEST_TIME 51 9410
TEST_TIME 52 9447
TEST_TIME 53 9369
TEST_TIME 54 9352
TEST_TIME 55 9302
TEST_TIME 56 9229
TEST_TIME 57 9149
TEST_TIME 58 9007
TEST_TIME 59 9092
TEST_TIME 60 9002
TEST_TIME 61 8906
TEST_TIME 62 8916
TEST_TIME 63 8988
TEST_TIME 64 9003
TEST_TIME 65 8979
TEST_TIME 66 8975
TEST_TIME 67 8999
TEST_TIME 68 9012
TEST_TIME 69 8997
TEST_TIME 70 9005
TEST_TIME 71 9066
TEST_TIME 72 9048
TEST_TIME 73 9014
TEST_TIME 74 9141
TEST_TIME 75 9093
TEST_TIME 76 9110
TEST_TIME 77 9034
TEST_TIME 78 9023
TEST_TIME 79 8945
TEST_TIME 80 8955
TEST_TIME 81 8897
TEST_TIME 82 8827
TEST_TIME 83 8797
TEST_TIME 84 8740
TEST_TIME 85 8683
TEST_TIME 86 8654
TEST_TIME 87 8676
TEST_TIME 88 8669
TEST_TIME 89 8715
TEST_TIME 90 8695
TEST_TIME 91 8667
TEST_TIME 92 8766
TEST_TIME 93 8749
TEST_TIME 94 8722
TEST_TIME 95 8726
TEST_TIME 96 8753
TEST_TIME 97 8871
TEST_TIME 98 8977
TEST_TIME 99 9134
TEST_TIME 100 9065
TEST_TIME 101 9005
TEST_TIME 102 9043
TEST_TIME 103 8985
TEST_TIME 104 8920
TEST_TIME 105 8891
TEST_TIME 106 8843
TEST_TIME 107 8798
TEST_TIME 108 8778
TEST_TIME 109 8790
TEST_TIME 110 8763
TEST_TIME 111 8813
TEST_TIME 112 8807
TEST_TIME 113 8784
TEST_TIME 114 8754
TEST_TIME 115 8729
TEST_TIME 116 8761
TEST_TIME 117 8774
TEST_TIME 118 8919
TEST_TIME 119 8968
TEST_TIME 120 8907

Performance per minute, chart


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

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 1460692 1460692 0 0.00 0 13409 12 0 0
doc_list_biud 1460677 1460677 0 0.00 0 48045 47 0 0
sp_add_invoice_to_stock 4693 4583 110 2.34 45 168801 25126 0 110
sp_cancel_adding_invoice 5670 5670 0 0.00 5 112963 1193 0 0
sp_cancel_client_order 1542 1542 0 0.00 6 9074 411 0 0
sp_cancel_customer_reserve 556129 556129 0 0.00 1 23285 109 0 0
sp_cancel_pay_from_customer 629 629 0 0.00 4 8652 148 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 2123 114 0 0
sp_cancel_supplier_invoice 6210 6198 12 0.19 2 48050 933 0 12
sp_cancel_supplier_order 390 390 0 0.00 11 1583738 195251 0 0
sp_cancel_write_off 10286 10286 0 0.00 1 8611 162 0 0
sp_client_order 18860 18860 0 0.00 6 45091 495 0 0
sp_customer_reserve 428206 428206 0 0.00 0 79690 234 0 0
sp_fill_shopping_cart 28834 28834 0 0.00 1 31286 98 0 0
sp_fill_shopping_cart_clo_res 428163 428163 0 0.00 0 14515 22 0 0
sp_get_clo_for_invoice 4534 4534 0 0.00 0 41207 455 0 0
sp_kill_cost_storno 16976 16976 0 0.00 0 9114 73 0 0
sp_kill_qstorno_ret_qs2qd 1129132 1129132 0 0.00 0 48043 47 0 0
sp_kill_qty_storno 593877 593877 0 0.00 0 48044 107 0 0
sp_lock_selected_doc 18944 18944 0 0.00 0 8580 7 0 0
sp_make_cost_storno 25497 25497 0 0.00 0 13377 78 0 0
sp_multiply_rows_for_pdistr 25495 25495 0 0.00 0 8722 35 0 0
sp_multiply_rows_for_qdistr 18859 18859 0 0.00 0 14171 195 0 0
sp_pay_from_customer 6467 6467 0 0.00 6 23788 170 0 0
sp_pay_to_supplier 5366 5366 0 0.00 6 40491 203 0 0
sp_payment_common 11833 11833 0 0.00 2 10152 46 0 0
sp_qd_handle_on_cancel_clo 1542 1542 0 0.00 0 9057 334 0 0
sp_qd_handle_on_invoice_upd_sts 10201 10201 0 0.00 0 22142 539 0 0
sp_qd_handle_on_reserve_upd_sts 9131 9131 0 0.00 0 12218 71 0 0
sp_reserve_write_off 9132 9132 0 0.00 8 13485 277 0 0
sp_supplier_invoice 5093 5093 0 0.00 7 101972 3576 0 0
sp_supplier_order 4889 4889 0 0.00 8 53683 1407 0 0
srv_aggregate_perf_data 103 103 0 0.00 2408 167046 15715 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 741 147260 39080 0 0
srv_find_qd_qs_mism 1020872 1020872 0 0.00 0 11703 8 0 0
srv_make_invnt_saldo 476 476 0 0.00 65 134347 4223 0 0
srv_make_money_saldo 867 867 0 0.00 1 9094 393 0 0
srv_recalc_idx_stat 4 4 0 0.00 28299 427761 232174 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 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-norm:sp_add_invoice_to_stock 4590 4590 0 0.00 1 16710 973 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 1 9273 537 0 0
t$perf-norm:sp_cancel_client_order 1542 1542 0 0.00 0 1430 29 0 0
t$perf-norm:sp_cancel_customer_reserve 627 627 0 0.00 0 678 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 629 629 0 0.00 0 1152 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 707 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 359 359 0 0.00 0 749 32 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 102559 7444 0 0
t$perf-norm:sp_cancel_write_off 964 964 0 0.00 0 691 18 0 0
t$perf-norm:sp_client_order 18860 18860 0 0.00 0 6097 37 0 0
t$perf-norm:sp_customer_reserve 910 910 0 0.00 0 1483 13 0 0
t$perf-norm:sp_pay_from_customer 6467 6467 0 0.00 0 4029 19 0 0
t$perf-norm:sp_pay_to_supplier 5367 5367 0 0.00 0 6000 22 0 0
t$perf-norm:sp_reserve_write_off 9132 9132 0 0.00 0 5888 22 0 0
t$perf-norm:sp_supplier_invoice 5094 5094 0 0.00 0 4893 34 0 0
t$perf-norm:sp_supplier_order 4889 4889 0 0.00 0 3316 32 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 1262 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 22 2 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 937 20 0 0
t$perf-norm:srv_make_money_saldo 867 867 0 0.00 0 554 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 93 47 0 0
v_add_invoice_to_stock 9276 9056 220 2.37 0 4568 26 0 220
v_all_customers 34652 34652 0 0.00 0 112 4 0 0
v_all_suppliers 27744 27744 0 0.00 0 119 4 0 0
v_all_wares 37712 37712 0 0.00 0 155 4 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 2271 14 0 0
v_cancel_client_order 3084 3084 0 0.00 0 4164 11 0 0
v_cancel_customer_prepayment 1258 1258 0 0.00 0 3395 15 0 0
v_cancel_customer_reserve 1254 1254 0 0.00 0 2256 11 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 1659 15 0 0
v_cancel_supplier_invoice 742 718 24 3.23 0 421 23 0 24
v_cancel_supplier_order 780 780 0 0.00 0 2285 12 0 0
v_cancel_write_off 1928 1928 0 0.00 0 1187 23 0 0
v_max_id_clo_ord 4888 4888 0 0.00 0 431 5 0 0
v_max_id_clo_res 910 910 0 0.00 0 1105 13 0 0
v_max_id_ord_sup 5087 5087 0 0.00 0 3550 7 0 0
v_max_non_paid_invoice 1468 1468 0 0.00 0 947 8 0 0
v_max_non_paid_realizn 3319 3319 0 0.00 0 924 8 0 0
v_min_id_clo_ord 4887 4887 0 0.00 0 2592 8 0 0
v_min_id_clo_res 910 910 0 0.00 0 1309 9 0 0
v_min_id_ord_sup 5087 5087 0 0.00 0 8050 18 0 0
v_min_non_paid_invoice 5366 5366 0 0.00 0 10131 16 0 0
v_min_non_paid_realizn 6467 6467 0 0.00 0 5214 17 0 0
v_our_firm 9353 9353 0 0.00 0 63 5 0 0
v_reserve_write_off 18262 18262 0 0.00 0 4720 12 0 0
x_lock_depdocs_on_canc_invoice 11509 11509 0 0.00 0 8917 123 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 9447 373 0 0
x_make_qstorno_1000_1200 4889 4889 0 0.00 2 53643 1246 0 0
x_make_qstorno_1200_2000 5093 5093 0 0.00 3 101711 3265 0 0
x_make_qstorno_2100_3300 428204 428204 0 0.00 0 79685 200 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-07-2022 13:38:12 to 07-07-2022 13:38:16.

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:21 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:23 26103 8192000000 9734187008 10035736576 805809632 8.955 100 81 162 0 833297344 7513920 19973792
11:09:52 24885 8192000000 9767645376 10075688960 839402064 9.294 100 90 180 0 873748992 12980112 21366816
11:11:20 13575 8192000000 9797243376 10114129920 867025536 9.570 100 89 178 0 907669296 20219536 20424224
11:12:39 12002 8192000000 9847590784 10177716224 907328512 9.971 100 78 156 1 957621296 32957120 17335664
11:13:53 2610 8192000000 9919523184 10262286336 947890912 10.370 100 90 173 0 1030468960 63999648 18578400
11:14:58 2893 8192000000 9955685296 10306064384 983995184 10.723 100 81 159 0 1063322848 63600592 15727072
11:16:03 1120 8192000000 10000647184 10358452224 1010619344 10.981 100 88 170 0 1111745072 84613952 16511776
11:17:06 1140 8192000000 10023128080 10389438464 1030811168 11.176 100 95 188 1 1132621888 83603776 18206944
11:18:08 1027 8192000000 10020572688 10395283456 1058905040 11.446 100 98 187 3 1139496448 62499712 18091696
11:19:10 923 8192000000 10033032816 10407563264 1066679520 11.520 100 98 191 2 1153945792 68306432 18959840
11:20:13 2330 8192000000 10031598992 10419671040 1071203344 11.564 100 73 146 1 1161724304 75979072 14541888
11:21:18 1123 8192000000 10043568128 10425884672 1080012480 11.648 100 93 179 1 1163893168 65576864 18303824
11:22:20 1318 8192000000 10081199808 10469007360 1084141024 11.687 100 98 194 1 1202325440 98391424 19792992
11:23:25 1088 8192000000 10059858832 10450362368 1091375536 11.756 100 93 179 1 1183099168 72363264 19360368
11:24:27 1100 8192000000 10048757232 10432712704 1094920384 11.789 100 97 184 1 1176332944 62315264 19097296
11:25:29 1008 8192000000 10063415072 10454106112 1098842800 11.827 100 100 196 1 1186420784 68006768 19571216
11:26:32 1198 8192000000 10056571072 10442018816 1096922624 11.808 100 95 182 0 1182337632 67549184 17865824
11:27:34 1204 8192000000 10075562336 10464571392 1094419648 11.785 100 99 185 2 1196565536 82009680 20136208
11:28:36 1130 8192000000 10049396256 10440314880 1089810144 11.741 99 93 174 0 1171576176 63436208 18329824
11:29:39 1190 8192000000 10042440448 10425114624 1084962256 11.695 100 95 180 2 1161857280 60704560 16190464
11:30:42 1229 8192000000 9999777200 10375974912 1045372432 11.316 98 97 185 2 1124731456 61130592 18228432
11:31:44 1413 8192000000 10022383232 10410274816 1061664224 11.472 100 81 149 1 1142635120 66556256 14414640
11:32:47 988 8192000000 9973624736 10350211072 1034091264 11.208 100 86 162 0 1102909568 52536784 16281520
11:33:49 1383 8192000000 9985930672 10360872960 1045805984 11.320 100 93 166 4 1108455696 44279024 18370688
11:34:51 1110 8192000000 9980313616 10354896896 1028181552 11.151 100 95 182 0 1099897152 53110816 18604784
11:35:54 1032 8192000000 10005955056 10380021760 1031617648 11.184 100 93 179 0 1130046000 79649808 18778544
11:36:57 1223 8192000000 10019113664 10392862720 1051376048 11.374 100 97 190 1 1140207728 70358720 18472960
11:38:06 32514 8192000000 10018132016 10406182912 1071140208 11.563 100 54 104 0 1135861280 54283840 10437232
11:39:47 2001 8192000000 10036864608 10422304768 1084101184 11.687 99 80 156 0 1161744448 60081600 17561664
11:41:00 4600 8192000000 10079008592 10474921984 1101018320 11.847 100 80 155 1 1199356752 81401120 16937312
11:42:07 1217 8192000000 10113851392 10502627328 1106757680 11.902 100 88 171 1 1231182496 105963584 18461232
11:43:18 1656 8192000000 10148706896 10546810880 1126514032 12.088 100 74 144 2 1270427584 128821088 15092464
11:44:24 3337 8192000000 10172183968 10574725120 1131466736 12.135 100 85 167 0 1288783120 138976816 18339568
11:45:37 1988 8192000000 10234122128 10636283904 1150133152 12.311 100 74 146 0 1349415440 183570880 15711408
11:46:43 1006 8192000000 10262022064 10657247232 1151657152 12.325 100 86 166 1 1372688608 202756976 18274480
11:47:50 2276 8192000000 10247571504 10648965120 1153873536 12.346 100 91 174 3 1364344032 191109696 19360800
11:48:57 2861 8192000000 10270125696 10689572864 1167787808 12.476 100 73 144 0 1381058848 196854352 16416688
11:50:05 1715 8192000000 10310044368 10711273472 1172835248 12.523 100 94 179 2 1415089984 221516848 20737888
11:51:11 805 8192000000 10360842784 10763378688 1187558544 12.661 100 92 178 0 1471943520 263404512 20980464
11:52:17 1812 8192000000 10369676400 10806505472 1183434704 12.622 100 96 186 2 1475810528 271231168 21144656
11:53:21 1240 8192000000 10392602576 10802262016 1178112432 12.573 100 96 186 2 1503160768 303243056 21805280
11:54:26 1214 8192000000 10413869648 10834042880 1188595984 12.670 100 92 169 1 1523289696 315422880 19270832
11:55:32 2966 8192000000 10453236992 10876944384 1182300800 12.612 100 89 177 1 1557687200 353610528 21775872
11:56:36 2132 8192000000 10507801792 10942922752 1192357216 12.705 100 87 174 0 1625977616 412813776 20806624
11:57:42 1238 8192000000 10501802848 10932432896 1188316720 12.668 100 98 195 2 1609159344 397943728 22898896
11:58:46 937 8192000000 10510238448 10948976640 1182203344 12.611 100 94 185 0 1616528304 411906592 22418368
11:59:49 763 8192000000 10547397616 11008897024 1197250784 12.751 100 94 185 0 1651794672 432211472 22332416
12:00:51 737 8192000000 10528356768 10949873664 1177021904 12.562 100 96 193 0 1633752160 432580224 24150032
12:01:54 21606 8192000000 10575761088 11009867776 1179179296 12.583 100 50 98 0 1680394304 488020080 13194928
12:03:29 771 8192000000 10594082080 11043192832 1173463024 12.529 100 97 190 1 1698108032 500723792 23921216
12:04:31 4612 8192000000 10530737728 11076063232 1177482816 12.567 100 51 100 1 1641383760 450381360 13519584
12:05:37 17359 8192000000 10523041136 10986344448 1169543440 12.493 99 44 88 0 1618571552 437880528 11147584
12:07:03 43580 8192000000 10471724592 10990993408 1163402960 12.435 100 36 70 0 1607667376 435654768 8609648
12:09:02 34656 8192000000 10234852672 10784227328 1130751920 12.128 99 20 40 0 1348022704 212077616 5193168
12:11:12 59095 8192000000 10120238384 10556252160 1120737424 12.034 97 24 46 0 1184546224 59034384 4774416
12:14:04 70659 8192000000 10105956176 10551611392 1109570512 11.928 96 25 50 0 1182138944 67468832 5099600
12:16:23 132576 8192000000 10094777136 10594295808 1113979584 11.970 97 12 25 0 1180456896 63899136 2578176
12:19:36 62533 8192000000 10078730016 10555740160 1121822208 12.044 98 19 37 0 1158942336 33212736 3907392
12:21:42 129642 8192000000 10096679168 10560385024 1099540944 11.833 96 16 33 0 1154560256 51576576 3442736
12:24:53 36977 8192000000 10093465232 10521292800 1118950880 12.017 98 56 106 2 1177092800 46532176 11609744
12:26:36 75174 8192000000 10086957568 10567929856 1123239552 12.058 100 22 42 0 1204435344 76920704 4275088
12:29:01 48538 8192000000 10086549504 10567864320 1124254240 12.067 99 24 48 0 1168342976 38773952 5314784
12:31:08 147260 8192000000 10085387280 10576551936 1095492448 11.795 96 20 40 0 1171013056 71271360 4249248
12:34:36 54205 8192000000 10083294768 10540666880 1119376400 12.021 99 17 32 0 1185044048 62212928 3454720
12:36:34 42637 8192000000 10062715072 10533797888 1128445072 12.107 100 16 31 0 1184760000 52761024 3553904
12:38:24 75187 8192000000 10098781600 10589597696 1132072592 12.141 99 16 32 0 1233941568 98498416 3370560
12:40:41 59259 8192000000 10042715088 10488565760 1091279536 11.755 96 11 23 0 1117026560 23576864 2170160
12:42:41 29760 8192000000 10063592672 10512547840 1123145328 12.057 100 33 66 0 1172976448 43403808 6427312
12:44:13 39184 8192000000 10093608752 10558058496 1136884832 12.186 100 33 64 1 1203399584 60164896 6349856
12:46:27 36447 8192000000 10115411984 10567266304 1139986640 12.215 99 27 54 0 1186094912 40343456 5764816
12:48:04 82024 8192000000 10084383984 10550849536 1113041632 11.961 97 16 32 0 1148837488 32082816 3713040
12:50:47 69855 8192000000 10080089552 10568511488 1130502496 12.126 99 18 36 1 1211663280 77042176 4118608
12:53:13 30596 8192000000 10109250576 10557435904 1124159648 12.066 99 37 74 1 1216197696 84871280 7166768
12:54:46 103319 8192000000 10059062096 10551652352 1114794816 11.978 98 27 54 0 1158177184 38013808 5368560
12:57:44 63869 8192000000 10035221040 10484019200 1097729072 11.816 97 10 19 0 1134700384 34886336 2084976
13:00:46 31229 8192000000 10091242896 10540851200 1109748992 11.930 98 28 52 1 1176164048 60097776 6317280
13:02:40 36910 8192000000 10098104544 10529677312 1092183168 11.763 96 21 41 0 1202694448 105812592 4698688
13:04:18 76470 8192000000 10181274096 10616295424 1102455760 11.861 97 25 50 2 1263820528 155221136 6143632
13:06:54 28485 8192000000 10162824368 10605182976 1121828112 12.044 98 35 70 0 1253594352 124351712 7414528
13:08:23 19925 8192000000 10206821856 10637115392 1116377072 11.993 97 38 71 1 1271219952 147135952 7706928
13:09:46 25004 8192000000 10198029760 10653917184 1134360992 12.162 99 44 86 0 1281517504 137442288 9714224
13:11:12 29324 8192000000 10231708816 10659336192 1126511824 12.088 98 47 94 0 1321875744 184714368 10649552
13:12:42 39459 8192000000 10275283488 10711904256 1128104592 12.103 98 31 62 0 1350792416 214902080 7785744
13:14:30 44914 8192000000 10236960368 10719928320 1118079136 12.009 98 24 47 0 1322741856 199253056 5409664
13:16:17 109898 8192000000 10086956624 10557464576 1054051344 11.400 94 14 28 1 1130398944 73384000 2963600
13:19:34 72738 8192000000 10063162160 10561392640 1117671824 12.005 100 12 24 0 1163325808 43201600 2452384
13:22:07 52306 8192000000 10056435040 10585247744 1096683104 11.806 98 5 10 0 1158859664 61156400 1020160
13:23:59 60818 8192000000 10094487232 10535239680 1097794528 11.817 97 31 62 2 1150502160 45861248 6846384
13:26:06 82634 8192000000 10117730368 10584768512 1108652800 11.920 98 12 24 0 1190927232 79732816 2541616
13:28:37 48358 8192000000 10075491712 10505166848 1094054304 11.781 97 35 65 0 1133949248 33416560 6478384
13:30:31 53894 8192000000 10130395472 10569211904 1101533808 11.852 97 32 64 0 1209002208 100920176 6548224
13:32:33 40048 8192000000 10200132896 10627022848 1103792208 11.874 97 32 61 0 1240907968 130402144 6713616
13:34:18 60971 8192000000 10230334624 10694434816 1123887552 12.064 99 23 46 0 1293565216 164167968 5509696
13:36:27 64372 8192000000 9030958512 9270476800 149854736 1.796 14 1 2 0 158433776 8543632 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 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 07-07-2022 13:38:34 to 07-07-2022 13:38:36.

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          211027
MON$OLDEST_ACTIVE               211028
MON$OLDEST_SNAPSHOT             211028
MON$NEXT_TRANSACTION            211028
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                       2071008
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             762
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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

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