Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.05.2022 07: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 16592452 kB
MemAvailable 31457320 kB
Buffers 0 kB
Cached 15080248 kB
SwapCached 2552 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-05-07 07:37:10
DTS_END                         2022-05-07 07:37:10.2800
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/43206
TRN_ID                          224188
ATT_ID                          605
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: 8872.78 28963 1064733
customer order: creation 165.63 450 19876
customer order: refuse 14.15 401 1698
order to supplier: creation 45.41 1267 5449
order to supplier: removal 3.25 168870 390
invoice (draft): creation 45.00 2882 5400
invoice (draft): removal 49.37 956 5924
invoice accept: apply 40.93 25480 4912
invoice accept: cancel 44.67 1206 5360
customer reserve: creation 3804.23 243 456508
customer reserve: removal 4372.36 100 524683
realization accept: apply 82.64 270 9917
realization accept: cancel 75.48 174 9058
payment from customer: creation 59.18 160 7101
payment from customer: removal 5.68 111 682
payment to supplier: creation 48.52 181 5822
payment to supplier: removal 3.52 99 422
service: total inventory turnovers 4.15 3460 498
service: total monetary turnovers 7.64 322 917
service: aggregate perf. data 0.93 13574 112
service: refresh index statistics 0.03 359048 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-05-2022 07:38:17 to 07-05-2022 07:38:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10433
TEST_TIME 2 8647
TEST_TIME 3 7121
TEST_TIME 4 7731
TEST_TIME 5 7035
TEST_TIME 6 7167
TEST_TIME 7 7545
TEST_TIME 8 7651
TEST_TIME 9 7750
TEST_TIME 10 7887
TEST_TIME 11 7677
TEST_TIME 12 7749
TEST_TIME 13 7722
TEST_TIME 14 7720
TEST_TIME 15 7623
TEST_TIME 16 7619
TEST_TIME 17 7672
TEST_TIME 18 7735
TEST_TIME 19 7827
TEST_TIME 20 7931
TEST_TIME 21 8211
TEST_TIME 22 8526
TEST_TIME 23 8418
TEST_TIME 24 8872
TEST_TIME 25 8952
TEST_TIME 26 9073
TEST_TIME 27 9501
TEST_TIME 28 10128
TEST_TIME 29 10812
TEST_TIME 30 11147
TEST_TIME 31 10982
TEST_TIME 32 11028
TEST_TIME 33 10827
TEST_TIME 34 10607
TEST_TIME 35 10675
TEST_TIME 36 10507
TEST_TIME 37 10316
TEST_TIME 38 10243
TEST_TIME 39 10223
TEST_TIME 40 10135
TEST_TIME 41 10188
TEST_TIME 42 10035
TEST_TIME 43 10053
TEST_TIME 44 9937
TEST_TIME 45 9796
TEST_TIME 46 9733
TEST_TIME 47 9769
TEST_TIME 48 9749
TEST_TIME 49 9673
TEST_TIME 50 9707
TEST_TIME 51 9707
TEST_TIME 52 9643
TEST_TIME 53 9565
TEST_TIME 54 9514
TEST_TIME 55 9494
TEST_TIME 56 9630
TEST_TIME 57 9755
TEST_TIME 58 9652
TEST_TIME 59 9616
TEST_TIME 60 9676
TEST_TIME 61 9645
TEST_TIME 62 9613
TEST_TIME 63 9626
TEST_TIME 64 9552
TEST_TIME 65 9450
TEST_TIME 66 9420
TEST_TIME 67 9361
TEST_TIME 68 9373
TEST_TIME 69 9338
TEST_TIME 70 9330
TEST_TIME 71 9333
TEST_TIME 72 9291
TEST_TIME 73 9277
TEST_TIME 74 9280
TEST_TIME 75 9237
TEST_TIME 76 9343
TEST_TIME 77 9362
TEST_TIME 78 9447
TEST_TIME 79 9392
TEST_TIME 80 9364
TEST_TIME 81 9424
TEST_TIME 82 9402
TEST_TIME 83 9328
TEST_TIME 84 9266
TEST_TIME 85 9220
TEST_TIME 86 9170
TEST_TIME 87 9145
TEST_TIME 88 9088
TEST_TIME 89 9010
TEST_TIME 90 9051
TEST_TIME 91 9083
TEST_TIME 92 9145
TEST_TIME 93 9102
TEST_TIME 94 9095
TEST_TIME 95 9095
TEST_TIME 96 9134
TEST_TIME 97 9159
TEST_TIME 98 9157
TEST_TIME 99 9168
TEST_TIME 100 9163
TEST_TIME 101 9208
TEST_TIME 102 9201
TEST_TIME 103 9199
TEST_TIME 104 9130
TEST_TIME 105 9113
TEST_TIME 106 9075
TEST_TIME 107 9008
TEST_TIME 108 8973
TEST_TIME 109 8959
TEST_TIME 110 8924
TEST_TIME 111 8942
TEST_TIME 112 8931
TEST_TIME 113 8934
TEST_TIME 114 8937
TEST_TIME 115 8905
TEST_TIME 116 8889
TEST_TIME 117 8853
TEST_TIME 118 8870
TEST_TIME 119 8949
TEST_TIME 120 8901

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-05-2022 07:38:21 to 07-05-2022 07:38:24.

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 1485664 1485664 0 0.00 0 13216 14 0 0
doc_list_biud 1485651 1485651 0 0.00 0 60111 41 0 0
sp_add_invoice_to_stock 5004 4912 92 1.83 32 222824 25480 0 92
sp_cancel_adding_invoice 5360 5360 0 0.00 6 77628 1206 0 0
sp_cancel_client_order 1698 1698 0 0.00 6 25483 401 0 0
sp_cancel_customer_reserve 524683 524683 0 0.00 1 60114 100 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 4 2711 111 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 4 2181 100 0 0
sp_cancel_supplier_invoice 5935 5924 11 0.18 2 44699 956 0 11
sp_cancel_supplier_order 390 390 0 0.00 9 1434807 168870 0 0
sp_cancel_write_off 9058 9058 0 0.00 2 9743 175 0 0
sp_client_order 19876 19876 0 0.00 6 41797 451 0 0
sp_customer_reserve 456508 456508 0 0.00 1 72051 244 0 0
sp_fill_shopping_cart 30720 30720 0 0.00 2 13400 80 0 0
sp_fill_shopping_cart_clo_res 456483 456483 0 0.00 0 13361 19 0 0
sp_get_clo_for_invoice 4862 4862 0 0.00 0 30847 305 0 0
sp_kill_cost_storno 15522 15522 0 0.00 0 9738 73 0 0
sp_kill_qstorno_ret_qs2qd 1064738 1064738 0 0.00 0 59627 44 0 0
sp_kill_qty_storno 561888 561888 0 0.00 0 60110 100 0 0
sp_lock_selected_doc 20434 20434 0 0.00 0 9810 5 0 0
sp_make_cost_storno 27698 27698 0 0.00 0 13194 71 0 0
sp_multiply_rows_for_pdistr 27698 27698 0 0.00 0 8503 36 0 0
sp_multiply_rows_for_qdistr 19876 19876 0 0.00 0 17352 181 0 0
sp_pay_from_customer 7101 7101 0 0.00 5 16229 161 0 0
sp_pay_to_supplier 5822 5822 0 0.00 6 25418 181 0 0
sp_payment_common 12923 12923 0 0.00 2 12495 45 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 0 14261 317 0 0
sp_qd_handle_on_invoice_upd_sts 10218 10218 0 0.00 0 28710 482 0 0
sp_qd_handle_on_reserve_upd_sts 9917 9917 0 0.00 0 32135 66 0 0
sp_reserve_write_off 9917 9917 0 0.00 8 32274 271 0 0
sp_supplier_invoice 5400 5400 0 0.00 9 69735 2882 0 0
sp_supplier_order 5449 5449 0 0.00 8 34557 1268 0 0
srv_aggregate_perf_data 112 112 0 0.00 2538 75267 13575 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 782 128557 40127 0 0
srv_find_qd_qs_mism 1016773 1016773 0 0.00 0 8024 12 0 0
srv_make_invnt_saldo 498 498 0 0.00 59 66131 3461 0 0
srv_make_money_saldo 917 917 0 0.00 1 6897 323 0 0
srv_recalc_idx_stat 4 4 0 0.00 209494 514112 359049 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4917 4917 0 0.00 1 12306 849 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 1 7485 451 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 3041 25 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 2270 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 3312 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 503 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 444 24 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 98444 6127 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 414 14 0 0
t$perf-norm:sp_client_order 19876 19876 0 0.00 0 49583 34 0 0
t$perf-norm:sp_customer_reserve 945 945 0 0.00 0 2937 14 0 0
t$perf-norm:sp_pay_from_customer 7101 7101 0 0.00 0 6761 16 0 0
t$perf-norm:sp_pay_to_supplier 5822 5822 0 0.00 0 6768 19 0 0
t$perf-norm:sp_reserve_write_off 9917 9917 0 0.00 0 4978 19 0 0
t$perf-norm:sp_supplier_invoice 5400 5400 0 0.00 0 4716 28 0 0
t$perf-norm:sp_supplier_order 5449 5449 0 0.00 0 3466 31 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 275 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 402 14 0 0
t$perf-norm:srv_make_invnt_saldo 498 498 0 0.00 0 1362 16 0 0
t$perf-norm:srv_make_money_saldo 917 917 0 0.00 0 2527 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 173 47 0 0
v_add_invoice_to_stock 9900 9716 184 1.85 0 5054 24 0 184
v_all_customers 37024 37024 0 0.00 0 156 3 0 0
v_all_suppliers 30418 30418 0 0.00 0 127 4 0 0
v_all_wares 39752 39752 0 0.00 0 86 3 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 25247 49 0 0
v_cancel_client_order 3396 3396 0 0.00 0 25277 19 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 2683 12 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 25245 28 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 2120 12 0 0
v_cancel_supplier_invoice 820 798 22 2.68 0 5977 33 0 22
v_cancel_supplier_order 780 780 0 0.00 0 5832 31 0 0
v_cancel_write_off 1922 1922 0 0.00 0 2521 25 0 0
v_max_id_clo_ord 5445 5445 0 0.00 0 4762 5 0 0
v_max_id_clo_res 945 945 0 0.00 0 2227 12 0 0
v_max_id_ord_sup 5398 5398 0 0.00 0 6925 8 0 0
v_max_non_paid_invoice 1456 1456 0 0.00 0 545 7 0 0
v_max_non_paid_realizn 3532 3532 0 0.00 0 483 6 0 0
v_min_id_clo_ord 5445 5445 0 0.00 0 5408 10 0 0
v_min_id_clo_res 945 945 0 0.00 0 7550 21 0 0
v_min_id_ord_sup 5398 5398 0 0.00 0 4892 11 0 0
v_min_non_paid_invoice 5822 5822 0 0.00 0 7009 16 0 0
v_min_non_paid_realizn 7101 7101 0 0.00 0 11421 21 0 0
v_our_firm 9866 9866 0 0.00 0 83 4 0 0
v_reserve_write_off 19834 19834 0 0.00 0 23955 17 0 0
x_lock_depdocs_on_canc_invoice 10885 10885 0 0.00 0 18299 123 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 8112 366 0 0
x_make_qstorno_1000_1200 5449 5449 0 0.00 2 34529 1106 0 0
x_make_qstorno_1200_2000 5400 5400 0 0.00 4 69606 2653 0 0
x_make_qstorno_2100_3300 456508 456508 0 0.00 0 72049 214 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-05-2022 07:38:25 to 07-05-2022 07:38:29.

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:07:14 3 8192000000 8798016576 8809074688 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:15 29905 8192000000 9732600016 10031943680 804582000 8.943 100 93 186 0 835370288 8156752 22631536
05:09:48 23632 8192000000 9769421296 10079490048 842885936 9.329 100 94 188 0 879230784 14044320 22300528
05:11:18 17537 8192000000 9812860848 10136457216 871230368 9.612 100 76 152 0 918924416 30706160 16987888
05:12:40 12235 8192000000 9866331744 10198384640 915417472 10.051 100 84 166 2 968074944 34522896 18134576
05:13:57 5086 8192000000 9923254064 10270240768 958004048 10.469 100 82 163 0 1041713008 66953184 16755776
05:15:05 3012 8192000000 9959393056 10316599296 991517376 10.796 100 82 153 2 1076892096 70025536 15349184
05:16:11 1194 8192000000 9978097856 10341314560 1020387584 11.076 100 91 177 1 1094396448 57259296 16749568
05:17:14 1101 8192000000 10032678352 10406662144 1043573424 11.299 100 96 186 0 1151877904 89090000 19214480
05:18:16 1020 8192000000 10036161216 10408517632 1060193680 11.458 100 91 166 0 1161560880 83918512 17448688
05:19:19 963 8192000000 10037176544 10416074752 1062936576 11.485 100 95 180 0 1158730096 77558368 18235152
05:20:21 1282 8192000000 10028236768 10408701952 1072395248 11.575 100 93 171 0 1149883856 60404944 17083664
05:21:24 2882 8192000000 10028696128 10421874688 1075775296 11.607 100 64 120 2 1149906096 62733120 11397680
05:22:27 1419 8192000000 10042184016 10426761216 1081005440 11.657 100 93 177 0 1164849184 67154096 16689648
05:23:30 1191 8192000000 10081638784 10464333824 1082715824 11.673 100 93 180 1 1202521200 101790672 18014704
05:24:33 1220 8192000000 10057104992 10446012416 1086738512 11.712 100 94 178 0 1182802928 77977616 18086800
05:25:35 1315 8192000000 10060495664 10448592896 1091685536 11.759 100 96 189 0 1182468064 72269136 18513392
05:26:38 1182 8192000000 10079560240 10464227328 1095921792 11.799 100 88 167 3 1196627792 85255216 15450784
05:27:40 1458 8192000000 10049653824 10434355200 1086305584 11.708 100 98 193 2 1170789872 65701440 18782848
05:28:42 1240 8192000000 10056817856 10444472320 1088521280 11.729 100 94 185 0 1178882528 72273696 18087552
05:29:44 1461 8192000000 10010019776 10397777920 1067317184 11.526 100 78 130 1 1135667488 55915792 12434512
05:30:46 1795 8192000000 10034538112 10420035584 1079438928 11.642 100 77 151 0 1153684528 59280880 14964720
05:31:48 1122 8192000000 10010460240 10389012480 1048563632 11.347 100 86 164 2 1130955360 65367168 17024560
05:32:54 1560 8192000000 9999746768 10379800576 1046781344 11.330 100 82 153 3 1118434144 55375824 16276976
05:33:57 2043 8192000000 9955095488 10330681344 1024040064 11.111 99 72 130 4 1078858896 41989216 12829616
05:35:00 1133 8192000000 9964920048 10335207424 1032416752 11.192 100 82 152 1 1085987648 38017216 15553680
05:36:02 1268 8192000000 9970451344 10344632320 1033902944 11.206 100 99 189 3 1095067440 42434992 18729504
05:37:04 1172 8192000000 9997109152 10367713280 1044350416 11.306 100 96 184 0 1117245632 52029888 20865328
05:38:10 21212 8192000000 10001164800 10386481152 1059952048 11.456 100 82 161 0 1120520192 44259712 16308432
05:39:39 1782 8192000000 10022071824 10402283520 1076808688 11.617 100 88 170 0 1143380320 48637296 17934336
05:40:45 1394 8192000000 10085761184 10464354304 1085880928 11.703 100 87 159 2 1199422192 96984832 16556432
05:41:49 2107 8192000000 10087984480 10480779264 1097423024 11.813 100 74 143 1 1202889840 90480544 14986272
05:42:55 11975 8192000000 10114848528 10511073280 1106543584 11.900 100 66 122 1 1231299168 111959152 12796432
05:44:11 2824 8192000000 10156914416 10551525376 1118605456 12.014 100 88 166 1 1270111936 132775504 18730976
05:45:16 1398 8192000000 10187080288 10584682496 1141095792 12.226 100 93 181 1 1299530720 138959200 19475728
05:46:21 1219 8192000000 10213825680 10615525376 1152381168 12.332 100 88 173 1 1332058416 161342704 18334544
05:47:26 1396 8192000000 10232855328 10629160960 1159347920 12.397 100 93 178 1 1343070880 164379600 19343360
05:48:31 2241 8192000000 10279867184 10674446336 1165783088 12.457 100 83 159 0 1390274752 207256928 17234736
05:49:38 1293 8192000000 10309756000 10710900736 1171273824 12.509 100 89 173 1 1417337824 227789936 18274064
05:50:43 5446 8192000000 10343827024 10749808640 1181147840 12.601 100 81 157 0 1453242736 254204608 17890288
05:51:51 1163 8192000000 10390543696 10801717248 1186435600 12.650 100 93 181 3 1496256832 289583152 20238080
05:52:58 1548 8192000000 10402672112 10807951360 1180833024 12.598 100 93 179 1 1511738160 309956704 20948432
05:54:02 1182 8192000000 10450857216 10864889856 1186762672 12.653 100 97 192 0 1555028912 346050352 22215888
05:55:06 1088 8192000000 10493766688 10912366592 1198726640 12.765 100 96 191 0 1600096832 378220656 23149536
05:56:11 951 8192000000 10499422992 10915344384 1185528912 12.642 100 96 187 1 1605848384 396825744 23493728
05:57:14 777 8192000000 10493102656 10927255552 1173959248 12.534 100 99 193 0 1599736752 402994992 22782512
05:58:18 1609 8192000000 10537151424 10947219456 1195965792 12.739 100 92 178 3 1642172304 425351792 20854720
05:59:22 6165 8192000000 10536239424 10992017408 1183519040 12.623 100 56 108 1 1642733024 445219168 13994816
06:00:33 7285 8192000000 10506919184 10954321920 1166658016 12.466 99 57 115 0 1609206608 428104560 14444032
06:01:44 4822 8192000000 10488044560 10924048384 1187110832 12.656 100 59 112 2 1590764672 389470304 14183536
06:03:01 19613 8192000000 10474846528 10957262848 1170747760 12.504 100 39 78 1 1586050096 405146096 10156240
06:04:29 18357 8192000000 10415492384 10929041408 1158261792 12.387 100 28 56 1 1544572720 378443552 7867376
06:05:49 21790 8192000000 10226041200 10740600832 1148463152 12.295 100 20 38 0 1385589888 232174864 4951872
06:07:12 48972 8192000000 10120007280 10629496832 1128374640 12.106 99 12 24 1 1201741136 70774240 2592256
06:09:09 12945 8192000000 10133060592 10560118784 1128701392 12.109 99 44 86 1 1218026752 80244336 9081024
06:10:30 85460 8192000000 10139199856 10610888704 1114862944 11.978 98 36 71 1 1237036176 113334240 8838992
06:13:12 102699 8192000000 10068471984 10527449088 1087967120 11.723 96 32 56 3 1137796576 43629504 6199952
06:15:58 84872 8192000000 10124048224 10586234880 1094700144 11.787 96 33 66 0 1215760176 113463776 7596256
06:18:24 28360 8192000000 10076300592 10565459968 1119012736 12.018 99 13 26 0 1194801856 73152528 2636592
06:19:53 45870 8192000000 10056281200 10540945408 1108566080 11.919 99 22 44 0 1171715568 58529456 4620032
06:21:46 94906 8192000000 10072186320 10553577472 1130580560 12.127 100 14 28 0 1196707504 63635824 2491120
06:24:22 62785 8192000000 10078350128 10516209664 1118538896 12.013 99 23 46 2 1147705568 24182656 4984016
06:26:26 15546 8192000000 10080824944 10509545472 1138643696 12.203 100 60 107 1 1177923616 28164656 11115264
06:27:47 53497 8192000000 10076293312 10542600192 1105438256 11.889 97 36 70 1 1188875552 75476416 7960880
06:29:42 17418 8192000000 10111410160 10548383744 1145858896 12.271 100 38 75 1 1201518624 48180240 7479488
06:31:00 19122 8192000000 10128782160 10559586304 1131277376 12.133 99 32 64 1 1224599456 86186160 7135920
06:32:31 19223 8192000000 10214277120 10659864576 1155718512 12.363 100 38 76 1 1311341152 147445136 8177504
06:34:59 35752 8192000000 10121486592 10555375616 1144709920 12.260 99 26 51 3 1199045312 48910832 5424560
06:36:53 85225 8192000000 10142096384 10619441152 1091901872 11.761 95 17 34 2 1201773536 106109184 3762480
06:39:18 95500 8192000000 10060181008 10533646336 1126526032 12.089 100 19 36 1 1204645792 74111872 4007888
06:41:54 54210 8192000000 10070701920 10524942336 1131353936 12.134 100 15 30 1 1173017360 38282992 3380432
06:43:55 61549 8192000000 10078283280 10561273856 1113287424 11.964 98 7 14 2 1153738288 38724304 1726560
06:46:17 66335 8192000000 10064565456 10510258176 1090058432 11.743 96 22 44 0 1129134944 33979712 5096800
06:48:24 57143 8192000000 10075728480 10537623552 1102915104 11.865 97 22 44 0 1143309392 36082496 4311792
06:50:34 30565 8192000000 10113601888 10545176576 1112169280 11.953 98 35 68 2 1188087024 68885904 7031840
06:52:07 77215 8192000000 10087661120 10600828928 1102918368 11.865 98 25 50 0 1203581088 95019536 5643184
06:54:30 118370 8192000000 10070889504 10522583040 1085294816 11.698 96 27 53 0 1145131040 54239968 5596256
06:57:28 36112 8192000000 10147001936 10610442240 1139025200 12.206 100 39 78 2 1236119008 88965520 8128288
06:59:06 128554 8192000000 10046280032 10477367296 1099976944 11.837 97 32 64 1 1158287040 51613248 6696848
07:02:29 38368 8192000000 10092366640 10554535936 1136007872 12.178 100 39 74 3 1190139312 45743136 8388304
07:04:12 59460 8192000000 10092746624 10581053440 1135504304 12.173 100 14 29 0 1205737808 66868576 3364928
07:06:14 79741 8192000000 10185769568 10650742784 1117771536 12.006 98 16 32 0 1266043792 144088832 4183424
07:08:36 124922 8192000000 10085519648 10528354304 1097778368 11.817 97 19 39 3 1194419552 92466096 4175088
07:11:43 69701 8192000000 10130850768 10592669696 1132569360 12.146 99 23 46 0 1234785856 96875824 5340672
07:14:00 26931 8192000000 10058398496 10514145280 1118990512 12.017 99 24 48 1 1187389536 63601232 4797792
07:15:31 16086 8192000000 10096696128 10529607680 1126330752 12.087 100 46 92 0 1201943440 66503440 9109248
07:16:50 29747 8192000000 10117781712 10555351040 1103683968 11.873 98 52 103 0 1177515904 63719824 10112112
07:18:28 37290 8192000000 10105138928 10566860800 1120443296 12.031 99 42 81 0 1202990128 74800560 7746272
07:20:06 109950 8192000000 10074962800 10520932352 1135642928 12.175 100 19 34 3 1193413840 54323616 3447296
07:22:57 44217 8192000000 10060489248 10511724544 1127340544 12.096 99 11 22 1 1160315440 31012144 1962752
07:24:58 93071 8192000000 10112316048 10586513408 1132285712 12.143 99 19 39 1 1222973424 85953568 4734144
07:27:32 82046 8192000000 10070355984 10506977280 1117290736 12.001 98 20 37 1 1171339120 49802256 4246128
07:29:58 61940 8192000000 10094186608 10540752896 1116713104 11.996 98 25 50 0 1182237072 60220864 5303104
07:32:03 49987 8192000000 10138142736 10589978624 1121707840 12.043 98 25 50 1 1218091360 90485536 5897984
07:34:02 21901 8192000000 10174382512 10612273152 1141270800 12.227 100 38 76 1 1262264432 112576128 8417504
07:35:32 104185 8192000000 9570618240 10000846848 673966384 7.601 59 12 22 2 813919104 137039760 2912960

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-05-2022 07:38:31 to 07-05-2022 07:38:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 92 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-05-2022 07:38:48 to 07-05-2022 07:38:50.

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          216675
MON$OLDEST_ACTIVE               224450
MON$OLDEST_SNAPSHOT             224450
MON$NEXT_TRANSACTION            224450
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                       2027872
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 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 ---
:42: 
:43: mscs-test-161.budzdorov.ru    Sat May  7 07:37:22 2022
:44:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:45: 
--- end of diff output ---

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

07.05.22 07:38:51. 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-05-07 07:38:53 No severe PSQL-related problems occured
07.05.22 07:38:55. End of report.