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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-12 13:37:16
DTS_END                         2022-04-12 13:37:16.7290
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/35238
TRN_ID                          220715
ATT_ID                          647
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: 9035.12 26019 1084214
customer order: creation 168.62 491 20234
customer order: refuse 14.88 469 1785
order to supplier: creation 45.74 1497 5489
order to supplier: removal 3.33 190176 399
invoice (draft): creation 45.89 3500 5507
invoice (draft): removal 48.47 1151 5816
invoice accept: apply 41.94 26814 5033
invoice accept: cancel 44.41 1312 5329
customer reserve: creation 3932.85 251 471942
customer reserve: removal 4401.07 112 528128
realization accept: apply 81.03 310 9724
realization accept: cancel 78.95 178 9474
payment from customer: creation 59.19 188 7103
payment from customer: removal 5.51 141 661
payment to supplier: creation 47.48 225 5698
payment to supplier: removal 3.37 140 404
service: total inventory turnovers 3.63 4669 436
service: total monetary turnovers 7.88 411 945
service: aggregate perf. data 0.85 17822 102
service: refresh index statistics 0.04 270532 5

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6199
TEST_TIME 2 4561
TEST_TIME 3 5378
TEST_TIME 4 5126
TEST_TIME 5 5261
TEST_TIME 6 5353
TEST_TIME 7 4984
TEST_TIME 8 4858
TEST_TIME 9 5083
TEST_TIME 10 5599
TEST_TIME 11 5874
TEST_TIME 12 6045
TEST_TIME 13 6239
TEST_TIME 14 6457
TEST_TIME 15 6634
TEST_TIME 16 6812
TEST_TIME 17 7083
TEST_TIME 18 7385
TEST_TIME 19 7543
TEST_TIME 20 7535
TEST_TIME 21 7581
TEST_TIME 22 7761
TEST_TIME 23 7770
TEST_TIME 24 7878
TEST_TIME 25 7944
TEST_TIME 26 8118
TEST_TIME 27 8065
TEST_TIME 28 8315
TEST_TIME 29 8263
TEST_TIME 30 8436
TEST_TIME 31 8942
TEST_TIME 32 9106
TEST_TIME 33 9392
TEST_TIME 34 9818
TEST_TIME 35 9822
TEST_TIME 36 10282
TEST_TIME 37 10313
TEST_TIME 38 10348
TEST_TIME 39 10425
TEST_TIME 40 10476
TEST_TIME 41 10448
TEST_TIME 42 10422
TEST_TIME 43 10349
TEST_TIME 44 10259
TEST_TIME 45 10144
TEST_TIME 46 10216
TEST_TIME 47 10283
TEST_TIME 48 10193
TEST_TIME 49 10166
TEST_TIME 50 10115
TEST_TIME 51 10000
TEST_TIME 52 9908
TEST_TIME 53 9861
TEST_TIME 54 9811
TEST_TIME 55 9836
TEST_TIME 56 9711
TEST_TIME 57 9619
TEST_TIME 58 9650
TEST_TIME 59 9582
TEST_TIME 60 9510
TEST_TIME 61 9693
TEST_TIME 62 9608
TEST_TIME 63 9536
TEST_TIME 64 9533
TEST_TIME 65 9501
TEST_TIME 66 9421
TEST_TIME 67 9418
TEST_TIME 68 9404
TEST_TIME 69 9437
TEST_TIME 70 9455
TEST_TIME 71 9406
TEST_TIME 72 9465
TEST_TIME 73 9428
TEST_TIME 74 9533
TEST_TIME 75 9615
TEST_TIME 76 9568
TEST_TIME 77 9583
TEST_TIME 78 9610
TEST_TIME 79 9638
TEST_TIME 80 9626
TEST_TIME 81 9546
TEST_TIME 82 9521
TEST_TIME 83 9448
TEST_TIME 84 9393
TEST_TIME 85 9378
TEST_TIME 86 9344
TEST_TIME 87 9336
TEST_TIME 88 9309
TEST_TIME 89 9270
TEST_TIME 90 9178
TEST_TIME 91 9225
TEST_TIME 92 9208
TEST_TIME 93 9185
TEST_TIME 94 9201
TEST_TIME 95 9255
TEST_TIME 96 9277
TEST_TIME 97 9265
TEST_TIME 98 9278
TEST_TIME 99 9292
TEST_TIME 100 9291
TEST_TIME 101 9289
TEST_TIME 102 9384
TEST_TIME 103 9356
TEST_TIME 104 9392
TEST_TIME 105 9358
TEST_TIME 106 9287
TEST_TIME 107 9232
TEST_TIME 108 9200
TEST_TIME 109 9201
TEST_TIME 110 9149
TEST_TIME 111 9133
TEST_TIME 112 9118
TEST_TIME 113 9126
TEST_TIME 114 9106
TEST_TIME 115 9074
TEST_TIME 116 9050
TEST_TIME 117 9047
TEST_TIME 118 9040
TEST_TIME 119 9074
TEST_TIME 120 9058

Performance per minute, chart


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

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1517045 1517045 0 0.00 0 33259 14 0 0
doc_list_biud 1517036 1517036 0 0.00 0 27968 45 0 0
sp_add_invoice_to_stock 5130 5033 97 1.89 33 191474 26815 0 97
sp_cancel_adding_invoice 5329 5329 0 0.00 7 78381 1313 0 0
sp_cancel_client_order 1785 1785 0 0.00 5 11606 469 0 0
sp_cancel_customer_reserve 528128 528128 0 0.00 1 26700 113 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 4 6662 141 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 4 4455 141 0 0
sp_cancel_supplier_invoice 5821 5816 5 0.08 3 28060 1152 0 5
sp_cancel_supplier_order 399 399 0 0.00 10 1634672 190176 0 0
sp_cancel_write_off 9474 9474 0 0.00 2 7815 179 0 0
sp_client_order 20234 20234 0 0.00 5 30568 491 0 0
sp_customer_reserve 471942 471942 0 0.00 1 127449 251 0 0
sp_fill_shopping_cart 31217 31217 0 0.00 1 32400 92 0 0
sp_fill_shopping_cart_clo_res 471905 471905 0 0.00 0 17210 19 0 0
sp_get_clo_for_invoice 4992 4992 0 0.00 0 23675 292 0 0
sp_kill_cost_storno 15868 15868 0 0.00 0 19689 89 0 0
sp_kill_qstorno_ret_qs2qd 1071945 1071945 0 0.00 0 27962 49 0 0
sp_kill_qty_storno 565647 565647 0 0.00 0 27966 112 0 0
sp_lock_selected_doc 20416 20416 0 0.00 0 9514 9 0 0
sp_make_cost_storno 27517 27517 0 0.00 0 16727 90 0 0
sp_multiply_rows_for_pdistr 27517 27517 0 0.00 0 11323 40 0 0
sp_multiply_rows_for_qdistr 20234 20234 0 0.00 0 11348 174 0 0
sp_pay_from_customer 7103 7103 0 0.00 6 25547 188 0 0
sp_pay_to_supplier 5698 5698 0 0.00 6 24930 225 0 0
sp_payment_common 12801 12801 0 0.00 2 10405 49 0 0
sp_qd_handle_on_cancel_clo 1785 1785 0 0.00 0 11598 399 0 0
sp_qd_handle_on_invoice_upd_sts 10321 10321 0 0.00 0 21469 575 0 0
sp_qd_handle_on_reserve_upd_sts 9724 9724 0 0.00 0 22157 78 0 0
sp_reserve_write_off 9724 9724 0 0.00 8 22228 311 0 0
sp_supplier_invoice 5507 5507 0 0.00 9 111513 3500 0 0
sp_supplier_order 5489 5489 0 0.00 7 46185 1498 0 0
srv_aggregate_perf_data 102 102 0 0.00 2161 160318 17823 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 953 153239 33311 0 0
srv_find_qd_qs_mism 1034426 1034426 0 0.00 0 33257 11 0 0
srv_make_invnt_saldo 436 436 0 0.00 107 102369 4669 0 0
srv_make_money_saldo 945 945 0 0.00 1 14639 411 0 0
srv_recalc_idx_stat 5 5 0 0.00 62731 461606 270532 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5039 5039 0 0.00 0 11285 986 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 6039 476 0 0
t$perf-norm:sp_cancel_client_order 1785 1785 0 0.00 0 4802 33 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 593 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 616 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 2300 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 4644 37 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 93927 8764 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 3038 26 0 0
t$perf-norm:sp_client_order 20235 20235 0 0.00 0 8186 39 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 485 16 0 0
t$perf-norm:sp_pay_from_customer 7103 7103 0 0.00 0 11506 26 0 0
t$perf-norm:sp_pay_to_supplier 5698 5698 0 0.00 0 7603 22 0 0
t$perf-norm:sp_reserve_write_off 9724 9724 0 0.00 0 7705 25 0 0
t$perf-norm:sp_supplier_invoice 5507 5507 0 0.00 0 4560 32 0 0
t$perf-norm:sp_supplier_order 5489 5489 0 0.00 0 1936 32 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 560 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 242 7 0 0
t$perf-norm:srv_make_invnt_saldo 436 436 0 0.00 0 1807 27 0 0
t$perf-norm:srv_make_money_saldo 945 945 0 0.00 0 2218 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 15 251 105 0 0
v_add_invoice_to_stock 10170 9976 194 1.90 0 4622 26 0 194
v_all_customers 37374 37374 0 0.00 0 108 4 0 0
v_all_suppliers 30550 30550 0 0.00 0 125 4 0 0
v_all_wares 40462 40462 0 0.00 0 169 4 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 469 14 0 0
v_cancel_client_order 3570 3570 0 0.00 0 2828 9 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 2753 17 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 1711 10 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 1477 13 0 0
v_cancel_supplier_invoice 810 800 10 1.23 0 7863 50 0 10
v_cancel_supplier_order 798 798 0 0.00 0 2821 13 0 0
v_cancel_write_off 1922 1922 0 0.00 0 2884 28 0 0
v_max_id_clo_ord 5486 5486 0 0.00 0 2683 6 0 0
v_max_id_clo_res 965 965 0 0.00 0 4060 20 0 0
v_max_id_ord_sup 5499 5499 0 0.00 0 5627 7 0 0
v_max_non_paid_invoice 1408 1408 0 0.00 0 641 8 0 0
v_max_non_paid_realizn 3579 3579 0 0.00 0 4000 9 0 0
v_min_id_clo_ord 5486 5486 0 0.00 0 1637 9 0 0
v_min_id_clo_res 965 965 0 0.00 0 2789 13 0 0
v_min_id_ord_sup 5499 5499 0 0.00 0 13897 13 0 0
v_min_non_paid_invoice 5698 5698 0 0.00 0 9233 20 0 0
v_min_non_paid_realizn 7103 7103 0 0.00 0 6917 18 0 0
v_our_firm 10136 10136 0 0.00 0 85 5 0 0
v_reserve_write_off 19448 19448 0 0.00 0 2936 11 0 0
x_lock_depdocs_on_canc_invoice 10745 10745 0 0.00 0 11981 133 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 21203 431 0 0
x_make_qstorno_1000_1200 5489 5489 0 0.00 3 46173 1312 0 0
x_make_qstorno_1200_2000 5507 5507 0 0.00 4 111429 3249 0 0
x_make_qstorno_2100_3300 471941 471941 0 0.00 0 127448 220 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:20 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:21 35913 8192000000 9735910448 10038616064 810627920 9.004 100 91 182 0 840449152 8047264 21773968
11:10:02 20055 8192000000 9768593008 10078765056 839777392 9.298 100 87 174 0 873153696 12948864 20427440
11:11:27 13834 8192000000 9808393120 10130993152 874546288 9.645 100 85 170 0 919084512 25084128 19454096
11:12:47 10375 8192000000 9864374512 10197635072 915866656 10.055 100 84 168 0 977255840 43057664 18331520
11:14:02 4427 8192000000 9920225264 10265235456 963709936 10.525 100 84 168 0 1036329040 54595664 18023440
11:15:10 2453 8192000000 9969744064 10322477056 994826704 10.828 100 90 178 0 1087456240 73873632 18755904
11:16:16 4715 8192000000 9977851136 10342776832 1017330304 11.046 100 76 152 0 1094103152 61379648 15393200
11:17:22 1080 8192000000 10007685472 10380718080 1045940576 11.322 100 99 192 0 1132263104 68009424 18313104
11:18:25 1242 8192000000 10031900000 10410205184 1065739392 11.511 100 95 180 1 1157555248 72665088 19150768
11:19:27 12588 8192000000 10025335248 10401845248 1071467072 11.566 100 84 160 1 1147484512 61474000 14543440
11:20:41 1539 8192000000 10072323680 10451529728 1079730656 11.645 100 90 178 1 1188882752 91516304 17635792
11:21:44 1495 8192000000 10062364272 10447671296 1082911200 11.675 100 90 175 0 1184770176 83877168 17981808
11:22:47 815 8192000000 10061507856 10446020608 1083921568 11.685 100 93 184 2 1182418432 78801600 19695264
11:23:50 1325 8192000000 10051329856 10438668288 1088801744 11.731 100 91 181 1 1174708528 68260464 17646320
11:24:52 1253 8192000000 10045412864 10433519616 1096293952 11.802 100 95 185 0 1171756256 56186320 19275984
11:25:57 1310 8192000000 10060344160 10451492864 1098422432 11.823 100 96 186 2 1183370384 65706224 19241728
11:27:00 1193 8192000000 10076863360 10469044224 1100536624 11.843 100 96 176 1 1198630704 80611712 17482368
11:28:03 1342 8192000000 10070535472 10458714112 1090211616 11.745 100 97 183 0 1196558144 87217392 19129136
11:29:06 1243 8192000000 10048051344 10437849088 1097082080 11.810 100 93 183 1 1173707872 57843120 18782672
11:30:08 2567 8192000000 10034243792 10423709696 1080986240 11.657 100 74 136 2 1156692288 61822224 13883824
11:31:11 780 8192000000 10018987984 10405900288 1055706176 11.415 99 79 153 0 1143527248 71929616 15891456
11:32:13 1586 8192000000 10030462576 10412490752 1075917280 11.609 100 94 179 2 1151976800 57891600 18167920
11:33:16 1071 8192000000 10001639552 10374500352 1034980064 11.216 100 96 190 1 1126453712 72030976 19442672
11:34:18 1541 8192000000 10006452784 10380603392 1049908064 11.360 100 95 177 0 1128753200 59707232 19137904
11:35:21 1071 8192000000 10012474432 10390917120 1046986944 11.332 100 92 175 1 1139436416 73859104 18590368
11:36:23 1018 8192000000 9973966768 10342289408 1020818512 11.080 100 92 179 1 1097975008 58378832 18777664
11:37:27 24034 8192000000 9982291696 10360377344 1042830688 11.292 100 57 104 2 1097844272 44768000 10245584
11:38:59 8764 8192000000 10028698112 10413096960 1065427104 11.508 100 60 118 1 1149071456 70804432 12839920
11:40:15 986 8192000000 10059339600 10440777728 1088846864 11.732 100 96 189 0 1180192608 71229584 20116160
11:41:20 3294 8192000000 10098322528 10489978880 1104684400 11.882 100 78 143 0 1217774032 97947600 15142032
11:42:31 2904 8192000000 10126488176 10521858048 1113560448 11.966 100 74 141 0 1249365360 121130528 14674384
11:43:44 12545 8192000000 10132551056 10534965248 1123410272 12.059 100 56 106 1 1254830944 119595856 11824816
11:45:10 2273 8192000000 10191848736 10587987968 1133113120 12.151 100 91 178 1 1307924192 155337168 19473904
11:46:16 2700 8192000000 10244509024 10650128384 1149342976 12.303 100 86 165 0 1356085936 188647504 18095456
11:47:23 1398 8192000000 10237471056 10637201408 1152719440 12.335 100 79 156 1 1354107856 184708224 16680192
11:48:29 1018 8192000000 10273257296 10682703872 1170593936 12.502 100 93 180 0 1388576032 197385008 20597088
11:49:33 1035 8192000000 10328988192 10742820864 1181265328 12.602 100 98 187 0 1440063056 237253280 21544448
11:50:39 1469 8192000000 10321407520 10725195776 1178837744 12.579 100 91 177 0 1442179440 242764800 20576896
11:51:48 968 8192000000 10377757056 10798870528 1188452736 12.669 100 96 188 0 1486302144 276602448 21246960
11:52:52 1326 8192000000 10397152208 10811744256 1188488288 12.669 100 96 178 2 1501923488 293569008 19866192
11:53:56 4190 8192000000 10417766064 10857414656 1196855344 12.747 100 89 177 0 1524752240 306895280 21001616
11:55:04 1685 8192000000 10406120368 10865917952 1204298256 12.816 100 95 178 2 1508634112 284042144 20293712
11:56:08 979 8192000000 10448871328 10876375040 1200805904 12.784 100 97 188 0 1552970128 329267664 22896560
11:57:16 982 8192000000 10433271200 10855239680 1176972992 12.562 100 88 169 1 1538468208 341398128 20097088
11:58:27 9428 8192000000 10455660512 10895732736 1191414752 12.697 100 50 98 1 1568263792 363595936 13253104
11:59:40 2008 8192000000 10486308912 10919936000 1204713296 12.820 100 68 135 2 1593426480 372915600 15797584
12:00:47 2032 8192000000 10538638480 10951286784 1203566464 12.809 100 99 193 1 1640760144 414340160 22853520
12:01:54 2255 8192000000 10516261216 10955137024 1190502352 12.688 100 79 157 0 1627396128 418350672 18543104
12:03:04 1569 8192000000 10522584752 10962694144 1175741232 12.550 100 93 186 0 1625510592 427526240 22243120
12:04:10 1709 8192000000 10525638544 10967109632 1171079616 12.507 99 89 171 1 1629989344 439251056 19658672
12:05:15 940 8192000000 10536554240 10958962688 1183164688 12.620 100 97 189 0 1639521072 433583760 22772624
12:06:18 5260 8192000000 10594018768 11042435072 1181976432 12.609 100 56 109 1 1698503984 502519888 14007664
12:07:29 1701 8192000000 10559645168 11051802624 1164509856 12.445 100 80 161 0 1674853008 489597680 20745472
12:08:32 3031 8192000000 10516871648 10969591808 1172050240 12.516 100 47 93 0 1622081344 437872704 12158400
12:10:09 14669 8192000000 10464385680 10945032192 1161969888 12.422 99 35 66 0 1563155360 392439328 8746144
12:11:25 18911 8192000000 10346464496 10826096640 1168933056 12.487 100 28 56 0 1464567872 288713968 6920848
12:12:45 20422 8192000000 10233182784 10715631616 1137625184 12.193 98 32 63 3 1351220800 205944304 7651312
12:14:07 14375 8192000000 10176690176 10643787776 1142916528 12.243 100 47 95 0 1277568624 123747904 10904192
12:15:24 29335 8192000000 10173855952 10652778496 1148497664 12.295 100 26 47 0 1274580016 119952352 6130000
12:17:00 93405 8192000000 10125698256 10592509952 1113154064 11.962 97 19 38 0 1217713216 100356112 4203040
12:19:34 69327 8192000000 10082960432 10532564992 1118680672 12.015 98 19 38 0 1178560464 56181568 3698224
12:21:51 153233 8192000000 10090858256 10630688768 1101111904 11.848 97 9 18 0 1173107760 70222112 1773744
12:25:25 98753 8192000000 10030139072 10458189824 1020169728 11.074 90 15 28 1 1046342352 23385984 2786640
12:28:07 108975 8192000000 10104630512 10585100288 1107808720 11.912 98 16 32 0 1156435888 45330592 3296576
12:30:59 66661 8192000000 10082811776 10525138944 1107196320 11.906 98 32 62 0 1173581856 59935456 6450080
12:33:24 74525 8192000000 10062364208 10485641216 1090858144 11.751 96 26 50 2 1116383056 20458976 5065936
12:35:40 23332 8192000000 10110126112 10577309696 1128827328 12.110 99 24 49 1 1210469424 76326384 5315712
12:37:04 17291 8192000000 10133820000 10554195968 1123353856 12.059 98 63 123 2 1206820480 69638480 13828144
12:38:23 23342 8192000000 10114970592 10577743872 1124714064 12.072 99 36 71 1 1229570672 98056432 6800176
12:39:47 118098 8192000000 10096315680 10560307200 1133959232 12.159 99 15 30 0 1188561072 51652944 2948896
12:43:25 86720 8192000000 10104668176 10579718144 1115584064 11.985 97 14 28 0 1181109456 62638832 2886560
12:45:53 45978 8192000000 10140557536 10631647232 1155084608 12.357 100 29 56 1 1255989792 95075920 5829264
12:47:41 55743 8192000000 10149841808 10602418176 1097112816 11.810 95 21 42 0 1194785552 92496656 5176080
12:49:37 52185 8192000000 10200179888 10637783040 1120022576 12.027 97 23 47 0 1248436112 123113568 5299968
12:51:33 38357 8192000000 10162233424 10638348288 1141769424 12.232 99 38 72 1 1295011216 144968256 8273536
12:53:11 33127 8192000000 10124251056 10549882880 1128389648 12.106 98 55 106 1 1195895424 56286320 11219456
12:54:49 10009 8192000000 10141789968 10566787072 1145370720 12.266 100 53 106 1 1226713504 70827344 10515440
12:55:59 47283 8192000000 10109441472 10572021760 1122896992 12.054 98 40 80 2 1215849136 84971248 7980896
12:57:53 101404 8192000000 10038586000 10481922048 1101636448 11.853 98 32 64 0 1160513104 51308720 7567936
13:00:36 25026 8192000000 10065385008 10494439424 1102071136 11.857 99 53 104 1 1154123344 41880848 10171360
13:02:12 23145 8192000000 10081761200 10520535040 1110896960 11.941 99 43 78 0 1185391264 66831728 7662576
13:03:36 33166 8192000000 10087330288 10553729024 1108315968 11.916 99 18 36 2 1182219632 70033584 3870080
13:05:30 81895 8192000000 10086729616 10550255616 1113596416 11.966 99 13 25 1 1180055008 63821104 2637488
13:07:55 40546 8192000000 10056005152 10531299328 1081000464 11.657 96 13 25 0 1133545456 49903712 2641280
13:09:36 38649 8192000000 10096791856 10532286464 1120003088 12.027 99 32 63 0 1178762400 52785360 5973952
13:11:15 59826 8192000000 10090942496 10546683904 1111812384 11.950 98 17 34 0 1186770080 71202688 3755008
13:13:18 52017 8192000000 10093343280 10559549440 1107490752 11.909 98 33 64 2 1180603712 65971760 7141200
13:15:12 15419 8192000000 10081096720 10507202560 1125133968 12.075 100 53 102 0 1176407440 42071200 9202272
13:16:30 44773 8192000000 10087374128 10520879104 1100461248 11.842 98 34 66 0 1173952640 66982848 6508544
13:18:16 48376 8192000000 10113950256 10554568704 1103414000 11.870 98 16 32 0 1190725728 84026672 3285056
13:20:05 19698 8192000000 10072779840 10505027584 1116531584 11.994 99 44 88 0 1151634560 25511248 9591728
13:21:25 69458 8192000000 10090469664 10540613632 1087282704 11.717 96 25 50 0 1146071152 53542016 5246432
13:24:55 19461 8192000000 10052367536 10490703872 1125267232 12.077 100 46 86 2 1162953600 28735936 8950432
13:26:15 82788 8192000000 10098032880 10571493376 1118110672 12.009 98 15 30 1 1195506000 73631216 3764112
13:28:38 49752 8192000000 10107306016 10578206720 1125723280 12.081 99 19 38 0 1210792640 80692928 4376432
13:30:31 80893 8192000000 10111924160 10566131712 1130820976 12.129 99 14 28 0 1210692272 75893488 3977808
13:33:00 33630 8192000000 10169109008 10615816192 1148158240 12.292 100 24 48 2 1258053984 103598080 6297664
13:34:45 55119 8192000000 10183632192 10642178048 1135392112 12.172 98 26 52 1 1280253440 138709360 6151968
13:36:43 39836 8192000000 9458935584 9841434624 547483344 6.264 47 18 34 1 655861280 104122048 4255888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          211865
MON$OLDEST_ACTIVE               221143
MON$OLDEST_SNAPSHOT             221143
MON$NEXT_TRANSACTION            221143
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                       2097792
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2758 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2758 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2758 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2758 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 ---
:44: 
:45: mscs-test-161.budzdorov.ru    Tue Apr 12 13:37:27 2022
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
--- end of diff output ---

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

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