Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.01.2023 16:39
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 15164204 kB
MemAvailable 30395576 kB
Buffers 0 kB
Cached 16528196 kB
SwapCached 2152 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-01-15 16:38:38
DTS_END                         2023-01-15 16:38:38.5750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59376
TRN_ID                          243817
ATT_ID                          674
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: 9523.12 19696 1142774
customer order: creation 174.94 348 20993
customer order: refuse 14.61 340 1753
order to supplier: creation 46.50 1198 5580
order to supplier: removal 3.63 89825 435
invoice (draft): creation 46.85 2934 5622
invoice (draft): removal 54.28 717 6513
invoice accept: apply 42.78 17404 5134
invoice accept: cancel 49.28 709 5913
customer reserve: creation 3959.47 173 475136
customer reserve: removal 4833.92 53 580070
realization accept: apply 85.78 189 10293
realization accept: cancel 81.58 103 9789
payment from customer: creation 59.24 132 7109
payment from customer: removal 5.76 93 691
payment to supplier: creation 49.23 153 5908
payment to supplier: removal 3.48 106 417
service: total inventory turnovers 4.25 1103 510
service: total monetary turnovers 6.77 24 812
service: aggregate perf. data 0.77 24962 92
service: refresh index statistics 0.03 253362 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-01-2023 16:39:36 to 15-01-2023 16:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5869
TEST_TIME 2 4390
TEST_TIME 3 3548
TEST_TIME 4 3521
TEST_TIME 5 4158
TEST_TIME 6 4220
TEST_TIME 7 4651
TEST_TIME 8 5060
TEST_TIME 9 5190
TEST_TIME 10 6038
TEST_TIME 11 6016
TEST_TIME 12 6360
TEST_TIME 13 6629
TEST_TIME 14 6825
TEST_TIME 15 7212
TEST_TIME 16 7340
TEST_TIME 17 7343
TEST_TIME 18 7427
TEST_TIME 19 7468
TEST_TIME 20 7656
TEST_TIME 21 8006
TEST_TIME 22 8216
TEST_TIME 23 8441
TEST_TIME 24 9016
TEST_TIME 25 9232
TEST_TIME 26 10014
TEST_TIME 27 9725
TEST_TIME 28 9802
TEST_TIME 29 10141
TEST_TIME 30 10317
TEST_TIME 31 10197
TEST_TIME 32 10215
TEST_TIME 33 10066
TEST_TIME 34 10124
TEST_TIME 35 10052
TEST_TIME 36 9934
TEST_TIME 37 10189
TEST_TIME 38 10095
TEST_TIME 39 9979
TEST_TIME 40 10052
TEST_TIME 41 10129
TEST_TIME 42 10221
TEST_TIME 43 10087
TEST_TIME 44 9890
TEST_TIME 45 9815
TEST_TIME 46 9876
TEST_TIME 47 9890
TEST_TIME 48 9792
TEST_TIME 49 9812
TEST_TIME 50 9761
TEST_TIME 51 9689
TEST_TIME 52 9643
TEST_TIME 53 9845
TEST_TIME 54 9752
TEST_TIME 55 9709
TEST_TIME 56 9605
TEST_TIME 57 9607
TEST_TIME 58 9652
TEST_TIME 59 9566
TEST_TIME 60 9482
TEST_TIME 61 9478
TEST_TIME 62 9582
TEST_TIME 63 9535
TEST_TIME 64 9580
TEST_TIME 65 9554
TEST_TIME 66 9683
TEST_TIME 67 9641
TEST_TIME 68 9593
TEST_TIME 69 9765
TEST_TIME 70 9697
TEST_TIME 71 9710
TEST_TIME 72 9755
TEST_TIME 73 9703
TEST_TIME 74 9787
TEST_TIME 75 9824
TEST_TIME 76 9772
TEST_TIME 77 9700
TEST_TIME 78 9680
TEST_TIME 79 9619
TEST_TIME 80 9593
TEST_TIME 81 9629
TEST_TIME 82 9622
TEST_TIME 83 9552
TEST_TIME 84 9620
TEST_TIME 85 9565
TEST_TIME 86 9547
TEST_TIME 87 9565
TEST_TIME 88 9575
TEST_TIME 89 9545
TEST_TIME 90 9567
TEST_TIME 91 9644
TEST_TIME 92 9658
TEST_TIME 93 9670
TEST_TIME 94 9672
TEST_TIME 95 9657
TEST_TIME 96 9661
TEST_TIME 97 9718
TEST_TIME 98 9735
TEST_TIME 99 9719
TEST_TIME 100 9758
TEST_TIME 101 9699
TEST_TIME 102 9663
TEST_TIME 103 9645
TEST_TIME 104 9605
TEST_TIME 105 9624
TEST_TIME 106 9603
TEST_TIME 107 9589
TEST_TIME 108 9551
TEST_TIME 109 9506
TEST_TIME 110 9447
TEST_TIME 111 9505
TEST_TIME 112 9497
TEST_TIME 113 9494
TEST_TIME 114 9452
TEST_TIME 115 9466
TEST_TIME 116 9513
TEST_TIME 117 9554
TEST_TIME 118 9514
TEST_TIME 119 9543
TEST_TIME 120 9529

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-01-2023 16:39:40 to 15-01-2023 16:39:44.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1582512 1582512 0 0.00 0 24688 6 0 0
doc_list_biud 1582511 1582511 0 0.00 0 93496 25 0 0
sp_add_invoice_to_stock 5283 5134 149 2.82 21 215118 17404 0 149
sp_cancel_adding_invoice 5913 5913 0 0.00 4 54248 709 0 0
sp_cancel_client_order 1753 1753 0 0.00 4 9293 340 0 0
sp_cancel_customer_reserve 580070 580070 0 0.00 0 31881 53 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 3286 94 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 12663 106 0 0
sp_cancel_supplier_invoice 6527 6513 14 0.21 2 93562 718 0 14
sp_cancel_supplier_order 435 435 0 0.00 6 901798 89825 0 0
sp_cancel_write_off 9789 9789 0 0.00 1 10764 104 0 0
sp_client_order 20993 20993 0 0.00 3 26940 348 0 0
sp_customer_reserve 475136 475136 0 0.00 0 51731 173 0 0
sp_fill_shopping_cart 32185 32185 0 0.00 0 11620 34 0 0
sp_fill_shopping_cart_clo_res 475134 475134 0 0.00 0 14878 12 0 0
sp_get_clo_for_invoice 5129 5129 0 0.00 0 20003 258 0 0
sp_kill_cost_storno 16810 16810 0 0.00 0 24683 59 0 0
sp_kill_qstorno_ret_qs2qd 1176877 1176877 0 0.00 0 93494 29 0 0
sp_kill_qty_storno 619895 619895 0 0.00 0 93495 62 0 0
sp_lock_selected_doc 21267 21267 0 0.00 0 7923 5 0 0
sp_make_cost_storno 28439 28439 0 0.00 0 18076 63 0 0
sp_multiply_rows_for_pdistr 28439 28439 0 0.00 0 11164 30 0 0
sp_multiply_rows_for_qdistr 20992 20992 0 0.00 0 15538 155 0 0
sp_pay_from_customer 7109 7109 0 0.00 4 14683 132 0 0
sp_pay_to_supplier 5908 5908 0 0.00 4 22434 153 0 0
sp_payment_common 13017 13017 0 0.00 1 22362 37 0 0
sp_qd_handle_on_cancel_clo 1753 1753 0 0.00 0 9283 310 0 0
sp_qd_handle_on_invoice_upd_sts 11042 11042 0 0.00 0 26788 304 0 0
sp_qd_handle_on_reserve_upd_sts 10293 10293 0 0.00 0 16084 57 0 0
sp_reserve_write_off 10293 10293 0 0.00 6 18998 190 0 0
sp_supplier_invoice 5622 5622 0 0.00 5 90894 2934 0 0
sp_supplier_order 5580 5580 0 0.00 5 91708 1199 0 0
srv_aggregate_perf_data 92 92 0 0.00 2770 635970 24963 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1134 160653 47981 0 0
srv_find_qd_qs_mism 1094147 1094147 0 0.00 0 13035 5 0 0
srv_make_invnt_saldo 510 510 0 0.00 44 31002 1103 0 0
srv_make_money_saldo 812 812 0 0.00 0 571 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 89151 340416 253362 0 0
t$perf-abend:sp_add_invoice_to_stock 149 149 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 5135 5135 0 0.00 0 19628 172 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 8402 100 0 0
t$perf-norm:sp_cancel_client_order 1753 1753 0 0.00 0 5073 19 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 4369 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 3168 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 351 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 4943 26 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 24255 1812 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 5391 13 0 0
t$perf-norm:sp_client_order 20993 20993 0 0.00 0 11145 20 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 4826 14 0 0
t$perf-norm:sp_pay_from_customer 7109 7109 0 0.00 0 10951 14 0 0
t$perf-norm:sp_pay_to_supplier 5908 5908 0 0.00 0 10829 16 0 0
t$perf-norm:sp_reserve_write_off 10293 10293 0 0.00 0 7793 13 0 0
t$perf-norm:sp_supplier_invoice 5622 5622 0 0.00 0 10074 19 0 0
t$perf-norm:sp_supplier_order 5580 5580 0 0.00 0 5054 16 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 220 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 10996 228 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 2893 24 0 0
t$perf-norm:srv_make_money_saldo 812 812 0 0.00 0 3239 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 2 0 0
v_add_invoice_to_stock 10556 10258 298 2.82 0 9566 19 0 298
v_all_customers 37990 37990 0 0.00 0 230 1 0 0
v_all_suppliers 30886 30886 0 0.00 0 85 1 0 0
v_all_wares 41984 41984 0 0.00 0 104 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 4309 16 0 0
v_cancel_client_order 3506 3506 0 0.00 0 3214 5 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 3238 9 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 2849 7 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 1904 8 0 0
v_cancel_supplier_invoice 858 830 28 3.26 0 2795 24 0 28
v_cancel_supplier_order 870 870 0 0.00 0 1620 9 0 0
v_cancel_write_off 2040 2040 0 0.00 0 7612 17 0 0
v_max_id_clo_ord 5576 5576 0 0.00 0 702 1 0 0
v_max_id_clo_res 1043 1043 0 0.00 0 2739 14 0 0
v_max_id_ord_sup 5615 5615 0 0.00 0 2159 2 0 0
v_max_non_paid_invoice 1656 1656 0 0.00 0 243 3 0 0
v_max_non_paid_realizn 3924 3924 0 0.00 0 3350 4 0 0
v_min_id_clo_ord 5576 5576 0 0.00 0 7776 12 0 0
v_min_id_clo_res 1043 1043 0 0.00 0 10550 17 0 0
v_min_id_ord_sup 5615 5615 0 0.00 0 8609 6 0 0
v_min_non_paid_invoice 5908 5908 0 0.00 0 22356 30 0 0
v_min_non_paid_realizn 7109 7109 0 0.00 0 8245 29 0 0
v_our_firm 10364 10364 0 0.00 0 231 1 0 0
v_reserve_write_off 20586 20586 0 0.00 0 9439 7 0 0
x_lock_depdocs_on_canc_invoice 12011 12011 0 0.00 0 17533 105 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 4894 314 0 0
x_make_qstorno_1000_1200 5580 5580 0 0.00 1 91703 1121 0 0
x_make_qstorno_1200_2000 5622 5622 0 0.00 3 90849 2819 0 0
x_make_qstorno_2100_3300 475136 475136 0 0.00 0 50424 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-01-2023 16:39:45 to 15-01-2023 16:39:49.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:42 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:42 29325 8192000000 9907482912 10219245568 989460752 10.776 100 90 181 0 1019425376 6459136 23505488
14:11:15 3720 8192000000 9934654464 10253438976 1021556992 11.087 100 89 178 0 1052539472 8278096 22704384
14:12:29 14249 8192000000 9967236560 10297831424 1056636208 11.424 100 86 172 0 1087799744 9631792 21531744
14:13:46 11909 8192000000 10020658352 10365882368 1107652032 11.910 100 82 164 0 1137735888 10079648 20004208
14:15:00 4288 8192000000 10063411392 10421305344 1158090592 12.385 100 73 147 0 1186254864 10917856 17246416
14:16:08 3134 8192000000 10124974240 10493882368 1216591312 12.930 100 77 154 1 1246754416 12734064 17429040
14:17:14 2244 8192000000 10162024080 10533732352 1241993472 13.165 100 87 172 0 1274508432 13587088 18927872
14:18:18 1109 8192000000 10178664912 10555019264 1263141552 13.359 100 91 182 1 1297459504 14168768 20149184
14:19:21 1328 8192000000 10200669760 10586038272 1289246640 13.597 100 81 161 0 1317625168 10669264 17709264
14:20:23 17358 8192000000 10200369648 10595454976 1295684944 13.656 100 64 128 1 1317398560 7327504 14386112
14:21:41 2836 8192000000 10197771280 10602483712 1301526144 13.709 100 51 102 2 1321481520 8888608 11066768
14:22:45 8697 8192000000 10204471280 10615099392 1311846608 13.803 100 22 44 0 1322702416 5678688 5177120
14:23:56 11328 8192000000 10210076528 10624811008 1319219456 13.870 100 23 46 1 1334264576 10013808 5031312
14:25:13 66303 8192000000 10211976160 10629017600 1311757520 13.802 99 41 84 0 1327920512 6770896 9392096
14:27:22 10971 8192000000 10222065936 10646642688 1335633136 14.018 100 18 36 0 1344882944 5403360 3846448
14:28:39 11777 8192000000 10165418832 10577588224 1227863472 13.034 93 18 34 2 1236943952 5183136 3897344
14:29:51 17590 8192000000 10164554208 10572656640 1220673712 12.968 94 20 41 0 1231581136 5033952 5873472
14:31:47 4916 8192000000 10142923152 10549596160 1240599280 13.152 98 21 43 0 1251033792 5398304 5036208
14:32:53 5776 8192000000 10169680752 10574295040 1273725680 13.456 100 25 53 0 1284646880 5219248 5701952
14:33:59 7272 8192000000 10172773728 10579361792 1258680816 13.318 98 24 46 1 1269987616 5682848 5623952
14:35:09 51775 8192000000 10189825712 10599022592 1293241168 13.634 100 30 60 0 1305271920 4804624 7226128
14:37:01 8497 8192000000 10199130272 10615001088 1307515376 13.764 100 19 39 2 1320985744 9840592 3629776
14:38:11 9307 8192000000 10219981424 10638401536 1331764608 13.983 100 23 47 1 1341574896 4785600 5024688
14:39:24 25316 8192000000 10268631040 10674950144 1354532272 14.188 100 59 118 0 1377963872 10221440 13210160
14:40:58 110732 8192000000 10274445200 10699980800 1373249984 14.356 100 35 70 0 1392301440 11308112 7743344
14:43:56 11362 8192000000 10286347408 10705993728 1368709472 14.315 99 38 74 0 1387244352 9913232 8621648
14:45:13 17245 8192000000 10299405248 10723504128 1389183600 14.499 100 46 92 0 1415166128 15034752 10947776
14:46:34 9273 8192000000 10304979696 10729496576 1391027632 14.515 100 54 105 0 1418473536 14365504 13080400
14:47:52 15830 8192000000 10305903280 10730221568 1390720016 14.512 100 51 102 0 1420731040 18097792 11913232
14:49:12 4855 8192000000 10312512448 10733748224 1391298720 14.517 100 63 126 0 1424305680 17626656 15380304
14:50:26 1129 8192000000 10344574384 10752118784 1399769632 14.593 100 95 189 0 1447606080 25299584 22536864
14:51:30 6558 8192000000 10332150832 10742657024 1395007344 14.551 100 75 147 0 1437834096 25184208 17642544
14:52:46 1384 8192000000 10348088208 10760617984 1406146608 14.650 100 75 143 3 1454438176 30271568 18020000
14:53:53 2519 8192000000 10357884640 10773811200 1412517712 14.706 100 65 129 0 1461149680 32336800 16295168
14:55:00 13647 8192000000 10359774736 10780520448 1414807328 14.727 100 50 100 0 1464235680 36199056 13229296
14:56:25 5522 8192000000 10357541296 10772852736 1407836672 14.665 100 60 121 0 1462182368 38672832 15672864
14:57:36 5525 8192000000 10362142528 10775859200 1407186608 14.659 100 73 146 1 1468337904 43405472 17745824
14:58:50 5324 8192000000 10351529152 10765697024 1378126800 14.400 99 65 125 1 1441845808 47204880 16514128
15:00:01 9290 8192000000 10327535616 10751422464 1380194880 14.418 100 45 90 2 1437290736 44360176 12735680
15:01:22 15773 8192000000 10318400864 10743648256 1368356112 14.312 100 32 64 0 1426213360 49027712 8829536
15:02:48 18422 8192000000 10273245808 10701680640 1321193856 13.888 99 25 50 0 1373207168 44738464 7274848
15:04:16 10470 8192000000 10279771072 10719055872 1335020240 14.012 99 23 46 0 1376011952 34628048 6363664
15:05:32 44106 8192000000 10282541920 10713899008 1351338224 14.160 100 28 54 0 1380699472 22553376 6807872
15:07:21 21883 8192000000 10273017632 10727321600 1357657776 14.216 100 13 26 0 1383581456 22473392 3450288
15:08:44 102839 8192000000 10273801184 10714685440 1331546112 13.981 97 10 20 1 1351380080 17408272 2425696
15:11:30 89400 8192000000 10264468688 10708217856 1329382960 13.962 97 16 31 0 1339827936 7947200 2497776
15:14:00 63602 8192000000 10305275488 10726715392 1350445616 14.151 98 23 47 0 1370189840 15044048 4700176
15:16:08 61676 8192000000 10297112832 10725224448 1356422800 14.205 98 20 38 0 1371785152 11164176 4198176
15:18:16 92001 8192000000 10305273280 10755420160 1375375696 14.375 99 10 21 0 1392189264 14351568 2462000
15:21:30 110712 8192000000 10320539648 10770821120 1410566096 14.689 100 29 56 0 1427020864 9959424 6495344
15:24:21 33960 8192000000 10328501376 10772389888 1397793664 14.575 99 40 81 1 1418305264 11826624 8684976
15:26:02 44700 8192000000 10303871888 10753110016 1384606112 14.458 98 22 44 0 1399788496 9622448 5559936
15:27:50 39456 8192000000 10331395424 10783424512 1411096688 14.694 100 23 45 1 1432366720 15802768 5467264
15:29:35 52768 8192000000 10299814512 10753003520 1377947984 14.398 98 22 42 0 1400525792 17572736 5005072
15:31:29 56194 8192000000 10288260864 10750959616 1371758576 14.343 98 13 26 0 1387671424 12559552 3353296
15:33:26 42917 8192000000 10302943248 10761924608 1369727472 14.325 98 19 36 1 1388131872 14148624 4255776
15:35:09 77372 8192000000 10306814512 10777321472 1390344864 14.509 99 24 48 0 1411468032 15762912 5360256
15:38:18 56215 8192000000 10303154784 10761375744 1391301968 14.517 99 14 27 0 1402923296 8686256 2935072
15:40:14 50978 8192000000 10261868672 10721042432 1287375296 13.580 92 15 30 0 1300940672 10367248 3198128
15:42:09 39244 8192000000 10283815280 10720296960 1322644528 13.901 97 32 64 1 1341148304 11477408 7026368
15:43:49 50661 8192000000 10269792288 10735648768 1365184336 14.284 100 28 56 0 1387247568 15667440 6395792
15:45:41 27930 8192000000 10291087712 10725474304 1321474864 13.890 96 45 88 0 1342790400 11394976 9920560
15:47:12 95315 8192000000 10294392976 10741870592 1338329024 14.042 97 17 34 0 1366474576 24945088 3200464
15:49:54 71278 8192000000 10286522608 10735779840 1322404144 13.898 96 17 34 0 1341423952 15335264 3684544
15:52:24 39160 8192000000 10297538944 10747928576 1378869584 14.406 100 32 64 1 1403356496 17424944 7061968
15:54:05 160653 8192000000 10257954288 10713837568 1305546448 13.746 95 20 40 0 1321728032 11623328 4558256
15:58:28 51021 8192000000 10263966544 10731323392 1356011248 14.202 100 6 13 0 1379592544 22071152 1510144
16:00:39 45036 8192000000 10281260096 10732691456 1367964160 14.309 100 15 30 0 1390698416 19219312 3514944
16:02:24 64183 8192000000 10268670144 10740895744 1371609792 14.341 100 11 22 0 1389807520 15341184 2856544
16:04:29 36999 8192000000 10284722688 10746818560 1365132016 14.283 99 9 18 0 1377941728 10661632 2148080
16:06:32 93829 8192000000 10284031968 10736414720 1327173488 13.942 96 16 32 0 1343079360 11945200 3960672
16:09:06 102251 8192000000 10290267584 10751393792 1361590656 14.252 98 15 30 0 1379441712 14213664 3637392
16:12:58 77603 8192000000 10310594000 10762760192 1380175856 14.418 99 17 34 0 1393850080 10318384 3355840
16:15:20 77282 8192000000 10312274416 10786631680 1374811632 14.370 98 14 28 0 1394674784 16822176 3040976
16:17:37 24281 8192000000 10305858048 10751426560 1361938784 14.255 98 41 80 0 1386951760 16067856 8945120
16:19:02 69422 8192000000 10267594160 10717417472 1349459392 14.143 97 32 64 0 1366508528 9441280 7607856
16:21:31 80057 8192000000 10289083872 10748887040 1358784320 14.226 98 23 46 0 1376318624 12258768 5275536
16:23:54 50788 8192000000 10270965360 10739113984 1351629168 14.162 98 17 34 0 1364538000 8736832 4172000
16:26:05 66955 8192000000 10277110320 10732134400 1329518416 13.963 96 20 40 0 1347604496 13519312 4566768
16:28:12 63232 8192000000 10257523328 10717671424 1326615488 13.937 97 11 23 1 1345855216 16416832 2822896
16:30:20 28821 8192000000 10252514048 10709458944 1346890832 14.119 99 28 54 1 1360903616 8156704 5856080
16:31:52 41161 8192000000 10264813616 10713862144 1304888512 13.740 95 16 33 0 1315338000 7199568 3249920
16:33:36 73249 8192000000 10255086912 10719035392 1338781104 14.046 98 18 36 1 1355413408 12581808 4050496
16:35:49 79128 8192000000 10268350176 10715959296 1306710544 13.756 96 11 22 0 1323476320 14554432 2211344
16:38:12 34269 8192000000 9322047952 9652727808 421034256 4.888 31 5 8 0 425405520 3603552 767712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-01-2023 16:39:51 to 15-01-2023 16:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 149 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 15-01-2023 16:40:07 to 15-01-2023 16:40:09.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          244128
MON$OLDEST_ACTIVE               244129
MON$OLDEST_SNAPSHOT             244129
MON$NEXT_TRANSACTION            244129
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-03 16:41:55.3470
MON$PAGES                       2047392
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33653 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33653 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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-oltp30 according to config parameter 'remove_isql_logs'

15.01.23 16:40:10. There are 3 logs in /var/tmp/logs-oltp30 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-01-15 16:40:12 No severe PSQL-related problems occured
15.01.23 16:40:14. End of report.