Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.01.2023 22: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 15238852 kB
MemAvailable 30459856 kB
Buffers 0 kB
Cached 16498224 kB
SwapCached 1712 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-20 22:38:33
DTS_END                         2023-01-20 22:38:33.5840
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/55632
TRN_ID                          247430
ATT_ID                          670
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: 9562.17 19060 1147461
customer order: creation 183.90 340 22068
customer order: refuse 15.97 297 1916
order to supplier: creation 49.07 1303 5888
order to supplier: removal 3.48 89086 418
invoice (draft): creation 49.18 3274 5901
invoice (draft): removal 52.78 800 6334
invoice accept: apply 44.75 17867 5370
invoice accept: cancel 48.25 782 5790
customer reserve: creation 4100.33 179 492039
customer reserve: removal 4697.41 50 563689
realization accept: apply 89.15 195 10698
realization accept: cancel 89.18 101 10701
payment from customer: creation 64.79 100 7775
payment from customer: removal 5.59 60 671
payment to supplier: creation 51.60 121 6192
payment to supplier: removal 3.63 76 436
service: total inventory turnovers 5.15 1146 618
service: total monetary turnovers 7.03 26 843
service: aggregate perf. data 0.92 19514 110
service: refresh index statistics 0.03 245883 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-01-2023 22:40:19 to 20-01-2023 22:40:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5439
TEST_TIME 2 6007
TEST_TIME 3 6505
TEST_TIME 4 6086
TEST_TIME 5 6102
TEST_TIME 6 5677
TEST_TIME 7 5804
TEST_TIME 8 6160
TEST_TIME 9 6341
TEST_TIME 10 6209
TEST_TIME 11 6153
TEST_TIME 12 6659
TEST_TIME 13 6783
TEST_TIME 14 7216
TEST_TIME 15 7796
TEST_TIME 16 8034
TEST_TIME 17 8370
TEST_TIME 18 8884
TEST_TIME 19 9425
TEST_TIME 20 9465
TEST_TIME 21 9542
TEST_TIME 22 9823
TEST_TIME 23 10018
TEST_TIME 24 9814
TEST_TIME 25 9820
TEST_TIME 26 9718
TEST_TIME 27 9787
TEST_TIME 28 9747
TEST_TIME 29 10310
TEST_TIME 30 10639
TEST_TIME 31 10865
TEST_TIME 32 10714
TEST_TIME 33 10690
TEST_TIME 34 10691
TEST_TIME 35 10774
TEST_TIME 36 10562
TEST_TIME 37 10546
TEST_TIME 38 10575
TEST_TIME 39 10346
TEST_TIME 40 10200
TEST_TIME 41 10238
TEST_TIME 42 10146
TEST_TIME 43 10118
TEST_TIME 44 10066
TEST_TIME 45 10140
TEST_TIME 46 10161
TEST_TIME 47 10148
TEST_TIME 48 10049
TEST_TIME 49 9984
TEST_TIME 50 9944
TEST_TIME 51 9922
TEST_TIME 52 9884
TEST_TIME 53 9822
TEST_TIME 54 9869
TEST_TIME 55 9744
TEST_TIME 56 9805
TEST_TIME 57 9772
TEST_TIME 58 9769
TEST_TIME 59 9745
TEST_TIME 60 9885
TEST_TIME 61 9853
TEST_TIME 62 9947
TEST_TIME 63 9948
TEST_TIME 64 10050
TEST_TIME 65 10030
TEST_TIME 66 9970
TEST_TIME 67 10019
TEST_TIME 68 10110
TEST_TIME 69 10092
TEST_TIME 70 10088
TEST_TIME 71 9980
TEST_TIME 72 9917
TEST_TIME 73 10020
TEST_TIME 74 9939
TEST_TIME 75 9867
TEST_TIME 76 9822
TEST_TIME 77 9770
TEST_TIME 78 9742
TEST_TIME 79 9649
TEST_TIME 80 9683
TEST_TIME 81 9720
TEST_TIME 82 9695
TEST_TIME 83 9684
TEST_TIME 84 9686
TEST_TIME 85 9686
TEST_TIME 86 9720
TEST_TIME 87 9730
TEST_TIME 88 9777
TEST_TIME 89 9775
TEST_TIME 90 9788
TEST_TIME 91 9781
TEST_TIME 92 9829
TEST_TIME 93 9847
TEST_TIME 94 9893
TEST_TIME 95 9827
TEST_TIME 96 9747
TEST_TIME 97 9786
TEST_TIME 98 9757
TEST_TIME 99 9760
TEST_TIME 100 9733
TEST_TIME 101 9682
TEST_TIME 102 9681
TEST_TIME 103 9620
TEST_TIME 104 9596
TEST_TIME 105 9557
TEST_TIME 106 9532
TEST_TIME 107 9551
TEST_TIME 108 9596
TEST_TIME 109 9638
TEST_TIME 110 9644
TEST_TIME 111 9660
TEST_TIME 112 9635
TEST_TIME 113 9606
TEST_TIME 114 9635
TEST_TIME 115 9674
TEST_TIME 116 9658
TEST_TIME 117 9691
TEST_TIME 118 9703
TEST_TIME 119 9626
TEST_TIME 120 9573

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-01-2023 22:40:23 to 20-01-2023 22:40:27.

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 1601657 1601657 0 0.00 0 17213 6 0 0
doc_list_biud 1601653 1601653 0 0.00 0 56067 24 0 0
sp_add_invoice_to_stock 5527 5370 157 2.84 18 203401 17868 0 157
sp_cancel_adding_invoice 5790 5790 0 0.00 4 53283 782 0 0
sp_cancel_client_order 1916 1916 0 0.00 4 6021 297 0 0
sp_cancel_customer_reserve 563689 563689 0 0.00 0 56070 51 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 2 1860 60 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 5352 76 0 0
sp_cancel_supplier_invoice 6342 6334 8 0.12 1 30325 800 0 8
sp_cancel_supplier_order 418 418 0 0.00 7 870706 89086 0 0
sp_cancel_write_off 10701 10701 0 0.00 1 11791 102 0 0
sp_client_order 22068 22068 0 0.00 3 28781 341 0 0
sp_customer_reserve 492039 492039 0 0.00 0 104565 179 0 0
sp_fill_shopping_cart 33830 33830 0 0.00 1 12546 38 0 0
sp_fill_shopping_cart_clo_res 492024 492024 0 0.00 0 17897 13 0 0
sp_get_clo_for_invoice 5354 5354 0 0.00 0 29483 251 0 0
sp_kill_cost_storno 17598 17598 0 0.00 0 11788 51 0 0
sp_kill_qstorno_ret_qs2qd 1144831 1144831 0 0.00 0 56064 28 0 0
sp_kill_qty_storno 604898 604898 0 0.00 0 56067 62 0 0
sp_lock_selected_doc 22158 22158 0 0.00 0 8271 6 0 0
sp_make_cost_storno 30018 30018 0 0.00 0 17204 55 0 0
sp_multiply_rows_for_pdistr 30017 30017 0 0.00 0 7608 25 0 0
sp_multiply_rows_for_qdistr 22067 22067 0 0.00 0 17684 150 0 0
sp_pay_from_customer 7775 7775 0 0.00 4 10826 100 0 0
sp_pay_to_supplier 6192 6192 0 0.00 4 11623 121 0 0
sp_payment_common 13967 13967 0 0.00 1 10750 29 0 0
sp_qd_handle_on_cancel_clo 1916 1916 0 0.00 0 6013 274 0 0
sp_qd_handle_on_invoice_upd_sts 11142 11142 0 0.00 0 20131 315 0 0
sp_qd_handle_on_reserve_upd_sts 10698 10698 0 0.00 0 27554 64 0 0
sp_reserve_write_off 10698 10698 0 0.00 6 27670 195 0 0
sp_supplier_invoice 5901 5901 0 0.00 6 76813 3274 0 0
sp_supplier_order 5888 5888 0 0.00 5 32688 1303 0 0
srv_aggregate_perf_data 110 110 0 0.00 2871 169555 19515 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1201 144354 43228 0 0
srv_find_qd_qs_mism 1095757 1095757 0 0.00 0 9240 6 0 0
srv_make_invnt_saldo 618 618 0 0.00 24 57235 1146 0 0
srv_make_money_saldo 843 843 0 0.00 0 371 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 173787 349367 245884 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 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5370 5370 0 0.00 0 14765 174 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 0 4689 94 0 0
t$perf-norm:sp_cancel_client_order 1916 1916 0 0.00 0 5799 12 0 0
t$perf-norm:sp_cancel_customer_reserve 776 776 0 0.00 0 3887 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 3975 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 2283 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 2202 22 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 26433 1614 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 3097 11 0 0
t$perf-norm:sp_client_order 22068 22068 0 0.00 0 8230 16 0 0
t$perf-norm:sp_customer_reserve 1089 1089 0 0.00 0 2897 7 0 0
t$perf-norm:sp_pay_from_customer 7775 7775 0 0.00 0 7386 11 0 0
t$perf-norm:sp_pay_to_supplier 6192 6192 0 0.00 0 4865 9 0 0
t$perf-norm:sp_reserve_write_off 10698 10698 0 0.00 0 9738 13 0 0
t$perf-norm:sp_supplier_invoice 5901 5901 0 0.00 0 5658 17 0 0
t$perf-norm:sp_supplier_order 5888 5888 0 0.00 0 6942 16 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 2177 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 123 3 0 0
t$perf-norm:srv_make_invnt_saldo 618 618 0 0.00 0 1625 21 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 1711 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 5 0 0
v_add_invoice_to_stock 11008 10694 314 2.85 0 8922 20 0 314
v_all_customers 40798 40798 0 0.00 0 116 1 0 0
v_all_suppliers 32228 32228 0 0.00 0 25 1 0 0
v_all_wares 44134 44134 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 873 6 0 0
v_cancel_client_order 3832 3832 0 0.00 0 2290 4 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 1381 5 0 0
v_cancel_customer_reserve 1552 1552 0 0.00 0 1239 6 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 2924 11 0 0
v_cancel_supplier_invoice 814 798 16 1.96 0 394 13 0 16
v_cancel_supplier_order 836 836 0 0.00 0 266 3 0 0
v_cancel_write_off 2096 2096 0 0.00 0 5547 19 0 0
v_max_id_clo_ord 5883 5883 0 0.00 0 3675 2 0 0
v_max_id_clo_res 1089 1089 0 0.00 0 3203 15 0 0
v_max_id_ord_sup 5879 5879 0 0.00 0 4699 5 0 0
v_max_non_paid_invoice 1840 1840 0 0.00 0 1458 4 0 0
v_max_non_paid_realizn 3859 3859 0 0.00 0 6317 7 0 0
v_min_id_clo_ord 5883 5883 0 0.00 0 1229 5 0 0
v_min_id_clo_res 1089 1089 0 0.00 0 3195 12 0 0
v_min_id_ord_sup 5879 5879 0 0.00 0 8702 12 0 0
v_min_non_paid_invoice 6192 6192 0 0.00 0 7496 19 0 0
v_min_non_paid_realizn 7775 7775 0 0.00 0 10746 20 0 0
v_our_firm 11168 11168 0 0.00 0 22 1 0 0
v_reserve_write_off 21396 21396 0 0.00 0 5385 8 0 0
x_lock_depdocs_on_canc_invoice 11725 11725 0 0.00 0 19390 109 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 11525 440 0 0
x_make_qstorno_1000_1200 5888 5888 0 0.00 2 32664 1225 0 0
x_make_qstorno_1200_2000 5901 5901 0 0.00 2 70335 3137 0 0
x_make_qstorno_2100_3300 492039 492039 0 0.00 0 104564 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-01-2023 22:40:28 to 20-01-2023 22:40:32.

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:37 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:38 13806 8192000000 9896183296 10204418048 981637184 10.700 100 86 172 0 1009492096 5514896 22340016
20:10:59 15601 8192000000 9934199632 10254446592 1019993680 11.072 100 84 168 0 1048807392 7266432 21547280
20:12:20 17482 8192000000 9975978624 10304634880 1055228864 11.411 100 90 181 0 1087030160 8798720 23002576
20:13:42 14429 8192000000 10021750208 10364444672 1108943408 11.922 100 80 160 0 1137658000 9192432 19522160
20:14:59 6120 8192000000 10073672928 10430058496 1160269264 12.406 100 74 148 0 1188379568 10747232 17363072
20:16:05 4356 8192000000 10116663760 10480193536 1201303504 12.788 100 83 164 1 1232378784 12728400 18346880
20:17:13 2324 8192000000 10141665040 10514972672 1233482848 13.086 100 78 154 0 1264198320 14020864 16694608
20:18:18 2554 8192000000 10170469792 10549604352 1258063504 13.312 100 81 159 0 1289234480 13983584 17187392
20:19:22 1305 8192000000 10201089584 10583511040 1285963840 13.567 100 90 180 0 1319571280 12498800 21108640
20:20:25 17871 8192000000 10204859040 10601267200 1301496416 13.709 100 64 125 0 1323049984 7330256 14223312
20:21:44 5315 8192000000 10195834640 10605961216 1304747680 13.738 100 29 58 0 1319309856 8265088 6297088
20:22:50 10528 8192000000 10196130544 10614403072 1315190480 13.833 100 16 32 0 1324214608 5351344 3672784
20:24:03 42987 8192000000 10222959056 10640961536 1326620080 13.937 100 35 67 0 1343426528 9103488 7702960
20:25:47 5941 8192000000 10228158928 10644430848 1332998848 13.994 100 31 61 0 1346148976 6543568 6606560
20:26:55 7547 8192000000 10229883296 10653360128 1340501936 14.062 100 20 40 0 1351116832 5548688 5066208
20:28:05 69909 8192000000 10195086544 10617544704 1252463392 13.261 94 30 58 2 1264563008 4789824 7309792
20:30:17 8150 8192000000 10153629904 10562027520 1217802496 12.941 93 19 35 0 1226445728 4907296 3735936
20:31:28 6021 8192000000 10116083536 10508595200 1172692272 12.522 93 23 44 1 1182882528 5288496 4901760
20:32:35 49205 8192000000 10138163184 10535530496 1235329440 13.103 99 25 48 0 1246190496 5324352 5536704
20:34:25 4303 8192000000 10150774896 10549047296 1244027648 13.183 99 37 75 0 1260273872 7780992 8465232
20:35:33 6389 8192000000 10165538144 10571849728 1267122608 13.395 99 24 46 0 1277902208 5271696 5507904
20:36:40 6781 8192000000 10183601920 10592247808 1291126288 13.614 100 17 32 0 1299936976 4839680 3971008
20:37:49 50182 8192000000 10209786064 10621046784 1310163776 13.788 100 51 100 1 1328630912 6220496 12246640
20:39:50 10513 8192000000 10236899824 10644803584 1335857984 14.020 100 53 104 2 1358314288 10647168 11809136
20:41:05 15106 8192000000 10269695856 10683281408 1357902272 14.219 100 48 93 0 1382444880 14514848 10027760
20:42:33 20564 8192000000 10296564848 10712395776 1378843872 14.406 100 45 90 0 1406660784 17596544 10220368
20:43:56 7240 8192000000 10304487984 10723725312 1390872384 14.514 100 45 90 0 1419809664 18713808 10223472
20:45:09 16576 8192000000 10312162672 10733436928 1395333904 14.553 100 43 86 0 1426851152 20908848 10608400
20:46:40 4362 8192000000 10328864144 10749722624 1404705904 14.637 100 62 119 0 1442528336 23723856 14098576
20:47:47 3815 8192000000 10337819552 10763948032 1412855312 14.709 100 57 108 1 1447076064 20921888 13298864
20:48:58 11350 8192000000 10344424208 10773827584 1418280096 14.757 100 55 110 0 1459262224 27123728 13858400
20:50:15 5692 8192000000 10341184240 10761437184 1403615952 14.627 99 57 112 2 1446679568 29658640 13404976
20:51:28 5999 8192000000 10360393360 10780991488 1406524688 14.653 99 58 116 0 1456265280 35282880 14457712
20:52:40 4043 8192000000 10344044080 10761625600 1399046992 14.587 99 72 144 0 1447135968 30865888 17223088
20:53:50 3226 8192000000 10340860480 10760867840 1402215072 14.615 100 72 145 1 1451319920 31689840 17415008
20:54:57 6048 8192000000 10324002480 10745483264 1379828096 14.415 99 53 106 1 1427411792 33587312 13996384
20:56:08 1199 8192000000 10282672752 10689024000 1337846672 14.038 99 71 136 1 1387552224 33201616 16503936
20:57:11 22253 8192000000 10303618640 10739122176 1370459696 14.331 100 28 56 0 1410870048 32502432 7907920
20:58:35 17364 8192000000 10294440880 10720894976 1363163056 14.266 100 30 60 0 1399722864 28957888 7601920
20:59:58 11799 8192000000 10265836640 10687774720 1323024144 13.904 98 46 90 1 1367142032 32818736 11299152
21:01:12 6386 8192000000 10295901248 10704474112 1346395376 14.115 100 51 101 0 1391095424 31743200 12956848
21:02:22 15418 8192000000 10296479024 10721374208 1371166336 14.337 100 33 66 0 1404045472 24636160 8242976
21:03:42 5838 8192000000 10308767424 10732097536 1374846320 14.370 100 58 113 3 1414613056 25861488 13905248
21:04:53 18756 8192000000 10295556880 10730373120 1355717744 14.199 99 22 44 0 1394111216 32210896 6182576
21:06:25 22908 8192000000 10287504544 10719576064 1334689120 14.009 99 15 30 0 1378488896 39076656 4723120
21:07:58 20230 8192000000 10278820112 10703822848 1345122240 14.104 100 49 88 2 1379442128 23511088 10808800
21:09:20 17635 8192000000 10296136192 10715963392 1362744224 14.262 100 41 81 1 1391963744 19421520 9798000
21:10:39 24882 8192000000 10299116944 10733273088 1381634288 14.431 100 29 56 0 1398678672 9837456 7206928
21:12:15 51036 8192000000 10309203184 10754887680 1383710784 14.450 100 10 20 0 1404378576 18582912 2084880
21:15:10 43131 8192000000 10314158832 10745077760 1381311552 14.428 99 39 78 2 1400934688 10732688 8890448
21:16:58 98784 8192000000 10301181904 10753441792 1396781728 14.566 100 29 59 0 1414676256 10174800 7719728
21:19:41 96726 8192000000 10311662864 10759507968 1385097664 14.462 99 19 38 0 1407620464 18004672 4518128
21:22:20 36800 8192000000 10308289280 10763563008 1386442640 14.474 99 18 36 0 1407741968 17358368 3940960
21:23:58 30500 8192000000 10313321376 10767544320 1399319744 14.589 100 30 59 0 1425325392 17166112 8839536
21:25:34 92282 8192000000 10306787472 10768031744 1390259952 14.508 99 12 24 1 1410631488 17225344 3146192
21:28:10 84005 8192000000 10300429056 10780090368 1401232704 14.606 100 19 38 0 1417944512 12345616 4366192
21:30:54 56850 8192000000 10326321200 10787082240 1402575248 14.618 100 16 32 0 1418848032 12637888 3634896
21:32:51 93052 8192000000 10301270480 10787287040 1394846080 14.549 99 11 23 0 1411359872 14238352 2275440
21:35:26 32191 8192000000 10301074816 10760908800 1380351200 14.420 99 29 58 1 1395777824 9051264 6375360
21:37:06 62122 8192000000 10267816576 10737115136 1324516208 13.918 95 18 36 0 1344657616 15979392 4162016
21:39:15 55074 8192000000 10267301120 10724409344 1298459312 13.681 94 14 28 0 1312161936 10882992 2819632
21:41:11 28271 8192000000 10266739872 10708914176 1287779696 13.584 94 22 44 0 1303642640 11053792 4809152
21:42:45 65483 8192000000 10274877632 10717126656 1311607648 13.801 96 13 26 0 1324754272 10773056 2373568
21:44:51 73701 8192000000 10281580448 10733223936 1298910240 13.685 95 13 26 0 1322584880 20739296 2935344
21:47:05 41004 8192000000 10281292304 10720075776 1334140144 14.005 98 31 62 1 1353426432 12172496 7113792
21:48:50 144354 8192000000 10274900512 10717532160 1282666256 13.537 94 29 58 0 1307028352 17874320 6487776
21:52:15 25056 8192000000 10237842016 10705825792 1317166064 13.851 98 6 12 0 1335932592 17679392 1087136
21:53:41 83077 8192000000 10265275600 10717700096 1331423632 13.980 98 16 32 0 1346629776 11399152 3806992
21:56:04 52127 8192000000 10264502144 10724188160 1358269952 14.222 100 14 27 0 1372275184 11073600 2931632
21:57:59 70640 8192000000 10271340656 10741895168 1364808896 14.281 99 15 28 0 1385243344 16628608 3805840
22:00:17 31801 8192000000 10278285840 10723975168 1368344064 14.312 100 15 30 1 1378491584 6994576 3152944
22:01:49 14659 8192000000 10279712752 10728574976 1365025888 14.282 99 27 54 1 1378460288 7722288 5712112
22:03:05 66711 8192000000 10284282496 10752204800 1366907360 14.299 99 21 42 0 1387450256 15826320 4716576
22:05:13 34891 8192000000 10299560704 10748661760 1384884288 14.460 100 25 50 1 1403711584 13257024 5570272
22:06:50 49945 8192000000 10292020768 10751287296 1373110112 14.355 99 15 27 1 1384177872 8128032 2939728
22:08:40 52843 8192000000 10309579696 10753560576 1360050048 14.238 97 26 52 0 1374676000 9046192 5579760
22:10:36 51859 8192000000 10310599072 10765238272 1372720640 14.351 98 22 44 1 1388416176 11399152 4296384
22:12:46 99560 8192000000 10325195664 10780262400 1404037328 14.631 100 18 36 1 1426724272 19007760 3679184
22:15:51 31961 8192000000 10306083888 10766598144 1392573168 14.529 99 30 60 0 1411700560 12208624 6918768
22:17:24 36794 8192000000 10296978624 10769215488 1392737024 14.530 99 14 27 1 1403402784 7801264 2864496
22:19:06 58755 8192000000 10302094560 10770751488 1392172864 14.525 99 19 38 0 1404020208 7331072 4516272
22:21:05 76728 8192000000 10296811328 10766577664 1396018928 14.560 100 17 33 0 1413111344 12870720 4221696
22:23:30 64737 8192000000 10289407408 10754686976 1364732112 14.280 98 16 31 0 1382988352 14444576 3811664
22:25:38 39479 8192000000 10271344592 10725167104 1316286560 13.843 96 18 35 1 1333782608 13596864 3899184
22:27:20 96525 8192000000 10253591712 10709483520 1274460752 13.462 93 12 25 0 1289406960 12113056 2833152
22:29:57 76993 8192000000 10246921712 10688389120 1220910624 12.970 90 20 40 0 1234262800 9130640 4221536
22:32:30 47095 8192000000 10247309536 10699603968 1318557280 13.864 99 35 70 0 1339866704 13446032 7863392
22:34:29 63248 8192000000 10221403536 10674114560 1271038672 13.431 95 20 40 1 1286893488 11320432 4534384
22:36:33 135874 8192000000 8987772656 9187561472 163976976 1.962 13 7 14 0 166801680 1346688 1478016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-01-2023 22:40:34 to 20-01-2023 22:40:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 157 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 20-01-2023 22:40:50 to 20-01-2023 22:40:52.

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

20.01.23 22:40:53. 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-20 22:40:56 No severe PSQL-related problems occured
20.01.23 22:40:57. End of report.