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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-08-02 07:47:44
DTS_END                         2022-08-02 07:47:44.6820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47378
TRN_ID                          220052
ATT_ID                          637
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: 9118.63 26905 1094236
customer order: creation 172.80 479 20736
customer order: refuse 14.77 502 1772
order to supplier: creation 46.38 1354 5566
order to supplier: removal 3.23 192944 387
invoice (draft): creation 46.71 3162 5605
invoice (draft): removal 51.42 1030 6170
invoice accept: apply 41.14 28059 4937
invoice accept: cancel 46.64 1340 5597
customer reserve: creation 3873.31 260 464797
customer reserve: removal 4528.74 108 543449
realization accept: apply 82.84 303 9941
realization accept: cancel 78.41 185 9409
payment from customer: creation 60.81 181 7297
payment from customer: removal 5.93 161 712
payment to supplier: creation 48.95 203 5874
payment to supplier: removal 3.60 136 432
service: total inventory turnovers 4.08 4463 490
service: total monetary turnovers 7.87 396 944
service: aggregate perf. data 0.98 17396 117
service: refresh index statistics 0.03 285436 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 02-08-2022 07:48:35 to 02-08-2022 07:48:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4648
TEST_TIME 2 5343
TEST_TIME 3 5094
TEST_TIME 4 4572
TEST_TIME 5 5005
TEST_TIME 6 5324
TEST_TIME 7 5984
TEST_TIME 8 6036
TEST_TIME 9 6088
TEST_TIME 10 6114
TEST_TIME 11 6277
TEST_TIME 12 6511
TEST_TIME 13 6464
TEST_TIME 14 6814
TEST_TIME 15 7075
TEST_TIME 16 7045
TEST_TIME 17 7183
TEST_TIME 18 7076
TEST_TIME 19 7093
TEST_TIME 20 7436
TEST_TIME 21 7430
TEST_TIME 22 7618
TEST_TIME 23 7695
TEST_TIME 24 7624
TEST_TIME 25 7691
TEST_TIME 26 7835
TEST_TIME 27 8226
TEST_TIME 28 8188
TEST_TIME 29 8508
TEST_TIME 30 8630
TEST_TIME 31 9067
TEST_TIME 32 8924
TEST_TIME 33 9115
TEST_TIME 34 9120
TEST_TIME 35 9538
TEST_TIME 36 10141
TEST_TIME 37 10303
TEST_TIME 38 10476
TEST_TIME 39 10606
TEST_TIME 40 10818
TEST_TIME 41 10654
TEST_TIME 42 10430
TEST_TIME 43 10410
TEST_TIME 44 10337
TEST_TIME 45 10190
TEST_TIME 46 10037
TEST_TIME 47 9979
TEST_TIME 48 9897
TEST_TIME 49 9925
TEST_TIME 50 9783
TEST_TIME 51 9849
TEST_TIME 52 9830
TEST_TIME 53 9809
TEST_TIME 54 9772
TEST_TIME 55 9828
TEST_TIME 56 9764
TEST_TIME 57 9649
TEST_TIME 58 9732
TEST_TIME 59 9652
TEST_TIME 60 9564
TEST_TIME 61 9550
TEST_TIME 62 9696
TEST_TIME 63 9651
TEST_TIME 64 9640
TEST_TIME 65 9747
TEST_TIME 66 9765
TEST_TIME 67 9661
TEST_TIME 68 9673
TEST_TIME 69 9710
TEST_TIME 70 9643
TEST_TIME 71 9661
TEST_TIME 72 9724
TEST_TIME 73 9642
TEST_TIME 74 9543
TEST_TIME 75 9614
TEST_TIME 76 9513
TEST_TIME 77 9456
TEST_TIME 78 9489
TEST_TIME 79 9463
TEST_TIME 80 9402
TEST_TIME 81 9358
TEST_TIME 82 9333
TEST_TIME 83 9288
TEST_TIME 84 9270
TEST_TIME 85 9213
TEST_TIME 86 9253
TEST_TIME 87 9232
TEST_TIME 88 9236
TEST_TIME 89 9189
TEST_TIME 90 9207
TEST_TIME 91 9191
TEST_TIME 92 9174
TEST_TIME 93 9226
TEST_TIME 94 9258
TEST_TIME 95 9273
TEST_TIME 96 9391
TEST_TIME 97 9353
TEST_TIME 98 9318
TEST_TIME 99 9362
TEST_TIME 100 9392
TEST_TIME 101 9310
TEST_TIME 102 9271
TEST_TIME 103 9230
TEST_TIME 104 9226
TEST_TIME 105 9208
TEST_TIME 106 9162
TEST_TIME 107 9131
TEST_TIME 108 9117
TEST_TIME 109 9092
TEST_TIME 110 9096
TEST_TIME 111 9055
TEST_TIME 112 9067
TEST_TIME 113 9065
TEST_TIME 114 9052
TEST_TIME 115 9096
TEST_TIME 116 9128
TEST_TIME 117 9159
TEST_TIME 118 9117
TEST_TIME 119 9129
TEST_TIME 120 9144

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-08-2022 07:48:39 to 02-08-2022 07:48:42.

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 1521160 1521160 0 0.00 0 22411 14 0 0
doc_list_biud 1521147 1521147 0 0.00 0 30935 45 0 0
sp_add_invoice_to_stock 5073 4937 136 2.68 51 281791 28059 0 136
sp_cancel_adding_invoice 5597 5597 0 0.00 6 99713 1340 0 0
sp_cancel_client_order 1772 1772 0 0.00 5 19810 502 0 0
sp_cancel_customer_reserve 543449 543449 0 0.00 1 30937 108 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 4 14957 162 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 4 2559 136 0 0
sp_cancel_supplier_invoice 6180 6170 10 0.16 2 19513 1030 0 10
sp_cancel_supplier_order 387 387 0 0.00 9 2004211 192944 0 0
sp_cancel_write_off 9409 9409 0 0.00 2 12083 185 0 0
sp_client_order 20736 20736 0 0.00 6 20158 480 0 0
sp_customer_reserve 464797 464797 0 0.00 0 198876 260 0 0
sp_fill_shopping_cart 31903 31903 0 0.00 1 8199 87 0 0
sp_fill_shopping_cart_clo_res 464764 464764 0 0.00 0 18648 20 0 0
sp_get_clo_for_invoice 4900 4900 0 0.00 0 38812 338 0 0
sp_kill_cost_storno 16150 16150 0 0.00 0 14928 81 0 0
sp_kill_qstorno_ret_qs2qd 1102864 1102864 0 0.00 0 30931 47 0 0
sp_kill_qty_storno 581620 581620 0 0.00 0 30935 108 0 0
sp_lock_selected_doc 20699 20699 0 0.00 0 11319 8 0 0
sp_make_cost_storno 28012 28012 0 0.00 0 22391 91 0 0
sp_multiply_rows_for_pdistr 28008 28008 0 0.00 0 7852 40 0 0
sp_multiply_rows_for_qdistr 20736 20736 0 0.00 0 16638 193 0 0
sp_pay_from_customer 7297 7297 0 0.00 5 14695 182 0 0
sp_pay_to_supplier 5874 5874 0 0.00 6 19029 203 0 0
sp_payment_common 13171 13171 0 0.00 2 6713 48 0 0
sp_qd_handle_on_cancel_clo 1772 1772 0 0.00 1 9040 409 0 0
sp_qd_handle_on_invoice_upd_sts 10494 10494 0 0.00 0 18392 584 0 0
sp_qd_handle_on_reserve_upd_sts 9939 9939 0 0.00 0 22683 68 0 0
sp_reserve_write_off 9941 9941 0 0.00 8 22804 303 0 0
sp_supplier_invoice 5605 5605 0 0.00 10 85336 3163 0 0
sp_supplier_order 5566 5566 0 0.00 7 34513 1355 0 0
srv_aggregate_perf_data 117 117 0 0.00 2170 202983 17397 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 872 154882 32954 0 0
srv_find_qd_qs_mism 1044240 1044240 0 0.00 0 21379 10 0 0
srv_make_invnt_saldo 490 490 0 0.00 70 213385 4463 0 0
srv_make_money_saldo 944 944 0 0.00 1 17056 396 0 0
srv_recalc_idx_stat 4 4 0 0.00 124998 491756 285437 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4943 4943 0 0.00 1 21903 1143 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 2 7485 507 0 0
t$perf-norm:sp_cancel_client_order 1772 1772 0 0.00 0 5109 30 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 799 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 894 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 3563 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 5892 55 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 111646 9138 0 0
t$perf-norm:sp_cancel_write_off 1047 1047 0 0.00 0 3930 23 0 0
t$perf-norm:sp_client_order 20736 20736 0 0.00 0 22316 43 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 3231 20 0 0
t$perf-norm:sp_pay_from_customer 7297 7297 0 0.00 0 13872 22 0 0
t$perf-norm:sp_pay_to_supplier 5875 5875 0 0.00 0 10190 23 0 0
t$perf-norm:sp_reserve_write_off 9941 9941 0 0.00 0 3302 23 0 0
t$perf-norm:sp_supplier_invoice 5605 5605 0 0.00 0 7013 33 0 0
t$perf-norm:sp_supplier_order 5566 5566 0 0.00 0 8380 39 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 540 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 621 15 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 524 20 0 0
t$perf-norm:srv_make_money_saldo 944 944 0 0.00 0 1929 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 630 177 0 0
v_add_invoice_to_stock 10066 9794 272 2.70 0 3581 27 0 272
v_all_customers 38460 38460 0 0.00 0 78 4 0 0
v_all_suppliers 31272 31272 0 0.00 0 137 5 0 0
v_all_wares 41468 41468 0 0.00 0 77 4 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 1899 19 0 0
v_cancel_client_order 3544 3544 0 0.00 0 7804 15 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 2690 16 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 1 641 11 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 418 11 0 0
v_cancel_supplier_invoice 834 814 20 2.39 0 657 26 0 20
v_cancel_supplier_order 774 774 0 0.00 0 1766 16 0 0
v_cancel_write_off 2094 2094 0 0.00 0 1558 26 0 0
v_max_id_clo_ord 5566 5566 0 0.00 0 4487 7 0 0
v_max_id_clo_res 990 990 0 0.00 0 3317 14 0 0
v_max_id_ord_sup 5603 5603 0 0.00 0 2700 6 0 0
v_max_non_paid_invoice 1407 1407 0 0.00 0 78 9 0 0
v_max_non_paid_realizn 3604 3604 0 0.00 0 2019 9 0 0
v_min_id_clo_ord 5566 5566 0 0.00 0 5452 9 0 0
v_min_id_clo_res 990 990 0 0.00 0 260 8 0 0
v_min_id_ord_sup 5603 5603 0 0.00 0 6833 12 0 0
v_min_non_paid_invoice 5874 5874 0 0.00 0 6701 17 0 0
v_min_non_paid_realizn 7297 7297 0 0.00 0 5515 17 0 0
v_our_firm 10394 10394 0 0.00 0 65 5 0 0
v_reserve_write_off 19878 19878 0 0.00 0 2446 11 0 0
x_lock_depdocs_on_canc_invoice 11360 11360 0 0.00 0 10527 118 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 2886 402 0 0
x_make_qstorno_1000_1200 5566 5566 0 0.00 2 34444 1178 0 0
x_make_qstorno_1200_2000 5605 5605 0 0.00 4 85322 2923 0 0
x_make_qstorno_2100_3300 464796 464796 0 0.00 0 198872 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-08-2022 07:48:43 to 02-08-2022 07:48:47.

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
05:17:48 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:49 27818 8192000000 9728704320 10031431680 805435984 8.951 100 85 170 0 834142224 7555952 21150288
05:20:22 22178 8192000000 9778579920 10088759296 851937936 9.419 100 90 180 0 885323216 12512336 20872944
05:21:54 12264 8192000000 9799772224 10119802880 872260128 9.623 100 83 166 0 911981088 20354576 19366384
05:23:17 7456 8192000000 9863283104 10194300928 908210688 9.980 100 85 169 0 974379248 47190944 18977616
05:24:38 4379 8192000000 9915405792 10254479360 954834464 10.438 100 89 170 0 1027233648 53668016 18731168
05:25:48 2381 8192000000 9968193408 10325065728 994600432 10.826 100 83 162 2 1081309328 70535088 16173808
05:26:53 1643 8192000000 9999751264 10361094144 1021912144 11.090 100 97 186 1 1115335824 73895856 19527824
05:27:57 1168 8192000000 10015432608 10386358272 1042250528 11.286 100 94 178 1 1132777136 70999664 19526944
05:28:59 797 8192000000 10039012400 10416705536 1069366640 11.546 100 95 177 1 1162710080 74958608 18384832
05:30:02 1670 8192000000 10046879904 10424107008 1072628192 11.577 100 100 188 2 1171765168 79859184 19277792
05:31:05 1227 8192000000 10028418096 10410455040 1073987536 11.590 100 84 156 0 1152942048 63660896 15293616
05:32:07 881 8192000000 10038947248 10424229888 1083568352 11.681 100 90 177 2 1165354304 64646864 17139088
05:33:09 1080 8192000000 10069281520 10451607552 1093077312 11.772 100 97 193 1 1183230640 69768128 20385200
05:34:11 937 8192000000 10061669264 10450309120 1093095808 11.772 100 97 185 2 1183410976 71218192 19096976
05:35:13 1052 8192000000 10072830544 10464333824 1102976336 11.866 100 95 187 1 1198264496 76242400 19045760
05:36:15 1266 8192000000 10075303824 10463563776 1104176864 11.877 100 97 185 1 1192326496 69189728 18959904
05:37:18 1054 8192000000 10060818224 10450444288 1105448512 11.889 100 94 177 0 1181953248 58319232 18185504
05:38:20 1061 8192000000 10082363120 10476318720 1107956240 11.913 100 89 170 0 1203130880 77479424 17695216
05:39:23 1000 8192000000 10078810608 10471776256 1104876208 11.884 100 90 177 0 1202558960 79746848 17935904
05:40:27 1359 8192000000 10059002400 10452852736 1096385136 11.803 100 79 150 3 1185334304 73327872 15621296
05:41:30 1065 8192000000 10009642048 10393001984 1072120896 11.572 99 92 175 2 1136948480 47715008 17112576
05:42:32 1163 8192000000 10039587072 10420404224 1067742864 11.531 99 96 184 1 1162599504 75628816 19227824
05:43:34 1219 8192000000 10017203664 10390142976 1043821552 11.301 99 98 191 1 1138932688 74561648 20549488
05:44:36 1393 8192000000 10011688960 10397372416 1044715728 11.310 100 73 143 5 1135480544 76578944 14185872
05:45:39 2389 8192000000 9972041760 10346950656 1020336752 11.075 99 82 159 1 1097671952 61506624 15828576
05:46:42 1525 8192000000 9962785040 10343079936 1028144896 11.151 99 70 129 1 1088535008 47623760 12766352
05:47:45 1290 8192000000 9999964656 10377969664 1051987696 11.380 100 92 173 3 1127724112 57857136 17879280
05:48:53 9397 8192000000 10010579968 10388705280 1056443648 11.422 100 60 113 0 1124977888 57887360 10646880
05:50:10 4760 8192000000 10019551472 10401996800 1070592192 11.558 100 66 126 2 1150267200 66888560 12786448
05:51:21 5884 8192000000 10069547872 10447175680 1078083024 11.629 100 74 147 0 1183943888 90209424 15651440
05:52:39 2897 8192000000 10148449888 10537914368 1092825648 11.770 100 80 148 1 1263416128 154345408 16245072
05:53:47 1102 8192000000 10161316816 10549493760 1106658576 11.901 100 94 179 1 1284372384 157401440 20312368
05:54:52 8639 8192000000 10178708864 10571948032 1117245632 12.001 100 77 154 0 1291279760 157280000 16754128
05:56:06 1675 8192000000 10201990752 10595516416 1127578560 12.099 100 88 169 1 1320905872 175024272 18303040
05:57:11 1463 8192000000 10213559248 10619621376 1136461888 12.182 100 74 138 0 1330305744 178531504 15312352
05:58:17 1647 8192000000 10276150624 10680082432 1144451424 12.257 100 95 187 1 1392912816 227080144 21381248
05:59:26 907 8192000000 10265370976 10676264960 1148761232 12.298 100 84 162 1 1381887600 214476304 18650064
06:00:31 945 8192000000 10313011152 10710077440 1161731888 12.419 100 94 184 0 1426096832 244171536 20193408
06:01:34 1366 8192000000 10372387472 10781298688 1167517616 12.474 100 95 187 1 1483635024 294713008 21404400
06:02:39 1595 8192000000 10345021872 10761187328 1166027440 12.460 100 91 177 1 1457074480 270519232 20527808
06:03:43 991 8192000000 10366914432 10784645120 1176546080 12.558 100 97 186 4 1480335600 282633792 21155728
06:04:47 1025 8192000000 10401764336 10823618560 1185057552 12.637 100 93 179 0 1512780832 307325376 20397904
06:05:53 1049 8192000000 10423086848 10832695296 1173955808 12.534 100 96 187 1 1528574208 333122064 21496336
06:06:59 2690 8192000000 10457687280 10896531456 1190745488 12.690 100 91 178 1 1563948608 352058208 21144912
06:08:04 994 8192000000 10453677056 10872168448 1177747888 12.569 100 92 172 0 1556717120 357966016 21003216
06:09:11 888 8192000000 10482780448 10902855680 1166773776 12.467 100 98 188 1 1586099600 398363072 20962752
06:10:16 912 8192000000 10493747200 10919518208 1174587760 12.540 100 96 189 1 1601140304 403464192 23088352
06:11:25 2154 8192000000 10536353968 10944311296 1173289760 12.528 100 91 177 0 1636563840 441715632 21558448
06:12:31 1706 8192000000 10574176144 10989719552 1172887088 12.524 100 98 197 0 1678700368 482000624 23812656
06:13:35 1571 8192000000 10493611888 10966315008 1156652720 12.372 100 93 178 0 1600916528 422108320 22155488
06:14:40 864 8192000000 10522698624 10932744192 1162191888 12.424 100 95 190 1 1627857984 442864848 22801248
06:15:46 1011 8192000000 10538924112 10976620544 1171309440 12.509 100 97 191 1 1642569680 448530816 22729424
06:16:49 4656 8192000000 10464047216 10951872512 1168381632 12.482 100 64 125 1 1569842544 385336752 16124160
06:17:56 3627 8192000000 10487109728 10966663168 1179969376 12.590 100 72 143 0 1590552224 392642720 17940128
06:19:16 21294 8192000000 10503550384 10933800960 1177789520 12.570 99 47 94 0 1586026592 396385072 11852000
06:20:47 22695 8192000000 10476853312 10934669312 1175623072 12.549 99 45 90 0 1576791104 390362944 10805088
06:22:11 12157 8192000000 10465390064 10930839552 1173568016 12.530 100 36 73 0 1565378432 382235584 9574832
06:23:53 42872 8192000000 10207118592 10717704192 1122729568 12.053 98 18 36 0 1363978016 236916928 4331520
06:25:37 56567 8192000000 10157233104 10653827072 1124917840 12.073 98 15 30 0 1273297472 145065312 3314320
06:27:38 50938 8192000000 10099556352 10570584064 1136557760 12.183 99 24 43 0 1176936096 34782608 5595728
06:29:34 100258 8192000000 10105780784 10584764416 1144935152 12.262 100 41 81 0 1206269792 53067280 8267360
06:32:16 34281 8192000000 10114736928 10563969024 1145816080 12.270 100 24 48 0 1196541184 46052112 4672992
06:35:09 117701 8192000000 10135611504 10601730048 1127632800 12.099 97 31 62 0 1194078320 60179808 6265712
06:38:10 65584 8192000000 10156320800 10627129344 1140198992 12.217 99 18 36 0 1243280720 98882576 4199152
06:41:11 154882 8192000000 10094182848 10573430784 1085294880 11.698 93 15 30 0 1155744752 66976960 3472912
06:44:46 41615 8192000000 10140251184 10607054848 1128671904 12.109 99 31 62 1 1228663264 93672592 6318768
06:46:35 36323 8192000000 10107095344 10563846144 1133171376 12.151 99 23 46 0 1176316688 38512160 4633152
06:48:36 41496 8192000000 10163333024 10637590528 1124418064 12.069 98 22 39 0 1232913632 104020896 4474672
06:50:17 40286 8192000000 10134214816 10584231936 1133721520 12.156 99 23 42 0 1221033008 83002288 4309200
06:52:02 112117 8192000000 10080292688 10580127744 1121184352 12.038 97 12 24 0 1206997952 82815872 2997728
06:54:54 22650 8192000000 10136408032 10595164160 1145014800 12.263 100 36 71 0 1245683568 93159728 7509040
06:56:18 23137 8192000000 10122062848 10604523520 1144650944 12.259 100 48 93 0 1198714096 44848624 9214528
06:57:41 17517 8192000000 10151062064 10585939968 1124873360 12.073 98 36 70 0 1217427728 85665024 6889344
06:59:02 41114 8192000000 10074002112 10535088128 1090945040 11.752 97 24 48 0 1166041504 70499200 4597264
07:00:46 83734 8192000000 10098467680 10587697152 1109543952 11.928 99 44 86 0 1225084080 106546672 8993456
07:03:19 46904 8192000000 10089839952 10524823552 1131370528 12.134 100 42 82 0 1187553696 47578208 8604960
07:05:09 26473 8192000000 10083118528 10568515584 1135101952 12.169 100 35 71 0 1228613168 86621344 6889872
07:06:46 46914 8192000000 10049672752 10491998208 1090554048 11.748 97 18 36 0 1120523568 26112528 3856992
07:08:37 59119 8192000000 10091937072 10553798656 1089964656 11.742 97 13 24 0 1165859184 73577600 2316928
07:10:39 34828 8192000000 10034408496 10518577152 1112160688 11.953 100 21 41 1 1145228768 27279600 5788480
07:12:16 69117 8192000000 10062510320 10558816256 1090249776 11.745 97 13 26 1 1133977744 41059472 2668496
07:14:34 34793 8192000000 10058743936 10514018304 1115903392 11.988 99 33 62 2 1150718144 27783120 7031632
07:16:11 39473 8192000000 10104668896 10544861184 1123869952 12.064 100 27 52 1 1182300880 52285264 6145664
07:17:53 60172 8192000000 10165304752 10620329984 1133341888 12.153 99 32 60 0 1236854448 97066768 6445792
07:19:56 40975 8192000000 10163454944 10603376640 1137338656 12.190 99 28 56 1 1239389840 96133200 5917984
07:21:38 45919 8192000000 10146717648 10633539584 1143243568 12.246 100 31 62 0 1251132432 101465952 6422912
07:23:27 43816 8192000000 10071932336 10540441600 1128005056 12.103 99 21 42 0 1193745808 61417440 4323312
07:25:12 11372 8192000000 10133548032 10565005312 1140790064 12.223 100 63 126 0 1227161248 73864288 12506896
07:26:29 109440 8192000000 10074401424 10562203648 1099931088 11.837 96 21 40 0 1140223440 36507008 3785344
07:30:07 29426 8192000000 10082545968 10533474304 1126829216 12.091 99 26 47 0 1178708848 46807872 5071760
07:31:38 67251 8192000000 10052384224 10555068416 1124498432 12.069 99 9 18 0 1173913456 47746448 1668576
07:33:46 61063 8192000000 10089024320 10548842496 1105801232 11.893 96 21 42 1 1166729968 56267936 4660800
07:35:59 35890 8192000000 10062292416 10526232576 1121024800 12.037 98 36 72 0 1158379728 29364704 7990224
07:37:38 54951 8192000000 10110884928 10563584000 1137377344 12.191 99 27 52 0 1193248944 50566496 5305104
07:39:41 43906 8192000000 10112276064 10571452416 1138930208 12.205 100 20 40 0 1218424096 75333616 4160272
07:41:31 61269 8192000000 10149484656 10629099520 1123953680 12.064 99 32 64 0 1203892016 73340336 6598000
07:43:32 87914 8192000000 10069393280 10531917824 1085063920 11.696 96 18 33 0 1142653520 54394544 3195056
07:46:00 54496 8192000000 10132105264 10594267136 1136375984 12.181 100 49 98 0 1221368336 75271104 9721248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 02-08-2022 07:48:49 to 02-08-2022 07:49:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-08-2022 07:49:06 to 02-08-2022 07:49:08.

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          216721
MON$OLDEST_ACTIVE               220326
MON$OLDEST_SNAPSHOT             220326
MON$NEXT_TRANSACTION            220326
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                       2100224
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.2812 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2812 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2812 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2812 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Aug  2 07:47:51 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Aug  2 07:47:52 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Tue Aug  2 07:47:52 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Tue Aug  2 07:47:52 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

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