Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.03.2022 16:38
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 16893616 kB
MemAvailable 31584096 kB
Buffers 0 kB
Cached 14794628 kB
SwapCached 2172 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-03-28 16:38:15
DTS_END                         2022-03-28 16:38:15.8460
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/59526
TRN_ID                          233761
ATT_ID                          637
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: 9187.58 23290 1102510
customer order: creation 165.29 362 19835
customer order: refuse 14.28 317 1714
order to supplier: creation 44.63 1274 5355
order to supplier: removal 3.34 103310 401
invoice (draft): creation 43.12 3074 5174
invoice (draft): removal 52.70 800 6324
invoice accept: apply 40.64 18872 4877
invoice accept: cancel 47.80 633 5736
customer reserve: creation 3746.76 188 449611
customer reserve: removal 4742.52 56 569102
realization accept: apply 80.66 203 9679
realization accept: cancel 80.28 111 9634
payment from customer: creation 57.71 122 6925
payment from customer: removal 5.35 76 642
payment to supplier: creation 47.67 145 5720
payment to supplier: removal 3.21 66 385
service: total inventory turnovers 4.34 966 521
service: total monetary turnovers 6.43 39 772
service: aggregate perf. data 0.83 19193 99
service: refresh index statistics 0.03 315995 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-03-2022 16:38:56 to 28-03-2022 16:38:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4547
TEST_TIME 2 2817
TEST_TIME 3 3769
TEST_TIME 4 3707
TEST_TIME 5 4800
TEST_TIME 6 5208
TEST_TIME 7 5182
TEST_TIME 8 5767
TEST_TIME 9 5656
TEST_TIME 10 5801
TEST_TIME 11 5985
TEST_TIME 12 5911
TEST_TIME 13 6001
TEST_TIME 14 5935
TEST_TIME 15 6062
TEST_TIME 16 6583
TEST_TIME 17 6706
TEST_TIME 18 6962
TEST_TIME 19 7488
TEST_TIME 20 7900
TEST_TIME 21 8324
TEST_TIME 22 8291
TEST_TIME 23 8515
TEST_TIME 24 8817
TEST_TIME 25 10270
TEST_TIME 26 10624
TEST_TIME 27 10533
TEST_TIME 28 10288
TEST_TIME 29 10122
TEST_TIME 30 10103
TEST_TIME 31 9993
TEST_TIME 32 9813
TEST_TIME 33 9873
TEST_TIME 34 9721
TEST_TIME 35 9628
TEST_TIME 36 9473
TEST_TIME 37 9337
TEST_TIME 38 9433
TEST_TIME 39 9424
TEST_TIME 40 9347
TEST_TIME 41 9444
TEST_TIME 42 9299
TEST_TIME 43 9191
TEST_TIME 44 9294
TEST_TIME 45 9258
TEST_TIME 46 9155
TEST_TIME 47 9180
TEST_TIME 48 9194
TEST_TIME 49 9413
TEST_TIME 50 9436
TEST_TIME 51 9369
TEST_TIME 52 9386
TEST_TIME 53 9480
TEST_TIME 54 9436
TEST_TIME 55 9558
TEST_TIME 56 9446
TEST_TIME 57 9345
TEST_TIME 58 9311
TEST_TIME 59 9260
TEST_TIME 60 9200
TEST_TIME 61 9197
TEST_TIME 62 9156
TEST_TIME 63 9089
TEST_TIME 64 9011
TEST_TIME 65 9032
TEST_TIME 66 9160
TEST_TIME 67 9184
TEST_TIME 68 9183
TEST_TIME 69 9222
TEST_TIME 70 9205
TEST_TIME 71 9249
TEST_TIME 72 9259
TEST_TIME 73 9267
TEST_TIME 74 9302
TEST_TIME 75 9379
TEST_TIME 76 9334
TEST_TIME 77 9378
TEST_TIME 78 9360
TEST_TIME 79 9259
TEST_TIME 80 9200
TEST_TIME 81 9175
TEST_TIME 82 9166
TEST_TIME 83 9131
TEST_TIME 84 9085
TEST_TIME 85 9034
TEST_TIME 86 8989
TEST_TIME 87 9034
TEST_TIME 88 8993
TEST_TIME 89 9122
TEST_TIME 90 9122
TEST_TIME 91 9118
TEST_TIME 92 9143
TEST_TIME 93 9192
TEST_TIME 94 9154
TEST_TIME 95 9202
TEST_TIME 96 9240
TEST_TIME 97 9255
TEST_TIME 98 9215
TEST_TIME 99 9315
TEST_TIME 100 9330
TEST_TIME 101 9335
TEST_TIME 102 9245
TEST_TIME 103 9208
TEST_TIME 104 9187
TEST_TIME 105 9147
TEST_TIME 106 9135
TEST_TIME 107 9091
TEST_TIME 108 9082
TEST_TIME 109 9054
TEST_TIME 110 9061
TEST_TIME 111 9088
TEST_TIME 112 9079
TEST_TIME 113 9091
TEST_TIME 114 9065
TEST_TIME 115 9078
TEST_TIME 116 9109
TEST_TIME 117 9168
TEST_TIME 118 9184
TEST_TIME 119 9189
TEST_TIME 120 9188

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-03-2022 16:38:59 to 28-03-2022 16:39:03.

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 1520050 1520050 0 0.00 0 55257 6 0 0
doc_list_biud 1520048 1520048 0 0.00 0 58681 27 0 0
sp_add_invoice_to_stock 4972 4877 95 1.91 47 177911 18873 0 95
sp_cancel_adding_invoice 5736 5736 0 0.00 4 46272 633 0 0
sp_cancel_client_order 1714 1714 0 0.00 4 9713 318 0 0
sp_cancel_customer_reserve 569102 569102 0 0.00 1 58684 57 0 0
sp_cancel_pay_from_customer 642 642 0 0.00 2 4989 77 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 3 5525 67 0 0
sp_cancel_supplier_invoice 6340 6324 16 0.25 2 17926 800 0 16
sp_cancel_supplier_order 401 401 0 0.00 7 1215195 103311 0 0
sp_cancel_write_off 9634 9634 0 0.00 1 14861 112 0 0
sp_client_order 19835 19835 0 0.00 4 51850 362 0 0
sp_customer_reserve 449611 449611 0 0.00 0 61725 188 0 0
sp_fill_shopping_cart 30361 30361 0 0.00 1 14179 35 0 0
sp_fill_shopping_cart_clo_res 449609 449609 0 0.00 0 16795 17 0 0
sp_get_clo_for_invoice 4875 4875 0 0.00 0 32590 310 0 0
sp_kill_cost_storno 16397 16397 0 0.00 0 6896 53 0 0
sp_kill_qstorno_ret_qs2qd 1154563 1154563 0 0.00 0 58669 31 0 0
sp_kill_qty_storno 607465 607465 0 0.00 0 58680 66 0 0
sp_lock_selected_doc 20121 20121 0 0.00 0 6779 4 0 0
sp_make_cost_storno 27199 27199 0 0.00 0 44795 68 0 0
sp_multiply_rows_for_pdistr 27199 27199 0 0.00 0 55118 38 0 0
sp_multiply_rows_for_qdistr 19835 19835 0 0.00 0 13386 156 0 0
sp_pay_from_customer 6925 6925 0 0.00 4 13233 123 0 0
sp_pay_to_supplier 5720 5720 0 0.00 4 55264 146 0 0
sp_payment_common 12644 12644 0 0.00 1 13164 37 0 0
sp_qd_handle_on_cancel_clo 1714 1714 0 0.00 0 9707 293 0 0
sp_qd_handle_on_invoice_upd_sts 10611 10611 0 0.00 0 19581 298 0 0
sp_qd_handle_on_reserve_upd_sts 9679 9679 0 0.00 0 15152 68 0 0
sp_reserve_write_off 9679 9679 0 0.00 6 19292 203 0 0
sp_supplier_invoice 5174 5174 0 0.00 6 102372 3075 0 0
sp_supplier_order 5355 5355 0 0.00 5 52080 1275 0 0
srv_aggregate_perf_data 99 99 0 0.00 2167 119670 19194 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1542 137664 47357 0 0
srv_find_qd_qs_mism 1056923 1056923 0 0.00 0 19624 5 0 0
srv_make_invnt_saldo 521 521 0 0.00 22 51049 966 0 0
srv_make_money_saldo 772 772 0 0.00 0 14538 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 181133 512353 315996 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4877 4877 0 0.00 0 15707 153 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 5246 93 0 0
t$perf-norm:sp_cancel_client_order 1714 1714 0 0.00 0 3433 16 0 0
t$perf-norm:sp_cancel_customer_reserve 651 651 0 0.00 0 2108 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 642 642 0 0.00 0 1229 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 456 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 2093 15 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 34494 1672 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 6813 20 0 0
t$perf-norm:sp_client_order 19835 19835 0 0.00 0 9626 18 0 0
t$perf-norm:sp_customer_reserve 1058 1058 0 0.00 0 1638 6 0 0
t$perf-norm:sp_pay_from_customer 6925 6925 0 0.00 0 5162 12 0 0
t$perf-norm:sp_pay_to_supplier 5720 5720 0 0.00 0 10985 13 0 0
t$perf-norm:sp_reserve_write_off 9679 9679 0 0.00 0 12122 12 0 0
t$perf-norm:sp_supplier_invoice 5174 5174 0 0.00 0 4939 15 0 0
t$perf-norm:sp_supplier_order 5355 5355 0 0.00 0 5456 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 907 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 15 1 0 0
t$perf-norm:srv_make_invnt_saldo 521 521 0 0.00 0 2259 22 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 923 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9940 9750 190 1.91 0 6773 15 0 190
v_all_customers 36382 36382 0 0.00 0 18 0 0 0
v_all_suppliers 29536 29536 0 0.00 0 17 1 0 0
v_all_wares 39668 39668 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 2520 9 0 0
v_cancel_client_order 3428 3428 0 0.00 0 3407 6 0 0
v_cancel_customer_prepayment 1284 1284 0 0.00 0 743 4 0 0
v_cancel_customer_reserve 1302 1302 0 0.00 0 5077 7 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 741 3 0 0
v_cancel_supplier_invoice 800 768 32 4.00 0 922 13 0 32
v_cancel_supplier_order 802 802 0 0.00 0 958 5 0 0
v_cancel_write_off 2000 2000 0 0.00 0 7079 15 0 0
v_max_id_clo_ord 5354 5354 0 0.00 0 1384 1 0 0
v_max_id_clo_res 1058 1058 0 0.00 0 2664 16 0 0
v_max_id_ord_sup 5173 5173 0 0.00 0 1830 2 0 0
v_max_non_paid_invoice 1478 1478 0 0.00 0 1940 6 0 0
v_max_non_paid_realizn 3608 3608 0 0.00 0 1646 4 0 0
v_min_id_clo_ord 5354 5354 0 0.00 0 1683 4 0 0
v_min_id_clo_res 1058 1058 0 0.00 0 2136 5 0 0
v_min_id_ord_sup 5173 5173 0 0.00 0 9762 8 0 0
v_min_non_paid_invoice 5719 5719 0 0.00 0 10664 25 0 0
v_min_non_paid_realizn 6925 6925 0 0.00 0 10652 32 0 0
v_our_firm 9920 9920 0 0.00 0 10 1 0 0
v_reserve_write_off 19357 19357 0 0.00 0 5884 5 0 0
x_lock_depdocs_on_canc_invoice 11676 11676 0 0.00 0 13166 110 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 12707 420 0 0
x_make_qstorno_1000_1200 5355 5355 0 0.00 2 52070 1202 0 0
x_make_qstorno_1200_2000 5174 5174 0 0.00 2 102344 2945 0 0
x_make_qstorno_2100_3300 449611 449611 0 0.00 0 61149 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-03-2022 16:39:04 to 28-03-2022 16:39:08.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:19 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:20 18900 8192000000 9902577136 10214588416 988365744 10.766 100 87 174 0 1016636352 5753232 22517376
14:10:46 22697 8192000000 9941314832 10264084480 1024918976 11.119 100 89 179 1 1057020960 8303360 23798624
14:12:15 15755 8192000000 9983664592 10314989568 1066092848 11.515 100 85 168 1 1096579712 9306016 21180848
14:13:35 12105 8192000000 10027620048 10372960256 1112860544 11.959 100 73 146 0 1140974272 10777648 17336080
14:14:48 9556 8192000000 10083807360 10442862592 1173310288 12.528 100 86 173 0 1206356096 13052064 19993744
14:16:02 7929 8192000000 10134005904 10501267456 1223132528 12.991 100 80 160 1 1253978608 12888448 17957632
14:17:11 4412 8192000000 10159082048 10536214528 1248656304 13.226 100 78 153 2 1278339376 12378928 17304144
14:18:20 1910 8192000000 10203662384 10581626880 1283262256 13.543 100 90 179 0 1316888272 13729392 19896624
14:19:23 1507 8192000000 10217068560 10601836544 1300395840 13.699 100 93 186 0 1334767296 12415728 21955728
14:20:27 2494 8192000000 10225407552 10614644736 1307058128 13.759 100 86 171 2 1338103968 11048720 19997120
14:21:31 5445 8192000000 10206108752 10613600256 1315478720 13.836 100 41 82 1 1335870752 10920544 9471488
14:22:38 2967 8192000000 10204646336 10618216448 1319582144 13.873 100 40 80 0 1335454080 6699744 9172192
14:23:42 49801 8192000000 10224523904 10635853824 1325760656 13.929 100 36 71 0 1339527872 4763264 9003952
14:25:34 8870 8192000000 10221095216 10642219008 1333846544 14.002 100 17 34 2 1343365808 5810480 3708784
14:26:44 10549 8192000000 10226098544 10649800704 1336748848 14.028 100 18 35 3 1346253168 5990576 3513744
14:27:56 8303 8192000000 10214210256 10632970240 1302718912 13.720 98 19 36 1 1311763872 4889696 4155264
14:29:05 28488 8192000000 10203107728 10610884608 1271009632 13.431 96 38 76 1 1285264752 5748960 8506160
14:30:34 5507 8192000000 10142447552 10545192960 1216901728 12.933 95 29 58 0 1230040672 6307984 6830960
14:31:41 10623 8192000000 10127084016 10527506432 1222047360 12.981 98 24 48 0 1233789520 6461696 5280464
14:32:52 10674 8192000000 10139924416 10545373184 1249705536 13.236 99 23 46 1 1260527664 5913856 4908272
14:34:04 36199 8192000000 10143430848 10552029184 1250810128 13.246 99 31 61 1 1263057904 5140528 7107248
14:35:45 6859 8192000000 10175257936 10583552000 1281375312 13.526 100 28 54 1 1293708096 6047568 6285216
14:36:53 9460 8192000000 10181035280 10593402880 1290450800 13.608 100 30 59 0 1303271984 5416992 7404192
14:38:03 12591 8192000000 10192032992 10609524736 1305257296 13.743 100 22 44 1 1314752080 5306880 4187904
14:39:27 16265 8192000000 10237874000 10651258880 1334630288 14.009 100 41 83 0 1352446000 7646032 10169680
14:40:45 90545 8192000000 10258468416 10675875840 1344678608 14.100 100 31 62 1 1360882144 8770448 7433088
14:43:19 11904 8192000000 10257939712 10680131584 1354485648 14.188 100 34 68 1 1372099968 9579808 8034512
14:44:49 1540 8192000000 10289421728 10706358272 1366868160 14.299 100 60 120 2 1398874416 17035984 14970272
14:45:54 16330 8192000000 10311491728 10729488384 1381932064 14.434 100 56 112 0 1419506864 24444448 13130352
14:47:20 6757 8192000000 10316296800 10734862336 1379875008 14.415 99 51 100 3 1414462192 22565744 12021440
14:48:36 9732 8192000000 10329027312 10756554752 1403057392 14.622 100 46 92 3 1440288704 25746720 11484592
14:49:50 5217 8192000000 10335357840 10754093056 1392174896 14.525 99 56 110 0 1433703136 28526416 13001824
14:51:01 7229 8192000000 10348109968 10772271104 1412567568 14.707 100 53 106 1 1459342688 33570672 13204448
14:52:22 3590 8192000000 10349300176 10768216064 1408512576 14.671 100 70 135 3 1461524928 35114960 17897392
14:53:31 5317 8192000000 10310019520 10721841152 1360419152 14.241 98 62 124 2 1415804304 39523696 15861456
14:54:43 3263 8192000000 10336252640 10746798080 1384773920 14.459 100 67 131 0 1443911072 42212912 16924240
14:55:56 4497 8192000000 10353123488 10759520256 1388829376 14.495 100 77 154 2 1456431664 47977760 19624528
14:57:04 1631 8192000000 10346996400 10745704448 1373200928 14.356 99 88 172 1 1444709696 49781760 21727008
14:58:09 6547 8192000000 10331795520 10742214656 1361993344 14.255 99 66 131 2 1429942576 50082736 17866496
14:59:18 12473 8192000000 10320151344 10745262080 1377654320 14.396 100 38 76 1 1427596880 39887520 10055040
15:00:42 18339 8192000000 10322763904 10748968960 1361641344 14.252 99 38 75 1 1418136608 45850496 10644768
15:02:07 9354 8192000000 10323888288 10758512640 1357572048 14.216 99 25 50 0 1421993760 56821840 7599872
15:03:26 53556 8192000000 10284542304 10718027776 1322238096 13.897 97 16 32 0 1376263248 49276240 4748912
15:05:30 69401 8192000000 10279389072 10723909632 1369591504 14.323 100 22 45 0 1385498384 10630864 5276016
15:08:54 40638 8192000000 10257306720 10713899008 1346855248 14.119 99 10 19 1 1357020880 8016800 2148832
15:10:41 79028 8192000000 10258587840 10710179840 1332343264 13.988 97 19 38 0 1345637776 9557376 3737136
15:13:01 118105 8192000000 10275695872 10729709568 1366101088 14.292 99 15 30 0 1386120656 16710416 3309152
15:16:00 51839 8192000000 10281975568 10732781568 1372979856 14.354 100 24 48 1 1396089664 17523200 5586608
15:18:05 55574 8192000000 10257527984 10704564224 1329089264 13.959 97 27 53 2 1349152176 13918944 6143968
15:20:09 67304 8192000000 10266882144 10708008960 1330815840 13.975 97 6 12 1 1352305648 19994368 1495440
15:22:23 90130 8192000000 10286140304 10737172480 1353397392 14.178 98 13 26 1 1373762160 17464912 2899856
15:24:55 53619 8192000000 10300547072 10746535936 1389997824 14.506 100 22 44 0 1406327184 11038752 5290608
15:26:57 85711 8192000000 10303727280 10778722304 1383853888 14.451 99 14 28 0 1406384224 19704144 2826192
15:29:23 41331 8192000000 10329742768 10776961024 1403269216 14.624 100 25 50 2 1415592208 7422704 4900288
15:31:04 72476 8192000000 10328474896 10777075712 1395294432 14.553 99 27 54 2 1416001504 14768640 5938432
15:33:18 111753 8192000000 10310599600 10767589376 1377938976 14.398 98 11 22 1 1394727856 14422272 2366608
15:36:43 29451 8192000000 10322355488 10764447744 1393521312 14.537 99 50 98 1 1413399472 9373856 10504304
15:38:15 15149 8192000000 10319748304 10759892992 1401959680 14.612 100 57 114 2 1425710816 10985648 12765488
15:39:34 21539 8192000000 10298370768 10748170240 1376963808 14.389 98 35 69 1 1392421504 8184160 7273536
15:41:03 26236 8192000000 10272319472 10730991616 1351549392 14.161 97 6 12 0 1364087600 11356848 1181360
15:42:35 55053 8192000000 10276881008 10730737664 1360129904 14.239 99 11 23 0 1379766208 17215792 2420512
15:44:31 62726 8192000000 10266827040 10723631104 1344003584 14.093 98 11 22 2 1357978880 11799152 2176144
15:46:34 82710 8192000000 10240966528 10677506048 1219805632 12.960 89 16 32 0 1231028400 7275824 3946944
15:48:57 52277 8192000000 10278220928 10717523968 1348822272 14.137 99 17 34 0 1367922368 15127008 3973088
15:50:51 87988 8192000000 10276741744 10726965248 1317188384 13.851 96 14 28 1 1336549472 15846704 3514384
15:53:25 91182 8192000000 10272678112 10713767936 1346534288 14.116 99 9 18 0 1356873888 8542720 1796880
15:56:27 93755 8192000000 10281004528 10719375360 1358406384 14.223 99 38 76 1 1381697952 14337712 8953856
15:59:13 31509 8192000000 10254565120 10698764288 1339745872 14.055 98 37 74 2 1358826672 10616032 8464768
16:01:16 44141 8192000000 10242732960 10696515584 1333266208 13.997 98 10 20 0 1341991440 6790048 1935184
16:03:00 67959 8192000000 10267611760 10715119616 1328221584 13.951 97 20 40 2 1346915808 14093760 4600464
16:05:08 89455 8192000000 10278308960 10726846464 1362164560 14.257 100 9 18 1 1384710304 20434080 2111664
16:07:45 45169 8192000000 10275458368 10718220288 1345130768 14.104 98 23 46 0 1358712064 8380144 5201152
16:09:51 59542 8192000000 10286774272 10733953024 1350871696 14.155 98 31 59 0 1366899472 9152832 6874944
16:11:54 61152 8192000000 10289669904 10727673856 1360300368 14.240 99 22 44 0 1377131520 10924832 5906320
16:13:56 50175 8192000000 10288520256 10747441152 1379183696 14.409 100 9 18 1 1397120256 15984176 1952384
16:15:49 55647 8192000000 10303526144 10740781056 1347176176 14.122 97 33 66 1 1370569184 15461440 7931568
16:17:46 88086 8192000000 10310000240 10752552960 1387638816 14.485 100 15 30 2 1408631088 17836624 3155648
16:20:21 137664 8192000000 10302989136 10770743296 1398406608 14.581 100 31 61 2 1425200608 19703344 7090656
16:23:39 55361 8192000000 10296536960 10770984960 1388424912 14.492 100 9 18 0 1412234288 21529552 2279824
16:25:38 37157 8192000000 10286582592 10744487936 1367852816 14.308 98 14 27 1 1380331984 9341664 3137504
16:27:23 53031 8192000000 10300670896 10748850176 1381149488 14.427 99 21 42 1 1394360544 8567296 4643760
16:29:24 41929 8192000000 10284575232 10733793280 1350469808 14.152 97 18 34 2 1361964800 7313200 4181792
16:31:20 43851 8192000000 10272144144 10712719360 1298603152 13.683 94 17 34 0 1311893312 9615680 3674480
16:33:09 67505 8192000000 10307381504 10764816384 1377615200 14.395 99 19 38 0 1401014384 18599904 4799280
16:35:30 57984 8192000000 10293044592 10734477312 1332854464 13.993 96 23 46 0 1348805440 11206576 4744400
16:37:35 44384 8192000000 9340206704 9633632256 470504928 5.431 35 5 10 0 481654816 10067216 1082672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 28-03-2022 16:39:10 to 28-03-2022 16:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-03-2022 16:39:25 to 28-03-2022 16:39:27.

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          234579
MON$OLDEST_ACTIVE               234580
MON$OLDEST_SNAPSHOT             234580
MON$NEXT_TRANSACTION            234580
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                       2010304
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:606: 
:607: mscs-test-161.budzdorov.ru    Mon Mar 28 16:38:24 2022
:608:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:609: 
:610: 
:611: mscs-test-161.budzdorov.ru    Mon Mar 28 16:38:25 2022
:612:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:613: 
:614: 
:615: mscs-test-161.budzdorov.ru    Mon Mar 28 16:38:27 2022
:616:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:617: 
--- end of diff output ---

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

28.03.22 16:39:28. 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-03-28 16:39:31 No severe PSQL-related problems occured
28.03.22 16:39:32. End of report.