Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.04.2022 04: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 16668696 kB
MemAvailable 31576912 kB
Buffers 20 kB
Cached 15009600 kB
SwapCached 2216 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-03 04:37:14
DTS_END                         2022-04-03 04:37:14.8370
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46188
TRN_ID                          228474
ATT_ID                          619
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: 8732.33 28900 1047879
customer order: creation 175.20 429 21024
customer order: refuse 15.08 412 1810
order to supplier: creation 48.07 1238 5768
order to supplier: removal 3.31 152767 397
invoice (draft): creation 47.55 2951 5706
invoice (draft): removal 47.08 969 5649
invoice accept: apply 42.49 26762 5099
invoice accept: cancel 42.05 1297 5046
customer reserve: creation 3948.05 258 473766
customer reserve: removal 4067.58 99 488110
realization accept: apply 85.24 236 10229
realization accept: cancel 76.69 160 9203
payment from customer: creation 60.65 150 7278
payment from customer: removal 6.04 121 725
payment to supplier: creation 50.11 173 6013
payment to supplier: removal 3.79 127 455
service: total inventory turnovers 4.42 3398 530
service: total monetary turnovers 7.91 283 949
service: aggregate perf. data 0.98 16299 118
service: refresh index statistics 0.03 369862 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-04-2022 04:38:48 to 03-04-2022 04:38:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8087
TEST_TIME 2 6170
TEST_TIME 3 7114
TEST_TIME 4 6876
TEST_TIME 5 7226
TEST_TIME 6 6665
TEST_TIME 7 7152
TEST_TIME 8 7457
TEST_TIME 9 7683
TEST_TIME 10 7758
TEST_TIME 11 7720
TEST_TIME 12 7742
TEST_TIME 13 7799
TEST_TIME 14 7964
TEST_TIME 15 7954
TEST_TIME 16 7914
TEST_TIME 17 8172
TEST_TIME 18 8233
TEST_TIME 19 8256
TEST_TIME 20 8594
TEST_TIME 21 8915
TEST_TIME 22 8902
TEST_TIME 23 9115
TEST_TIME 24 9141
TEST_TIME 25 9029
TEST_TIME 26 9343
TEST_TIME 27 9631
TEST_TIME 28 10179
TEST_TIME 29 10672
TEST_TIME 30 11357
TEST_TIME 31 11473
TEST_TIME 32 11180
TEST_TIME 33 11075
TEST_TIME 34 10820
TEST_TIME 35 10642
TEST_TIME 36 10507
TEST_TIME 37 10404
TEST_TIME 38 10245
TEST_TIME 39 10244
TEST_TIME 40 10270
TEST_TIME 41 10354
TEST_TIME 42 10244
TEST_TIME 43 10144
TEST_TIME 44 9956
TEST_TIME 45 10050
TEST_TIME 46 10106
TEST_TIME 47 10118
TEST_TIME 48 10092
TEST_TIME 49 10037
TEST_TIME 50 9950
TEST_TIME 51 9899
TEST_TIME 52 9800
TEST_TIME 53 9967
TEST_TIME 54 9908
TEST_TIME 55 9880
TEST_TIME 56 9867
TEST_TIME 57 9817
TEST_TIME 58 9791
TEST_TIME 59 9727
TEST_TIME 60 9674
TEST_TIME 61 9594
TEST_TIME 62 9519
TEST_TIME 63 9539
TEST_TIME 64 9489
TEST_TIME 65 9417
TEST_TIME 66 9359
TEST_TIME 67 9287
TEST_TIME 68 9235
TEST_TIME 69 9317
TEST_TIME 70 9347
TEST_TIME 71 9312
TEST_TIME 72 9289
TEST_TIME 73 9328
TEST_TIME 74 9324
TEST_TIME 75 9294
TEST_TIME 76 9268
TEST_TIME 77 9245
TEST_TIME 78 9338
TEST_TIME 79 9411
TEST_TIME 80 9446
TEST_TIME 81 9517
TEST_TIME 82 9439
TEST_TIME 83 9358
TEST_TIME 84 9342
TEST_TIME 85 9301
TEST_TIME 86 9270
TEST_TIME 87 9246
TEST_TIME 88 9177
TEST_TIME 89 9117
TEST_TIME 90 9086
TEST_TIME 91 9040
TEST_TIME 92 9049
TEST_TIME 93 9105
TEST_TIME 94 9076
TEST_TIME 95 9045
TEST_TIME 96 9004
TEST_TIME 97 9012
TEST_TIME 98 9070
TEST_TIME 99 9055
TEST_TIME 100 9115
TEST_TIME 101 9145
TEST_TIME 102 9148
TEST_TIME 103 9209
TEST_TIME 104 9141
TEST_TIME 105 9085
TEST_TIME 106 9077
TEST_TIME 107 9031
TEST_TIME 108 8979
TEST_TIME 109 8964
TEST_TIME 110 8923
TEST_TIME 111 8915
TEST_TIME 112 8873
TEST_TIME 113 8872
TEST_TIME 114 8871
TEST_TIME 115 8855
TEST_TIME 116 8817
TEST_TIME 117 8793
TEST_TIME 118 8765
TEST_TIME 119 8798
TEST_TIME 120 8762

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-04-2022 04:38:52 to 03-04-2022 04:38:55.

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 1482633 1482633 0 0.00 0 18955 14 0 0
doc_list_biud 1482621 1482621 0 0.00 0 35898 38 0 0
sp_add_invoice_to_stock 5245 5099 146 2.78 65 213723 26763 0 146
sp_cancel_adding_invoice 5046 5046 0 0.00 7 81913 1297 0 0
sp_cancel_client_order 1810 1810 0 0.00 6 20624 412 0 0
sp_cancel_customer_reserve 488110 488110 0 0.00 1 35913 100 0 0
sp_cancel_pay_from_customer 725 725 0 0.00 4 6162 121 0 0
sp_cancel_pay_to_supplier 455 455 0 0.00 4 4354 128 0 0
sp_cancel_supplier_invoice 5664 5649 15 0.26 3 33676 970 0 15
sp_cancel_supplier_order 397 397 0 0.00 10 1336037 152767 0 0
sp_cancel_write_off 9203 9203 0 0.00 2 9496 161 0 0
sp_client_order 21024 21024 0 0.00 6 29052 430 0 0
sp_customer_reserve 473766 473766 0 0.00 1 94069 258 0 0
sp_fill_shopping_cart 32488 32488 0 0.00 2 11228 77 0 0
sp_fill_shopping_cart_clo_res 473728 473728 0 0.00 0 16388 22 0 0
sp_get_clo_for_invoice 5051 5051 0 0.00 0 17846 268 0 0
sp_kill_cost_storno 15429 15429 0 0.00 0 9152 67 0 0
sp_kill_qstorno_ret_qs2qd 991469 991469 0 0.00 0 35886 44 0 0
sp_kill_qty_storno 525495 525495 0 0.00 0 35896 99 0 0
sp_lock_selected_doc 21252 21252 0 0.00 0 6101 7 0 0
sp_make_cost_storno 28571 28571 0 0.00 0 15157 70 0 0
sp_multiply_rows_for_pdistr 28571 28571 0 0.00 0 18949 32 0 0
sp_multiply_rows_for_qdistr 21024 21024 0 0.00 0 28990 180 0 0
sp_pay_from_customer 7278 7278 0 0.00 5 7170 151 0 0
sp_pay_to_supplier 6013 6013 0 0.00 6 18961 174 0 0
sp_payment_common 13291 13291 0 0.00 2 6106 38 0 0
sp_qd_handle_on_cancel_clo 1810 1810 0 0.00 1 20596 341 0 0
sp_qd_handle_on_invoice_upd_sts 10097 10097 0 0.00 0 27681 474 0 0
sp_qd_handle_on_reserve_upd_sts 10229 10229 0 0.00 0 21444 51 0 0
sp_reserve_write_off 10229 10229 0 0.00 8 21568 237 0 0
sp_supplier_invoice 5706 5706 0 0.00 10 118601 2951 0 0
sp_supplier_order 5768 5768 0 0.00 7 47684 1238 0 0
srv_aggregate_perf_data 118 118 0 0.00 2535 171230 16300 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 810 181722 37131 0 0
srv_find_qd_qs_mism 996849 996849 0 0.00 0 14876 12 0 0
srv_make_invnt_saldo 530 530 0 0.00 54 87017 3398 0 0
srv_make_money_saldo 949 949 0 0.00 1 9862 284 0 0
srv_recalc_idx_stat 4 4 0 0.00 202047 616116 369863 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5103 5103 0 0.00 1 15345 862 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 1 9257 445 0 0
t$perf-norm:sp_cancel_client_order 1810 1810 0 0.00 0 2907 24 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 1119 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 725 725 0 0.00 0 2107 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 455 455 0 0.00 0 405 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 1310 33 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 79848 5921 0 0
t$perf-norm:sp_cancel_write_off 1053 1053 0 0.00 0 2714 22 0 0
t$perf-norm:sp_client_order 21024 21024 0 0.00 0 9303 31 0 0
t$perf-norm:sp_customer_reserve 1053 1053 0 0.00 0 1240 13 0 0
t$perf-norm:sp_pay_from_customer 7278 7278 0 0.00 0 4511 16 0 0
t$perf-norm:sp_pay_to_supplier 6013 6013 0 0.00 0 3309 17 0 0
t$perf-norm:sp_reserve_write_off 10229 10229 0 0.00 0 3437 20 0 0
t$perf-norm:sp_supplier_invoice 5706 5706 0 0.00 0 6079 27 0 0
t$perf-norm:sp_supplier_order 5769 5769 0 0.00 0 2633 28 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 268 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 822 23 0 0
t$perf-norm:srv_make_invnt_saldo 530 530 0 0.00 0 2835 18 0 0
t$perf-norm:srv_make_money_saldo 949 949 0 0.00 0 189 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 234 76 0 0
v_add_invoice_to_stock 10394 10102 292 2.80 0 7079 26 0 292
v_all_customers 38206 38206 0 0.00 0 148 3 0 0
v_all_suppliers 31816 31816 0 0.00 0 134 4 0 0
v_all_wares 42044 42044 0 0.00 0 75 3 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 117 12 0 0
v_cancel_client_order 3620 3620 0 0.00 0 9396 15 0 0
v_cancel_customer_prepayment 1450 1450 0 0.00 0 1763 10 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 237 9 0 0
v_cancel_payment_to_supplier 910 910 0 0.00 0 413 10 0 0
v_cancel_supplier_invoice 824 794 30 3.64 0 464 22 0 30
v_cancel_supplier_order 794 794 0 0.00 0 1203 12 0 0
v_cancel_write_off 2106 2106 0 0.00 0 9413 27 0 0
v_max_id_clo_ord 5768 5768 0 0.00 0 511 5 0 0
v_max_id_clo_res 1053 1053 0 0.00 0 12723 27 0 0
v_max_id_ord_sup 5697 5697 0 0.00 0 7275 7 0 0
v_max_non_paid_invoice 1569 1569 0 0.00 0 3891 10 0 0
v_max_non_paid_realizn 3820 3820 0 0.00 0 3388 9 0 0
v_min_id_clo_ord 5768 5768 0 0.00 0 1351 8 0 0
v_min_id_clo_res 1053 1053 0 0.00 0 2152 8 0 0
v_min_id_ord_sup 5697 5697 0 0.00 0 4841 10 0 0
v_min_non_paid_invoice 6013 6013 0 0.00 0 2961 11 0 0
v_min_non_paid_realizn 7278 7278 0 0.00 0 5243 14 0 0
v_our_firm 10754 10754 0 0.00 0 52 4 0 0
v_reserve_write_off 20458 20458 0 0.00 0 12486 10 0 0
x_lock_depdocs_on_canc_invoice 10298 10298 0 0.00 0 19854 126 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 14123 352 0 0
x_make_qstorno_1000_1200 5768 5768 0 0.00 2 47670 1081 0 0
x_make_qstorno_1200_2000 5706 5706 0 0.00 4 118150 2744 0 0
x_make_qstorno_2100_3300 473765 473765 0 0.00 0 94067 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-04-2022 04:38:56 to 03-04-2022 04:39:00.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:18 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:19 32321 8192000000 9718516944 10020036608 800043088 8.897 100 86 172 1 828554688 7604160 20907440
02:09:58 18018 8192000000 9768328752 10076852224 839125760 9.291 100 87 174 0 872399824 12518048 20756016
02:11:20 18263 8192000000 9804156800 10121416704 868949872 9.590 100 81 160 1 907210368 19639904 18620592
02:12:41 14798 8192000000 9867639504 10205601792 918209792 10.078 100 77 154 0 980606048 45014688 17381568
02:13:59 3918 8192000000 9930631328 10276474880 970795216 10.594 100 85 170 0 1045376112 56664208 17916688
02:15:05 3269 8192000000 9965414016 10322173952 1000977488 10.888 100 77 146 1 1082663072 67060080 14625504
02:16:10 1023 8192000000 10005633824 10371436544 1030595712 11.174 100 93 177 0 1121538880 73552816 17390352
02:17:14 893 8192000000 10013703648 10383749120 1053484384 11.394 100 89 176 2 1128806352 58003952 17318016
02:18:17 1221 8192000000 10039247056 10417045504 1058255568 11.440 100 95 185 3 1162251088 85353472 18642048
02:19:20 9052 8192000000 10024365136 10408513536 1066130608 11.515 100 80 153 0 1158567584 77179328 15257648
02:20:30 1192 8192000000 10031347856 10413486080 1069105856 11.544 100 88 172 0 1152073376 66122944 16844576
02:21:32 1016 8192000000 10042982272 10427297792 1081716528 11.664 100 92 178 0 1163204288 62099104 19388656
02:22:34 1159 8192000000 10046524880 10439540736 1085905088 11.704 100 87 171 1 1173146384 69169056 18072240
02:23:36 5503 8192000000 10025818128 10415656960 1085493984 11.700 100 74 137 2 1147153584 48788592 12871008
02:24:42 1172 8192000000 10072264016 10461597696 1091437376 11.756 100 94 172 1 1190448032 81058944 17951712
02:25:44 1236 8192000000 10067196016 10455080960 1090007728 11.743 100 91 173 0 1189104768 81425776 17671264
02:26:46 1118 8192000000 10049588080 10439823360 1093840816 11.779 100 96 176 2 1174662128 62673200 18148112
02:27:48 3132 8192000000 10065432464 10455834624 1094844064 11.789 100 75 145 1 1186007232 76051664 15111504
02:28:52 1111 8192000000 10039880816 10430337024 1085755328 11.702 100 88 166 0 1160123504 57654160 16714016
02:29:54 2246 8192000000 10037302032 10424446976 1088336496 11.727 100 78 153 2 1157521600 53033216 16151888
02:30:57 2421 8192000000 10025563344 10406506496 1050855888 11.369 98 82 154 4 1141229024 74724224 15648912
02:32:00 1023 8192000000 10047932528 10431049728 1073055424 11.581 100 89 177 0 1174680320 84183632 17441264
02:33:01 1007 8192000000 10014537104 10390351872 1057120944 11.429 100 94 176 0 1138056112 61707216 19227952
02:34:03 5182 8192000000 9989082688 10376204288 1046178672 11.324 100 65 122 0 1115695376 57107712 12408992
02:35:13 1552 8192000000 9999172992 10383081472 1050531024 11.366 100 76 150 0 1119688896 54040608 15117264
02:36:19 865 8192000000 9948007184 10318585856 1023842080 11.109 99 78 145 4 1070558272 32587344 14128848
02:37:21 11845 8192000000 9990692320 10377711616 1045123696 11.314 100 62 110 1 1120209296 63303952 11781648
02:39:00 3211 8192000000 10002366528 10382032896 1058640768 11.443 100 67 125 1 1118691824 47158448 12892608
02:40:10 11272 8192000000 10078021360 10463830016 1060739472 11.464 99 71 138 0 1179665280 104910064 14015744
02:41:30 17648 8192000000 10109924112 10492747776 1083361952 11.679 100 74 148 0 1221538336 123143504 15032880
02:42:54 12128 8192000000 10116352064 10505252864 1096867056 11.808 100 83 152 1 1231537536 117831232 16839248
02:44:11 1561 8192000000 10134372816 10543636480 1108730688 11.920 100 89 161 2 1255585888 127415760 19439440
02:45:17 983 8192000000 10181088880 10569687040 1129658480 12.118 100 89 164 5 1295282144 148292608 17331056
02:46:20 1299 8192000000 10221739472 10623959040 1151237152 12.321 100 96 183 1 1336971248 166809728 18924368
02:47:26 4019 8192000000 10228610160 10626408448 1164490752 12.445 100 83 154 0 1340254896 159797008 15967136
02:48:33 2368 8192000000 10252888272 10648121344 1168840512 12.486 100 87 164 2 1358434736 171647424 17946800
02:49:40 1143 8192000000 10323253424 10723766272 1180646112 12.596 100 87 171 0 1430486752 230160864 19679776
02:50:43 1005 8192000000 10307319504 10724700160 1183829536 12.626 100 95 188 2 1419967776 214417312 21720928
02:51:46 957 8192000000 10346607984 10759606272 1179062896 12.581 99 95 189 1 1456099568 254030944 23005728
02:52:51 904 8192000000 10380573136 10779213824 1184418944 12.631 100 94 182 0 1483890256 278186688 21284624
02:53:53 804 8192000000 10436318752 10851115008 1191993552 12.702 100 90 174 2 1540883728 328877488 20012688
02:54:57 993 8192000000 10444966336 10871463936 1195923728 12.738 100 97 186 0 1551788448 333454048 22410672
02:56:00 1018 8192000000 10472672816 10892992512 1188057744 12.665 100 97 191 4 1578798992 369254944 21486304
02:57:03 965 8192000000 10483550608 10918248448 1188917488 12.673 99 93 181 0 1589626304 377736256 22972560
02:58:06 2207 8192000000 10468650544 10917097472 1195501312 12.735 100 80 160 0 1573268176 358838352 18928512
02:59:09 9073 8192000000 10483761744 10917601280 1197417328 12.752 100 55 110 0 1595659952 383346272 14896352
03:00:22 3375 8192000000 10487322672 10932944896 1193329376 12.714 100 56 110 1 1599710048 392048976 14331696
03:01:29 7861 8192000000 10512948912 10974830592 1189829648 12.682 100 58 116 0 1625769856 421093856 14846352
03:02:52 2436 8192000000 10513237040 10975645696 1188908880 12.673 100 59 112 2 1617954096 415926304 13118912
03:04:00 14604 8192000000 10445332304 10942689280 1157835968 12.383 99 49 96 0 1538137728 367667760 12634000
03:05:19 28156 8192000000 10343479376 10817196032 1158275280 12.387 100 24 48 0 1452873456 288151408 6446768
03:06:49 35985 8192000000 10188719568 10696736768 1142047648 12.235 100 18 37 0 1313247440 166605536 4594256
03:08:49 24565 8192000000 10046358640 10462310400 1093937920 11.780 97 54 82 4 1127516208 24721920 8856368
03:10:15 181721 8192000000 10082804992 10541666304 1105536992 11.890 98 29 58 1 1179552592 66875312 7140288
03:14:19 22687 8192000000 10143366848 10582700032 1131418096 12.135 99 43 86 0 1226140032 85390368 9331568
03:15:42 55181 8192000000 10191586128 10657849344 1150194000 12.311 100 26 53 2 1287884928 132130480 5560448
03:17:39 114312 8192000000 10072438272 10527309824 1122764816 12.053 98 18 36 0 1183689088 56910064 4014208
03:20:36 48936 8192000000 10111208448 10572320768 1139480576 12.211 99 13 26 0 1205947984 63513520 2953888
03:22:35 78151 8192000000 10088257792 10595696640 1140311568 12.218 100 9 18 0 1224311808 82098816 1901424
03:25:07 68372 8192000000 10102506368 10558308352 1118145488 12.009 97 23 46 0 1169427920 46393840 4888592
03:27:15 20596 8192000000 10155284240 10593935360 1145885008 12.271 100 57 113 3 1242743184 85268384 11589792
03:29:49 101925 8192000000 10069019264 10515210240 1086996944 11.714 95 15 30 1 1136264512 46258208 3009360
03:32:39 21845 8192000000 10092918528 10528595968 1121942832 12.045 99 40 80 0 1163159440 32855104 8361504
03:34:01 38604 8192000000 10118408288 10548277248 1122604896 12.052 99 41 82 1 1198781760 68390688 7786176
03:35:41 125805 8192000000 10061503568 10497048576 1088697184 11.730 96 33 66 2 1143656240 48082592 6876464
03:38:48 26473 8192000000 10099560576 10525749248 1124999280 12.074 99 55 108 2 1189325760 53422288 10904192
03:40:16 51371 8192000000 10050833600 10525822976 1112384272 11.955 99 28 55 1 1172206432 54493008 5329152
03:42:07 58118 8192000000 10060548976 10508324864 1116028960 11.989 99 14 28 1 1147864000 28849904 2985136
03:44:06 73199 8192000000 10119381824 10599989248 1133506736 12.154 100 11 22 0 1206732288 70839552 2386000
03:46:33 56534 8192000000 10079251136 10532663296 1102069024 11.857 97 17 35 0 1209272240 103457744 3745472
03:48:30 28836 8192000000 10090600928 10515746816 1133071664 12.150 99 34 65 4 1184735584 44715264 6948656
03:50:02 113172 8192000000 10071914848 10507255808 1030726400 11.175 90 20 40 1 1070719168 35495728 4497040
03:52:57 34146 8192000000 10148900224 10583605248 1108189728 11.915 98 47 94 1 1227540288 109730080 9620480
03:54:44 53881 8192000000 10157948032 10632294400 1134472816 12.164 100 31 62 0 1308434560 167257488 6704256
03:57:09 65190 8192000000 10080447824 10545455104 1110815424 11.940 98 29 58 0 1169744208 52764320 6164464
03:59:16 30179 8192000000 10065982528 10484019200 1125993024 12.084 100 55 108 0 1163974240 27276320 10704896
04:02:13 32432 8192000000 10063452144 10521223168 1132154864 12.142 100 24 45 1 1162724080 25577280 4991936
04:03:47 43377 8192000000 10039581504 10504810496 1109638960 11.929 99 11 20 1 1154651632 42845728 2166944
04:05:33 32997 8192000000 10060907776 10497282048 1110500192 11.937 99 22 44 2 1143796912 28361616 4935104
04:07:08 59986 8192000000 10068646368 10526261248 1105007968 11.885 98 9 18 0 1153394752 46351344 2035440
04:09:08 53575 8192000000 10092061344 10529288192 1116979696 11.998 99 31 58 1 1178856272 55181440 6695136
04:11:02 43004 8192000000 10067239104 10499788800 1128392944 12.106 100 24 48 0 1152892688 19639984 4859760
04:12:47 23182 8192000000 10114406912 10550521856 1129756096 12.119 100 44 88 3 1208190000 68729600 9704304
04:14:19 14047 8192000000 10170737472 10606333952 1123145392 12.057 99 34 68 0 1252329408 121752112 7431904
04:15:36 49566 8192000000 10119593216 10580307968 1134292416 12.162 100 20 40 1 1246410720 107956416 4161888
04:17:26 89258 8192000000 10093675808 10522284032 1050006064 11.361 93 28 56 0 1102950272 47504624 5439584
04:20:05 60568 8192000000 10072024448 10531266560 1101877584 11.855 98 24 48 0 1155432160 48798848 4755728
04:22:07 52033 8192000000 10075993264 10510839808 1120465568 12.031 100 44 81 1 1181380752 52095584 8819600
04:24:08 80864 8192000000 10039490192 10498899968 1107793840 11.912 98 8 16 1 1144302480 34936112 1572528
04:26:29 93620 8192000000 10078732032 10566184960 1122974768 12.055 99 9 18 0 1175065072 49985360 2104944
04:29:08 53617 8192000000 10055195888 10496962560 1083176464 11.678 95 23 45 1 1132367120 44128416 5062240
04:31:27 38408 8192000000 10100928256 10540740608 1118151392 12.010 98 16 32 1 1174232032 52497296 3583344
04:33:12 27876 8192000000 10159910128 10592747520 1152240768 12.331 100 45 90 0 1255528304 93538880 9748656
04:34:41 24997 8192000000 10193730448 10620174336 1115705984 11.986 96 47 94 0 1272180784 146182976 10291824
04:36:11 16680 8192000000 10245909440 10691137536 1136886432 12.186 100 44 86 1 1325105264 178673456 9545376

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-04-2022 04:39:02 to 03-04-2022 04:39:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 146 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-04-2022 04:39:19 to 03-04-2022 04:39:21.

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          216455
MON$OLDEST_ACTIVE               228586
MON$OLDEST_SNAPSHOT             228586
MON$NEXT_TRANSACTION            228586
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                       2033248
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             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 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 ---
:385: 
:386: mscs-test-161.budzdorov.ru    Sun Apr  3 04:37:16 2022
:387:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:388: 
:389: 
:390: mscs-test-161.budzdorov.ru    Sun Apr  3 04:37:17 2022
:391:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:392: 
:393: 
:394: mscs-test-161.budzdorov.ru    Sun Apr  3 04:37:26 2022
:395:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:396: 
:397: 
:398: mscs-test-161.budzdorov.ru    Sun Apr  3 04:37:29 2022
:399:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:400: 
:401: 
:402: mscs-test-161.budzdorov.ru    Sun Apr  3 04:37:30 2022
:403:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:404: 
:405: 
:406: mscs-test-161.budzdorov.ru    Sun Apr  3 04:37:30 2022
:407:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:408: 
--- end of diff output ---

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

03.04.22 04:39:22. 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-03 04:39:24 No severe PSQL-related problems occured
03.04.22 04:39:26. End of report.