Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.12.2021 19: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 16721200 kB
MemAvailable 31411416 kB
Buffers 0 kB
Cached 14980956 kB
SwapCached 440 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.9
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 2021-12-24 19:38:01
DTS_END                         2021-12-24 19:38:01.6020
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/37376
TRN_ID                          233942
ATT_ID                          699
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: 8907.78 25135 1068934
customer order: creation 165.43 340 19851
customer order: refuse 14.81 351 1777
order to supplier: creation 43.18 1320 5182
order to supplier: removal 3.36 105835 403
invoice (draft): creation 44.24 3435 5309
invoice (draft): removal 52.82 819 6338
invoice accept: apply 39.17 19151 4700
invoice accept: cancel 47.33 797 5679
customer reserve: creation 3642.17 188 437060
customer reserve: removal 4567.08 60 548050
realization accept: apply 79.61 190 9553
realization accept: cancel 85.35 117 10242
payment from customer: creation 56.62 125 6794
payment from customer: removal 5.40 83 648
payment to supplier: creation 46.58 156 5590
payment to supplier: removal 3.39 102 407
service: total inventory turnovers 4.19 1263 503
service: total monetary turnovers 6.24 23 749
service: aggregate perf. data 0.79 20915 95
service: refresh index statistics 0.03 347434 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-12-2021 19:39:29 to 24-12-2021 19:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4165
TEST_TIME 2 5308
TEST_TIME 3 4189
TEST_TIME 4 5150
TEST_TIME 5 5361
TEST_TIME 6 5773
TEST_TIME 7 5774
TEST_TIME 8 5694
TEST_TIME 9 6266
TEST_TIME 10 6129
TEST_TIME 11 5929
TEST_TIME 12 5960
TEST_TIME 13 6217
TEST_TIME 14 6170
TEST_TIME 15 6412
TEST_TIME 16 6620
TEST_TIME 17 6768
TEST_TIME 18 6997
TEST_TIME 19 6934
TEST_TIME 20 7725
TEST_TIME 21 8226
TEST_TIME 22 8522
TEST_TIME 23 8910
TEST_TIME 24 9309
TEST_TIME 25 9749
TEST_TIME 26 10120
TEST_TIME 27 10657
TEST_TIME 28 10471
TEST_TIME 29 10333
TEST_TIME 30 10195
TEST_TIME 31 9984
TEST_TIME 32 10067
TEST_TIME 33 9941
TEST_TIME 34 9844
TEST_TIME 35 9698
TEST_TIME 36 9681
TEST_TIME 37 9521
TEST_TIME 38 9422
TEST_TIME 39 9303
TEST_TIME 40 9216
TEST_TIME 41 9187
TEST_TIME 42 9073
TEST_TIME 43 9046
TEST_TIME 44 9182
TEST_TIME 45 9148
TEST_TIME 46 9148
TEST_TIME 47 9060
TEST_TIME 48 8933
TEST_TIME 49 9012
TEST_TIME 50 8929
TEST_TIME 51 8869
TEST_TIME 52 8771
TEST_TIME 53 8811
TEST_TIME 54 8826
TEST_TIME 55 8822
TEST_TIME 56 8773
TEST_TIME 57 8807
TEST_TIME 58 8926
TEST_TIME 59 8883
TEST_TIME 60 8852
TEST_TIME 61 8973
TEST_TIME 62 8964
TEST_TIME 63 9046
TEST_TIME 64 8969
TEST_TIME 65 9106
TEST_TIME 66 9167
TEST_TIME 67 9178
TEST_TIME 68 9203
TEST_TIME 69 9137
TEST_TIME 70 9072
TEST_TIME 71 9022
TEST_TIME 72 9024
TEST_TIME 73 8958
TEST_TIME 74 8901
TEST_TIME 75 8841
TEST_TIME 76 8940
TEST_TIME 77 8875
TEST_TIME 78 8862
TEST_TIME 79 8923
TEST_TIME 80 8921
TEST_TIME 81 8944
TEST_TIME 82 8970
TEST_TIME 83 8961
TEST_TIME 84 8984
TEST_TIME 85 8944
TEST_TIME 86 8950
TEST_TIME 87 8969
TEST_TIME 88 8994
TEST_TIME 89 9033
TEST_TIME 90 9028
TEST_TIME 91 9172
TEST_TIME 92 9198
TEST_TIME 93 9215
TEST_TIME 94 9131
TEST_TIME 95 9061
TEST_TIME 96 9067
TEST_TIME 97 9016
TEST_TIME 98 9000
TEST_TIME 99 8976
TEST_TIME 100 8986
TEST_TIME 101 9010
TEST_TIME 102 8998
TEST_TIME 103 9040
TEST_TIME 104 8980
TEST_TIME 105 8998
TEST_TIME 106 9054
TEST_TIME 107 9064
TEST_TIME 108 9041
TEST_TIME 109 9053
TEST_TIME 110 9110
TEST_TIME 111 9141
TEST_TIME 112 9141
TEST_TIME 113 9094
TEST_TIME 114 9038
TEST_TIME 115 9021
TEST_TIME 116 9002
TEST_TIME 117 8997
TEST_TIME 118 8992
TEST_TIME 119 8982
TEST_TIME 120 8908

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-12-2021 19:39:33 to 24-12-2021 19:39:36.

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 1475000 1475000 0 0.00 0 18557 6 0 0
doc_list_biud 1475000 1475000 0 0.00 0 74362 29 0 0
sp_add_invoice_to_stock 4804 4700 104 2.16 29 158405 19151 0 104
sp_cancel_adding_invoice 5679 5679 0 0.00 4 60636 798 0 0
sp_cancel_client_order 1777 1777 0 0.00 4 14425 352 0 0
sp_cancel_customer_reserve 548050 548050 0 0.00 1 36097 60 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 2 8805 83 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 6832 102 0 0
sp_cancel_supplier_invoice 6342 6338 4 0.06 2 74369 819 0 4
sp_cancel_supplier_order 403 403 0 0.00 7 1005282 105836 0 0
sp_cancel_write_off 10242 10242 0 0.00 1 33611 118 0 0
sp_client_order 19851 19851 0 0.00 3 33621 341 0 0
sp_customer_reserve 437060 437060 0 0.00 0 56354 188 0 0
sp_fill_shopping_cart 30332 30332 0 0.00 1 15307 41 0 0
sp_fill_shopping_cart_clo_res 437059 437059 0 0.00 0 15162 13 0 0
sp_get_clo_for_invoice 4699 4699 0 0.00 0 31029 299 0 0
sp_kill_cost_storno 16976 16976 0 0.00 0 12071 58 0 0
sp_kill_qstorno_ret_qs2qd 1113083 1113083 0 0.00 0 74359 33 0 0
sp_kill_qty_storno 586741 586741 0 0.00 0 74361 71 0 0
sp_lock_selected_doc 19937 19937 0 0.00 0 11979 8 0 0
sp_make_cost_storno 26636 26636 0 0.00 0 18482 62 0 0
sp_multiply_rows_for_pdistr 26636 26636 0 0.00 0 10112 30 0 0
sp_multiply_rows_for_qdistr 19851 19851 0 0.00 0 19174 144 0 0
sp_pay_from_customer 6794 6794 0 0.00 4 10459 125 0 0
sp_pay_to_supplier 5590 5590 0 0.00 5 16663 156 0 0
sp_payment_common 12384 12384 0 0.00 1 10434 31 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 14418 314 0 0
sp_qd_handle_on_invoice_upd_sts 10378 10378 0 0.00 0 28951 350 0 0
sp_qd_handle_on_reserve_upd_sts 9553 9553 0 0.00 0 31059 70 0 0
sp_reserve_write_off 9553 9553 0 0.00 6 31073 190 0 0
sp_supplier_invoice 5309 5309 0 0.00 5 93414 3435 0 0
sp_supplier_order 5182 5182 0 0.00 5 41324 1320 0 0
srv_aggregate_perf_data 95 95 0 0.00 3065 303796 20915 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1494 171510 48816 0 0
srv_find_qd_qs_mism 1024156 1024156 0 0.00 0 11871 5 0 0
srv_make_invnt_saldo 503 503 0 0.00 39 87013 1263 0 0
srv_make_money_saldo 749 749 0 0.00 1 1614 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 155326 588423 347435 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4700 4700 0 0.00 0 12813 178 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 3249 78 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 2960 15 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 7547 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 5863 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 1311 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 504 12 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 23373 1544 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 1011 4 0 0
t$perf-norm:sp_client_order 19851 19851 0 0.00 0 17359 18 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 6586 15 0 0
t$perf-norm:sp_pay_from_customer 6794 6794 0 0.00 0 20875 14 0 0
t$perf-norm:sp_pay_to_supplier 5590 5590 0 0.00 0 5772 13 0 0
t$perf-norm:sp_reserve_write_off 9553 9553 0 0.00 0 9862 12 0 0
t$perf-norm:sp_supplier_invoice 5309 5309 0 0.00 0 7595 19 0 0
t$perf-norm:sp_supplier_order 5182 5182 0 0.00 0 6140 17 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 280 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 28 1 0 0
t$perf-norm:srv_make_invnt_saldo 503 503 0 0.00 0 685 20 0 0
t$perf-norm:srv_make_money_saldo 749 749 0 0.00 0 2586 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9606 9398 208 2.16 0 8869 20 0 208
v_all_customers 36130 36130 0 0.00 0 13 1 0 0
v_all_suppliers 28882 28882 0 0.00 0 79 1 0 0
v_all_wares 39702 39702 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 361 6 0 0
v_cancel_client_order 3554 3554 0 0.00 0 5014 10 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 7811 9 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 441 3 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 4277 11 0 0
v_cancel_supplier_invoice 770 762 8 1.03 0 1607 19 0 8
v_cancel_supplier_order 806 806 0 0.00 0 2080 9 0 0
v_cancel_write_off 2032 2032 0 0.00 0 11811 22 0 0
v_max_id_clo_ord 5178 5178 0 0.00 0 4018 2 0 0
v_max_id_clo_res 944 944 0 0.00 0 4543 16 0 0
v_max_id_ord_sup 5302 5302 0 0.00 0 5590 4 0 0
v_max_non_paid_invoice 1629 1629 0 0.00 0 511 2 0 0
v_max_non_paid_realizn 3614 3614 0 0.00 0 5932 7 0 0
v_min_id_clo_ord 5178 5178 0 0.00 0 8827 17 0 0
v_min_id_clo_res 944 944 0 0.00 0 10247 26 0 0
v_min_id_ord_sup 5302 5302 0 0.00 0 6222 9 0 0
v_min_non_paid_invoice 5590 5590 0 0.00 0 8113 21 0 0
v_min_non_paid_realizn 6794 6794 0 0.00 0 7410 22 0 0
v_our_firm 9932 9932 0 0.00 0 15 1 0 0
v_reserve_write_off 19106 19106 0 0.00 0 8018 9 0 0
x_lock_depdocs_on_canc_invoice 11636 11636 0 0.00 0 12998 116 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 15142 358 0 0
x_make_qstorno_1000_1200 5182 5182 0 0.00 1 41316 1232 0 0
x_make_qstorno_1200_2000 5309 5309 0 0.00 2 93201 3295 0 0
x_make_qstorno_2100_3300 437060 437060 0 0.00 0 56352 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-12-2021 19:39:37 to 24-12-2021 19:39:41.

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
17:08:05 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:05 36023 8192000000 9900847776 10211983360 974264992 10.628 100 89 178 1 1004696640 6792016 23639632
17:10:47 23688 8192000000 9946066000 10267492352 1024443808 11.115 100 86 171 0 1055663312 8837088 22382416
17:12:18 14243 8192000000 9974499360 10306908160 1059081200 11.448 100 81 162 1 1089557744 10190016 20286528
17:13:35 14657 8192000000 10037215552 10387533824 1124284912 12.067 100 72 142 1 1151938272 10987200 16666160
17:14:54 7373 8192000000 10102408128 10465521664 1189094048 12.675 100 76 152 0 1218531760 12242592 17195120
17:16:04 4201 8192000000 10153996112 10525806592 1236565952 13.115 100 80 158 1 1267278528 12778400 17934176
17:17:12 7041 8192000000 10171217744 10547343360 1256564720 13.299 100 84 169 1 1289071712 13567536 18939456
17:18:23 1323 8192000000 10209186400 10592747520 1290155728 13.606 100 96 188 3 1325384432 14015184 21213520
17:19:26 27071 8192000000 10189757792 10596126720 1300005168 13.695 100 67 135 1 1326191664 10772288 15414208
17:20:56 4234 8192000000 10210077360 10614861824 1310480256 13.790 100 49 95 3 1331308144 9980560 10847328
17:22:01 21747 8192000000 10222334112 10621009920 1317612720 13.855 100 71 142 3 1341013424 5559920 17840784
17:23:24 5918 8192000000 10211500112 10626060288 1321751328 13.893 100 33 63 1 1336054096 7216400 7086368
17:24:30 7395 8192000000 10216718368 10635243520 1331357168 13.979 100 23 46 0 1342250624 5665120 5228336
17:25:38 42680 8192000000 10235481312 10648256512 1314189856 13.824 98 19 36 2 1322834960 4666912 3978192
17:27:43 6149 8192000000 10229188688 10650165248 1338799584 14.047 100 20 40 1 1349498592 6247808 4451200
17:28:52 4199 8192000000 10199209952 10611621888 1309982736 13.786 100 31 62 1 1322906816 5074992 7849088
17:30:01 55713 8192000000 10165300384 10552209408 1197456512 12.753 94 46 93 2 1213273328 4367088 11449728
17:32:02 9630 8192000000 10140546016 10536775680 1213456208 12.901 97 24 47 1 1229290048 10353968 5479872
17:33:12 8862 8192000000 10152784224 10551439360 1259192976 13.323 100 26 51 0 1270776384 5704800 5878608
17:34:23 32059 8192000000 10167462672 10580340736 1282949376 13.540 100 27 53 0 1295313280 5002944 7360960
17:36:16 8192 8192000000 10183386272 10591961088 1288361008 13.589 100 40 80 2 1304715376 7296384 9057984
17:37:25 11932 8192000000 10194174112 10605551616 1300472608 13.700 100 20 40 0 1311070896 5261968 5336320
17:38:43 25609 8192000000 10232083248 10639519744 1321816112 13.893 100 39 79 1 1337011904 6503280 8692512
17:40:11 17954 8192000000 10248761168 10659004416 1343534784 14.089 100 51 102 1 1367423808 12315040 11573984
17:41:36 19500 8192000000 10254763104 10671333376 1349222240 14.140 100 45 89 2 1373820960 14554928 10043792
17:43:04 49313 8192000000 10278338896 10696134656 1362067248 14.256 100 35 70 0 1385293936 14773120 8453568
17:45:00 9915 8192000000 10294806800 10710573056 1373877968 14.362 100 51 102 1 1403057232 17081440 12097824
17:46:14 4044 8192000000 10300313296 10714312704 1375699472 14.378 100 60 120 2 1412194160 22024320 14470368
17:47:25 21681 8192000000 10300195360 10730504192 1387445344 14.483 100 34 67 2 1421021792 25214256 8362192
17:48:55 6864 8192000000 10328147856 10751938560 1407107184 14.658 100 50 100 1 1440986880 21444304 12435392
17:50:04 4939 8192000000 10335926576 10749816832 1400405280 14.599 100 61 118 1 1444069856 29077136 14587440
17:51:14 3233 8192000000 10363589808 10770161664 1415161008 14.730 100 78 152 0 1464379120 30306176 18911936
17:52:21 4071 8192000000 10339979152 10750693376 1396080688 14.560 100 66 130 1 1445442032 32970352 16390992
17:53:31 7722 8192000000 10366253520 10780897280 1416737632 14.744 100 71 142 0 1475455024 40570976 18146416
17:54:45 3010 8192000000 10373899968 10786185216 1417601296 14.751 100 74 147 1 1476881456 40006208 19273952
17:55:54 1934 8192000000 10383491888 10792202240 1415422896 14.732 100 82 166 0 1485158224 49116992 20618336
17:57:00 7213 8192000000 10374255872 10790789120 1409958768 14.684 99 65 130 1 1476078128 48727088 17392272
17:58:14 1490 8192000000 10372376832 10778877952 1395105568 14.551 100 78 152 0 1474224080 58388832 20729680
17:59:19 11246 8192000000 10350321232 10780909568 1394644496 14.547 100 34 68 2 1459331584 54670512 10016576
18:00:45 18076 8192000000 10324213440 10766278656 1375522736 14.376 100 32 64 1 1435644224 50617136 9504352
18:02:09 23016 8192000000 10297470864 10731028480 1331574640 13.981 98 24 48 1 1382337392 43807568 6955184
18:03:37 26654 8192000000 10288908064 10732212224 1356508192 14.206 100 21 42 0 1408259664 45187376 6564096
18:05:17 32440 8192000000 10266023552 10714320896 1359153632 14.230 100 12 24 0 1376178992 14526432 2498928
18:06:57 33074 8192000000 10264134352 10710667264 1337436048 14.034 98 14 29 1 1349104336 8069632 3598656
18:08:56 82496 8192000000 10256085712 10710904832 1331147568 13.978 97 11 22 0 1346441584 12898544 2395472
18:11:21 68456 8192000000 10253410048 10691883008 1308445888 13.772 96 26 52 1 1322599632 8792800 5360944
18:13:46 29490 8192000000 10263287520 10711760896 1357959216 14.219 100 24 47 1 1374037296 10440304 5637776
18:15:31 50502 8192000000 10282600224 10711990272 1347822544 14.128 99 46 91 1 1364834944 6362256 10650144
18:17:32 44796 8192000000 10284330976 10725412864 1373185392 14.356 100 32 65 1 1388433856 7649680 7598784
18:19:24 54242 8192000000 10292244768 10747056128 1389372976 14.500 100 28 56 0 1407938112 11815520 6749616
18:21:22 59734 8192000000 10283723744 10743984128 1369011328 14.318 99 18 36 0 1390104640 16428512 4664800
18:23:50 83324 8192000000 10265479520 10725150720 1357614400 14.216 98 21 42 0 1373872560 11109200 5148960
18:26:13 103105 8192000000 10272570896 10738511872 1356576512 14.207 98 22 43 3 1370418320 8957104 4884704
18:28:58 49991 8192000000 10268806320 10726060032 1354849664 14.191 98 20 40 1 1368978208 9560752 4567792
18:31:51 79958 8192000000 10272669456 10736492544 1354901504 14.192 98 10 20 1 1369531472 12223120 2406848
18:34:20 37940 8192000000 10273312480 10719932416 1336934176 14.030 96 28 56 0 1357693776 14211504 6548096
18:35:58 43789 8192000000 10293010448 10754662400 1371672096 14.342 99 13 26 0 1388854480 14403680 2778704
18:37:42 18257 8192000000 10283959104 10720657408 1355810784 14.200 98 41 82 0 1373744208 9206752 8726672
18:39:01 45784 8192000000 10291553056 10749665280 1345605760 14.108 97 39 78 0 1373875280 20073152 8196368
18:40:56 81442 8192000000 10274162736 10728873984 1317809840 13.857 95 10 20 0 1332045344 12078160 2157344
18:43:18 53804 8192000000 10282903648 10737577984 1323368304 13.907 96 28 56 0 1349708208 20415952 5923952
18:45:19 70619 8192000000 10287274672 10738823168 1366716976 14.298 100 12 25 0 1386025664 16378528 2930160
18:47:37 34532 8192000000 10271328240 10712543232 1354558720 14.188 99 45 88 0 1373093984 8950464 9584800
18:49:13 52834 8192000000 10267215680 10714681344 1350875792 14.155 99 38 76 1 1368378320 8664672 8837856
18:51:09 88963 8192000000 10272853216 10708926464 1295343072 13.653 94 34 68 0 1322062032 18786272 7932688
18:53:44 52614 8192000000 10255585504 10704932864 1312787888 13.811 96 14 28 0 1339141408 22938112 3415408
18:55:37 99710 8192000000 10256446896 10710937600 1348233248 14.132 99 20 40 1 1371189552 17703840 5252464
18:58:17 55503 8192000000 10269789760 10727239680 1329616336 13.964 97 11 22 0 1344866976 12844800 2405840
19:00:15 50897 8192000000 10248388240 10693054464 1325744016 13.929 98 18 34 1 1338374608 8587616 4042976
19:02:19 69158 8192000000 10273404192 10716659712 1356616784 14.207 99 17 34 0 1368829248 8373440 3839024
19:04:30 27517 8192000000 10288153424 10720681984 1349940896 14.147 98 27 54 1 1365093632 9099312 6053424
19:05:59 66509 8192000000 10284867424 10729201664 1359136736 14.230 99 21 42 1 1375486368 12071792 4277840
19:08:11 56650 8192000000 10299249984 10750038016 1356883664 14.209 98 21 42 2 1385650864 23867840 4899360
19:11:10 171509 8192000000 10259083120 10703699968 1300062896 13.696 94 19 38 1 1313613840 9491488 4059456
19:15:04 73989 8192000000 10255203520 10706612224 1304168560 13.733 95 12 22 0 1314145520 7455136 2521824
19:17:25 72805 8192000000 10290707952 10753589248 1373989744 14.363 100 16 32 0 1390158048 12233216 3935088
19:19:44 106148 8192000000 10308875632 10766319616 1388024448 14.488 100 17 36 1 1404943936 12763120 4156368
19:22:32 50252 8192000000 10313565920 10763071488 1393730416 14.539 100 24 46 1 1413167856 14178816 5258624
19:24:26 130190 8192000000 10286335472 10744061952 1318080608 13.859 95 9 18 0 1330749568 10161440 2507520
19:27:36 104872 8192000000 10297456064 10757435392 1340363024 14.061 98 9 18 1 1357318320 15264112 1691184
19:30:29 128231 8192000000 10288055504 10725613568 1324130832 13.914 96 31 62 0 1339738928 8909264 6698832
19:33:38 58917 8192000000 10270799056 10720002048 1350457328 14.152 98 23 46 0 1364655712 9024080 5174304
19:35:38 32579 8192000000 10270059312 10724335616 1359830784 14.236 99 21 43 0 1372290320 7710432 4749104
19:37:18 51303 8192000000 9056213440 9280630784 225342448 2.677 17 7 14 0 228893056 2042672 1507936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 24-12-2021 19:39:43 to 24-12-2021 19:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-12-2021 19:39:58 to 24-12-2021 19:40:00.

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          234291
MON$OLDEST_ACTIVE               234292
MON$OLDEST_SNAPSHOT             234292
MON$NEXT_TRANSACTION            234292
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                       2016832
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 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 ---
:71: 
:72: mscs-test-161.budzdorov.ru    Fri Dec 24 19:38:16 2021
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:74: 
--- end of diff output ---

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

24.12.21 19:40:01. 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
2021-12-24 19:40:04 No severe PSQL-related problems occured
24.12.21 19:40:05. End of report.