Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.06.2022 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 16328668 kB
MemAvailable 31269912 kB
Buffers 0 kB
Cached 15418692 kB
SwapCached 2484 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-21 19:38:22
DTS_END                         2022-06-21 19:38:22.4430
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/48778
TRN_ID                          237761
ATT_ID                          633
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.56 23171 1087507
customer order: creation 167.68 385 20121
customer order: refuse 14.15 360 1698
order to supplier: creation 45.01 1341 5401
order to supplier: removal 3.35 112328 402
invoice (draft): creation 45.92 3629 5510
invoice (draft): removal 51.65 801 6198
invoice accept: apply 40.48 18803 4857
invoice accept: cancel 46.63 784 5595
customer reserve: creation 3764.01 184 451681
customer reserve: removal 4594.51 65 551341
realization accept: apply 81.33 197 9759
realization accept: cancel 81.29 118 9755
payment from customer: creation 58.68 121 7041
payment from customer: removal 5.52 85 662
payment to supplier: creation 47.31 120 5677
payment to supplier: removal 3.30 95 396
service: total inventory turnovers 4.60 1057 552
service: total monetary turnovers 6.48 52 778
service: aggregate perf. data 0.66 27647 79
service: refresh index statistics 0.03 295245 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-06-2022 19:40:19 to 21-06-2022 19:40:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4016
TEST_TIME 2 5524
TEST_TIME 3 5020
TEST_TIME 4 4911
TEST_TIME 5 4835
TEST_TIME 6 5088
TEST_TIME 7 5520
TEST_TIME 8 5267
TEST_TIME 9 5457
TEST_TIME 10 5871
TEST_TIME 11 5956
TEST_TIME 12 6222
TEST_TIME 13 6202
TEST_TIME 14 6180
TEST_TIME 15 6673
TEST_TIME 16 6770
TEST_TIME 17 6947
TEST_TIME 18 7086
TEST_TIME 19 7487
TEST_TIME 20 8244
TEST_TIME 21 8278
TEST_TIME 22 8681
TEST_TIME 23 9180
TEST_TIME 24 9329
TEST_TIME 25 9407
TEST_TIME 26 9900
TEST_TIME 27 10773
TEST_TIME 28 10749
TEST_TIME 29 10701
TEST_TIME 30 10374
TEST_TIME 31 10341
TEST_TIME 32 10244
TEST_TIME 33 10066
TEST_TIME 34 10056
TEST_TIME 35 9888
TEST_TIME 36 9864
TEST_TIME 37 9773
TEST_TIME 38 9852
TEST_TIME 39 9810
TEST_TIME 40 9743
TEST_TIME 41 9660
TEST_TIME 42 9612
TEST_TIME 43 9502
TEST_TIME 44 9297
TEST_TIME 45 9365
TEST_TIME 46 9237
TEST_TIME 47 9178
TEST_TIME 48 9142
TEST_TIME 49 9143
TEST_TIME 50 9008
TEST_TIME 51 9141
TEST_TIME 52 9154
TEST_TIME 53 9260
TEST_TIME 54 9284
TEST_TIME 55 9344
TEST_TIME 56 9297
TEST_TIME 57 9285
TEST_TIME 58 9313
TEST_TIME 59 9382
TEST_TIME 60 9407
TEST_TIME 61 9428
TEST_TIME 62 9479
TEST_TIME 63 9532
TEST_TIME 64 9549
TEST_TIME 65 9487
TEST_TIME 66 9379
TEST_TIME 67 9403
TEST_TIME 68 9346
TEST_TIME 69 9284
TEST_TIME 70 9352
TEST_TIME 71 9279
TEST_TIME 72 9263
TEST_TIME 73 9238
TEST_TIME 74 9145
TEST_TIME 75 9155
TEST_TIME 76 9091
TEST_TIME 77 9040
TEST_TIME 78 9027
TEST_TIME 79 9078
TEST_TIME 80 9082
TEST_TIME 81 9099
TEST_TIME 82 9218
TEST_TIME 83 9167
TEST_TIME 84 9188
TEST_TIME 85 9291
TEST_TIME 86 9297
TEST_TIME 87 9239
TEST_TIME 88 9385
TEST_TIME 89 9354
TEST_TIME 90 9393
TEST_TIME 91 9351
TEST_TIME 92 9256
TEST_TIME 93 9257
TEST_TIME 94 9198
TEST_TIME 95 9226
TEST_TIME 96 9193
TEST_TIME 97 9258
TEST_TIME 98 9239
TEST_TIME 99 9215
TEST_TIME 100 9244
TEST_TIME 101 9206
TEST_TIME 102 9213
TEST_TIME 103 9240
TEST_TIME 104 9220
TEST_TIME 105 9276
TEST_TIME 106 9340
TEST_TIME 107 9327
TEST_TIME 108 9350
TEST_TIME 109 9343
TEST_TIME 110 9328
TEST_TIME 111 9258
TEST_TIME 112 9205
TEST_TIME 113 9214
TEST_TIME 114 9236
TEST_TIME 115 9202
TEST_TIME 116 9186
TEST_TIME 117 9165
TEST_TIME 118 9134
TEST_TIME 119 9129
TEST_TIME 120 9063

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-06-2022 19:40:23 to 21-06-2022 19:40:26.

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 1506249 1506249 0 0.00 0 20693 6 0 0
doc_list_biud 1506249 1506249 0 0.00 0 22427 30 0 0
sp_add_invoice_to_stock 5002 4857 145 2.89 14 193144 18803 0 145
sp_cancel_adding_invoice 5595 5595 0 0.00 5 67518 784 0 0
sp_cancel_client_order 1698 1698 0 0.00 4 14391 360 0 0
sp_cancel_customer_reserve 551341 551341 0 0.00 1 22429 65 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 3 4033 86 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 2 7130 95 0 0
sp_cancel_supplier_invoice 6203 6198 5 0.08 2 15569 801 0 5
sp_cancel_supplier_order 402 402 0 0.00 7 1112423 112329 0 0
sp_cancel_write_off 9755 9755 0 0.00 1 9845 118 0 0
sp_client_order 20121 20121 0 0.00 3 15299 385 0 0
sp_customer_reserve 451681 451681 0 0.00 0 81118 184 0 0
sp_fill_shopping_cart 31023 31023 0 0.00 1 16891 41 0 0
sp_fill_shopping_cart_clo_res 451679 451679 0 0.00 0 15749 14 0 0
sp_get_clo_for_invoice 4855 4855 0 0.00 0 27828 310 0 0
sp_kill_cost_storno 16408 16408 0 0.00 0 8225 59 0 0
sp_kill_qstorno_ret_qs2qd 1119037 1119037 0 0.00 0 22423 35 0 0
sp_kill_qty_storno 589603 589603 0 0.00 0 22426 75 0 0
sp_lock_selected_doc 20193 20193 0 0.00 0 7818 6 0 0
sp_make_cost_storno 27332 27332 0 0.00 0 16000 62 0 0
sp_multiply_rows_for_pdistr 27332 27332 0 0.00 0 8017 30 0 0
sp_multiply_rows_for_qdistr 20121 20121 0 0.00 0 15291 168 0 0
sp_pay_from_customer 7041 7041 0 0.00 4 19790 122 0 0
sp_pay_to_supplier 5677 5677 0 0.00 5 20181 120 0 0
sp_payment_common 12718 12718 0 0.00 1 19782 31 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 0 14358 327 0 0
sp_qd_handle_on_invoice_upd_sts 10450 10450 0 0.00 0 14903 346 0 0
sp_qd_handle_on_reserve_upd_sts 9759 9759 0 0.00 0 12689 66 0 0
sp_reserve_write_off 9759 9759 0 0.00 6 13728 198 0 0
sp_supplier_invoice 5510 5510 0 0.00 8 78301 3629 0 0
sp_supplier_order 5401 5401 0 0.00 4 35709 1342 0 0
srv_aggregate_perf_data 79 79 0 0.00 3194 256800 27648 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1458 154235 46795 0 0
srv_find_qd_qs_mism 1041043 1041043 0 0.00 0 13740 5 0 0
srv_make_invnt_saldo 552 552 0 0.00 28 87602 1057 0 0
srv_make_money_saldo 778 778 0 0.00 0 14118 53 0 0
srv_recalc_idx_stat 4 4 0 0.00 127857 539267 295246 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4857 4857 0 0.00 0 10338 188 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 16632 169 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 5762 18 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 2660 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 2447 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 192 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 1663 17 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 24571 1805 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 8622 32 0 0
t$perf-norm:sp_client_order 20121 20121 0 0.00 0 7496 19 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 7699 20 0 0
t$perf-norm:sp_pay_from_customer 7041 7041 0 0.00 0 5965 9 0 0
t$perf-norm:sp_pay_to_supplier 5677 5677 0 0.00 0 4363 12 0 0
t$perf-norm:sp_reserve_write_off 9759 9759 0 0.00 0 24574 19 0 0
t$perf-norm:sp_supplier_invoice 5510 5510 0 0.00 0 6288 26 0 0
t$perf-norm:sp_supplier_order 5401 5401 0 0.00 0 10237 20 0 0
t$perf-norm:srv_aggregate_perf_data 79 79 0 0.00 0 4 0 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 80 3 0 0
t$perf-norm:srv_make_invnt_saldo 552 552 0 0.00 0 3508 33 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 1688 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 14 5 0 0
v_add_invoice_to_stock 9990 9700 290 2.90 0 6580 19 0 290
v_all_customers 37212 37212 0 0.00 0 112 1 0 0
v_all_suppliers 29922 29922 0 0.00 0 116 1 0 0
v_all_wares 40242 40242 0 0.00 0 12 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 2115 7 0 0
v_cancel_client_order 3396 3396 0 0.00 0 3085 5 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 2238 8 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 439 4 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 5140 14 0 0
v_cancel_supplier_invoice 794 784 10 1.25 0 3715 21 0 10
v_cancel_supplier_order 804 804 0 0.00 0 173 2 0 0
v_cancel_write_off 1952 1952 0 0.00 0 3671 15 0 0
v_max_id_clo_ord 5401 5401 0 0.00 0 7701 3 0 0
v_max_id_clo_res 939 939 0 0.00 0 2829 11 0 0
v_max_id_ord_sup 5501 5501 0 0.00 0 4183 4 0 0
v_max_non_paid_invoice 1618 1618 0 0.00 0 328 2 0 0
v_max_non_paid_realizn 3569 3569 0 0.00 0 7147 6 0 0
v_min_id_clo_ord 5401 5401 0 0.00 0 7378 10 0 0
v_min_id_clo_res 939 939 0 0.00 0 2151 10 0 0
v_min_id_ord_sup 5501 5501 0 0.00 0 5140 4 0 0
v_min_non_paid_invoice 5677 5677 0 0.00 0 10347 17 0 0
v_min_non_paid_realizn 7041 7041 0 0.00 0 19777 27 0 0
v_our_firm 9974 9974 0 0.00 0 19 1 0 0
v_reserve_write_off 19518 19518 0 0.00 0 5605 6 0 0
x_lock_depdocs_on_canc_invoice 11401 11401 0 0.00 0 14933 125 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 4899 356 0 0
x_make_qstorno_1000_1200 5401 5401 0 0.00 2 34403 1251 0 0
x_make_qstorno_1200_2000 5510 5510 0 0.00 4 72388 3486 0 0
x_make_qstorno_2100_3300 451681 451681 0 0.00 0 81117 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-06-2022 19:40:27 to 21-06-2022 19:40:31.

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:26 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:26 15364 8192000000 9891399936 10198409216 972508592 10.611 100 86 172 0 1000611792 5570608 22532592
17:10:48 27062 8192000000 9950869104 10271555584 1024193936 11.112 100 89 178 0 1055580336 8395072 22991328
17:12:24 14509 8192000000 9985411648 10322309120 1069326912 11.546 100 83 166 0 1100415056 10742944 20345200
17:13:48 13387 8192000000 10061403680 10410782720 1134726960 12.166 100 84 167 1 1166692944 12307472 19658512
17:15:04 9009 8192000000 10097016480 10457419776 1179241328 12.583 100 87 175 2 1212773968 13706880 19825760
17:16:16 5574 8192000000 10136690784 10512502784 1225895440 13.016 100 69 138 0 1253659648 11545408 16218800
17:17:23 2067 8192000000 10182869424 10562924544 1270004288 13.422 100 86 172 2 1302488304 12604336 19879680
17:18:27 2421 8192000000 10202207728 10583998464 1284749744 13.556 100 93 186 1 1319166336 12937792 21478800
17:19:30 16981 8192000000 10185550560 10593587200 1296039104 13.659 100 37 75 0 1312774592 8209968 8525520
17:20:50 3117 8192000000 10197401520 10603405312 1302847264 13.721 100 33 67 0 1318070752 7145408 8078080
17:21:55 7086 8192000000 10193196768 10611077120 1309968784 13.786 100 15 30 0 1318898992 5290352 3639856
17:23:03 57702 8192000000 10206117328 10618736640 1311428720 13.799 100 32 64 3 1324257296 5923584 6904992
17:25:01 6174 8192000000 10212152512 10631053312 1320415232 13.880 100 24 48 0 1331427200 5744080 5267888
17:26:10 7804 8192000000 10218429376 10637361152 1315659008 13.837 99 23 46 0 1331396992 10643728 5094256
17:27:19 30377 8192000000 10205203056 10624704512 1309309920 13.780 99 16 32 0 1318782864 5104640 4368304
17:28:51 7036 8192000000 10193902832 10608275456 1300448976 13.699 100 26 51 3 1311270560 4837920 5983664
17:30:00 9588 8192000000 10108752112 10501746688 1134257216 12.161 90 15 30 0 1142805408 4778624 3769568
17:31:13 9083 8192000000 10141480864 10543661056 1212893552 12.896 96 26 52 0 1225063600 5791376 6378672
17:32:22 38106 8192000000 10146642224 10550292480 1234789072 13.098 97 40 80 2 1249189232 4306208 10093952
17:34:02 11159 8192000000 10172999888 10582278144 1282264832 13.534 100 23 45 1 1292845392 5219616 5360944
17:35:15 11129 8192000000 10184105408 10600214528 1299259424 13.689 100 19 38 0 1309089664 5497344 4332896
17:36:26 10748 8192000000 10199628928 10613956608 1305396768 13.744 100 18 36 0 1314951344 5485712 4068864
17:37:38 28620 8192000000 10199917648 10620461056 1312404944 13.808 100 32 63 2 1326055872 6467712 7183216
17:39:15 16959 8192000000 10249648704 10661285888 1342365968 14.079 100 46 92 1 1362713520 9211376 11136176
17:40:40 5120 8192000000 10273443216 10685607936 1361601216 14.252 100 55 110 0 1387181824 12460976 13119632
17:42:22 11709 8192000000 10294742816 10713284608 1374900192 14.371 100 40 80 0 1405753504 21115232 9738080
17:43:40 13771 8192000000 10307806384 10726322176 1388146272 14.489 100 56 112 0 1421263936 19929376 13188288
17:44:56 13755 8192000000 10323686336 10745786368 1398811840 14.584 100 62 125 0 1435911072 22247488 14851744
17:46:24 6610 8192000000 10329758016 10748694528 1399699248 14.592 100 67 134 1 1439169328 23531408 15938672
17:47:37 11704 8192000000 10334771264 10757992448 1403458000 14.626 100 58 116 1 1444390176 27422352 13509824
17:49:16 3071 8192000000 10362865120 10775941120 1413688368 14.717 100 74 148 0 1462112784 30153168 18271248
17:50:22 1762 8192000000 10355962496 10764595200 1405214928 14.641 100 80 156 0 1456863472 32587744 19060800
17:51:31 11446 8192000000 10335717312 10749390848 1380599312 14.422 99 60 115 0 1433209056 38115872 14493872
17:52:47 2077 8192000000 10347708656 10754842624 1389759072 14.504 100 78 156 1 1451702192 42367360 19575760
17:53:53 3956 8192000000 10352001648 10755588096 1391964800 14.523 100 77 151 2 1449181104 37926976 19289328
17:55:06 8121 8192000000 10333820384 10748514304 1382652128 14.440 100 60 120 1 1442551552 43312848 16586576
17:56:21 3413 8192000000 10346289584 10755956736 1383561808 14.448 100 71 142 2 1448425056 46888672 17974576
17:57:30 5237 8192000000 10353817680 10764513280 1393876544 14.540 100 74 148 0 1456993728 43324272 19792912
17:58:38 11327 8192000000 10329976576 10751324160 1391404464 14.518 100 46 91 0 1444165888 39870272 12891152
17:59:54 1454 8192000000 10364111424 10770231296 1401984208 14.613 100 82 162 0 1464735744 41297200 21454336
18:00:59 17339 8192000000 10344582128 10766041088 1386708016 14.476 100 40 80 0 1438188064 40226912 11253136
18:02:18 4283 8192000000 10335889600 10755702784 1380395200 14.420 99 51 102 1 1427128336 33005808 13727328
18:03:31 14629 8192000000 10318128704 10749358080 1377272640 14.392 100 29 58 0 1424350176 38690272 8387264
18:04:47 13170 8192000000 10324524096 10778808320 1377906656 14.398 100 22 44 0 1430502768 46414320 6181792
18:06:01 42430 8192000000 10287198320 10739441664 1366336192 14.294 100 10 20 0 1394205104 25468736 2400176
18:07:44 64078 8192000000 10283960688 10723958784 1365094608 14.283 99 33 67 1 1380228320 7791344 7342368
18:09:53 154235 8192000000 10293720256 10746318848 1370685104 14.333 98 23 45 1 1384812304 9611792 4515408
18:13:28 29928 8192000000 10297026208 10731954176 1360052208 14.238 98 45 90 1 1385613968 15195824 10365936
18:14:58 34097 8192000000 10283759984 10739187712 1382421728 14.438 100 12 25 0 1403668272 18420960 2825584
18:16:35 93358 8192000000 10284994784 10740834304 1341919056 14.075 97 18 36 0 1358104064 12039968 4145040
18:20:24 60642 8192000000 10300068528 10761064448 1400634240 14.601 100 9 18 0 1412156032 9007840 2513952
18:22:25 80120 8192000000 10295841296 10762936320 1405643024 14.645 100 25 48 1 1421764496 10631952 5489520
18:25:00 14749 8192000000 10320324080 10777210880 1413818656 14.718 100 33 65 0 1437173664 15850016 7504992
18:26:34 63676 8192000000 10314890080 10795241472 1415358976 14.732 100 13 26 0 1440822192 22141216 3322000
18:28:41 68206 8192000000 10320009728 10787688448 1415546224 14.733 100 14 29 0 1435416368 16337776 3532368
18:30:54 53039 8192000000 10318735200 10795778048 1407344704 14.660 99 7 15 0 1423609552 14626368 1638480
18:32:47 105803 8192000000 10303958608 10769858560 1364089904 14.274 97 12 24 1 1378705840 11955168 2660768
18:35:36 65864 8192000000 10309066624 10756931584 1345650416 14.108 96 16 32 1 1365352560 16009728 3692416
18:37:49 75711 8192000000 10282472240 10739707904 1336396368 14.025 97 19 38 1 1350739104 10285344 4057392
18:40:17 67651 8192000000 10257051360 10716950528 1322576656 13.900 96 23 44 1 1342610160 14709888 5323616
18:42:26 53273 8192000000 10265477488 10697957376 1326518704 13.936 98 26 52 1 1346043648 13683504 5841440
18:44:20 82033 8192000000 10252633872 10689875968 1313948400 13.822 97 42 84 1 1338247056 15096784 9201872
18:47:12 8008 8192000000 10251904768 10694447104 1337197008 14.032 99 30 60 2 1355597920 11515648 6885264
18:48:21 47453 8192000000 10255226496 10713849856 1357514464 14.215 100 13 25 1 1370062816 9276208 3272144
18:50:09 95146 8192000000 10252453184 10700136448 1312923712 13.813 96 16 33 0 1325149376 8186128 4039536
18:52:46 60309 8192000000 10255026256 10706087936 1334051104 14.004 98 19 37 1 1347089952 8781312 4257536
18:54:48 37629 8192000000 10245972688 10698555392 1341498528 14.071 99 13 25 1 1351356912 6971280 2887104
18:56:39 67540 8192000000 10254687664 10706284544 1325520320 13.927 97 22 44 1 1343160448 12158624 5481504
18:58:52 64619 8192000000 10265585280 10714140672 1325238736 13.924 97 13 26 1 1340921280 12246288 3436256
19:01:09 43388 8192000000 10268055008 10701135872 1319150656 13.869 97 24 49 0 1334761280 9574208 6036416
19:02:58 102098 8192000000 10268041392 10713612288 1333240400 13.996 98 14 28 0 1352627136 16104080 3282656
19:05:40 34451 8192000000 10293189808 10732474368 1367085520 14.301 99 45 90 0 1391547680 13301152 11161008
19:07:22 123277 8192000000 10281401600 10740432896 1342259936 14.078 97 12 24 0 1362441664 17631504 2550224
19:10:27 60270 8192000000 10298023104 10751307776 1386979120 14.479 100 36 72 1 1405546384 9471664 9095600
19:12:36 65627 8192000000 10298264384 10759839744 1383315760 14.446 99 6 12 1 1395198080 10795088 1087232
19:15:00 103028 8192000000 10300606640 10753474560 1382011904 14.435 99 14 28 0 1396909648 11613568 3284176
19:17:44 76722 8192000000 10294839184 10759319552 1367862064 14.308 98 25 50 0 1384537056 10836192 5838800
19:20:03 66573 8192000000 10303746560 10747195392 1364793104 14.280 98 34 69 0 1383553024 10802256 7957664
19:22:18 44318 8192000000 10317789952 10768322560 1396122336 14.560 100 29 57 1 1414521552 12231552 6167664
19:24:03 53868 8192000000 10299692800 10764070912 1390962416 14.514 100 23 46 1 1409850000 13828208 5059376
19:25:57 68306 8192000000 10296146432 10748407808 1324849984 13.921 95 23 46 0 1339439264 9674352 4914928
19:28:07 76235 8192000000 10266090656 10710011904 1335699632 14.019 98 11 22 0 1346504432 8307344 2497456
19:30:52 60732 8192000000 10289432592 10735878144 1363207008 14.266 99 24 48 0 1382773440 13579984 5986448
19:32:56 38104 8192000000 10256189872 10720006144 1338586528 14.045 98 5 10 0 1355088720 15371136 1131056
19:34:35 72605 8192000000 10250281040 10691801088 1318830736 13.866 97 22 44 0 1331563696 7598928 5134032
19:36:48 87907 8192000000 10253752288 10710147072 1331780800 13.983 99 17 34 1 1353013776 17234368 3998608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-06-2022 19:40:33 to 21-06-2022 19:40:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-06-2022 19:40:49 to 21-06-2022 19:40:51.

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          236861
MON$OLDEST_ACTIVE               238545
MON$OLDEST_SNAPSHOT             238545
MON$NEXT_TRANSACTION            238545
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                       2028896
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:231: 
:232: mscs-test-161.budzdorov.ru    Tue Jun 21 19:38:30 2022
:233:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:234: 
:235: 
:236: mscs-test-161.budzdorov.ru    Tue Jun 21 19:38:38 2022
:237:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:238: 
--- end of diff output ---

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

21.06.22 19:40:52. 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-21 19:40:54 No severe PSQL-related problems occured
21.06.22 19:40:55. End of report.