Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.09.2022 22: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 15582688 kB
MemAvailable 30576100 kB
Buffers 0 kB
Cached 16117736 kB
SwapCached 2032 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 2022-09-26 22:38:26
DTS_END                         2022-09-26 22:38:26.9830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50036
TRN_ID                          245185
ATT_ID                          651
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: 9470.53 19621 1136463
customer order: creation 181.45 356 21774
customer order: refuse 15.73 375 1888
order to supplier: creation 48.67 1284 5840
order to supplier: removal 3.68 84308 442
invoice (draft): creation 49.33 3278 5920
invoice (draft): removal 54.63 709 6555
invoice accept: apply 43.23 17104 5187
invoice accept: cancel 48.37 722 5804
customer reserve: creation 3985.45 170 478254
customer reserve: removal 4739.25 50 568710
realization accept: apply 87.16 200 10459
realization accept: cancel 77.98 108 9358
payment from customer: creation 62.75 124 7530
payment from customer: removal 5.83 94 700
payment to supplier: creation 50.89 159 6107
payment to supplier: removal 3.60 56 432
service: total inventory turnovers 4.80 1078 576
service: total monetary turnovers 6.73 25 808
service: aggregate perf. data 0.96 20570 115
service: refresh index statistics 0.03 261644 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-09-2022 22:39:04 to 26-09-2022 22:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5972
TEST_TIME 2 5667
TEST_TIME 3 5973
TEST_TIME 4 5647
TEST_TIME 5 5924
TEST_TIME 6 5734
TEST_TIME 7 5791
TEST_TIME 8 5910
TEST_TIME 9 6165
TEST_TIME 10 6283
TEST_TIME 11 6887
TEST_TIME 12 7159
TEST_TIME 13 7373
TEST_TIME 14 7613
TEST_TIME 15 7645
TEST_TIME 16 7690
TEST_TIME 17 7719
TEST_TIME 18 8304
TEST_TIME 19 8315
TEST_TIME 20 8799
TEST_TIME 21 9095
TEST_TIME 22 9273
TEST_TIME 23 9422
TEST_TIME 24 9348
TEST_TIME 25 9325
TEST_TIME 26 9640
TEST_TIME 27 9734
TEST_TIME 28 10974
TEST_TIME 29 10762
TEST_TIME 30 10767
TEST_TIME 31 10717
TEST_TIME 32 10564
TEST_TIME 33 10624
TEST_TIME 34 10455
TEST_TIME 35 10632
TEST_TIME 36 10623
TEST_TIME 37 10579
TEST_TIME 38 10340
TEST_TIME 39 10262
TEST_TIME 40 10207
TEST_TIME 41 10377
TEST_TIME 42 10229
TEST_TIME 43 10079
TEST_TIME 44 10052
TEST_TIME 45 10046
TEST_TIME 46 10026
TEST_TIME 47 10054
TEST_TIME 48 9928
TEST_TIME 49 9871
TEST_TIME 50 9847
TEST_TIME 51 9678
TEST_TIME 52 9775
TEST_TIME 53 9677
TEST_TIME 54 9666
TEST_TIME 55 9569
TEST_TIME 56 9502
TEST_TIME 57 9658
TEST_TIME 58 9580
TEST_TIME 59 9658
TEST_TIME 60 9616
TEST_TIME 61 9618
TEST_TIME 62 9695
TEST_TIME 63 9699
TEST_TIME 64 9749
TEST_TIME 65 9814
TEST_TIME 66 9759
TEST_TIME 67 9845
TEST_TIME 68 9816
TEST_TIME 69 9849
TEST_TIME 70 9866
TEST_TIME 71 9964
TEST_TIME 72 9897
TEST_TIME 73 9785
TEST_TIME 74 9758
TEST_TIME 75 9721
TEST_TIME 76 9675
TEST_TIME 77 9717
TEST_TIME 78 9655
TEST_TIME 79 9605
TEST_TIME 80 9566
TEST_TIME 81 9553
TEST_TIME 82 9530
TEST_TIME 83 9454
TEST_TIME 84 9483
TEST_TIME 85 9487
TEST_TIME 86 9450
TEST_TIME 87 9507
TEST_TIME 88 9534
TEST_TIME 89 9506
TEST_TIME 90 9523
TEST_TIME 91 9545
TEST_TIME 92 9597
TEST_TIME 93 9626
TEST_TIME 94 9589
TEST_TIME 95 9723
TEST_TIME 96 9686
TEST_TIME 97 9699
TEST_TIME 98 9664
TEST_TIME 99 9684
TEST_TIME 100 9742
TEST_TIME 101 9670
TEST_TIME 102 9594
TEST_TIME 103 9576
TEST_TIME 104 9564
TEST_TIME 105 9510
TEST_TIME 106 9484
TEST_TIME 107 9471
TEST_TIME 108 9452
TEST_TIME 109 9453
TEST_TIME 110 9435
TEST_TIME 111 9410
TEST_TIME 112 9403
TEST_TIME 113 9437
TEST_TIME 114 9498
TEST_TIME 115 9503
TEST_TIME 116 9472
TEST_TIME 117 9499
TEST_TIME 118 9522
TEST_TIME 119 9526
TEST_TIME 120 9471

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-09-2022 22:39:07 to 26-09-2022 22:39:11.

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 1579770 1579770 0 0.00 0 19398 6 0 0
doc_list_biud 1579762 1579762 0 0.00 0 25480 24 0 0
sp_add_invoice_to_stock 5344 5187 157 2.93 39 194596 17105 0 157
sp_cancel_adding_invoice 5804 5804 0 0.00 4 56837 723 0 0
sp_cancel_client_order 1888 1888 0 0.00 4 22847 376 0 0
sp_cancel_customer_reserve 568710 568710 0 0.00 0 25482 50 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 2 12198 95 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 2 3315 56 0 0
sp_cancel_supplier_invoice 6564 6555 9 0.13 1 21188 710 0 9
sp_cancel_supplier_order 442 442 0 0.00 6 888778 84308 0 0
sp_cancel_write_off 9358 9358 0 0.00 1 22669 109 0 0
sp_client_order 21774 21774 0 0.00 3 23776 356 0 0
sp_customer_reserve 478254 478254 0 0.00 0 132471 170 0 0
sp_fill_shopping_cart 33505 33505 0 0.00 1 10750 37 0 0
sp_fill_shopping_cart_clo_res 478241 478241 0 0.00 0 13543 12 0 0
sp_get_clo_for_invoice 5174 5174 0 0.00 0 15076 265 0 0
sp_kill_cost_storno 16294 16294 0 0.00 0 6675 50 0 0
sp_kill_qstorno_ret_qs2qd 1153775 1153775 0 0.00 0 25478 28 0 0
sp_kill_qty_storno 608387 608387 0 0.00 0 25479 61 0 0
sp_lock_selected_doc 21724 21724 0 0.00 0 9080 5 0 0
sp_make_cost_storno 29270 29270 0 0.00 0 18104 52 0 0
sp_multiply_rows_for_pdistr 29265 29265 0 0.00 0 7548 26 0 0
sp_multiply_rows_for_qdistr 21774 21774 0 0.00 0 10370 141 0 0
sp_pay_from_customer 7530 7530 0 0.00 4 17775 125 0 0
sp_pay_to_supplier 6107 6107 0 0.00 4 19290 160 0 0
sp_payment_common 13635 13635 0 0.00 1 16509 31 0 0
sp_qd_handle_on_cancel_clo 1888 1888 0 0.00 0 22839 331 0 0
sp_qd_handle_on_invoice_upd_sts 10976 10976 0 0.00 0 25233 316 0 0
sp_qd_handle_on_reserve_upd_sts 10458 10458 0 0.00 0 23693 64 0 0
sp_reserve_write_off 10459 10459 0 0.00 6 23975 200 0 0
sp_supplier_invoice 5920 5920 0 0.00 5 96392 3278 0 0
sp_supplier_order 5840 5840 0 0.00 4 39812 1284 0 0
srv_aggregate_perf_data 115 115 0 0.00 2092 346631 20571 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2103 188307 46481 0 0
srv_find_qd_qs_mism 1086702 1086702 0 0.00 0 19398 5 0 0
srv_make_invnt_saldo 576 576 0 0.00 30 46706 1078 0 0
srv_make_money_saldo 808 808 0 0.00 1 991 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 186508 430069 261645 0 0
t$perf-abend:sp_add_invoice_to_stock 157 157 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5187 5187 0 0.00 0 8759 154 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 7386 130 0 0
t$perf-norm:sp_cancel_client_order 1888 1888 0 0.00 0 6118 15 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 1165 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 3047 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 382 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 449 449 0 0.00 0 6942 37 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 25532 1476 0 0
t$perf-norm:sp_cancel_write_off 1037 1037 0 0.00 0 4047 14 0 0
t$perf-norm:sp_client_order 21774 21774 0 0.00 0 5964 15 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 1146 4 0 0
t$perf-norm:sp_pay_from_customer 7530 7530 0 0.00 0 5996 8 0 0
t$perf-norm:sp_pay_to_supplier 6107 6107 0 0.00 0 5465 12 0 0
t$perf-norm:sp_reserve_write_off 10459 10459 0 0.00 0 8960 11 0 0
t$perf-norm:sp_supplier_invoice 5920 5920 0 0.00 0 4767 20 0 0
t$perf-norm:sp_supplier_order 5840 5840 0 0.00 0 5722 11 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 563 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 6 0 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 1908 28 0 0
t$perf-norm:srv_make_money_saldo 808 808 0 0.00 0 1053 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 2 0 0
v_add_invoice_to_stock 10658 10344 314 2.94 0 8313 28 0 314
v_all_customers 40246 40246 0 0.00 0 105 1 0 0
v_all_suppliers 32576 32576 0 0.00 0 106 1 0 0
v_all_wares 43548 43548 0 0.00 0 170 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 1690 10 0 0
v_cancel_client_order 3776 3776 0 0.00 0 12169 13 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 12171 17 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 5898 10 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 3025 6 0 0
v_cancel_supplier_invoice 916 898 18 1.96 0 6708 38 0 18
v_cancel_supplier_order 884 884 0 0.00 0 14427 24 0 0
v_cancel_write_off 2074 2074 0 0.00 0 2564 14 0 0
v_max_id_clo_ord 5829 5829 0 0.00 0 1013 1 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 2053 12 0 0
v_max_id_ord_sup 5902 5902 0 0.00 0 4996 4 0 0
v_max_non_paid_invoice 1549 1549 0 0.00 0 352 3 0 0
v_max_non_paid_realizn 3912 3912 0 0.00 0 6238 7 0 0
v_min_id_clo_ord 5829 5829 0 0.00 0 2186 6 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 4181 15 0 0
v_min_id_ord_sup 5902 5902 0 0.00 0 5058 8 0 0
v_min_non_paid_invoice 6106 6106 0 0.00 0 9212 25 0 0
v_min_non_paid_realizn 7529 7529 0 0.00 0 12962 24 0 0
v_our_firm 10536 10536 0 0.00 0 14 1 0 0
v_reserve_write_off 20916 20916 0 0.00 0 17717 16 0 0
x_lock_depdocs_on_canc_invoice 11910 11910 0 0.00 0 16645 117 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 6193 322 0 0
x_make_qstorno_1000_1200 5840 5840 0 0.00 1 39805 1214 0 0
x_make_qstorno_1200_2000 5920 5920 0 0.00 2 96372 3134 0 0
x_make_qstorno_2100_3300 478254 478254 0 0.00 0 132471 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-09-2022 22:39:12 to 26-09-2022 22:39:16.

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
20:08:30 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:32 20793 8192000000 9894673536 10205978624 973576192 10.622 100 84 168 0 1002034992 5824528 22634272
20:10:58 16187 8192000000 9950226240 10272968704 1031644176 11.184 100 89 178 0 1062704112 7953456 23106480
20:12:16 15969 8192000000 9978570608 10309996544 1062342816 11.479 100 82 164 0 1092071040 8986928 20741296
20:13:43 12632 8192000000 10022555712 10367746048 1108637120 11.920 100 76 152 0 1136086928 9198272 18251536
20:15:00 5995 8192000000 10063148032 10422255616 1157143056 12.376 100 74 147 0 1184577696 10197264 17237376
20:16:07 4463 8192000000 10110527424 10479738880 1202997696 12.804 100 74 146 0 1230911856 11150576 16763584
20:17:15 1845 8192000000 10152347296 10524221440 1234219312 13.093 100 80 160 0 1265696672 14040864 17436496
20:18:19 1387 8192000000 10173505584 10550013952 1262298048 13.351 100 85 168 1 1292675456 11984560 18392848
20:19:22 1720 8192000000 10202536208 10588069888 1292647440 13.628 100 80 160 0 1322720704 12417056 17656208
20:20:26 15165 8192000000 10200624304 10598305792 1299224608 13.688 100 62 125 0 1323965600 11656496 13084496
20:21:42 3010 8192000000 10191986400 10604060672 1308544080 13.773 100 30 60 0 1322124320 7110624 6469616
20:22:47 3061 8192000000 10209559472 10616582144 1316650160 13.846 100 36 72 0 1330371264 5949504 7771600
20:23:50 56367 8192000000 10212311584 10632359936 1325298032 13.925 100 30 60 1 1337207344 4942480 6966832
20:25:48 6742 8192000000 10225690320 10644332544 1334980832 14.012 100 23 47 0 1345582912 5677392 4924688
20:26:56 7135 8192000000 10229576224 10651729920 1341806272 14.074 100 30 59 1 1352967664 4976560 6184832
20:28:06 10680 8192000000 10218219936 10629672960 1288313600 13.589 96 26 54 2 1299542128 5216720 6011808
20:29:17 31314 8192000000 10192397264 10603266048 1244159968 13.185 94 45 90 1 1264603200 9478176 10965056
20:30:51 5637 8192000000 10164430096 10567712768 1261079184 13.340 99 25 51 0 1272766336 6334752 5352400
20:31:57 6595 8192000000 10144524560 10543837184 1219794608 12.960 97 20 40 0 1235277504 10140960 5341936
20:33:08 60372 8192000000 10128777360 10528772096 1171655024 12.512 93 22 44 0 1182508528 5703456 5150048
20:35:09 5951 8192000000 10167952704 10576904192 1281796384 13.529 100 26 52 0 1293130240 5412752 5921104
20:36:15 9945 8192000000 10184922432 10600456192 1298150640 13.678 100 16 32 0 1307735776 5515808 4069328
20:37:26 7773 8192000000 10207604608 10622222336 1311351696 13.798 100 20 41 0 1321138768 5503408 4283664
20:38:35 36792 8192000000 10228334704 10639814656 1330021168 13.967 100 38 76 0 1345604448 7146928 8436352
20:40:27 37000 8192000000 10263520304 10681876480 1356770096 14.208 100 42 85 0 1373324976 7703744 8851136
20:42:08 21470 8192000000 10286255008 10704547840 1376449840 14.385 100 58 117 0 1399661456 10501584 12710032
20:43:36 18321 8192000000 10282723680 10713018368 1382480928 14.439 100 46 92 0 1403696288 10214160 11001200
20:44:58 4202 8192000000 10308849216 10735218688 1398595984 14.582 100 37 74 0 1422658416 15438704 8623728
20:46:07 21469 8192000000 10320463280 10746732544 1404726736 14.637 100 49 97 0 1433885360 17856000 11302624
20:47:33 13917 8192000000 10337062544 10762252288 1411330048 14.696 100 53 104 2 1458843168 33902240 13610880
20:49:22 4901 8192000000 10338393280 10768080896 1416247248 14.739 100 51 102 0 1454680880 26382640 12050992
20:50:38 10826 8192000000 10326865760 10756136960 1406554992 14.653 100 40 79 1 1441254784 25001856 9697936
20:51:55 2102 8192000000 10309615584 10727583744 1354934480 14.192 97 68 133 1 1396299840 25700928 15664432
20:53:04 2672 8192000000 10332872064 10747432960 1395488080 14.555 100 71 139 0 1437159488 25076752 16594656
20:54:12 2536 8192000000 10351858672 10768138240 1408220160 14.668 100 75 147 0 1459618368 34121280 17276928
20:55:19 3963 8192000000 10364247040 10778800128 1410769760 14.691 100 79 158 0 1470595984 40507456 19318768
20:56:28 3274 8192000000 10337767488 10765611008 1395135792 14.552 100 61 120 0 1444244864 33569600 15539472
20:57:39 6425 8192000000 10303523824 10714562560 1352493456 14.170 99 54 108 0 1405165616 38349744 14322416
20:58:50 26860 8192000000 10313457744 10740056064 1363907232 14.272 99 35 70 0 1416911280 43116576 9887472
21:00:27 20020 8192000000 10320996528 10746937344 1377634560 14.395 100 32 64 0 1429254272 42566096 9053616
21:01:54 34010 8192000000 10303202576 10719543296 1351159088 14.158 99 47 94 2 1405378720 42214192 12005440
21:03:34 24035 8192000000 10303924736 10740232192 1367965184 14.309 100 23 46 0 1419980096 45514944 6499968
21:05:00 39109 8192000000 10285595472 10721071104 1340971888 14.066 100 18 36 0 1388849888 42500512 5377488
21:06:53 40878 8192000000 10261195968 10683686912 1312727232 13.811 98 17 34 1 1331231984 14271072 4233680
21:08:39 30146 8192000000 10255061824 10686251008 1329442016 13.962 98 32 65 2 1344049024 7737408 6869600
21:10:10 34751 8192000000 10292927280 10726744064 1372403392 14.349 100 29 58 0 1398718336 19731024 6583920
21:11:45 100703 8192000000 10279828992 10730262528 1342156592 14.077 97 12 24 0 1363346208 18216800 2972816
21:14:38 48299 8192000000 10293024912 10723893248 1353472064 14.179 98 14 23 1 1363023616 7453072 2098480
21:16:28 101589 8192000000 10300303184 10734415872 1366736864 14.298 99 34 65 0 1383711328 9553552 7420912
21:19:16 188306 8192000000 10292091472 10739945472 1339789824 14.056 97 18 37 0 1359445808 15530880 4125104
21:23:26 32170 8192000000 10298726672 10739015680 1375724704 14.378 99 42 80 1 1394767392 8027856 11014832
21:25:12 25506 8192000000 10287440304 10749501440 1383431936 14.447 100 12 24 0 1396015968 9988912 2595120
21:27:36 56758 8192000000 10291987840 10749976576 1371293760 14.339 98 16 32 0 1389883424 14893200 3696464
21:29:47 45880 8192000000 10292642480 10753900544 1377911824 14.398 99 33 66 0 1400358976 13944928 8502224
21:31:42 39968 8192000000 10298246240 10760171520 1394346640 14.545 100 20 40 0 1408142880 9203728 4592512
21:33:26 59601 8192000000 10301141472 10775392256 1396032976 14.560 100 14 28 0 1414785392 15523232 3229184
21:35:30 72725 8192000000 10286489520 10744250368 1345981184 14.111 96 8 16 0 1360435872 12795776 1658912
21:37:43 49197 8192000000 10296861040 10737868800 1329480608 13.962 95 35 70 0 1348669072 11156800 8031664
21:39:33 47143 8192000000 10271032048 10734809088 1316242752 13.843 95 15 28 0 1329996832 10455568 3298512
21:41:26 30819 8192000000 10301918992 10742476800 1322582352 13.900 95 37 74 0 1345419856 15183456 7654048
21:42:57 75588 8192000000 10278290112 10734587904 1303335744 13.726 95 14 28 0 1324159632 18186816 2637072
21:45:14 40452 8192000000 10295611552 10739154944 1369201424 14.320 100 30 60 0 1393242320 17779312 6261584
21:46:59 84011 8192000000 10263430800 10711887872 1302443488 13.717 95 9 18 0 1321280784 17128720 1708576
21:49:24 82829 8192000000 10263790528 10713403392 1300083072 13.696 96 19 38 0 1319852832 15616496 4153264
21:51:47 110640 8192000000 10250605344 10700099584 1286746048 13.575 95 18 36 0 1302338832 10900208 4692576
21:55:01 46189 8192000000 10227684528 10678202368 1304076720 13.732 97 32 64 0 1321220512 10221568 6922224
21:57:36 64986 8192000000 10250283872 10704171008 1356148560 14.203 100 12 24 1 1368370928 9223328 2999040
21:59:42 38017 8192000000 10249140864 10706612224 1358116784 14.220 100 15 30 0 1368138592 6570112 3451696
22:01:20 64202 8192000000 10255622592 10718593024 1349571664 14.144 99 16 32 0 1366417536 12863920 3981952
22:03:34 85604 8192000000 10269430384 10730655744 1342062368 14.076 99 12 24 1 1356164400 11658800 2443232
22:06:00 44776 8192000000 10277452416 10730135552 1366502768 14.296 99 34 69 1 1384519088 10556624 7459696
22:07:51 42641 8192000000 10268123376 10726727680 1351509360 14.161 98 36 72 0 1372132272 12649664 7973248
22:09:43 106362 8192000000 10286699200 10746548224 1346708192 14.118 97 21 42 0 1362533248 11333888 4491168
22:12:30 41757 8192000000 10313413456 10768887808 1395015488 14.551 100 27 54 0 1417215984 16113840 6086656
22:14:12 74204 8192000000 10301081664 10752946176 1360909120 14.246 98 25 50 1 1376502976 10058144 5535712
22:16:30 33501 8192000000 10300008240 10745294848 1360221120 14.239 98 39 77 1 1378698832 10334880 8142832
22:18:06 106752 8192000000 10291665856 10754695168 1363840960 14.272 98 15 30 1 1384713984 17467216 3405808
22:21:34 41014 8192000000 10267694896 10732187648 1363631008 14.270 98 34 68 0 1381243104 9886352 7725744
22:23:15 34547 8192000000 10258946464 10718261248 1358562384 14.224 99 10 20 0 1369707168 8500624 2644160
22:24:50 70725 8192000000 10275457616 10742054912 1346889616 14.119 97 15 30 1 1360908272 10516816 3501840
22:27:04 27115 8192000000 10263191776 10718478336 1352507712 14.170 98 21 43 0 1364148320 7107904 4532704
22:28:32 52250 8192000000 10265646000 10720313344 1337770928 14.037 97 16 32 0 1351492080 9961696 3759456
22:30:54 71099 8192000000 10273485056 10733064192 1330136816 13.968 97 11 22 0 1349814720 17077408 2600496
22:33:31 47491 8192000000 10284379440 10732400640 1345445056 14.106 98 21 38 0 1361473024 12059776 3968192
22:35:22 51565 8192000000 10269345744 10705702912 1293479888 13.636 95 25 50 0 1309470656 10345344 5645424
22:37:13 51583 8192000000 10267221168 10700775424 1294644384 13.647 96 39 78 0 1313009808 10259120 8106304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-09-2022 22:39:18 to 26-09-2022 22:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 157 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-09-2022 22:39:34 to 26-09-2022 22:39:36.

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          242087
MON$OLDEST_ACTIVE               245343
MON$OLDEST_SNAPSHOT             245343
MON$NEXT_TRANSACTION            245343
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                       2028832
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.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 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'

26.09.22 22:39:37. 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
2022-09-26 22:39:40 No severe PSQL-related problems occured
26.09.22 22:39:41. End of report.