Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.03.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 15332484 kB
MemAvailable 30444960 kB
Buffers 0 kB
Cached 16387128 kB
SwapCached 3016 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-03-02 16:38:42
DTS_END                         2023-03-02 16:38:42.5620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59976
TRN_ID                          240368
ATT_ID                          684
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: 9237.93 21750 1108552
customer order: creation 174.17 356 20900
customer order: refuse 14.47 361 1736
order to supplier: creation 46.80 1271 5616
order to supplier: removal 3.61 96669 433
invoice (draft): creation 47.23 3638 5667
invoice (draft): removal 53.17 887 6380
invoice accept: apply 41.85 18365 5022
invoice accept: cancel 47.58 800 5710
customer reserve: creation 3860.00 183 463200
customer reserve: removal 4655.23 57 558627
realization accept: apply 83.96 176 10075
realization accept: cancel 80.82 111 9698
payment from customer: creation 59.68 136 7161
payment from customer: removal 5.68 63 681
payment to supplier: creation 48.61 165 5833
payment to supplier: removal 3.23 101 387
service: total inventory turnovers 4.81 926 577
service: total monetary turnovers 6.36 40 763
service: aggregate perf. data 0.68 23341 82
service: refresh index statistics 0.03 287357 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-03-2023 16:40:08 to 02-03-2023 16:40:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7436
TEST_TIME 2 5669
TEST_TIME 3 4665
TEST_TIME 4 3926
TEST_TIME 5 5699
TEST_TIME 6 5476
TEST_TIME 7 5527
TEST_TIME 8 5555
TEST_TIME 9 5395
TEST_TIME 10 6169
TEST_TIME 11 6691
TEST_TIME 12 7028
TEST_TIME 13 7055
TEST_TIME 14 6858
TEST_TIME 15 6993
TEST_TIME 16 7000
TEST_TIME 17 7222
TEST_TIME 18 7216
TEST_TIME 19 7311
TEST_TIME 20 7367
TEST_TIME 21 7889
TEST_TIME 22 8182
TEST_TIME 23 8572
TEST_TIME 24 8804
TEST_TIME 25 8683
TEST_TIME 26 8769
TEST_TIME 27 8659
TEST_TIME 28 8738
TEST_TIME 29 8667
TEST_TIME 30 8872
TEST_TIME 31 9391
TEST_TIME 32 10348
TEST_TIME 33 10365
TEST_TIME 34 10378
TEST_TIME 35 10472
TEST_TIME 36 10307
TEST_TIME 37 10305
TEST_TIME 38 10108
TEST_TIME 39 10012
TEST_TIME 40 9957
TEST_TIME 41 9947
TEST_TIME 42 9864
TEST_TIME 43 9872
TEST_TIME 44 9771
TEST_TIME 45 9891
TEST_TIME 46 9878
TEST_TIME 47 9760
TEST_TIME 48 9661
TEST_TIME 49 9533
TEST_TIME 50 9532
TEST_TIME 51 9695
TEST_TIME 52 9668
TEST_TIME 53 9721
TEST_TIME 54 9759
TEST_TIME 55 9771
TEST_TIME 56 9766
TEST_TIME 57 9792
TEST_TIME 58 9815
TEST_TIME 59 9795
TEST_TIME 60 9820
TEST_TIME 61 9896
TEST_TIME 62 9938
TEST_TIME 63 9878
TEST_TIME 64 9765
TEST_TIME 65 9656
TEST_TIME 66 9691
TEST_TIME 67 9621
TEST_TIME 68 9539
TEST_TIME 69 9485
TEST_TIME 70 9435
TEST_TIME 71 9348
TEST_TIME 72 9358
TEST_TIME 73 9468
TEST_TIME 74 9421
TEST_TIME 75 9432
TEST_TIME 76 9485
TEST_TIME 77 9453
TEST_TIME 78 9457
TEST_TIME 79 9430
TEST_TIME 80 9508
TEST_TIME 81 9444
TEST_TIME 82 9603
TEST_TIME 83 9612
TEST_TIME 84 9608
TEST_TIME 85 9659
TEST_TIME 86 9636
TEST_TIME 87 9574
TEST_TIME 88 9528
TEST_TIME 89 9474
TEST_TIME 90 9526
TEST_TIME 91 9471
TEST_TIME 92 9441
TEST_TIME 93 9458
TEST_TIME 94 9419
TEST_TIME 95 9399
TEST_TIME 96 9357
TEST_TIME 97 9413
TEST_TIME 98 9492
TEST_TIME 99 9460
TEST_TIME 100 9447
TEST_TIME 101 9505
TEST_TIME 102 9562
TEST_TIME 103 9560
TEST_TIME 104 9533
TEST_TIME 105 9571
TEST_TIME 106 9569
TEST_TIME 107 9572
TEST_TIME 108 9567
TEST_TIME 109 9525
TEST_TIME 110 9494
TEST_TIME 111 9439
TEST_TIME 112 9444
TEST_TIME 113 9393
TEST_TIME 114 9370
TEST_TIME 115 9350
TEST_TIME 116 9291
TEST_TIME 117 9277
TEST_TIME 118 9299
TEST_TIME 119 9294
TEST_TIME 120 9248

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-03-2023 16:40:11 to 02-03-2023 16:40:15.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1538513 1538513 0 0.00 0 20992 7 0 0
doc_list_biud 1538511 1538511 0 0.00 0 29094 27 0 0
sp_add_invoice_to_stock 5132 5022 110 2.14 26 230853 18365 0 110
sp_cancel_adding_invoice 5710 5710 0 0.00 3 53666 800 0 0
sp_cancel_client_order 1736 1736 0 0.00 4 8614 361 0 0
sp_cancel_customer_reserve 558627 558627 0 0.00 0 29096 57 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 2007 63 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 2 13182 101 0 0
sp_cancel_supplier_invoice 6388 6380 8 0.12 2 17764 887 0 8
sp_cancel_supplier_order 433 433 0 0.00 6 919759 96669 0 0
sp_cancel_write_off 9698 9698 0 0.00 1 11048 111 0 0
sp_client_order 20900 20900 0 0.00 3 31985 356 0 0
sp_customer_reserve 463200 463200 0 0.00 0 62797 183 0 0
sp_fill_shopping_cart 32177 32177 0 0.00 1 17334 42 0 0
sp_fill_shopping_cart_clo_res 463188 463188 0 0.00 0 16209 14 0 0
sp_get_clo_for_invoice 5009 5009 0 0.00 0 35550 269 0 0
sp_kill_cost_storno 16476 16476 0 0.00 0 13172 58 0 0
sp_kill_qstorno_ret_qs2qd 1133765 1133765 0 0.00 0 29092 32 0 0
sp_kill_qty_storno 597666 597666 0 0.00 0 29094 69 0 0
sp_lock_selected_doc 20921 20921 0 0.00 0 6412 5 0 0
sp_make_cost_storno 28078 28078 0 0.00 0 20855 58 0 0
sp_multiply_rows_for_pdistr 28076 28076 0 0.00 0 14174 35 0 0
sp_multiply_rows_for_qdistr 20900 20900 0 0.00 0 15961 164 0 0
sp_pay_from_customer 7161 7161 0 0.00 4 14217 137 0 0
sp_pay_to_supplier 5833 5833 0 0.00 4 24007 165 0 0
sp_payment_common 12994 12994 0 0.00 1 23687 38 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 0 8605 333 0 0
sp_qd_handle_on_invoice_upd_sts 10717 10717 0 0.00 0 23207 344 0 0
sp_qd_handle_on_reserve_upd_sts 10075 10075 0 0.00 0 11463 52 0 0
sp_reserve_write_off 10075 10075 0 0.00 5 14331 177 0 0
sp_supplier_invoice 5667 5667 0 0.00 5 84608 3638 0 0
sp_supplier_order 5616 5616 0 0.00 5 33593 1271 0 0
srv_aggregate_perf_data 83 82 1 1.20 2636 215614 23342 0 1
srv_fill_mon_cache_memory 67 67 0 0.00 1210 117796 40362 0 0
srv_find_qd_qs_mism 1061063 1061063 0 0.00 0 7471 6 0 0
srv_make_invnt_saldo 577 577 0 0.00 35 32523 927 0 0
srv_make_money_saldo 763 763 0 0.00 0 8596 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 103868 414812 287358 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5024 5024 0 0.00 0 18408 164 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 2171 79 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 16076 35 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 3030 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 479 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 879 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 751 15 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 31936 1642 0 0
t$perf-norm:sp_cancel_write_off 1037 1037 0 0.00 0 3929 7 0 0
t$perf-norm:sp_client_order 20900 20900 0 0.00 0 11939 17 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 1183 5 0 0
t$perf-norm:sp_pay_from_customer 7161 7161 0 0.00 0 11098 10 0 0
t$perf-norm:sp_pay_to_supplier 5833 5833 0 0.00 0 4091 9 0 0
t$perf-norm:sp_reserve_write_off 10075 10075 0 0.00 0 20186 15 0 0
t$perf-norm:sp_supplier_invoice 5667 5667 0 0.00 0 30051 21 0 0
t$perf-norm:sp_supplier_order 5616 5616 0 0.00 0 5234 15 0 0
t$perf-norm:srv_aggregate_perf_data 82 82 0 0.00 0 11 0 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 67 3 0 0
t$perf-norm:srv_make_invnt_saldo 577 577 0 0.00 0 1309 27 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 1204 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10234 10014 220 2.14 0 4739 19 0 220
v_all_customers 37932 37932 0 0.00 0 106 1 0 0
v_all_suppliers 31096 31096 0 0.00 0 81 1 0 0
v_all_wares 41798 41798 0 0.00 0 108 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 1524 9 0 0
v_cancel_client_order 3472 3472 0 0.00 0 4745 4 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 107 2 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 4513 6 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 439 3 0 0
v_cancel_supplier_invoice 894 878 16 1.78 0 1493 18 0 16
v_cancel_supplier_order 866 866 0 0.00 0 3934 8 0 0
v_cancel_write_off 2074 2074 0 0.00 0 4910 19 0 0
v_max_id_clo_ord 5616 5616 0 0.00 0 81 1 0 0
v_max_id_clo_res 963 963 0 0.00 0 3557 13 0 0
v_max_id_ord_sup 5662 5662 0 0.00 0 5033 5 0 0
v_max_non_paid_invoice 1563 1563 0 0.00 0 1750 5 0 0
v_max_non_paid_realizn 3886 3886 0 0.00 0 5085 6 0 0
v_min_id_clo_ord 5616 5616 0 0.00 0 2189 6 0 0
v_min_id_clo_res 963 963 0 0.00 0 4015 10 0 0
v_min_id_ord_sup 5662 5662 0 0.00 0 9486 17 0 0
v_min_non_paid_invoice 5833 5833 0 0.00 0 23684 32 0 0
v_min_non_paid_realizn 7161 7161 0 0.00 0 13181 29 0 0
v_our_firm 10416 10416 0 0.00 0 106 1 0 0
v_reserve_write_off 20150 20150 0 0.00 0 4764 5 0 0
x_lock_depdocs_on_canc_invoice 11651 11651 0 0.00 0 13939 129 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 6644 346 0 0
x_make_qstorno_1000_1200 5616 5616 0 0.00 1 33583 1200 0 0
x_make_qstorno_1200_2000 5667 5667 0 0.00 2 81215 3476 0 0
x_make_qstorno_2100_3300 463199 463199 0 0.00 0 62794 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-03-2023 16:40:16 to 02-03-2023 16:40:20.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:46 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:46 19087 8192000000 9888096064 10191122432 961970864 10.508 100 90 180 0 992445248 5946272 24528112
14:11:10 16995 8192000000 9925880736 10242727936 1006821008 10.945 100 90 180 0 1038586048 7623472 24141568
14:12:34 18745 8192000000 9962468000 10291204096 1045363664 11.316 100 89 178 0 1077461248 8973296 23124288
14:13:57 7195 8192000000 10016103616 10358677504 1105516160 11.890 100 76 152 0 1133917952 9527952 18873840
14:15:11 6409 8192000000 10078802528 10431524864 1163584896 12.437 100 87 172 1 1196028320 12438544 20004880
14:16:24 3478 8192000000 10123669312 10491371520 1207671936 12.848 100 83 164 0 1239469040 12904592 18892512
14:17:34 2498 8192000000 10154898416 10527879168 1240284208 13.149 100 89 173 1 1272192096 13079840 18828048
14:18:39 1837 8192000000 10179824608 10562088960 1269253728 13.415 100 71 141 1 1296016544 11279280 15483536
14:19:43 1206 8192000000 10207482864 10588512256 1285803424 13.566 100 91 183 1 1321483632 14912800 20767408
14:20:46 20854 8192000000 10193597680 10594308096 1297354640 13.671 100 49 97 2 1316355184 8495792 10504752
14:22:13 16980 8192000000 10192834672 10606223360 1305310624 13.744 100 47 94 0 1325651072 10040080 10300368
14:23:36 4565 8192000000 10213714656 10619195392 1317664848 13.856 100 30 61 0 1331537920 6557600 7315472
14:24:42 8015 8192000000 10214217728 10628403200 1324310480 13.916 100 17 35 0 1333117584 5142400 3664704
14:25:56 55512 8192000000 10234815280 10646274048 1334688432 14.009 100 35 69 0 1347588976 5202528 7698016
14:27:52 5195 8192000000 10229992960 10648150016 1337759856 14.037 100 15 27 0 1346064800 5120512 3184432
14:28:59 9644 8192000000 10206965280 10621456384 1288200096 13.588 97 19 37 0 1298226784 5421696 4604992
14:30:09 12282 8192000000 10198493344 10606084096 1284067632 13.550 98 35 71 2 1297734944 5524768 8142544
14:31:24 20588 8192000000 10158014608 10569240576 1204322672 12.816 93 16 32 1 1212284240 4706336 3255232
14:32:45 6458 8192000000 10127636320 10525011968 1215968416 12.924 96 22 43 1 1226284528 4777408 5538704
14:33:52 9210 8192000000 10151165136 10553978880 1250669856 13.244 99 32 64 0 1265007104 5288144 9049104
14:35:02 21495 8192000000 10170144672 10577035264 1270613232 13.427 99 21 43 0 1281497744 5084880 5799632
14:36:27 14996 8192000000 10175186672 10591457280 1289558704 13.600 100 17 34 0 1298664480 5341488 3764288
14:37:44 4279 8192000000 10189245680 10602729472 1302063376 13.714 100 27 54 0 1314224016 6035728 6124912
14:38:51 3616 8192000000 10233297920 10640846848 1328187040 13.951 100 53 102 0 1345517168 4806464 12523664
14:40:01 59960 8192000000 10247583632 10675589120 1351264176 14.159 100 38 76 0 1368204160 7587776 9352208
14:42:16 82514 8192000000 10271377584 10706755584 1364802144 14.280 100 27 55 0 1388163056 16454272 6906640
14:44:42 5662 8192000000 10289690160 10715426816 1380292304 14.419 100 41 82 1 1403276544 13228912 9755328
14:45:56 5544 8192000000 10306904784 10728824832 1386638032 14.476 100 54 108 1 1415441104 16088896 12714176
14:47:07 10624 8192000000 10317388816 10742833152 1391464736 14.519 100 48 94 1 1424183568 20905200 11813632
14:48:25 7232 8192000000 10319501376 10749939712 1395943936 14.559 100 46 92 0 1430445872 23875600 10626336
14:49:37 3700 8192000000 10321143648 10744008704 1398503408 14.582 100 63 125 1 1429867632 16988768 14375456
14:50:45 1209 8192000000 10330223072 10739449856 1392952944 14.532 100 85 166 1 1434675136 22409632 19312560
14:51:50 5733 8192000000 10315631024 10740727808 1399305088 14.589 100 55 110 0 1433473728 20752064 13416576
14:53:01 10197 8192000000 10314265552 10740441088 1392363472 14.527 100 43 85 3 1425477792 22805824 10308496
14:54:18 1599 8192000000 10335094656 10753212416 1401693264 14.610 100 71 142 1 1443320640 23418688 18208688
14:55:29 4224 8192000000 10334165616 10749181952 1396935312 14.568 100 65 130 1 1438657680 25552896 16169472
14:56:43 8669 8192000000 10336827392 10756628480 1398586640 14.582 100 66 129 1 1442866112 28360208 15919264
14:57:59 1776 8192000000 10342125984 10754428928 1390151808 14.507 100 68 133 1 1446152064 38580480 17419776
14:59:08 2402 8192000000 10337538112 10748424192 1374201392 14.365 100 75 150 0 1443988016 50062720 19723904
15:00:15 13002 8192000000 10319904944 10740686848 1365365584 14.286 99 49 96 1 1415979664 37436544 13177536
15:01:33 14640 8192000000 10329001168 10744930304 1382610528 14.440 100 55 109 2 1437806272 39342176 15853568
15:02:49 15863 8192000000 10295951824 10728644608 1361794928 14.253 99 32 64 0 1406153200 35473792 8884480
15:04:06 8266 8192000000 10319755856 10749054976 1381783968 14.432 100 39 76 0 1431244112 39192896 10267248
15:05:18 29430 8192000000 10310898448 10737078272 1365153040 14.284 99 24 48 0 1413695616 41885680 6656896
15:06:52 32814 8192000000 10299316896 10741710848 1341139712 14.068 99 24 49 0 1399525904 51121840 7264352
15:08:45 45500 8192000000 10316033808 10754629632 1324788960 13.920 99 20 40 1 1406819840 76130832 5900048
15:10:32 34400 8192000000 10240899072 10679308288 1300079872 13.696 97 14 30 0 1329503792 25998736 3425184
15:12:09 80562 8192000000 10272629280 10721107968 1336773344 14.028 99 14 28 3 1359268608 19436784 3058480
15:14:30 107460 8192000000 10268182144 10709344256 1339291280 14.051 98 37 74 0 1356549424 9036848 8221296
15:17:19 40750 8192000000 10286241168 10714095616 1348075376 14.130 98 44 84 2 1365119840 6768144 10276320
15:19:02 15532 8192000000 10289877840 10729787392 1376785760 14.388 100 51 103 0 1397577696 9084448 11707488
15:20:29 14339 8192000000 10288508560 10724044800 1367956128 14.309 99 46 92 0 1386986784 9070352 9960304
15:21:46 23967 8192000000 10302176048 10746200064 1380900272 14.425 100 18 35 0 1402462032 17875712 3686048
15:23:10 64511 8192000000 10290530288 10756677632 1372497648 14.349 99 15 30 0 1391717504 15055504 4164352
15:25:38 43455 8192000000 10300270480 10753105920 1391334096 14.518 100 13 26 1 1410382960 15730784 3318080
15:28:46 64331 8192000000 10307064144 10767425536 1393592128 14.538 100 22 44 1 1417806192 19029776 5184288
15:30:53 28739 8192000000 10306209920 10766454784 1404886192 14.638 100 28 56 2 1425233616 13808880 6538544
15:32:37 73496 8192000000 10279999776 10740682752 1366089168 14.292 98 10 20 0 1380696064 12412080 2194816
15:34:59 60252 8192000000 10268382576 10723639296 1336070960 14.022 97 9 18 0 1349865696 11731424 2063312
15:37:09 4769 8192000000 10299854544 10740322304 1382016352 14.435 100 38 76 0 1398718816 8523232 8179232
15:38:24 95020 8192000000 10294671616 10747781120 1356445984 14.205 97 22 44 0 1375659728 14221440 4992304
15:41:00 62749 8192000000 10272289728 10720845824 1333161872 13.996 96 16 32 0 1345120752 8241712 3717168
15:43:15 45762 8192000000 10281163840 10730962944 1371735584 14.343 100 33 66 0 1390326544 11342704 7248256
15:45:01 26850 8192000000 10279267072 10731053056 1366678016 14.297 99 32 63 0 1379346720 4713152 7955552
15:46:35 71439 8192000000 10270825504 10726244352 1343450544 14.089 97 15 30 0 1356953952 10048192 3455216
15:48:47 66224 8192000000 10276687808 10721964032 1331982800 13.985 96 26 52 0 1350902704 12753504 6166400
15:50:54 61535 8192000000 10258802992 10735939584 1344363376 14.097 98 20 40 1 1364339344 14869808 5106160
15:52:55 44877 8192000000 10282444576 10736848896 1358761856 14.226 99 22 44 0 1374556304 10991008 4803440
15:54:54 56046 8192000000 10253429040 10707755008 1291490560 13.618 95 15 30 0 1307692944 12983248 3219136
15:56:50 33208 8192000000 10270330848 10713600000 1346385232 14.115 99 32 61 0 1362861424 9564256 6911936
15:58:25 35777 8192000000 10282525664 10737565696 1370371728 14.330 100 39 78 1 1398341488 19300032 8669728
16:00:07 108260 8192000000 10271907152 10724315136 1344304512 14.096 99 19 36 1 1369184544 20740288 4139744
16:03:12 56015 8192000000 10271671296 10706034688 1340831696 14.065 99 21 42 0 1361553904 15794144 4928064
16:05:08 102158 8192000000 10258154672 10707165184 1293656256 13.638 95 20 40 1 1305698640 7727664 4314720
16:07:59 26916 8192000000 10284380384 10733944832 1372906336 14.353 100 27 48 1 1392053296 13136208 6010752
16:10:56 43385 8192000000 10261844144 10725212160 1356507536 14.206 99 12 24 0 1370935568 11541456 2886576
16:12:49 32575 8192000000 10283362768 10742530048 1384778256 14.459 100 26 52 0 1400309472 9764624 5766592
16:14:22 35716 8192000000 10304055584 10740490240 1350054192 14.148 97 35 70 0 1372268688 13862896 8351600
16:15:59 64377 8192000000 10289122240 10754097152 1375749008 14.379 99 17 34 1 1393233968 13275344 4209616
16:18:04 33508 8192000000 10302335184 10746265600 1369621792 14.324 98 35 70 1 1390874176 13372224 7880160
16:19:47 67688 8192000000 10295666784 10753110016 1379599152 14.413 99 8 16 0 1390893776 9528032 1766592
16:21:58 22016 8192000000 10304379456 10751242240 1392692864 14.530 100 40 81 1 1410668032 8950384 9024784
16:23:26 45696 8192000000 10298267008 10753744896 1362568912 14.260 98 33 66 0 1382959184 13327040 7063232
16:25:12 19481 8192000000 10313739936 10752598016 1388919344 14.496 100 44 88 0 1409579920 10952336 9708240
16:26:32 117796 8192000000 10284674336 10739388416 1366808608 14.298 99 18 36 0 1382784800 11890480 4085712
16:29:34 56767 8192000000 10280098656 10746519552 1374181008 14.364 99 17 34 0 1389145488 10641024 4323456
16:31:33 41047 8192000000 10283819184 10745737216 1370639520 14.333 99 12 24 1 1384044848 10696656 2708672
16:33:14 110767 8192000000 10283293888 10754060288 1365442080 14.286 98 11 22 0 1381100672 12840144 2818448
16:36:18 71513 8192000000 10243583584 10698805248 1299675872 13.692 95 10 20 0 1312172272 10222432 2273968
16:38:31 18269 8192000000 9097648672 9336561664 265396960 3.138 20 10 20 0 270885312 3231072 2257280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-03-2023 16:40:22 to 02-03-2023 16:40:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 110 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 02-03-2023 16:40:38 to 02-03-2023 16:40:40.

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          240672
MON$OLDEST_ACTIVE               240673
MON$OLDEST_SNAPSHOT             240673
MON$NEXT_TRANSACTION            240673
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                       2028320
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:874: 
:875: mscs-test-161.budzdorov.ru    Thu Mar  2 16:38:53 2023
:876:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:877: 
:878: 
:879: mscs-test-161.budzdorov.ru    Thu Mar  2 16:38:53 2023
:880:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:881: 
--- end of diff output ---

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

02.03.23 16:40:41. 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-03-02 16:40:44 No severe PSQL-related problems occured
02.03.23 16:40:45. End of report.