Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.09.2021 22:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16123928 kB
MemAvailable 30874900 kB
Buffers 0 kB
Cached 15586240 kB
SwapCached 380 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.8
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-09-10 22:37:47
DTS_END                         2021-09-10 22:37:47.7120
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/50742
TRN_ID                          238408
ATT_ID                          654
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: 9062.46 21617 1087495
customer order: creation 170.58 388 20470
customer order: refuse 14.38 384 1726
order to supplier: creation 45.66 1247 5479
order to supplier: removal 3.54 99572 425
invoice (draft): creation 46.44 3363 5573
invoice (draft): removal 52.19 861 6263
invoice accept: apply 40.73 16833 4887
invoice accept: cancel 47.14 778 5657
customer reserve: creation 3758.20 166 450984
customer reserve: removal 4587.49 60 550499
realization accept: apply 83.58 184 10030
realization accept: cancel 82.95 92 9954
payment from customer: creation 58.52 110 7022
payment from customer: removal 6.09 76 731
payment to supplier: creation 49.22 156 5906
payment to supplier: removal 3.44 87 413
service: total inventory turnovers 4.89 1252 587
service: total monetary turnovers 6.48 35 777
service: aggregate perf. data 0.90 20904 108
service: refresh index statistics 0.03 285786 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-09-2021 22:40:01 to 10-09-2021 22:40:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1092
TEST_TIME 2 2644
TEST_TIME 3 4121
TEST_TIME 4 3832
TEST_TIME 5 5028
TEST_TIME 6 5132
TEST_TIME 7 5172
TEST_TIME 8 5319
TEST_TIME 9 5456
TEST_TIME 10 5988
TEST_TIME 11 6200
TEST_TIME 12 6209
TEST_TIME 13 6449
TEST_TIME 14 6881
TEST_TIME 15 7245
TEST_TIME 16 7514
TEST_TIME 17 7975
TEST_TIME 18 8141
TEST_TIME 19 8449
TEST_TIME 20 9146
TEST_TIME 21 9103
TEST_TIME 22 9363
TEST_TIME 23 9352
TEST_TIME 24 9243
TEST_TIME 25 9083
TEST_TIME 26 9586
TEST_TIME 27 9790
TEST_TIME 28 10102
TEST_TIME 29 10782
TEST_TIME 30 10891
TEST_TIME 31 11051
TEST_TIME 32 10768
TEST_TIME 33 10575
TEST_TIME 34 10423
TEST_TIME 35 10358
TEST_TIME 36 10229
TEST_TIME 37 10099
TEST_TIME 38 9912
TEST_TIME 39 9741
TEST_TIME 40 9651
TEST_TIME 41 9570
TEST_TIME 42 9448
TEST_TIME 43 9499
TEST_TIME 44 9516
TEST_TIME 45 9472
TEST_TIME 46 9366
TEST_TIME 47 9356
TEST_TIME 48 9337
TEST_TIME 49 9202
TEST_TIME 50 9178
TEST_TIME 51 9245
TEST_TIME 52 9204
TEST_TIME 53 9252
TEST_TIME 54 9192
TEST_TIME 55 9176
TEST_TIME 56 9288
TEST_TIME 57 9277
TEST_TIME 58 9275
TEST_TIME 59 9299
TEST_TIME 60 9386
TEST_TIME 61 9344
TEST_TIME 62 9417
TEST_TIME 63 9494
TEST_TIME 64 9459
TEST_TIME 65 9447
TEST_TIME 66 9461
TEST_TIME 67 9409
TEST_TIME 68 9311
TEST_TIME 69 9355
TEST_TIME 70 9291
TEST_TIME 71 9216
TEST_TIME 72 9287
TEST_TIME 73 9213
TEST_TIME 74 9154
TEST_TIME 75 9126
TEST_TIME 76 9115
TEST_TIME 77 9090
TEST_TIME 78 9035
TEST_TIME 79 9012
TEST_TIME 80 8940
TEST_TIME 81 8924
TEST_TIME 82 8919
TEST_TIME 83 8947
TEST_TIME 84 8892
TEST_TIME 85 8920
TEST_TIME 86 8946
TEST_TIME 87 8961
TEST_TIME 88 8966
TEST_TIME 89 9016
TEST_TIME 90 9064
TEST_TIME 91 9097
TEST_TIME 92 9127
TEST_TIME 93 9155
TEST_TIME 94 9207
TEST_TIME 95 9222
TEST_TIME 96 9145
TEST_TIME 97 9061
TEST_TIME 98 9106
TEST_TIME 99 9091
TEST_TIME 100 9032
TEST_TIME 101 9034
TEST_TIME 102 9026
TEST_TIME 103 9046
TEST_TIME 104 9096
TEST_TIME 105 9084
TEST_TIME 106 9044
TEST_TIME 107 9041
TEST_TIME 108 9075
TEST_TIME 109 9066
TEST_TIME 110 9121
TEST_TIME 111 9156
TEST_TIME 112 9186
TEST_TIME 113 9175
TEST_TIME 114 9208
TEST_TIME 115 9175
TEST_TIME 116 9110
TEST_TIME 117 9098
TEST_TIME 118 9054
TEST_TIME 119 9058
TEST_TIME 120 9066

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-09-2021 22:40:05 to 10-09-2021 22:40:08.

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 1504771 1504771 0 0.00 0 21097 6 0 0
doc_list_biud 1504771 1504771 0 0.00 0 43100 28 0 0
sp_add_invoice_to_stock 5035 4887 148 2.93 26 161720 16834 0 148
sp_cancel_adding_invoice 5657 5657 0 0.00 6 57243 779 0 0
sp_cancel_client_order 1726 1726 0 0.00 4 8245 384 0 0
sp_cancel_customer_reserve 550499 550499 0 0.00 1 35103 61 0 0
sp_cancel_pay_from_customer 731 731 0 0.00 3 6695 77 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 2 8351 87 0 0
sp_cancel_supplier_invoice 6278 6263 15 0.23 2 23947 861 0 15
sp_cancel_supplier_order 425 425 0 0.00 7 1201456 99573 0 0
sp_cancel_write_off 9954 9954 0 0.00 1 6914 92 0 0
sp_client_order 20470 20470 0 0.00 3 26199 389 0 0
sp_customer_reserve 450984 450984 0 0.00 0 51270 166 0 0
sp_fill_shopping_cart 31514 31514 0 0.00 1 26128 43 0 0
sp_fill_shopping_cart_clo_res 450980 450980 0 0.00 0 11698 13 0 0
sp_get_clo_for_invoice 4878 4878 0 0.00 0 31581 346 0 0
sp_kill_cost_storno 16755 16755 0 0.00 0 9192 49 0 0
sp_kill_qstorno_ret_qs2qd 1117640 1117640 0 0.00 0 43096 33 0 0
sp_kill_qty_storno 589426 589426 0 0.00 0 43098 72 0 0
sp_lock_selected_doc 20638 20638 0 0.00 0 8309 6 0 0
sp_make_cost_storno 27830 27830 0 0.00 0 21094 53 0 0
sp_multiply_rows_for_pdistr 27830 27830 0 0.00 0 17534 26 0 0
sp_multiply_rows_for_qdistr 20470 20470 0 0.00 0 20713 169 0 0
sp_pay_from_customer 7022 7022 0 0.00 4 22595 110 0 0
sp_pay_to_supplier 5906 5906 0 0.00 4 27767 157 0 0
sp_payment_common 12928 12928 0 0.00 1 18143 33 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 0 7022 339 0 0
sp_qd_handle_on_invoice_upd_sts 10529 10529 0 0.00 0 24101 342 0 0
sp_qd_handle_on_reserve_upd_sts 10030 10030 0 0.00 0 24137 59 0 0
sp_reserve_write_off 10030 10030 0 0.00 6 24161 185 0 0
sp_supplier_invoice 5573 5573 0 0.00 7 81440 3363 0 0
sp_supplier_order 5479 5479 0 0.00 5 37849 1247 0 0
srv_aggregate_perf_data 108 108 0 0.00 2522 170519 20905 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1613 160696 46975 0 0
srv_find_qd_qs_mism 1040068 1040068 0 0.00 0 11902 5 0 0
srv_make_invnt_saldo 587 587 0 0.00 28 102266 1252 0 0
srv_make_money_saldo 777 777 0 0.00 1 5546 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 240553 377198 285786 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4887 4887 0 0.00 0 21818 191 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 0 19067 141 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 33841 33 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 1430 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 731 731 0 0.00 0 1303 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 5284 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 1752 18 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 22852 1648 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 3823 9 0 0
t$perf-norm:sp_client_order 20470 20470 0 0.00 0 11969 16 0 0
t$perf-norm:sp_customer_reserve 1047 1047 0 0.00 0 5924 21 0 0
t$perf-norm:sp_pay_from_customer 7022 7022 0 0.00 0 8719 10 0 0
t$perf-norm:sp_pay_to_supplier 5906 5906 0 0.00 0 8839 11 0 0
t$perf-norm:sp_reserve_write_off 10030 10030 0 0.00 0 35186 21 0 0
t$perf-norm:sp_supplier_invoice 5573 5573 0 0.00 0 11136 17 0 0
t$perf-norm:sp_supplier_order 5479 5479 0 0.00 0 7309 16 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 1141 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 233 4 0 0
t$perf-norm:srv_make_invnt_saldo 587 587 0 0.00 0 1215 21 0 0
t$perf-norm:srv_make_money_saldo 777 777 0 0.00 0 1996 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 10040 9744 296 2.94 0 5501 21 0 296
v_all_customers 36898 36898 0 0.00 0 25 1 0 0
v_all_suppliers 30560 30560 0 0.00 0 35 1 0 0
v_all_wares 40940 40940 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 7969 20 0 0
v_cancel_client_order 3452 3452 0 0.00 0 5227 9 0 0
v_cancel_customer_prepayment 1462 1462 0 0.00 0 1844 5 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 2955 6 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 136 2 0 0
v_cancel_supplier_invoice 780 750 30 3.84 0 1043 18 0 30
v_cancel_supplier_order 850 850 0 0.00 0 2433 10 0 0
v_cancel_write_off 1960 1960 0 0.00 0 3201 15 0 0
v_max_id_clo_ord 5479 5479 0 0.00 0 2155 2 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 3757 16 0 0
v_max_id_ord_sup 5565 5565 0 0.00 0 2769 4 0 0
v_max_non_paid_invoice 1670 1670 0 0.00 0 2232 5 0 0
v_max_non_paid_realizn 3940 3940 0 0.00 0 3743 6 0 0
v_min_id_clo_ord 5479 5479 0 0.00 0 3198 8 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 2893 8 0 0
v_min_id_ord_sup 5565 5565 0 0.00 0 4395 6 0 0
v_min_non_paid_invoice 5906 5906 0 0.00 0 12584 24 0 0
v_min_non_paid_realizn 7022 7022 0 0.00 0 10093 15 0 0
v_our_firm 10206 10206 0 0.00 0 21 1 0 0
v_reserve_write_off 20060 20060 0 0.00 0 5557 9 0 0
x_lock_depdocs_on_canc_invoice 11545 11545 0 0.00 0 11174 109 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 10566 352 0 0
x_make_qstorno_1000_1200 5479 5479 0 0.00 2 37841 1159 0 0
x_make_qstorno_1200_2000 5573 5573 0 0.00 4 81429 3210 0 0
x_make_qstorno_2100_3300 450984 450984 0 0.00 0 40007 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-09-2021 22:40:09 to 10-09-2021 22:40:13.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:51 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:53 20456 8192000000 10357117600 10679013376 1420104064 14.774 100 90 180 0 1455560096 5889184 29566848
20:10:19 21383 8192000000 10444339520 10773639168 1490313472 15.392 100 85 170 0 1525563312 7891168 27358672
20:11:45 17554 8192000000 10478773232 10819571712 1531718864 15.752 100 83 166 2 1567252688 9593728 25940096
20:13:07 9547 8192000000 10552554704 10906599424 1608703552 16.414 100 82 164 0 1645452752 11867552 24881648
20:14:25 9468 8192000000 10629645152 10998116352 1689932480 17.101 100 84 168 0 1727298000 13297008 24068512
20:15:37 6662 8192000000 10695234272 11074318336 1758427504 17.671 100 71 140 1 1789337536 11168080 19741952
20:16:46 4003 8192000000 10746127680 11125796864 1803295936 18.041 100 93 182 0 1842260416 14385008 24579472
20:17:52 2863 8192000000 10789566528 11171291136 1848524080 18.410 100 88 174 1 1886687600 14061840 24101680
20:18:57 1717 8192000000 10818284400 11208654848 1874316176 18.619 100 81 162 0 1910244912 14064272 21864464
20:19:59 10226 8192000000 10823378496 11220303872 1881955968 18.681 100 69 139 2 1908690160 6582096 20152096
20:21:10 7101 8192000000 10791466544 11223728128 1884655680 18.703 100 26 52 1 1898433408 6774560 7003168
20:22:18 6449 8192000000 10803761232 11236622336 1896350848 18.797 100 18 36 1 1907396016 6261152 4784016
20:23:26 6958 8192000000 10810744032 11243655168 1905365664 18.869 100 28 56 2 1919745568 6514432 7865472
20:24:35 50955 8192000000 10847010192 11266641920 1915304032 18.949 100 38 76 2 1931297040 5473184 10519824
20:26:30 6456 8192000000 10839901424 11269615616 1918711248 18.977 100 33 66 1 1935218080 7572592 8934240
20:27:38 6365 8192000000 10805412352 11240329216 1896372832 18.797 100 35 69 1 1913408256 6679904 10355520
20:28:46 12173 8192000000 10753952160 11180453888 1823594080 18.207 97 29 58 2 1838519664 6832208 8093376
20:30:02 6940 8192000000 10689619232 11090563072 1665869392 16.898 91 30 60 1 1680626832 6341408 8416032
20:31:12 9160 8192000000 10712526176 11120869376 1749642016 17.599 97 19 38 0 1761196096 5879936 5674144
20:32:21 57339 8192000000 10704659536 11129397248 1767278432 17.745 98 22 44 0 1779329504 5631264 6419808
20:34:19 6398 8192000000 10754418656 11178700800 1837701616 18.322 100 26 52 0 1850913840 5513712 7698512
20:35:28 9899 8192000000 10780827328 11200974848 1856171632 18.472 100 20 40 0 1868483312 6488576 5823104
20:36:38 18325 8192000000 10789005120 11222183936 1878286944 18.651 100 25 50 0 1895603936 10032224 7284768
20:37:57 44605 8192000000 10823202080 11249229824 1902379456 18.845 100 46 92 0 1921940544 6518096 13042992
20:39:53 8979 8192000000 10870993152 11289440256 1935052304 19.107 100 67 132 1 1965889024 11374048 19462672
20:41:07 23831 8192000000 10895686576 11325005824 1954053024 19.259 100 44 88 2 1984181008 17124304 13003680
20:42:34 19664 8192000000 10898304928 11340107776 1967244160 19.364 100 39 74 1 1995346576 16595168 11507248
20:44:06 4253 8192000000 10948271968 11371503616 1994270336 19.578 100 76 148 3 2037276656 21054256 21952064
20:45:17 6697 8192000000 10947489072 11384774656 2004254256 19.656 100 50 99 1 2040924176 22016992 14652928
20:46:28 3010 8192000000 10960541056 11395457024 2010144064 19.703 100 61 122 0 2052698064 24473328 18080672
20:47:36 4314 8192000000 10973945456 11399106560 2009264160 19.696 100 72 142 1 2060333264 29700672 21368432
20:48:47 3093 8192000000 10968147248 11400114176 1977397168 19.444 98 53 106 2 2020551264 26957952 16196144
20:49:56 2568 8192000000 10981740912 11405598720 2007580800 19.682 100 74 148 0 2065246128 34267152 23398176
20:51:04 4795 8192000000 10982798416 11403198464 1999856304 19.622 100 83 164 2 2064888560 39839312 25192944
20:52:11 1611 8192000000 10972464368 11387441152 1984698720 19.502 100 84 168 1 2052448576 41133344 26616512
20:53:18 3870 8192000000 10940335040 11363856384 1945650592 19.192 99 63 127 0 2007019296 41819968 19548736
20:54:27 4445 8192000000 10957917664 11374227456 1959211776 19.300 99 69 136 0 2020591552 40165856 21213920
20:55:34 2356 8192000000 10950477504 11369684992 1962550144 19.326 100 75 149 1 2031057440 45059280 23448016
20:56:44 9628 8192000000 10916055952 11354890240 1949336208 19.221 100 47 94 2 2004499200 39638352 15524640
20:58:00 9523 8192000000 10899264976 11347066880 1919027728 18.979 99 36 72 0 1964367840 33827776 11512336
20:59:29 12697 8192000000 10886673648 11327918080 1909553904 18.903 99 36 72 0 1962061440 41036992 11470544
21:00:53 24646 8192000000 10904267072 11346837504 1920249408 18.989 99 30 60 2 1966017104 35957840 9809856
21:02:29 20226 8192000000 10933556048 11378192384 1939563840 19.143 99 32 64 1 2004344592 53568976 11211776
21:03:51 17327 8192000000 10942735488 11399716864 1962343232 19.325 100 29 58 1 2023484272 51440432 9700608
21:05:11 21070 8192000000 10938629376 11402084352 1961174752 19.315 100 25 50 3 2027118976 56647792 9296432
21:06:43 32512 8192000000 10918824352 11363774464 1958083520 19.291 100 27 55 2 1990885088 24334832 8466736
21:08:18 43509 8192000000 10899467024 11370541056 1980288656 19.467 100 16 32 1 1991973472 7601664 4083152
21:10:02 85733 8192000000 10892113584 11366436864 1955645968 19.271 99 31 61 0 1971261616 7328304 8287344
21:13:05 31328 8192000000 10891278528 11340390400 1916065456 18.955 97 22 43 1 1929255120 6106736 7082928
21:14:38 42311 8192000000 10911300288 11365806080 1958727072 19.296 99 24 48 3 1975036272 8997088 7312112
21:16:22 23180 8192000000 10935907504 11380215808 1988783200 19.534 100 62 117 5 2015517536 9179840 17554496
21:17:47 48920 8192000000 10893758368 11358244864 1934755152 19.105 98 13 27 1 1951101936 12308928 4037856
21:19:43 24016 8192000000 10960645344 11402641408 2000184928 19.624 100 47 93 0 2030744096 15384736 15174432
21:21:18 79762 8192000000 10925864912 11405979648 1996258720 19.593 100 18 36 0 2016649696 14692832 5698144
21:24:48 101484 8192000000 10926752384 11385004032 1960374800 19.309 98 40 79 2 1984603136 12001568 12226768
21:27:38 82462 8192000000 10909697328 11406004224 1949771808 19.225 98 13 26 1 1978338112 24430720 4135584
21:30:06 84287 8192000000 10890102160 11363872768 1904674080 18.864 96 11 22 0 1922920320 15434576 2811664
21:32:34 84782 8192000000 10906865792 11382308864 1922616496 19.008 97 12 24 0 1943697040 17346784 3733760
21:35:00 101627 8192000000 10910690448 11379003392 1923501856 19.015 97 21 42 1 1942431392 12577888 6351648
21:37:43 84232 8192000000 10927224128 11390345216 1948431232 19.214 98 12 24 0 1963502976 11913504 3158240
21:40:10 87771 8192000000 10933585376 11403489280 1962281088 19.324 98 13 27 0 1978768272 12988752 3498432
21:42:47 50017 8192000000 10928303776 11382640640 1925088240 19.028 97 32 64 1 1944280928 10800928 8391760
21:44:42 81965 8192000000 10887375264 11349917696 1927803744 19.049 98 31 62 2 1946703536 9920672 8979120
21:47:11 82339 8192000000 10841050272 11299516416 1851264672 18.432 96 20 40 0 1867012528 9044672 6703184
21:49:34 80505 8192000000 10870066544 11341774848 1913578704 18.935 99 10 22 0 1924734512 8410560 2745248
21:51:59 50191 8192000000 10876828816 11351990272 1937230688 19.125 99 22 44 1 1954708752 11202864 6275200
21:53:53 69613 8192000000 10838981488 11315474432 1893922000 18.777 98 9 18 0 1906577408 10042608 2612800
21:56:05 38361 8192000000 10851534288 11330908160 1911773728 18.921 99 18 36 1 1929074272 12558000 4742544
21:57:49 61960 8192000000 10830999248 11308302336 1907682192 18.888 99 15 30 1 1920080288 7455472 4942624
21:59:52 76061 8192000000 10866760592 11356405760 1930286528 19.069 99 25 48 2 1951640384 14126688 7227168
22:02:08 50680 8192000000 10869587824 11343474688 1907217696 18.884 98 17 34 2 1926456400 14700720 4537984
22:04:00 80662 8192000000 10854496704 11314323456 1858143424 18.488 96 22 44 0 1878964592 14329392 6491776
22:06:22 86676 8192000000 10875313776 11346284544 1878769872 18.655 97 5 10 0 1895793232 15491680 1531680
22:08:54 160696 8192000000 10908872304 11392294912 1931107632 19.076 98 10 20 0 1954902512 21044880 2750000
22:12:42 22952 8192000000 10915446768 11376607232 1963486848 19.334 99 41 82 0 1983296704 8127968 11681888
22:14:19 57783 8192000000 10912843488 11374247936 1958750224 19.296 99 46 91 2 1987420192 15584528 13085440
22:16:18 49708 8192000000 10893243952 11383103488 1964391456 19.341 100 7 14 0 1977595184 11618848 1584880
22:18:48 93557 8192000000 10894884704 11369623552 1881356496 18.676 95 20 40 0 1903842384 17127872 5358016
22:21:22 106579 8192000000 10897266816 11373342720 1919016832 18.979 98 12 23 0 1934489552 12525712 2947008
22:24:09 54222 8192000000 10906751680 11380940800 1972778448 19.407 100 20 40 2 1989342880 10613712 5950720
22:26:07 53441 8192000000 10922831216 11380260864 1942095344 19.163 98 24 49 1 1957527840 8641312 6791184
22:28:19 58869 8192000000 10897717360 11387904000 1923671216 19.016 98 10 20 0 1940932848 14709968 2551664
22:30:20 56846 8192000000 10898281568 11357155328 1851969136 18.438 94 13 26 0 1864732640 9360304 3403200
22:32:17 98912 8192000000 10885768176 11345989632 1929260416 19.061 98 27 54 0 1945695952 8264992 8170544
22:35:00 12690 8192000000 10903642224 11361779712 1950615376 19.231 99 58 116 2 1978956080 11138960 17201744
22:36:16 32885 8192000000 10900823040 11371077632 1962566688 19.326 100 42 84 1 1988701552 14264400 11870464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-09-2021 22:40:15 to 10-09-2021 22:40:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-09-2021 22:40:31 to 10-09-2021 22:40:33.

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          236017
MON$OLDEST_ACTIVE               238527
MON$OLDEST_SNAPSHOT             238527
MON$NEXT_TRANSACTION            238527
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                       2034080
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33494 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33494 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33494 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33494 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 ---
:76: 
:77: mscs-test-161.budzdorov.ru    Fri Sep 10 22:37:50 2021
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Fri Sep 10 22:38:26 2021
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Fri Sep 10 22:38:26 2021
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Fri Sep 10 22:38:26 2021
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:91: 
--- end of diff output ---

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

10.09.21 22:40:34. 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-09-10 22:40:36 No severe PSQL-related problems occured
10.09.21 22:40:37. End of report.