Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.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 16556656 kB
MemAvailable 31578368 kB
Buffers 0 kB
Cached 15186796 kB
SwapCached 2660 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-22 13:37:11
DTS_END                         2022-04-22 13:37:11.0720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46022
TRN_ID                          215499
ATT_ID                          568
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: 8937.11 24927 1072453
customer order: creation 165.09 513 19811
customer order: refuse 14.02 362 1682
order to supplier: creation 43.89 1317 5267
order to supplier: removal 3.45 177994 414
invoice (draft): creation 44.54 3107 5345
invoice (draft): removal 49.34 968 5921
invoice accept: apply 40.31 26842 4837
invoice accept: cancel 44.85 1293 5382
customer reserve: creation 3806.43 253 456771
customer reserve: removal 4441.29 111 532955
realization accept: apply 79.41 255 9529
realization accept: cancel 79.31 161 9517
payment from customer: creation 56.55 171 6786
payment from customer: removal 5.50 124 660
payment to supplier: creation 47.05 182 5646
payment to supplier: removal 3.52 130 422
service: total inventory turnovers 3.88 3717 466
service: total monetary turnovers 7.76 330 931
service: aggregate perf. data 0.89 15775 107
service: refresh index statistics 0.03 264943 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-04-2022 13:39:08 to 22-04-2022 13:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4371
TEST_TIME 2 5307
TEST_TIME 3 5483
TEST_TIME 4 5869
TEST_TIME 5 5826
TEST_TIME 6 5664
TEST_TIME 7 5839
TEST_TIME 8 5965
TEST_TIME 9 6165
TEST_TIME 10 6104
TEST_TIME 11 6380
TEST_TIME 12 6481
TEST_TIME 13 6658
TEST_TIME 14 7074
TEST_TIME 15 7450
TEST_TIME 16 7560
TEST_TIME 17 7572
TEST_TIME 18 7663
TEST_TIME 19 7845
TEST_TIME 20 8166
TEST_TIME 21 8121
TEST_TIME 22 8279
TEST_TIME 23 8873
TEST_TIME 24 8971
TEST_TIME 25 9316
TEST_TIME 26 9953
TEST_TIME 27 10408
TEST_TIME 28 11256
TEST_TIME 29 11549
TEST_TIME 30 11401
TEST_TIME 31 11294
TEST_TIME 32 10999
TEST_TIME 33 10704
TEST_TIME 34 10532
TEST_TIME 35 10531
TEST_TIME 36 10529
TEST_TIME 37 10446
TEST_TIME 38 10318
TEST_TIME 39 10223
TEST_TIME 40 10119
TEST_TIME 41 10089
TEST_TIME 42 9974
TEST_TIME 43 9862
TEST_TIME 44 9794
TEST_TIME 45 9747
TEST_TIME 46 9610
TEST_TIME 47 9526
TEST_TIME 48 9476
TEST_TIME 49 9365
TEST_TIME 50 9240
TEST_TIME 51 9339
TEST_TIME 52 9378
TEST_TIME 53 9351
TEST_TIME 54 9303
TEST_TIME 55 9281
TEST_TIME 56 9249
TEST_TIME 57 9280
TEST_TIME 58 9376
TEST_TIME 59 9372
TEST_TIME 60 9424
TEST_TIME 61 9402
TEST_TIME 62 9444
TEST_TIME 63 9423
TEST_TIME 64 9457
TEST_TIME 65 9407
TEST_TIME 66 9317
TEST_TIME 67 9255
TEST_TIME 68 9197
TEST_TIME 69 9167
TEST_TIME 70 9115
TEST_TIME 71 9087
TEST_TIME 72 9045
TEST_TIME 73 9038
TEST_TIME 74 8997
TEST_TIME 75 8984
TEST_TIME 76 8958
TEST_TIME 77 8998
TEST_TIME 78 9030
TEST_TIME 79 9028
TEST_TIME 80 9129
TEST_TIME 81 9226
TEST_TIME 82 9180
TEST_TIME 83 9172
TEST_TIME 84 9257
TEST_TIME 85 9290
TEST_TIME 86 9204
TEST_TIME 87 9168
TEST_TIME 88 9132
TEST_TIME 89 9116
TEST_TIME 90 9073
TEST_TIME 91 9023
TEST_TIME 92 8976
TEST_TIME 93 8941
TEST_TIME 94 8904
TEST_TIME 95 8881
TEST_TIME 96 8877
TEST_TIME 97 8858
TEST_TIME 98 8848
TEST_TIME 99 8831
TEST_TIME 100 8823
TEST_TIME 101 8822
TEST_TIME 102 8794
TEST_TIME 103 8802
TEST_TIME 104 8847
TEST_TIME 105 8985
TEST_TIME 106 8945
TEST_TIME 107 9096
TEST_TIME 108 9155
TEST_TIME 109 9157
TEST_TIME 110 9145
TEST_TIME 111 9140
TEST_TIME 112 9104
TEST_TIME 113 9078
TEST_TIME 114 9107
TEST_TIME 115 9172
TEST_TIME 116 9159
TEST_TIME 117 9096
TEST_TIME 118 9069
TEST_TIME 119 9030
TEST_TIME 120 8955

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-04-2022 13:39:12 to 22-04-2022 13:39:15.

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 1492195 1492195 0 0.00 0 21190 14 0 0
doc_list_biud 1492168 1492168 0 0.00 0 40280 45 0 0
sp_add_invoice_to_stock 4931 4837 94 1.90 42 209699 26842 0 94
sp_cancel_adding_invoice 5382 5382 0 0.00 7 82175 1294 0 0
sp_cancel_client_order 1682 1682 0 0.00 5 14195 362 0 0
sp_cancel_customer_reserve 532955 532955 0 0.00 1 40283 112 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 3 4015 124 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 4 4708 130 0 0
sp_cancel_supplier_invoice 5935 5921 14 0.23 3 32341 969 0 14
sp_cancel_supplier_order 414 414 0 0.00 9 1498932 177995 0 0
sp_cancel_write_off 9517 9517 0 0.00 2 13494 162 0 0
sp_client_order 19811 19811 0 0.00 6 43954 514 0 0
sp_customer_reserve 456771 456771 0 0.00 1 110020 253 0 0
sp_fill_shopping_cart 30413 30413 0 0.00 2 15177 79 0 0
sp_fill_shopping_cart_clo_res 456746 456746 0 0.00 0 18858 21 0 0
sp_get_clo_for_invoice 4799 4799 0 0.00 0 21720 326 0 0
sp_kill_cost_storno 15981 15981 0 0.00 0 14475 70 0 0
sp_kill_qstorno_ret_qs2qd 1081762 1081762 0 0.00 0 40026 48 0 0
sp_kill_qty_storno 570198 570198 0 0.00 0 40279 109 0 0
sp_lock_selected_doc 19906 19906 0 0.00 0 7212 7 0 0
sp_make_cost_storno 26760 26760 0 0.00 0 19765 79 0 0
sp_multiply_rows_for_pdistr 26759 26759 0 0.00 0 16753 38 0 0
sp_multiply_rows_for_qdistr 19811 19811 0 0.00 0 12442 216 0 0
sp_pay_from_customer 6786 6786 0 0.00 6 18895 171 0 0
sp_pay_to_supplier 5646 5646 0 0.00 6 16782 183 0 0
sp_payment_common 12430 12430 0 0.00 2 18838 43 0 0
sp_qd_handle_on_cancel_clo 1682 1682 0 0.00 0 14183 305 0 0
sp_qd_handle_on_invoice_upd_sts 10180 10180 0 0.00 0 18775 520 0 0
sp_qd_handle_on_reserve_upd_sts 9529 9529 0 0.00 0 10284 54 0 0
sp_reserve_write_off 9529 9529 0 0.00 9 17686 256 0 0
sp_supplier_invoice 5345 5345 0 0.00 11 92171 3108 0 0
sp_supplier_order 5267 5267 0 0.00 8 66423 1318 0 0
srv_aggregate_perf_data 107 107 0 0.00 2501 161950 15776 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 952 142300 36918 0 0
srv_find_qd_qs_mism 1024444 1024444 0 0.00 0 21189 11 0 0
srv_make_invnt_saldo 466 466 0 0.00 69 97665 3718 0 0
srv_make_money_saldo 931 931 0 0.00 1 13135 330 0 0
srv_recalc_idx_stat 4 4 0 0.00 138078 398769 264944 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4837 4837 0 0.00 1 12175 858 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 2 6614 462 0 0
t$perf-norm:sp_cancel_client_order 1682 1682 0 0.00 0 7718 30 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 1916 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 603 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 973 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 360 360 0 0.00 0 830 26 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 92965 6989 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 2210 16 0 0
t$perf-norm:sp_client_order 19811 19811 0 0.00 0 3683 31 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 2317 14 0 0
t$perf-norm:sp_pay_from_customer 6786 6786 0 0.00 0 5462 17 0 0
t$perf-norm:sp_pay_to_supplier 5646 5646 0 0.00 0 13909 23 0 0
t$perf-norm:sp_reserve_write_off 9529 9529 0 0.00 0 16051 21 0 0
t$perf-norm:sp_supplier_invoice 5345 5345 0 0.00 0 3155 28 0 0
t$perf-norm:sp_supplier_order 5267 5267 0 0.00 0 3024 27 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 344 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 98 4 0 0
t$perf-norm:srv_make_invnt_saldo 466 466 0 0.00 0 1765 18 0 0
t$perf-norm:srv_make_money_saldo 931 931 0 0.00 0 403 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 258 85 0 0
v_add_invoice_to_stock 9782 9594 188 1.92 0 5415 24 0 188
v_all_customers 36430 36430 0 0.00 0 143 3 0 0
v_all_suppliers 29662 29662 0 0.00 0 98 4 0 0
v_all_wares 39612 39612 0 0.00 0 196 4 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 617 13 0 0
v_cancel_client_order 3364 3364 0 0.00 0 894 8 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 1914 12 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 569 9 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 2731 14 0 0
v_cancel_supplier_invoice 748 720 28 3.74 0 938 25 0 28
v_cancel_supplier_order 828 828 0 0.00 0 844 11 0 0
v_cancel_write_off 2032 2032 0 0.00 0 1210 21 0 0
v_max_id_clo_ord 5265 5265 0 0.00 0 378 4 0 0
v_max_id_clo_res 971 971 0 0.00 0 1298 12 0 0
v_max_id_ord_sup 5342 5342 0 0.00 0 5478 6 0 0
v_max_non_paid_invoice 1420 1420 0 0.00 0 1107 8 0 0
v_max_non_paid_realizn 3510 3510 0 0.00 0 3153 8 0 0
v_min_id_clo_ord 5265 5265 0 0.00 0 3342 9 0 0
v_min_id_clo_res 971 971 0 0.00 0 5139 17 0 0
v_min_id_ord_sup 5342 5342 0 0.00 0 5903 15 0 0
v_min_non_paid_invoice 5644 5644 0 0.00 0 7093 11 0 0
v_min_non_paid_realizn 6786 6786 0 0.00 0 18774 21 0 0
v_our_firm 9734 9734 0 0.00 0 61 4 0 0
v_reserve_write_off 19058 19058 0 0.00 0 2705 9 0 0
x_lock_depdocs_on_canc_invoice 10943 10943 0 0.00 0 14228 120 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 2722 288 0 0
x_make_qstorno_1000_1200 5267 5267 0 0.00 3 66411 1161 0 0
x_make_qstorno_1200_2000 5345 5345 0 0.00 6 92155 2893 0 0
x_make_qstorno_2100_3300 456771 456771 0 0.00 0 110012 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-04-2022 13:39:16 to 22-04-2022 13:39:20.

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:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:15 40654 8192000000 9738566000 10039545856 811579488 9.013 100 88 176 0 840809952 8272928 20957536
11:10:10 9612 8192000000 9771911168 10080239616 844487584 9.345 100 94 188 0 881189744 14356416 22345744
11:11:31 13258 8192000000 9819959296 10144100352 875292816 9.653 100 83 166 0 929451440 35520064 18638560
11:12:56 9579 8192000000 9881386512 10221043712 930308256 10.198 100 78 155 0 987788304 41170640 16309408
11:14:09 4079 8192000000 9939657488 10287235072 972024800 10.606 100 94 188 0 1052508256 60992384 19491072
11:15:17 1970 8192000000 9975380016 10328760320 997387472 10.853 100 98 194 1 1093930480 77094848 19448160
11:16:21 1128 8192000000 10012193232 10378145792 1021097296 11.083 100 95 180 0 1128281232 88810800 18373136
11:17:25 1000 8192000000 10007464592 10382413824 1049238048 11.353 100 95 176 1 1130951920 63171584 18542288
11:18:27 1068 8192000000 10040209984 10413285376 1054108288 11.400 100 98 184 0 1156165408 83304544 18752576
11:19:29 790 8192000000 10035593952 10415550464 1063177136 11.487 100 95 185 0 1156280576 74935696 18167744
11:20:32 764 8192000000 10050018688 10430509056 1068608128 11.539 100 94 183 0 1168232400 81044016 18580256
11:21:34 9644 8192000000 10012372816 10396774400 1077345600 11.622 100 88 170 3 1139413728 44885984 17182144
11:22:44 9039 8192000000 10033869392 10434072576 1081945152 11.666 100 62 119 1 1153245920 59255440 12045328
11:23:55 875 8192000000 10031652688 10425241600 1085197040 11.697 100 92 180 2 1155489024 51655040 18636944
11:24:56 1083 8192000000 10050730400 10440355840 1095502176 11.795 100 92 175 3 1174760336 61272528 17985632
11:25:59 1357 8192000000 10045344320 10433396736 1084035216 11.686 99 95 179 0 1167127744 64482880 18609648
11:27:01 1223 8192000000 10071812128 10462330880 1092357872 11.765 100 92 178 0 1190222560 79379776 18484912
11:28:03 1165 8192000000 10057655872 10449874944 1099355600 11.832 100 97 185 3 1181392336 62717280 19319456
11:29:06 1461 8192000000 10047136960 10432888832 1085976640 11.704 100 95 187 1 1175321760 69416960 19928160
11:30:08 1009 8192000000 10032459296 10419085312 1084116272 11.687 100 94 178 0 1157350416 54598448 18635696
11:31:10 1148 8192000000 10020881616 10402725888 1057149104 11.429 100 86 168 2 1140512816 67117952 16245760
11:32:12 1001 8192000000 10003105296 10380886016 1058484784 11.442 99 86 159 2 1125587280 50388256 16714240
11:33:14 4438 8192000000 9986230672 10367819776 1033574736 11.203 99 59 117 0 1113577760 67315280 12687744
11:34:19 1556 8192000000 9998107792 10383110144 1041450912 11.279 100 80 158 2 1115827344 58089744 16286688
11:35:22 872 8192000000 10007183104 10382143488 1039339824 11.258 100 93 177 0 1130054384 71514592 19199968
11:36:24 5797 8192000000 9979573616 10355367936 1035861168 11.225 100 79 148 0 1109078576 57669360 15548048
11:37:35 15185 8192000000 9989504288 10368516096 1051332864 11.373 100 64 113 3 1110486128 48336896 10816368
11:39:03 4405 8192000000 10021374912 10406715392 1078535184 11.634 100 72 138 2 1145941936 53048256 14358496
11:40:12 16291 8192000000 10066447760 10446913536 1088231936 11.726 99 86 158 3 1186213888 79250608 18731344
11:41:32 1075 8192000000 10125990304 10509582336 1115262944 11.982 100 86 165 2 1245401968 113703936 16435088
11:42:39 10721 8192000000 10148590208 10545401856 1129627632 12.118 100 73 146 0 1263006000 118425360 14953008
11:43:53 1181 8192000000 10206169712 10594443264 1140880448 12.224 100 92 171 3 1321851600 162228640 18742512
11:44:59 2733 8192000000 10230593056 10627760128 1150616608 12.315 100 81 157 1 1345942928 177556432 17769888
11:46:05 1832 8192000000 10300532544 10699214848 1167229024 12.471 100 94 186 0 1412609104 224078272 21301808
11:47:10 7442 8192000000 10333064240 10742181888 1178652592 12.578 100 70 140 1 1445295600 250502688 16140320
11:48:21 1123 8192000000 10355838960 10769264640 1184102496 12.628 100 83 162 0 1464759536 261684544 18972496
11:49:25 1119 8192000000 10409928176 10820235264 1184334256 12.631 100 94 184 0 1519764928 313487856 21942816
11:50:30 7966 8192000000 10407890368 10832551936 1193148592 12.713 100 78 156 0 1522214832 310848464 18217776
11:51:40 1246 8192000000 10421092816 10852544512 1190422288 12.687 100 91 181 0 1533719136 320939968 22356880
11:52:46 1006 8192000000 10435964432 10862796800 1208555344 12.856 100 95 191 1 1548314368 318030480 21728544
11:53:50 2592 8192000000 10452300800 10863484928 1194995872 12.730 100 87 172 1 1559519712 344031712 20492128
11:54:56 1509 8192000000 10507706816 10930135040 1206469168 12.836 100 96 191 2 1613266976 384648688 22149120
11:56:00 945 8192000000 10538485360 10966646784 1198335680 12.761 100 91 181 0 1646958480 426305584 22317216
11:57:04 1028 8192000000 10543134560 10999226368 1202643408 12.801 100 93 186 1 1648585648 423471856 22470384
11:58:09 2397 8192000000 10540023056 10986102784 1192439088 12.706 100 77 149 0 1646916704 435696432 18781184
11:59:15 1600 8192000000 10593339936 11033112576 1195335504 12.733 100 88 170 1 1700238352 483072368 21830480
12:00:19 3395 8192000000 10561664320 11051126784 1180280768 12.593 100 85 170 0 1671297584 470135120 20881696
12:01:25 9132 8192000000 10568310688 11013156864 1179077040 12.582 100 52 102 0 1667139104 474240992 13821072
12:02:45 5994 8192000000 10440860992 10971734016 1163751424 12.438 99 52 102 0 1546394048 368696160 13946464
12:03:55 30286 8192000000 10310214448 10836381696 1130553824 12.127 99 28 56 0 1479679088 341520944 7604320
12:05:29 53576 8192000000 10131532864 10678878208 1104118000 11.877 98 11 22 0 1277353216 170223632 3011584
12:07:38 36574 8192000000 10103051744 10537889792 1126835024 12.092 99 31 59 4 1183454784 50713264 5906496
12:09:17 84399 8192000000 10089438128 10525868032 1118876288 12.016 99 36 66 0 1175626608 49597776 7152544
12:11:42 24870 8192000000 10132138992 10585800704 1127225552 12.095 99 34 68 2 1219396816 85387376 6783888
12:13:13 43369 8192000000 10105545312 10597277696 1132783568 12.148 100 14 27 0 1233826752 97792416 3250768
12:15:31 114779 8192000000 10113623632 10581893120 1123500064 12.060 98 22 43 0 1185730272 57490432 4739776
12:18:33 70095 8192000000 10080765904 10527576064 1111731152 11.949 98 17 31 0 1160644032 45641312 3271568
12:20:44 59114 8192000000 10071549680 10536820736 1136402176 12.182 100 14 29 0 1186705856 46759936 3543744
12:22:53 23476 8192000000 10069100528 10555703296 1136620336 12.184 100 16 32 1 1181639872 41279472 3740064
12:24:17 59682 8192000000 10114288752 10577252352 1111458288 11.946 97 14 28 1 1191279792 76960608 2860896
12:26:17 111585 8192000000 10136196960 10595319808 1110761920 11.940 98 17 34 0 1211653984 97242048 3650016
12:29:28 40206 8192000000 10071293872 10571669504 1107250416 11.906 98 15 29 0 1154994928 45021232 2723280
12:31:13 14366 8192000000 10044832320 10475900928 1094036768 11.781 98 27 52 0 1120933584 21802720 5094096
12:32:29 28437 8192000000 10094491680 10543513600 1128457184 12.107 100 45 90 0 1207523440 69690752 9375504
12:33:59 17038 8192000000 10135076256 10557476864 1111221056 11.944 98 53 106 0 1207266496 83266400 12779040
12:35:22 39899 8192000000 10125861616 10604654592 1109881728 11.931 98 31 61 0 1233002528 116685712 6435088
12:37:05 42791 8192000000 10062062080 10496753664 1093885696 11.780 97 16 32 2 1133937424 37310064 2741664
12:38:48 99703 8192000000 10077996832 10538717184 1089090480 11.734 96 34 68 1 1137085216 41384592 6610144
12:41:32 34652 8192000000 10101359248 10530635776 1118895168 12.017 99 35 70 0 1172992624 46583584 7513872
12:43:58 42505 8192000000 10062320352 10498539520 1110949024 11.941 98 37 74 1 1172131712 54075984 7106704
12:45:42 62298 8192000000 10096477104 10563923968 1131011920 12.131 99 31 62 0 1212217712 74607744 6598048
12:47:44 63819 8192000000 10080952016 10551144448 1127801008 12.101 99 11 22 1 1185226416 54945296 2480112
12:49:56 78269 8192000000 10096062816 10560315392 1138852272 12.205 100 28 56 0 1187453584 42309456 6291856
12:52:25 44882 8192000000 10158993664 10605785088 1159130064 12.395 100 23 46 1 1245364464 81027488 5206912
12:54:19 56221 8192000000 10175896144 10628100096 1137816720 12.195 98 27 54 1 1248252928 104073696 6362512
12:56:15 59755 8192000000 10154290992 10624147456 1107044304 11.904 96 26 52 2 1227718496 115201984 5472208
12:58:18 73583 8192000000 10085527520 10528591872 1059582432 11.452 93 15 30 0 1107063472 44451360 3029680
13:00:32 32524 8192000000 10165061504 10614149120 1118667136 12.014 99 35 70 0 1239989488 114093552 7228800
13:02:06 25562 8192000000 10127400208 10562154496 1135146592 12.170 100 37 74 1 1216322000 73621936 7553472
13:03:38 107904 8192000000 10050602704 10519134208 1108977808 11.923 98 24 48 1 1143131792 28865872 5288112
13:06:27 68884 8192000000 10036956064 10488758272 1108581424 11.919 98 16 32 0 1142268336 30768720 2918192
13:08:42 86155 8192000000 10054990656 10527764480 1089684720 11.740 96 15 30 0 1123971696 31077168 3209808
13:11:08 28470 8192000000 10068078832 10510913536 1117449392 12.003 98 22 43 1 1144625808 23368416 3808000
13:12:38 7427 8192000000 10114887216 10550104064 1136361616 12.181 99 50 100 1 1204552112 57832656 10357840
13:13:46 17472 8192000000 10121170832 10554322944 1127890496 12.101 98 39 78 0 1203987504 67517856 8579152
13:15:04 13902 8192000000 10164072944 10593751040 1147180288 12.283 100 45 90 0 1257100704 99543440 10376976
13:16:19 21854 8192000000 10197679424 10635423744 1156993696 12.375 99 46 92 3 1282459104 114658416 10806992
13:17:47 14054 8192000000 10255591888 10690867200 1172971328 12.525 100 58 116 0 1351032176 164764848 13296000
13:19:01 31370 8192000000 10298434752 10734952448 1160113376 12.404 99 40 80 1 1358583968 188460464 10010128
13:20:44 30338 8192000000 10323112192 10763771904 1156339200 12.369 99 41 82 0 1395585232 229417984 9828048
13:22:18 90673 8192000000 10195656672 10636193792 1062838544 11.484 92 23 44 2 1211295312 142647664 5809104
13:24:53 66609 8192000000 10081763312 10619408384 1115028048 11.980 99 12 23 0 1174983856 57212720 2743088
13:27:05 83385 8192000000 10072144560 10549841920 1128512384 12.107 100 12 21 1 1178282016 47279808 2489824
13:29:29 17896 8192000000 10062863456 10489516032 1093857312 11.779 97 52 104 2 1140613488 33651616 13104560
13:30:53 61424 8192000000 10120905648 10604003328 1124500656 12.069 99 31 62 0 1233292432 101659792 7131984
13:32:57 60575 8192000000 10068235568 10494832640 1106457184 11.899 98 20 37 2 1134523536 24671600 3394752
13:35:02 142300 8192000000 9798736272 10223738880 868190720 9.582 77 27 51 2 940213872 66341696 5681456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

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

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          214618
MON$OLDEST_ACTIVE               215969
MON$OLDEST_SNAPSHOT             215969
MON$NEXT_TRANSACTION            215969
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                       2047712
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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

22.04.22 13:39:42. 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-22 13:39:45 No severe PSQL-related problems occured
22.04.22 13:39:46. End of report.