Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.06.2022 16:42
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 16502000 kB
MemAvailable 31321128 kB
Buffers 0 kB
Cached 15200516 kB
SwapCached 2444 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.10
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-06-04 16:42:12
DTS_END                         2022-06-04 16:42:12.2410
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/39522
TRN_ID                          233739
ATT_ID                          688
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: 8993.78 28110 1079254
customer order: creation 165.94 402 19913
customer order: refuse 14.58 364 1750
order to supplier: creation 44.99 1446 5399
order to supplier: removal 3.41 107225 409
invoice (draft): creation 45.02 3925 5402
invoice (draft): removal 50.55 898 6066
invoice accept: apply 40.46 18861 4855
invoice accept: cancel 45.59 884 5471
customer reserve: creation 3768.35 183 452202
customer reserve: removal 4529.38 62 543525
realization accept: apply 81.59 207 9791
realization accept: cancel 77.28 117 9274
payment from customer: creation 57.28 132 6874
payment from customer: removal 5.64 155 677
payment to supplier: creation 47.73 175 5727
payment to supplier: removal 3.73 92 447
service: total inventory turnovers 4.79 1321 575
service: total monetary turnovers 6.76 36 811
service: aggregate perf. data 0.68 27054 82
service: refresh index statistics 0.03 398666 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-06-2022 16:43:08 to 04-06-2022 16:43:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3816
TEST_TIME 2 3799
TEST_TIME 3 3748
TEST_TIME 4 3613
TEST_TIME 5 3886
TEST_TIME 6 3552
TEST_TIME 7 4326
TEST_TIME 8 4307
TEST_TIME 9 4403
TEST_TIME 10 4622
TEST_TIME 11 4894
TEST_TIME 12 5165
TEST_TIME 13 5490
TEST_TIME 14 5796
TEST_TIME 15 5934
TEST_TIME 16 6409
TEST_TIME 17 6895
TEST_TIME 18 6982
TEST_TIME 19 7154
TEST_TIME 20 7842
TEST_TIME 21 8145
TEST_TIME 22 8557
TEST_TIME 23 8475
TEST_TIME 24 8545
TEST_TIME 25 8813
TEST_TIME 26 8924
TEST_TIME 27 8836
TEST_TIME 28 9119
TEST_TIME 29 9157
TEST_TIME 30 9038
TEST_TIME 31 9406
TEST_TIME 32 9390
TEST_TIME 33 9841
TEST_TIME 34 10245
TEST_TIME 35 10074
TEST_TIME 36 9971
TEST_TIME 37 9918
TEST_TIME 38 9785
TEST_TIME 39 9697
TEST_TIME 40 9576
TEST_TIME 41 9682
TEST_TIME 42 9550
TEST_TIME 43 9513
TEST_TIME 44 9519
TEST_TIME 45 9516
TEST_TIME 46 9385
TEST_TIME 47 9274
TEST_TIME 48 9208
TEST_TIME 49 9157
TEST_TIME 50 9132
TEST_TIME 51 9116
TEST_TIME 52 9063
TEST_TIME 53 9075
TEST_TIME 54 9052
TEST_TIME 55 9041
TEST_TIME 56 8968
TEST_TIME 57 8983
TEST_TIME 58 8959
TEST_TIME 59 8859
TEST_TIME 60 8850
TEST_TIME 61 8830
TEST_TIME 62 8832
TEST_TIME 63 8872
TEST_TIME 64 8918
TEST_TIME 65 8862
TEST_TIME 66 8923
TEST_TIME 67 8909
TEST_TIME 68 8990
TEST_TIME 69 8931
TEST_TIME 70 8965
TEST_TIME 71 8901
TEST_TIME 72 9050
TEST_TIME 73 9119
TEST_TIME 74 9105
TEST_TIME 75 9140
TEST_TIME 76 9158
TEST_TIME 77 9050
TEST_TIME 78 9021
TEST_TIME 79 9050
TEST_TIME 80 9010
TEST_TIME 81 9002
TEST_TIME 82 9004
TEST_TIME 83 8947
TEST_TIME 84 8943
TEST_TIME 85 8966
TEST_TIME 86 8922
TEST_TIME 87 8914
TEST_TIME 88 8918
TEST_TIME 89 8929
TEST_TIME 90 8890
TEST_TIME 91 8913
TEST_TIME 92 8995
TEST_TIME 93 9023
TEST_TIME 94 9006
TEST_TIME 95 9064
TEST_TIME 96 9008
TEST_TIME 97 9099
TEST_TIME 98 9094
TEST_TIME 99 9056
TEST_TIME 100 9067
TEST_TIME 101 9150
TEST_TIME 102 9106
TEST_TIME 103 9054
TEST_TIME 104 9043
TEST_TIME 105 8985
TEST_TIME 106 9010
TEST_TIME 107 8980
TEST_TIME 108 8953
TEST_TIME 109 8942
TEST_TIME 110 8968
TEST_TIME 111 8926
TEST_TIME 112 8900
TEST_TIME 113 8953
TEST_TIME 114 8925
TEST_TIME 115 8942
TEST_TIME 116 8993
TEST_TIME 117 8980
TEST_TIME 118 9027
TEST_TIME 119 9062
TEST_TIME 120 8997

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-06-2022 16:43:12 to 04-06-2022 16:43: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 1497293 1497293 0 0.00 0 29730 7 0 0
doc_list_biud 1497293 1497293 0 0.00 0 65650 29 0 0
sp_add_invoice_to_stock 4965 4855 110 2.21 35 186601 18862 0 110
sp_cancel_adding_invoice 5471 5471 0 0.00 5 73686 884 0 0
sp_cancel_client_order 1750 1750 0 0.00 4 26354 365 0 0
sp_cancel_customer_reserve 543525 543525 0 0.00 1 65651 63 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 2 20158 155 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 5973 93 0 0
sp_cancel_supplier_invoice 6080 6066 14 0.23 2 18597 899 0 14
sp_cancel_supplier_order 409 409 0 0.00 7 1034482 107225 0 0
sp_cancel_write_off 9274 9274 0 0.00 1 15962 117 0 0
sp_client_order 19913 19913 0 0.00 3 22624 403 0 0
sp_customer_reserve 452202 452202 0 0.00 0 68998 184 0 0
sp_fill_shopping_cart 30712 30712 0 0.00 1 13125 46 0 0
sp_fill_shopping_cart_clo_res 452194 452194 0 0.00 0 19664 15 0 0
sp_get_clo_for_invoice 4847 4847 0 0.00 0 45081 381 0 0
sp_kill_cost_storno 15869 15869 0 0.00 0 20795 69 0 0
sp_kill_qstorno_ret_qs2qd 1102799 1102799 0 0.00 0 65643 34 0 0
sp_kill_qty_storno 581133 581133 0 0.00 0 65645 74 0 0
sp_lock_selected_doc 20410 20410 0 0.00 0 8255 6 0 0
sp_make_cost_storno 27239 27239 0 0.00 0 29718 65 0 0
sp_multiply_rows_for_pdistr 27239 27239 0 0.00 0 20458 33 0 0
sp_multiply_rows_for_qdistr 19913 19913 0 0.00 0 21305 182 0 0
sp_pay_from_customer 6874 6874 0 0.00 4 25290 133 0 0
sp_pay_to_supplier 5727 5727 0 0.00 5 15327 175 0 0
sp_payment_common 12601 12601 0 0.00 1 25276 47 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 26345 329 0 0
sp_qd_handle_on_invoice_upd_sts 10318 10318 0 0.00 0 23916 380 0 0
sp_qd_handle_on_reserve_upd_sts 9791 9791 0 0.00 0 17380 65 0 0
sp_reserve_write_off 9791 9791 0 0.00 6 17562 207 0 0
sp_supplier_invoice 5402 5402 0 0.00 6 92267 3925 0 0
sp_supplier_order 5399 5399 0 0.00 5 30110 1447 0 0
srv_aggregate_perf_data 82 82 0 0.00 2658 476552 27055 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1312 142627 46925 0 0
srv_find_qd_qs_mism 1032355 1032355 0 0.00 0 18115 5 0 0
srv_make_invnt_saldo 575 575 0 0.00 34 105145 1321 0 0
srv_make_money_saldo 811 811 0 0.00 0 4821 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 264493 528616 398667 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 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4855 4855 0 0.00 0 21601 203 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 10840 117 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 5884 21 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 2339 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 9898 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 374 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 1103 13 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 57372 1875 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 4495 12 0 0
t$perf-norm:sp_client_order 19913 19913 0 0.00 0 12442 23 0 0
t$perf-norm:sp_customer_reserve 1038 1038 0 0.00 0 15503 23 0 0
t$perf-norm:sp_pay_from_customer 6874 6874 0 0.00 0 11586 15 0 0
t$perf-norm:sp_pay_to_supplier 5727 5727 0 0.00 0 21236 20 0 0
t$perf-norm:sp_reserve_write_off 9791 9791 0 0.00 0 13401 14 0 0
t$perf-norm:sp_supplier_invoice 5402 5402 0 0.00 0 8796 20 0 0
t$perf-norm:sp_supplier_order 5399 5399 0 0.00 0 9109 18 0 0
t$perf-norm:srv_aggregate_perf_data 82 82 0 0.00 0 122 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 480 15 0 0
t$perf-norm:srv_make_invnt_saldo 575 575 0 0.00 0 886 27 0 0
t$perf-norm:srv_make_money_saldo 811 811 0 0.00 0 1688 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9912 9692 220 2.21 0 7501 22 0 220
v_all_customers 36238 36238 0 0.00 0 44 1 0 0
v_all_suppliers 29792 29792 0 0.00 0 118 1 0 0
v_all_wares 39824 39824 0 0.00 0 62 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 998 9 0 0
v_cancel_client_order 3500 3500 0 0.00 0 4684 9 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 6496 10 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 2687 6 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 218 3 0 0
v_cancel_supplier_invoice 848 820 28 3.30 0 7472 24 0 28
v_cancel_supplier_order 818 818 0 0.00 0 1309 9 0 0
v_cancel_write_off 1994 1994 0 0.00 0 5008 21 0 0
v_max_id_clo_ord 5398 5398 0 0.00 0 300 1 0 0
v_max_id_clo_res 1038 1038 0 0.00 0 1819 9 0 0
v_max_id_ord_sup 5401 5401 0 0.00 0 4870 6 0 0
v_max_non_paid_invoice 1630 1630 0 0.00 0 1144 3 0 0
v_max_non_paid_realizn 3760 3760 0 0.00 0 801 3 0 0
v_min_id_clo_ord 5398 5398 0 0.00 0 9590 15 0 0
v_min_id_clo_res 1038 1038 0 0.00 0 1972 7 0 0
v_min_id_ord_sup 5401 5401 0 0.00 0 6817 8 0 0
v_min_non_paid_invoice 5727 5727 0 0.00 0 15269 43 0 0
v_min_non_paid_realizn 6874 6874 0 0.00 0 25248 36 0 0
v_our_firm 9814 9814 0 0.00 0 14 1 0 0
v_reserve_write_off 19580 19580 0 0.00 0 7956 9 0 0
x_lock_depdocs_on_canc_invoice 11127 11127 0 0.00 0 13203 132 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 4347 373 0 0
x_make_qstorno_1000_1200 5399 5399 0 0.00 1 30096 1349 0 0
x_make_qstorno_1200_2000 5402 5402 0 0.00 2 86156 3767 0 0
x_make_qstorno_2100_3300 452202 452202 0 0.00 0 68997 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-06-2022 16:43:16 to 04-06-2022 16:43: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:12:16 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:18 41882 8192000000 9902556304 10210574336 979069408 10.675 100 90 180 0 1009138560 6020672 24048480
14:15:10 24876 8192000000 9943438640 10263408640 1024834160 11.119 100 86 172 1 1054975760 7955552 22186048
14:16:39 12215 8192000000 9975855424 10309107712 1066523712 11.519 100 80 160 0 1095323856 8903280 19896864
14:17:57 15467 8192000000 10034784336 10383081472 1123296992 12.058 100 76 150 1 1151565376 10450768 17817616
14:19:16 7772 8192000000 10092135472 10454368256 1180216208 12.592 100 75 150 0 1208985984 11502128 17267648
14:20:26 5532 8192000000 10133417792 10502438912 1223358592 12.993 100 87 174 1 1257497520 14218672 19920256
14:21:35 3660 8192000000 10169529824 10545250304 1257073360 13.303 100 83 166 1 1288247536 13253088 17921088
14:22:42 2366 8192000000 10189961728 10574565376 1278733584 13.501 100 85 172 0 1311611984 13763408 19114992
14:23:48 2031 8192000000 10215854592 10600013824 1296457488 13.663 100 87 174 1 1329945232 14187824 19299920
14:24:52 6242 8192000000 10224279808 10609205248 1307310704 13.762 100 80 159 4 1338266736 13641104 17314928
14:25:59 4806 8192000000 10200814608 10610307072 1312148112 13.806 100 42 82 2 1330294832 8124832 10021888
14:27:04 5988 8192000000 10206620384 10619953152 1322383792 13.898 100 20 40 1 1333787584 6420576 4983216
14:28:13 5441 8192000000 10225499968 10636554240 1332178800 13.987 100 34 68 0 1347674960 7787520 7708640
14:29:19 45346 8192000000 10225838512 10646867968 1332400848 13.989 100 28 55 0 1344664864 6025424 6238592
14:31:05 6467 8192000000 10225533696 10643587072 1332713712 13.992 100 28 56 2 1345568512 6095056 6759744
14:32:12 6509 8192000000 10219854992 10636488704 1318988144 13.868 99 18 36 3 1328546768 5649008 3909616
14:33:26 7310 8192000000 10179857152 10591604736 1263670368 13.364 96 21 42 0 1273794560 5458032 4666160
14:34:39 29626 8192000000 10171826416 10572591104 1217580208 12.939 94 48 96 2 1235432704 5884112 11968384
14:36:16 4585 8192000000 10130333616 10521387008 1206524128 12.837 96 35 71 1 1220220208 5511936 8184144
14:37:24 7148 8192000000 10154298864 10543800320 1244948160 13.192 99 26 51 1 1257101584 6065392 6088032
14:38:34 16091 8192000000 10168050256 10575732736 1269423952 13.416 99 24 48 2 1280244800 4846064 5974784
14:39:51 9776 8192000000 10169370016 10585812992 1286328080 13.571 100 19 37 1 1296894912 6413728 4153104
14:41:02 6684 8192000000 10192342304 10604523520 1295121296 13.651 100 36 72 2 1313772368 10398192 8252880
14:42:09 8267 8192000000 10211085504 10619174912 1317159504 13.851 100 22 44 1 1327066128 4728272 5178352
14:43:25 6094 8192000000 10228548400 10642255872 1331379904 13.980 100 41 83 0 1348052544 7502720 9169920
14:44:48 55338 8192000000 10258252944 10677665792 1351482464 14.161 100 29 58 0 1366355296 7980096 6892736
14:46:47 60521 8192000000 10279164784 10706300928 1375203248 14.374 100 44 87 2 1397528768 11427520 10898000
14:49:04 27194 8192000000 10299558848 10725720064 1386038000 14.471 100 43 86 2 1415900352 19235728 10626624
14:50:36 17256 8192000000 10306802288 10732711936 1394485536 14.546 100 40 78 2 1419106960 15689776 8931648
14:51:58 7817 8192000000 10328119312 10748338176 1401629216 14.609 100 59 118 1 1436561376 20619536 14312624
14:53:10 8343 8192000000 10332640624 10755219456 1407184224 14.659 100 61 121 2 1445812560 24273216 14355120
14:54:27 5864 8192000000 10344736592 10765164544 1411730816 14.699 100 58 115 2 1453104880 27289680 14084384
14:55:37 3477 8192000000 10354328144 10775371776 1418443856 14.759 100 60 120 0 1464507488 31083760 14979872
14:56:44 7528 8192000000 10362096256 10780516352 1416279792 14.740 100 83 167 1 1471866736 35753056 19833888
14:57:54 11405 8192000000 10369336080 10790834176 1408409600 14.670 99 69 135 0 1466765280 41208160 17147520
14:59:13 4893 8192000000 10349724512 10767966208 1400882608 14.603 100 70 140 3 1456086336 37512448 17691280
15:00:20 2369 8192000000 10370948240 10782035968 1408728688 14.673 100 88 174 0 1471753712 41098160 21926864
15:01:27 1310 8192000000 10352027360 10761388032 1391787744 14.522 100 94 189 1 1455970640 41146880 23036016
15:02:31 13980 8192000000 10329224624 10757971968 1387362464 14.482 100 36 71 0 1443732368 45821136 10548768
15:04:18 1851 8192000000 10316687312 10723807232 1362244400 14.258 100 87 170 1 1419797488 34998144 22554944
15:05:26 10123 8192000000 10303632960 10724454400 1358745072 14.226 100 41 80 0 1406614816 36833552 11036192
15:06:40 1392 8192000000 10337895232 10746593280 1378632112 14.404 100 89 178 0 1441546416 41313664 21600640
15:07:45 9223 8192000000 10302489648 10721492992 1356491536 14.206 100 56 113 0 1407594112 36663744 14438832
15:08:59 9856 8192000000 10329898160 10754441216 1380779600 14.424 100 42 84 0 1434041200 42361296 10900304
15:10:13 13403 8192000000 10339119104 10775289856 1388236896 14.490 100 28 56 1 1446303360 49673344 8393120
15:11:31 19299 8192000000 10311085056 10742435840 1351079344 14.157 98 31 62 1 1396670656 37600864 7990448
15:12:52 41648 8192000000 10303718496 10755952640 1367092496 14.301 100 20 40 0 1425339296 52131264 6115536
15:14:35 31210 8192000000 10327025520 10760863744 1373729408 14.360 100 43 84 0 1423643536 39721088 10193040
15:16:08 21844 8192000000 10287567568 10741948416 1382156704 14.436 100 13 27 1 1395743280 10972112 2614464
15:18:05 74689 8192000000 10306900496 10750160896 1384465984 14.456 100 29 57 0 1403549216 12268000 6815232
15:20:21 142626 8192000000 10297137584 10740523008 1360413216 14.241 97 36 71 0 1379991040 11253680 8324144
15:23:56 77768 8192000000 10323970048 10762280960 1393539552 14.537 100 28 56 1 1408880864 9058256 6283056
15:26:14 65897 8192000000 10296497600 10747559936 1368538032 14.314 98 15 29 0 1387260880 15003152 3719696
15:28:21 44655 8192000000 10304528400 10747023360 1381929776 14.434 99 33 65 1 1396899440 6919824 8049840
15:30:26 3863 8192000000 10312131936 10747342848 1378245984 14.401 99 61 121 3 1406880832 13428448 15206400
15:31:32 27534 8192000000 10329144944 10768797696 1406825312 14.656 100 41 82 2 1430927888 14191072 9911504
15:33:00 67189 8192000000 10301169040 10763190272 1387083408 14.480 99 8 16 2 1404492928 15385088 2024432
15:35:09 59563 8192000000 10307454576 10776846336 1390016256 14.506 99 16 32 1 1420518032 26562592 3939184
15:37:32 104393 8192000000 10291306384 10751664128 1359155168 14.230 98 20 40 0 1384863936 21124640 4584128
15:40:17 84994 8192000000 10291302992 10747822080 1372526544 14.350 98 24 48 1 1387558224 9475344 5556336
15:42:50 60511 8192000000 10292655360 10755145728 1382690944 14.441 99 10 20 1 1395519936 10689024 2139968
15:44:59 81338 8192000000 10281312384 10738716672 1341306112 14.069 96 17 34 0 1355189136 10324976 3558048
15:47:25 69739 8192000000 10274562352 10725765120 1314122512 13.823 95 20 40 0 1329988576 11653440 4212624
15:49:46 115842 8192000000 10273706608 10704592896 1221220992 12.973 88 22 44 2 1234506512 7961456 5324064
15:52:44 46452 8192000000 10280245456 10711392256 1327701296 13.946 97 40 80 0 1348739488 12077840 8960352
15:54:34 89537 8192000000 10258321680 10704293888 1295793040 13.657 95 11 22 0 1314234144 16011232 2429872
15:57:04 68697 8192000000 10275764368 10729164800 1352794832 14.173 99 16 32 0 1373677760 17471264 3411664
15:59:14 87220 8192000000 10266112688 10710597632 1346471840 14.116 98 25 50 0 1359756864 7979104 5305920
16:01:42 38125 8192000000 10267378992 10721767424 1352239008 14.168 99 32 64 1 1369283680 9635264 7409408
16:03:20 88699 8192000000 10246195760 10705342464 1306624880 13.755 96 10 20 0 1324937920 15916480 2396560
16:05:49 73250 8192000000 10267112864 10724773888 1369179648 14.320 100 11 22 0 1383143232 11350416 2613168
16:08:04 86546 8192000000 10266158576 10731266048 1329984160 13.967 97 11 23 1 1344129952 11886464 2259328
16:10:54 76018 8192000000 10262411344 10715508736 1312808480 13.812 96 14 28 0 1327632272 11356144 3467648
16:13:10 65866 8192000000 10286815696 10742677504 1362894688 14.263 99 20 40 0 1377041888 9512160 4635040
16:15:27 45575 8192000000 10303040352 10759901184 1381474496 14.430 100 20 40 1 1403392272 17524064 4393712
16:17:27 59122 8192000000 10306542544 10751184896 1380090304 14.417 99 26 52 0 1395481600 9736352 5654944
16:19:29 101468 8192000000 10292017920 10748125184 1364312768 14.276 98 7 14 0 1375648496 9959136 1376592
16:22:11 49152 8192000000 10308378976 10765418496 1372465872 14.349 98 17 34 0 1392003216 15843536 3693808
16:24:05 26192 8192000000 10303636432 10745991168 1382725696 14.441 99 20 40 1 1394247744 7247312 4274736
16:25:38 104637 8192000000 10297730672 10756624384 1373055328 14.354 98 15 30 0 1387418640 11038240 3325072
16:28:41 36182 8192000000 10271531280 10727821312 1332643536 13.991 96 18 36 0 1344219152 7086528 4489088
16:30:19 69045 8192000000 10266392464 10728321024 1357844416 14.218 98 18 36 0 1372053616 9932864 4276336
16:32:37 42844 8192000000 10255535840 10718248960 1354447568 14.187 99 7 14 0 1363884240 7974464 1462208
16:34:21 63913 8192000000 10273576224 10734440448 1365743760 14.289 99 19 38 0 1386774768 17293136 3737872
16:36:25 25293 8192000000 10262721168 10708418560 1323972112 13.913 97 48 95 2 1347542560 12208720 11361728
16:37:54 70542 8192000000 10262398832 10718646272 1333535648 13.999 97 9 18 1 1349117696 13495680 2086368
16:40:11 106631 8192000000 10265828512 10725818368 1294637824 13.646 94 9 18 0 1306153408 9702160 1813424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-06-2022 16:43:22 to 04-06-2022 16:43:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-06-2022 16:43:38 to 04-06-2022 16:43: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          234086
MON$OLDEST_ACTIVE               234087
MON$OLDEST_SNAPSHOT             234087
MON$NEXT_TRANSACTION            234087
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                       2017472
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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'

04.06.22 16:43: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
2022-06-04 16:43:43 No severe PSQL-related problems occured
04.06.22 16:43:45. End of report.