Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.02.2023 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 14831960 kB
MemAvailable 30410776 kB
Buffers 0 kB
Cached 16866972 kB
SwapCached 3140 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.3
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 2023-02-25 13:37:27
DTS_END                         2023-02-25 13:37:27.1240
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/51992
TRN_ID                          214958
ATT_ID                          604
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: 9089.71 25156 1090765
customer order: creation 163.78 497 19654
customer order: refuse 14.17 427 1700
order to supplier: creation 43.33 1352 5200
order to supplier: removal 3.35 169948 402
invoice (draft): creation 44.27 3334 5312
invoice (draft): removal 52.56 961 6307
invoice accept: apply 40.41 27422 4849
invoice accept: cancel 47.63 1201 5716
customer reserve: creation 3770.84 259 452501
customer reserve: removal 4625.52 96 555063
realization accept: apply 79.62 303 9554
realization accept: cancel 78.80 173 9456
payment from customer: creation 56.47 180 6776
payment from customer: removal 5.48 144 657
payment to supplier: creation 47.81 210 5737
payment to supplier: removal 3.36 126 403
service: total inventory turnovers 3.85 4171 462
service: total monetary turnovers 7.43 388 891
service: aggregate perf. data 1.01 14410 121
service: refresh index statistics 0.03 277521 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-02-2023 13:39:10 to 25-02-2023 13:39:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7960
TEST_TIME 2 5585
TEST_TIME 3 5965
TEST_TIME 4 5867
TEST_TIME 5 5589
TEST_TIME 6 5972
TEST_TIME 7 5810
TEST_TIME 8 5795
TEST_TIME 9 6331
TEST_TIME 10 6408
TEST_TIME 11 6535
TEST_TIME 12 6686
TEST_TIME 13 6961
TEST_TIME 14 6945
TEST_TIME 15 6967
TEST_TIME 16 7146
TEST_TIME 17 7170
TEST_TIME 18 7172
TEST_TIME 19 7301
TEST_TIME 20 7686
TEST_TIME 21 7781
TEST_TIME 22 8043
TEST_TIME 23 8271
TEST_TIME 24 8404
TEST_TIME 25 8309
TEST_TIME 26 8628
TEST_TIME 27 8685
TEST_TIME 28 8807
TEST_TIME 29 9125
TEST_TIME 30 9908
TEST_TIME 31 9816
TEST_TIME 32 10100
TEST_TIME 33 10228
TEST_TIME 34 10531
TEST_TIME 35 11360
TEST_TIME 36 11415
TEST_TIME 37 11241
TEST_TIME 38 11214
TEST_TIME 39 11240
TEST_TIME 40 11053
TEST_TIME 41 10918
TEST_TIME 42 10687
TEST_TIME 43 10505
TEST_TIME 44 10628
TEST_TIME 45 10519
TEST_TIME 46 10383
TEST_TIME 47 10335
TEST_TIME 48 10347
TEST_TIME 49 10351
TEST_TIME 50 10454
TEST_TIME 51 10360
TEST_TIME 52 10253
TEST_TIME 53 10115
TEST_TIME 54 10011
TEST_TIME 55 9988
TEST_TIME 56 9876
TEST_TIME 57 9863
TEST_TIME 58 9782
TEST_TIME 59 9824
TEST_TIME 60 9730
TEST_TIME 61 9669
TEST_TIME 62 9638
TEST_TIME 63 9593
TEST_TIME 64 9547
TEST_TIME 65 9580
TEST_TIME 66 9581
TEST_TIME 67 9594
TEST_TIME 68 9626
TEST_TIME 69 9585
TEST_TIME 70 9607
TEST_TIME 71 9595
TEST_TIME 72 9742
TEST_TIME 73 9714
TEST_TIME 74 9708
TEST_TIME 75 9823
TEST_TIME 76 9808
TEST_TIME 77 9698
TEST_TIME 78 9641
TEST_TIME 79 9645
TEST_TIME 80 9613
TEST_TIME 81 9621
TEST_TIME 82 9552
TEST_TIME 83 9503
TEST_TIME 84 9442
TEST_TIME 85 9450
TEST_TIME 86 9377
TEST_TIME 87 9323
TEST_TIME 88 9400
TEST_TIME 89 9375
TEST_TIME 90 9355
TEST_TIME 91 9336
TEST_TIME 92 9393
TEST_TIME 93 9374
TEST_TIME 94 9342
TEST_TIME 95 9377
TEST_TIME 96 9419
TEST_TIME 97 9421
TEST_TIME 98 9468
TEST_TIME 99 9461
TEST_TIME 100 9486
TEST_TIME 101 9516
TEST_TIME 102 9501
TEST_TIME 103 9426
TEST_TIME 104 9429
TEST_TIME 105 9444
TEST_TIME 106 9407
TEST_TIME 107 9364
TEST_TIME 108 9316
TEST_TIME 109 9295
TEST_TIME 110 9230
TEST_TIME 111 9230
TEST_TIME 112 9195
TEST_TIME 113 9189
TEST_TIME 114 9178
TEST_TIME 115 9185
TEST_TIME 116 9161
TEST_TIME 117 9156
TEST_TIME 118 9166
TEST_TIME 119 9171
TEST_TIME 120 9121

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-02-2023 13:39:13 to 25-02-2023 13:39:17.

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 1507331 1507331 0 0.00 0 15381 14 0 0
doc_list_biud 1507316 1507316 0 0.00 0 44792 41 0 0
sp_add_invoice_to_stock 4970 4849 121 2.43 62 199659 27422 0 121
sp_cancel_adding_invoice 5716 5716 0 0.00 7 74140 1201 0 0
sp_cancel_client_order 1700 1700 0 0.00 6 8313 427 0 0
sp_cancel_customer_reserve 555063 555063 0 0.00 1 39293 96 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 4 5454 144 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 4 5009 126 0 0
sp_cancel_supplier_invoice 6315 6307 8 0.12 3 40487 962 0 8
sp_cancel_supplier_order 402 402 0 0.00 11 1433066 169949 0 0
sp_cancel_write_off 9456 9456 0 0.00 1 20706 173 0 0
sp_client_order 19654 19654 0 0.00 5 35223 497 0 0
sp_customer_reserve 452501 452501 0 0.00 1 86162 259 0 0
sp_fill_shopping_cart 30151 30151 0 0.00 1 16089 85 0 0
sp_fill_shopping_cart_clo_res 452467 452467 0 0.00 0 23964 20 0 0
sp_get_clo_for_invoice 4804 4804 0 0.00 0 32641 258 0 0
sp_kill_cost_storno 16232 16232 0 0.00 0 10042 75 0 0
sp_kill_qstorno_ret_qs2qd 1126291 1126291 0 0.00 0 40417 42 0 0
sp_kill_qty_storno 593000 593000 0 0.00 0 44791 97 0 0
sp_lock_selected_doc 19962 19962 0 0.00 0 7516 9 0 0
sp_make_cost_storno 26871 26871 0 0.00 0 15358 91 0 0
sp_multiply_rows_for_pdistr 26869 26869 0 0.00 0 9118 40 0 0
sp_multiply_rows_for_qdistr 19654 19654 0 0.00 0 12342 176 0 0
sp_pay_from_customer 6776 6776 0 0.00 5 13132 181 0 0
sp_pay_to_supplier 5737 5737 0 0.00 6 15626 211 0 0
sp_payment_common 12513 12513 0 0.00 1 7936 50 0 0
sp_qd_handle_on_cancel_clo 1700 1700 0 0.00 1 8303 333 0 0
sp_qd_handle_on_invoice_upd_sts 10518 10518 0 0.00 0 44786 534 0 0
sp_qd_handle_on_reserve_upd_sts 9554 9554 0 0.00 0 24298 73 0 0
sp_reserve_write_off 9554 9554 0 0.00 9 24454 304 0 0
sp_supplier_invoice 5312 5312 0 0.00 9 96643 3335 0 0
sp_supplier_order 5200 5200 0 0.00 7 40715 1352 0 0
srv_aggregate_perf_data 121 121 0 0.00 2582 145135 14411 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 737 132116 34696 0 0
srv_find_qd_qs_mism 1043391 1043391 0 0.00 0 10699 11 0 0
srv_make_invnt_saldo 462 462 0 0.00 62 97626 4172 0 0
srv_make_money_saldo 891 891 0 0.00 1 24121 388 0 0
srv_recalc_idx_stat 4 4 0 0.00 33149 508289 277521 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4856 4856 0 0.00 1 15337 979 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 1 6040 453 0 0
t$perf-norm:sp_cancel_client_order 1700 1700 0 0.00 0 11107 35 0 0
t$perf-norm:sp_cancel_customer_reserve 651 651 0 0.00 0 14198 53 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 10282 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 247 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 366 366 0 0.00 0 599 33 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 93284 6891 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 3090 23 0 0
t$perf-norm:sp_client_order 19655 19655 0 0.00 0 15174 38 0 0
t$perf-norm:sp_customer_reserve 932 932 0 0.00 0 1626 14 0 0
t$perf-norm:sp_pay_from_customer 6776 6776 0 0.00 0 8179 22 0 0
t$perf-norm:sp_pay_to_supplier 5737 5737 0 0.00 0 4384 20 0 0
t$perf-norm:sp_reserve_write_off 9554 9554 0 0.00 0 9728 24 0 0
t$perf-norm:sp_supplier_invoice 5312 5312 0 0.00 0 5594 33 0 0
t$perf-norm:sp_supplier_order 5200 5200 0 0.00 0 6929 33 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 510 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 463 9 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 1731 17 0 0
t$perf-norm:srv_make_money_saldo 891 891 0 0.00 0 12469 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 876 231 0 0
v_add_invoice_to_stock 9844 9602 242 2.45 0 9597 29 0 242
v_all_customers 35985 35985 0 0.00 0 76 4 0 0
v_all_suppliers 29458 29458 0 0.00 0 102 4 0 0
v_all_wares 39304 39304 0 0.00 0 70 4 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 163 14 0 0
v_cancel_client_order 3400 3400 0 0.00 0 2705 12 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 2490 14 0 0
v_cancel_customer_reserve 1302 1302 0 0.00 0 2913 13 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 1158 15 0 0
v_cancel_supplier_invoice 748 732 16 2.13 0 403 24 0 16
v_cancel_supplier_order 804 804 0 0.00 0 1040 13 0 0
v_cancel_write_off 2050 2050 0 0.00 0 1503 25 0 0
v_max_id_clo_ord 5198 5198 0 0.00 0 11407 8 0 0
v_max_id_clo_res 932 932 0 0.00 0 3805 24 0 0
v_max_id_ord_sup 5301 5301 0 0.00 0 6068 8 0 0
v_max_non_paid_invoice 1507 1507 0 0.00 0 1458 8 0 0
v_max_non_paid_realizn 3541 3541 0 0.00 0 6681 10 0 0
v_min_id_clo_ord 5198 5198 0 0.00 0 2986 9 0 0
v_min_id_clo_res 932 932 0 0.00 0 1673 14 0 0
v_min_id_ord_sup 5301 5301 0 0.00 0 8235 12 0 0
v_min_non_paid_invoice 5737 5737 0 0.00 0 5680 23 0 0
v_min_non_paid_realizn 6776 6776 0 0.00 0 7760 18 0 0
v_our_firm 9788 9788 0 0.00 0 53 5 0 0
v_reserve_write_off 19108 19108 0 0.00 0 10024 11 0 0
x_lock_depdocs_on_canc_invoice 11657 11657 0 0.00 0 19625 133 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 8199 441 0 0
x_make_qstorno_1000_1200 5200 5200 0 0.00 2 40695 1174 0 0
x_make_qstorno_1200_2000 5312 5312 0 0.00 4 96575 3115 0 0
x_make_qstorno_2100_3300 452500 452500 0 0.00 0 86159 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-02-2023 13:39:18 to 25-02-2023 13:39:22.

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:31 13 8192000000 8809649456 8825004032 13459312 0.164 5 4 5 0 13735024 163184 112528
11:08:32 13368 8192000000 9710967872 10008363008 788771824 8.782 100 88 176 0 818547632 7552272 22223536
11:09:49 18378 8192000000 9761398096 10072289280 838902032 9.289 100 87 174 0 870276208 10739744 20634432
11:11:26 16480 8192000000 9816327168 10137681920 881753248 9.717 100 87 174 0 919488304 17927632 19807424
11:12:48 6443 8192000000 9872117104 10204172288 924041088 10.136 100 88 173 0 985476368 42546048 18889232
11:14:00 3416 8192000000 9930396064 10273759232 974834688 10.634 100 88 171 0 1049161664 56716880 17610096
11:15:05 2708 8192000000 9980219792 10337603584 1004402512 10.921 100 91 179 1 1095647632 73041312 18203808
11:16:10 898 8192000000 10000078848 10361622528 1028687216 11.156 100 96 180 1 1120146352 74181584 17277552
11:17:13 985 8192000000 10015694096 10383638528 1043172048 11.295 100 93 178 0 1144249936 82165056 18912832
11:18:17 1108 8192000000 10024526384 10397298688 1059178880 11.449 100 96 184 0 1148713904 69917328 19617696
11:19:19 1196 8192000000 10056809584 10439516160 1069976032 11.552 100 95 189 0 1180358208 90448368 19933808
11:20:23 849 8192000000 10056343856 10441576448 1073198112 11.583 100 91 174 1 1179448384 89056720 17193552
11:21:25 1092 8192000000 10050493968 10435080192 1076749712 11.616 100 94 178 0 1175193616 80553072 17890832
11:22:27 1158 8192000000 10060846288 10449408000 1081378016 11.661 100 95 186 0 1181656480 80995024 19283440
11:23:29 1278 8192000000 10062240096 10448052224 1084863920 11.694 100 94 179 1 1185765344 82413840 18487584
11:24:32 1013 8192000000 10061198784 10448879616 1090899968 11.751 100 92 176 2 1183947568 74750368 18297232
11:25:34 1081 8192000000 10060152080 10456809472 1098082944 11.819 100 92 181 1 1190591040 72873232 19634864
11:26:36 1027 8192000000 10064717440 10453553152 1096013552 11.800 100 95 182 2 1184597856 69549168 19035136
11:27:38 979 8192000000 10051075328 10437898240 1091608192 11.758 100 95 184 1 1174823808 62656448 20559168
11:28:39 1127 8192000000 10075886000 10463956992 1085676432 11.702 100 94 179 0 1198438448 94991824 17770192
11:29:42 2770 8192000000 10015106128 10397110272 1070189904 11.554 99 83 147 5 1134404032 51534656 12679472
11:30:45 10750 8192000000 10031341328 10426818560 1060184560 11.458 99 62 116 3 1133177552 61813216 11179776
11:31:58 2171 8192000000 10005149968 10389741568 1059354624 11.450 100 68 126 1 1125423024 53575216 12493184
11:33:00 1134 8192000000 9971648480 10343485440 1020336256 11.075 100 84 158 1 1095423680 58474448 16612976
11:34:02 1029 8192000000 10022385136 10398224384 1051667104 11.377 100 94 174 2 1144058656 72902384 19489168
11:35:05 1437 8192000000 9982403168 10349445120 1015809232 11.032 99 97 188 2 1103251936 67783440 19659264
11:36:07 1212 8192000000 10047897152 10425614336 1055790800 11.416 100 95 180 2 1165559408 91240720 18527888
11:37:09 2405 8192000000 10009313600 10388705280 1049124720 11.352 99 83 161 1 1129648752 64685136 15838896
11:38:18 4190 8192000000 10015211328 10392350720 1060956912 11.466 100 74 127 0 1136499744 63755808 11787024
11:39:27 16066 8192000000 10038437872 10424614912 1080808672 11.655 100 66 117 1 1156187456 63051072 12327712
11:40:45 1926 8192000000 10072976160 10459484160 1097644416 11.815 100 97 188 0 1192628592 75637088 19347088
11:41:51 6508 8192000000 10095985488 10492653568 1110650048 11.939 100 73 135 2 1216726432 91672320 14404064
11:43:02 2456 8192000000 10136425392 10540208128 1125516160 12.079 100 85 161 1 1260861776 117957168 17388448
11:44:09 1226 8192000000 10171516608 10566701056 1133079760 12.150 100 79 153 0 1285602912 135898544 16624608
11:45:25 1490 8192000000 10207553216 10610421760 1152220784 12.330 100 76 138 0 1325122096 158099728 14801584
11:46:31 1137 8192000000 10250330832 10659102720 1169408192 12.491 100 96 179 1 1364741040 176810032 18522816
11:47:35 3143 8192000000 10306785360 10709692416 1178481664 12.576 100 91 174 1 1422394672 224438256 19474752
11:48:41 1222 8192000000 10307978128 10727784448 1182552272 12.614 100 82 161 1 1422872672 222522496 17797904
11:49:48 1372 8192000000 10344554480 10746408960 1175800640 12.551 99 90 173 2 1456851728 260188784 20862304
11:50:52 1049 8192000000 10388109312 10804088832 1193711664 12.718 100 94 184 0 1501458864 287235712 20511488
11:51:59 907 8192000000 10410266272 10820186112 1202555792 12.800 100 96 179 3 1522791280 299508528 20726960
11:53:02 1481 8192000000 10449036080 10865741824 1200433232 12.780 100 91 177 2 1555081744 333726224 20922288
11:54:05 1109 8192000000 10472518096 10881863680 1197032032 12.749 100 95 186 3 1582005104 363603264 21369808
11:55:10 953 8192000000 10507356304 10932482048 1201799664 12.793 100 94 183 1 1612798304 389377952 21620688
11:56:17 1202 8192000000 10522436736 10967097344 1195899776 12.738 100 82 162 0 1633872224 418208400 19764048
11:57:21 862 8192000000 10532454400 10957066240 1201863952 12.794 100 98 189 1 1638906272 415175120 21867200
11:58:25 2400 8192000000 10510622704 10962350080 1179352352 12.584 100 87 174 0 1622382976 421000032 22030592
11:59:31 724 8192000000 10515412160 10955468800 1176816688 12.560 100 96 182 1 1621991312 422941584 22233040
12:00:34 743 8192000000 10521048400 10964160512 1188908160 12.673 100 79 155 0 1627564208 419628240 19027808
12:01:37 1713 8192000000 10539113552 10961846272 1192017408 12.702 100 67 134 0 1643671104 435678880 15974816
12:02:43 4987 8192000000 10550192416 10998300672 1194706448 12.727 100 66 130 0 1649966464 439213936 16046080
12:03:57 4560 8192000000 10593378368 11039961088 1192715616 12.709 100 57 112 0 1705605744 498903856 13986272
12:05:12 13571 8192000000 10538077408 11061899264 1174864576 12.542 100 41 78 0 1660750768 473885568 12000624
12:06:48 4589 8192000000 10495452496 11010203648 1172094336 12.516 100 47 94 0 1602092288 417929984 12067968
12:08:05 40866 8192000000 10412060944 10874531840 1134393424 12.163 97 27 54 0 1466355040 325313472 6648144
12:10:05 35108 8192000000 10359406928 10840846336 1134989456 12.168 98 15 30 0 1455019248 316125200 3904592
12:11:40 70609 8192000000 10083668064 10629582848 1059958448 11.456 93 14 25 0 1208717200 145964144 2794608
12:13:51 113666 8192000000 10109174192 10575728640 1070375440 11.556 93 15 30 0 1174740864 101116464 3248960
12:16:45 35609 8192000000 10115753712 10549563392 1141429296 12.229 100 45 83 1 1204770880 54698224 8643360
12:18:21 132114 8192000000 10099932448 10560622592 1118821760 12.016 98 38 70 1 1206523600 80925872 6775968
12:21:34 106243 8192000000 10135770528 10576461824 1119246864 12.020 97 27 52 0 1195527728 70949664 5331200
12:24:22 50554 8192000000 10165942704 10638336000 1143186032 12.245 99 46 85 0 1276530464 123278944 10065488
12:26:38 56535 8192000000 10113490304 10604261376 1138656416 12.203 99 15 30 0 1232017968 89612672 3748880
12:29:25 110488 8192000000 10067291648 10539970560 1104419904 11.880 97 20 38 0 1130231904 22311824 3500176
12:32:17 55506 8192000000 10076115056 10533773312 1128407664 12.106 99 22 44 0 1167223808 34458512 4357632
12:34:22 62582 8192000000 10108440560 10597023744 1135713824 12.175 99 19 38 0 1205451216 65258176 4479216
12:36:25 44430 8192000000 10060295168 10529120256 1090187664 11.744 95 18 36 2 1144231056 50461344 3582048
12:38:10 67469 8192000000 10067789248 10540273664 1129615408 12.118 99 14 28 0 1165032096 32724512 2692176
12:40:26 51938 8192000000 10074416848 10539433984 1115357280 11.983 97 9 18 0 1164318832 47382736 1578816
12:42:32 42223 8192000000 10122335856 10574438400 1142710144 12.241 99 22 43 0 1198220464 51364608 4145712
12:44:17 38334 8192000000 10156237504 10628505600 1160767504 12.410 100 31 60 0 1255183216 88212656 6203056
12:45:56 12917 8192000000 10139247936 10573783040 1150931232 12.318 99 52 100 1 1239847520 78944496 9971792
12:47:24 43657 8192000000 10177663104 10615541760 1110406080 11.936 96 24 48 0 1229312016 113963520 4942416
12:49:16 22681 8192000000 10141542864 10605105152 1132606144 12.146 99 37 72 0 1247969504 108062928 7300432
12:50:42 65759 8192000000 10146564784 10615189504 1137622656 12.193 99 32 64 0 1217156768 72226992 7307120
12:52:48 58016 8192000000 10083525120 10541629440 1117458832 12.003 97 8 16 0 1175223808 56451680 1313296
12:54:50 63305 8192000000 10153876064 10617851904 1155518464 12.361 100 45 90 0 1248575232 83612480 9444288
12:57:05 32233 8192000000 10105870416 10578157568 1157412592 12.379 100 26 52 0 1206082336 43511952 5157792
12:58:42 46314 8192000000 10054630992 10507153408 1108470544 11.918 97 13 25 0 1133475488 22459744 2545200
13:00:28 90373 8192000000 10062917360 10512543744 1080025248 11.648 95 27 52 0 1152973872 66815008 6133616
13:02:59 38535 8192000000 10069457072 10540781568 1124966176 12.074 100 11 20 0 1181570448 54162816 2441456
13:04:44 88109 8192000000 10064776704 10596167680 1071466720 11.566 95 9 18 0 1120100208 46744480 1889008
13:07:25 42854 8192000000 10063890016 10537422848 1106074608 11.895 98 28 52 1 1164733376 53430704 5228064
13:09:13 50811 8192000000 10067305824 10503860224 1082182800 11.668 96 29 58 0 1143889744 55375136 6331808
13:11:07 22942 8192000000 10138627952 10588999680 1131616240 12.137 100 38 76 0 1223042064 83302528 8123296
13:12:31 59367 8192000000 10108182160 10596265984 1111529248 11.947 98 32 64 0 1221532720 103409760 6593712
13:14:32 11400 8192000000 10135181040 10555109376 1128699504 12.109 100 64 124 0 1234427968 93340560 12387904
13:16:40 18349 8192000000 10120400064 10567524352 1118895856 12.017 99 37 73 1 1191495216 65609296 6990064
13:18:02 22519 8192000000 10096346816 10546163712 1095480464 11.795 97 46 92 1 1169370688 64292016 9598208
13:19:29 87493 8192000000 10121418928 10565656576 1122485632 12.050 99 44 89 1 1203163744 71700784 8977328
13:22:15 46496 8192000000 10070570976 10592817152 1121246608 12.039 99 25 48 0 1221670544 95264432 5159504
13:24:20 49004 8192000000 10057097632 10529296384 1122836208 12.054 99 11 22 0 1153815216 28558368 2420640
13:26:13 78723 8192000000 10091586400 10561384448 1137570096 12.193 100 21 42 0 1208098208 65762224 4765888
13:28:41 66907 8192000000 10106364464 10570694656 1120455184 12.031 98 11 22 0 1201772816 78786784 2530848
13:31:02 25946 8192000000 10130909856 10578997248 1147012352 12.281 99 29 58 0 1234457360 81121872 6323136
13:32:35 27958 8192000000 10171756880 10622590976 1162666320 12.428 100 27 54 1 1283877424 114603744 6607360
13:34:03 38287 8192000000 10204964448 10653184000 1129607152 12.118 97 42 82 0 1279489264 140711152 9170960
13:35:46 14268 8192000000 10236440592 10687037440 1163181024 12.433 100 57 112 0 1333902912 158022448 12699440
13:37:08 42045 8192000000 9281323264 9694830592 383936736 4.476 33 11 22 0 515057776 128538304 2582736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-02-2023 13:39:24 to 25-02-2023 13:39:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-02-2023 13:39:41 to 25-02-2023 13:39:43.

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          214342
MON$OLDEST_ACTIVE               217556
MON$OLDEST_SNAPSHOT             217556
MON$NEXT_TRANSACTION            217556
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                       2086560
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.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 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 ---
--- end of diff output ---

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

25.02.23 13:39:45. 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
2023-02-25 13:39:47 No severe PSQL-related problems occured
25.02.23 13:39:48. End of report.