Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.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 16461552 kB
MemAvailable 31218620 kB
Buffers 0 kB
Cached 15231684 kB
SwapCached 2472 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-20 19:38:22
DTS_END                         2022-06-20 19:38:22.3700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33276
TRN_ID                          233395
ATT_ID                          642
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: 9032.58 24521 1083910
customer order: creation 167.91 373 20149
customer order: refuse 14.58 372 1750
order to supplier: creation 43.38 1251 5206
order to supplier: removal 3.42 105151 410
invoice (draft): creation 45.33 3137 5439
invoice (draft): removal 51.42 784 6170
invoice accept: apply 40.58 18625 4869
invoice accept: cancel 46.48 801 5578
customer reserve: creation 3774.61 183 452953
customer reserve: removal 4555.08 63 546610
realization accept: apply 80.62 189 9674
realization accept: cancel 82.86 125 9943
payment from customer: creation 57.61 132 6913
payment from customer: removal 5.70 85 684
payment to supplier: creation 47.36 141 5683
payment to supplier: removal 3.33 83 400
service: total inventory turnovers 4.79 969 575
service: total monetary turnovers 6.69 23 803
service: aggregate perf. data 0.81 18409 97
service: refresh index statistics 0.03 339514 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-06-2022 19:39:39 to 20-06-2022 19:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7498
TEST_TIME 2 5178
TEST_TIME 3 4817
TEST_TIME 4 4416
TEST_TIME 5 4589
TEST_TIME 6 4476
TEST_TIME 7 4547
TEST_TIME 8 4424
TEST_TIME 9 4414
TEST_TIME 10 5024
TEST_TIME 11 5055
TEST_TIME 12 5150
TEST_TIME 13 5267
TEST_TIME 14 5499
TEST_TIME 15 5881
TEST_TIME 16 6296
TEST_TIME 17 6796
TEST_TIME 18 7256
TEST_TIME 19 7298
TEST_TIME 20 7741
TEST_TIME 21 8118
TEST_TIME 22 8273
TEST_TIME 23 9129
TEST_TIME 24 9086
TEST_TIME 25 9199
TEST_TIME 26 9602
TEST_TIME 27 10606
TEST_TIME 28 10830
TEST_TIME 29 10589
TEST_TIME 30 10458
TEST_TIME 31 10288
TEST_TIME 32 10110
TEST_TIME 33 10036
TEST_TIME 34 9976
TEST_TIME 35 9891
TEST_TIME 36 9794
TEST_TIME 37 9705
TEST_TIME 38 9463
TEST_TIME 39 9329
TEST_TIME 40 9219
TEST_TIME 41 9397
TEST_TIME 42 9324
TEST_TIME 43 9209
TEST_TIME 44 9265
TEST_TIME 45 9222
TEST_TIME 46 9296
TEST_TIME 47 9175
TEST_TIME 48 9156
TEST_TIME 49 9309
TEST_TIME 50 9299
TEST_TIME 51 9308
TEST_TIME 52 9385
TEST_TIME 53 9296
TEST_TIME 54 9504
TEST_TIME 55 9441
TEST_TIME 56 9557
TEST_TIME 57 9628
TEST_TIME 58 9576
TEST_TIME 59 9471
TEST_TIME 60 9400
TEST_TIME 61 9404
TEST_TIME 62 9398
TEST_TIME 63 9368
TEST_TIME 64 9365
TEST_TIME 65 9297
TEST_TIME 66 9341
TEST_TIME 67 9266
TEST_TIME 68 9262
TEST_TIME 69 9301
TEST_TIME 70 9293
TEST_TIME 71 9328
TEST_TIME 72 9388
TEST_TIME 73 9435
TEST_TIME 74 9353
TEST_TIME 75 9436
TEST_TIME 76 9466
TEST_TIME 77 9519
TEST_TIME 78 9501
TEST_TIME 79 9576
TEST_TIME 80 9532
TEST_TIME 81 9452
TEST_TIME 82 9400
TEST_TIME 83 9415
TEST_TIME 84 9369
TEST_TIME 85 9356
TEST_TIME 86 9321
TEST_TIME 87 9277
TEST_TIME 88 9240
TEST_TIME 89 9234
TEST_TIME 90 9197
TEST_TIME 91 9205
TEST_TIME 92 9170
TEST_TIME 93 9206
TEST_TIME 94 9284
TEST_TIME 95 9282
TEST_TIME 96 9295
TEST_TIME 97 9296
TEST_TIME 98 9311
TEST_TIME 99 9329
TEST_TIME 100 9294
TEST_TIME 101 9317
TEST_TIME 102 9329
TEST_TIME 103 9360
TEST_TIME 104 9363
TEST_TIME 105 9360
TEST_TIME 106 9293
TEST_TIME 107 9229
TEST_TIME 108 9223
TEST_TIME 109 9198
TEST_TIME 110 9157
TEST_TIME 111 9110
TEST_TIME 112 9086
TEST_TIME 113 9137
TEST_TIME 114 9114
TEST_TIME 115 9107
TEST_TIME 116 9122
TEST_TIME 117 9097
TEST_TIME 118 9101
TEST_TIME 119 9081
TEST_TIME 120 9035

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-06-2022 19:39:42 to 20-06-2022 19:39:46.

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 1503086 1503086 0 0.00 0 15898 7 0 0
doc_list_biud 1503084 1503084 0 0.00 0 36676 29 0 0
sp_add_invoice_to_stock 4971 4869 102 2.05 26 153584 18625 0 102
sp_cancel_adding_invoice 5578 5578 0 0.00 5 70717 801 0 0
sp_cancel_client_order 1750 1750 0 0.00 4 16306 372 0 0
sp_cancel_customer_reserve 546610 546610 0 0.00 1 36678 63 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 4428 86 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 2 7435 83 0 0
sp_cancel_supplier_invoice 6184 6170 14 0.22 2 27728 784 0 14
sp_cancel_supplier_order 410 410 0 0.00 7 1133430 105151 0 0
sp_cancel_write_off 9943 9943 0 0.00 1 14398 125 0 0
sp_client_order 20149 20149 0 0.00 4 28708 374 0 0
sp_customer_reserve 452953 452953 0 0.00 0 103791 184 0 0
sp_fill_shopping_cart 30783 30783 0 0.00 1 25641 40 0 0
sp_fill_shopping_cart_clo_res 452948 452948 0 0.00 0 14964 16 0 0
sp_get_clo_for_invoice 4864 4864 0 0.00 0 30321 350 0 0
sp_kill_cost_storno 16605 16605 0 0.00 0 9695 57 0 0
sp_kill_qstorno_ret_qs2qd 1109743 1109743 0 0.00 0 36200 34 0 0
sp_kill_qty_storno 584997 584997 0 0.00 0 36675 73 0 0
sp_lock_selected_doc 20295 20295 0 0.00 0 9979 8 0 0
sp_make_cost_storno 27133 27133 0 0.00 0 13024 57 0 0
sp_multiply_rows_for_pdistr 27132 27132 0 0.00 0 9722 32 0 0
sp_multiply_rows_for_qdistr 20148 20148 0 0.00 0 14784 179 0 0
sp_pay_from_customer 6913 6913 0 0.00 4 13217 132 0 0
sp_pay_to_supplier 5683 5683 0 0.00 5 15857 142 0 0
sp_payment_common 12596 12596 0 0.00 1 15403 36 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 16300 340 0 0
sp_qd_handle_on_invoice_upd_sts 10441 10441 0 0.00 0 28809 323 0 0
sp_qd_handle_on_reserve_upd_sts 9673 9673 0 0.00 0 26570 57 0 0
sp_reserve_write_off 9674 9674 0 0.00 6 26614 190 0 0
sp_supplier_invoice 5439 5439 0 0.00 6 90422 3138 0 0
sp_supplier_order 5206 5206 0 0.00 5 41788 1251 0 0
srv_aggregate_perf_data 97 97 0 0.00 2596 152849 18410 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1553 163740 50188 0 0
srv_find_qd_qs_mism 1037209 1037209 0 0.00 0 15665 6 0 0
srv_make_invnt_saldo 575 575 0 0.00 38 41695 970 0 0
srv_make_money_saldo 803 803 0 0.00 1 2134 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 72739 474840 339515 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4869 4869 0 0.00 0 14278 208 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 1967 68 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 7510 15 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 913 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 1080 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 1417 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 435 435 0 0.00 0 364 8 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 20528 1478 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 3839 12 0 0
t$perf-norm:sp_client_order 20149 20149 0 0.00 0 8113 17 0 0
t$perf-norm:sp_customer_reserve 1037 1037 0 0.00 0 8179 15 0 0
t$perf-norm:sp_pay_from_customer 6913 6913 0 0.00 0 10510 10 0 0
t$perf-norm:sp_pay_to_supplier 5683 5683 0 0.00 0 8937 12 0 0
t$perf-norm:sp_reserve_write_off 9674 9674 0 0.00 0 11170 13 0 0
t$perf-norm:sp_supplier_invoice 5439 5439 0 0.00 0 9724 25 0 0
t$perf-norm:sp_supplier_order 5206 5206 0 0.00 0 4861 16 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 500 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 328 6 0 0
t$perf-norm:srv_make_invnt_saldo 575 575 0 0.00 0 1053 23 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 983 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 9926 9722 204 2.05 0 6538 19 0 204
v_all_customers 36902 36902 0 0.00 0 93 0 0 0
v_all_suppliers 29878 29878 0 0.00 0 58 1 0 0
v_all_wares 40294 40294 0 0.00 0 76 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 1773 10 0 0
v_cancel_client_order 3500 3500 0 0.00 0 4477 8 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 1674 9 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 1537 5 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 2740 14 0 0
v_cancel_supplier_invoice 898 870 28 3.11 0 4373 30 0 28
v_cancel_supplier_order 820 820 0 0.00 0 1590 6 0 0
v_cancel_write_off 1970 1970 0 0.00 0 4115 18 0 0
v_max_id_clo_ord 5205 5205 0 0.00 0 566 1 0 0
v_max_id_clo_res 1037 1037 0 0.00 0 2605 26 0 0
v_max_id_ord_sup 5431 5431 0 0.00 0 2969 4 0 0
v_max_non_paid_invoice 1380 1380 0 0.00 0 276 2 0 0
v_max_non_paid_realizn 3492 3492 0 0.00 0 6893 5 0 0
v_min_id_clo_ord 5205 5205 0 0.00 0 3933 7 0 0
v_min_id_clo_res 1037 1037 0 0.00 0 2742 8 0 0
v_min_id_ord_sup 5431 5431 0 0.00 0 7851 9 0 0
v_min_non_paid_invoice 5683 5683 0 0.00 0 15397 25 0 0
v_min_non_paid_realizn 6913 6913 0 0.00 0 13173 31 0 0
v_our_firm 10234 10234 0 0.00 0 22 1 0 0
v_reserve_write_off 19344 19344 0 0.00 0 4404 7 0 0
x_lock_depdocs_on_canc_invoice 11313 11313 0 0.00 0 10803 119 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 18254 400 0 0
x_make_qstorno_1000_1200 5206 5206 0 0.00 1 34918 1173 0 0
x_make_qstorno_1200_2000 5439 5439 0 0.00 2 90413 2990 0 0
x_make_qstorno_2100_3300 452953 452953 0 0.00 0 103790 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-06-2022 19:39:47 to 20-06-2022 19:39:51.

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 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:26 26668 8192000000 9895690080 10207535104 979074416 10.675 100 94 188 0 1010722912 6224864 25423632
17:11:01 13633 8192000000 9942042448 10263760896 1025813792 11.128 100 85 170 0 1055448480 8213264 21421424
17:12:28 11576 8192000000 9984246576 10316992512 1070471440 11.557 100 83 165 0 1100870048 9567568 20831040
17:13:43 12692 8192000000 10034785280 10382438400 1120018528 12.027 100 72 145 0 1146932912 9753168 17161216
17:15:02 10275 8192000000 10097205456 10454589440 1169217312 12.490 100 82 164 1 1204974896 16489952 19267632
17:16:17 6292 8192000000 10123693904 10493759488 1207880848 12.849 100 78 156 0 1238042672 12789776 17372048
17:17:26 4279 8192000000 10161291856 10537041920 1248445232 13.224 100 83 166 2 1280913584 13673280 18795072
17:18:33 3191 8192000000 10193079648 10570608640 1275094752 13.468 100 89 176 1 1307252672 12386288 19771632
17:19:38 2536 8192000000 10209798784 10593914880 1291497648 13.618 100 90 176 0 1324057888 12294048 20266192
17:20:42 8198 8192000000 10209345344 10602385408 1305259744 13.743 100 61 122 1 1329055392 10707808 13087840
17:21:55 19659 8192000000 10196664896 10609295360 1307928608 13.767 100 37 74 0 1324219840 8255696 8035536
17:23:16 3510 8192000000 10201146768 10616705024 1315336128 13.834 100 28 56 2 1328692160 7301952 6054080
17:24:21 5757 8192000000 10213048960 10627420160 1326145584 13.932 100 34 68 2 1340615264 6630512 7839168
17:25:29 59726 8192000000 10239361440 10646409216 1331222544 13.978 100 42 83 3 1347313360 5615376 10475440
17:27:29 8624 8192000000 10217274864 10641391616 1328472384 13.953 100 24 46 1 1340424800 6602720 5349696
17:28:41 6874 8192000000 10202156096 10621992960 1286922720 13.576 97 23 46 1 1302975296 10795072 5257504
17:29:48 38917 8192000000 10228222688 10621534208 1271707552 13.437 96 64 131 2 1293242144 6016688 15517904
17:31:27 4564 8192000000 10155857024 10553577472 1244992976 13.192 98 39 78 4 1261987088 8468304 8525808
17:32:35 32202 8192000000 10163469216 10572152832 1270876656 13.430 99 28 56 0 1282998368 5801760 6319952
17:34:12 6623 8192000000 10167494992 10566418432 1269583728 13.418 100 35 70 1 1283142336 5655648 7902960
17:35:22 8415 8192000000 10176370608 10581307392 1257675360 13.309 98 24 48 0 1272769168 9162144 5931664
17:36:31 43556 8192000000 10190572432 10602741760 1298431520 13.681 100 30 59 1 1310499056 5524672 6542864
17:38:16 5582 8192000000 10218099952 10635419648 1322810304 13.902 100 34 70 0 1336696288 6208240 7677744
17:39:27 56649 8192000000 10260335152 10671943680 1346445872 14.115 100 49 99 2 1365882832 8478544 10958416
17:41:30 105026 8192000000 10281202960 10711506944 1373282240 14.356 100 48 95 2 1405388752 20907584 11198928
17:44:39 11472 8192000000 10298680368 10723778560 1380278688 14.419 99 39 78 2 1414706144 25433552 8993904
17:45:53 10631 8192000000 10329361920 10754998272 1404352032 14.634 100 48 93 4 1442440400 26805712 11282656
17:47:08 2833 8192000000 10346181248 10768994304 1404155360 14.632 99 65 130 1 1451005808 30456832 16393616
17:48:16 13015 8192000000 10356284528 10784182272 1421046240 14.782 100 54 107 5 1471193120 36966320 13180560
17:49:35 4775 8192000000 10377382704 10804428800 1432437088 14.883 100 52 100 2 1485315440 39110848 13767504
17:50:45 4748 8192000000 10399937968 10822823936 1445513360 14.998 100 69 138 0 1506750896 44929472 16308064
17:51:55 3669 8192000000 10402795552 10821632000 1433925104 14.896 99 81 161 3 1500873184 47718624 19229456
17:53:05 3082 8192000000 10388204320 10798653440 1416054000 14.738 99 85 170 1 1490598672 53419024 21125648
17:54:15 2802 8192000000 10386160720 10800635904 1397534032 14.573 98 78 156 3 1477587568 59705104 20348432
17:55:21 6398 8192000000 10382754752 10793803776 1408284768 14.669 99 82 162 1 1484963456 55713760 20964928
17:56:32 1553 8192000000 10407389568 10820837376 1424197808 14.810 100 89 176 2 1507770432 61116192 22456432
17:57:40 12917 8192000000 10386026736 10813419520 1415106592 14.729 100 46 92 1 1490888544 62962864 12819088
17:59:07 8745 8192000000 10368118240 10795536384 1388448336 14.492 98 42 84 2 1457118224 57276304 11393584
18:00:25 15313 8192000000 10346702688 10773102592 1388009120 14.488 100 40 80 1 1450887984 51295648 11583216
18:01:44 21932 8192000000 10317766448 10752888832 1359055744 14.229 98 29 58 1 1411590816 44696448 7838624
18:03:13 31107 8192000000 10309557856 10753200128 1373898144 14.362 100 24 48 1 1421617856 40918416 6801296
18:04:46 44097 8192000000 10302150240 10753642496 1355566032 14.198 100 22 44 0 1410779712 48314064 6899616
18:06:34 68275 8192000000 10265614016 10695622656 1344384640 14.097 99 48 95 1 1364656976 8786048 11486288
18:08:44 104930 8192000000 10290795616 10727690240 1342691024 14.082 98 24 48 0 1355245200 7591728 4962448
18:11:32 37712 8192000000 10267021520 10720862208 1358563888 14.224 99 21 44 0 1378265504 15274048 4427568
18:13:14 60939 8192000000 10276287776 10721112064 1327527376 13.945 97 17 34 0 1344636512 13012096 4097040
18:15:16 128273 8192000000 10289776112 10735534080 1361907136 14.254 99 13 26 1 1380404272 15363472 3133664
18:18:40 32165 8192000000 10258151680 10718740480 1352172832 14.167 98 13 26 1 1375458896 19933168 3352896
18:20:18 74950 8192000000 10275423984 10735067136 1377126880 14.391 100 26 52 0 1396388448 13134560 6127008
18:22:34 80619 8192000000 10284709648 10751586304 1382179536 14.436 100 11 22 0 1401116800 16008400 2928864
18:25:24 43711 8192000000 10306078208 10761039872 1397415408 14.572 100 37 74 2 1419700272 14093632 8191232
18:27:09 66631 8192000000 10296349344 10757505024 1369849136 14.326 98 11 22 0 1382264000 9717296 2697568
18:29:21 65185 8192000000 10309428656 10769297408 1397108816 14.569 100 23 47 0 1415737632 13427808 5201008
18:31:28 52852 8192000000 10325827520 10782220288 1402087568 14.614 100 48 96 1 1426512112 13660800 10763744
18:33:21 67925 8192000000 10329352528 10771918848 1373821824 14.361 98 14 29 0 1394625888 17949472 2854592
18:35:30 79703 8192000000 10315966144 10771030016 1370679872 14.333 98 15 30 2 1388678608 14731376 3267360
18:37:50 98193 8192000000 10300813856 10755813376 1379000704 14.408 98 22 44 0 1396901520 13269440 4631376
18:40:44 28602 8192000000 10307939792 10767142912 1396985504 14.568 100 24 48 1 1414050128 11261712 5802912
18:42:18 43837 8192000000 10285120368 10755850240 1381653520 14.431 99 7 14 0 1398868416 15773280 1441616
18:44:04 63522 8192000000 10293921104 10762207232 1387039680 14.479 100 9 18 1 1409390176 20274160 2076336
18:46:18 51697 8192000000 10284286512 10742157312 1360155248 14.239 98 25 50 3 1376684800 11473184 5056368
18:48:21 53536 8192000000 10297173664 10752139264 1369165392 14.320 99 34 67 1 1397784960 20911952 7707616
18:50:16 83484 8192000000 10285508464 10741657600 1331273536 13.979 96 15 30 0 1355271664 20742976 3255152
18:52:50 83601 8192000000 10279449360 10735706112 1314204928 13.824 95 19 37 1 1330834224 12585696 4043600
18:55:14 56551 8192000000 10290173024 10733670400 1324807600 13.920 97 16 32 0 1340682896 12569712 3305584
18:57:15 52141 8192000000 10269791344 10735955968 1350845520 14.155 99 26 52 2 1371783648 15204736 5733392
18:59:07 71888 8192000000 10262450272 10701287424 1335258256 14.015 97 32 64 3 1349235296 6538832 7438208
19:01:27 55323 8192000000 10268854896 10710548480 1327583760 13.945 97 35 70 1 1344391904 9188592 7619552
19:03:28 39794 8192000000 10254934544 10703785984 1355158640 14.194 99 25 50 0 1369047776 7961472 5927664
19:05:10 67650 8192000000 10250346992 10699014144 1312147008 13.806 96 18 36 0 1328553680 12553168 3853504
19:07:36 59097 8192000000 10253647216 10710773760 1339849616 14.056 99 7 14 0 1352363424 10757920 1755888
19:09:36 35561 8192000000 10256291552 10716635136 1350346000 14.151 99 22 42 1 1370044496 14084320 5614176
19:11:14 78469 8192000000 10252738592 10699804672 1287558656 13.582 95 14 28 0 1304167664 13404768 3204240
19:13:32 55000 8192000000 10274237776 10722099200 1349803872 14.146 99 25 50 1 1364335360 9426064 5105424
19:15:52 36211 8192000000 10292371984 10738548736 1379662816 14.414 100 32 62 0 1397045328 9974800 7407712
19:17:30 60928 8192000000 10302983696 10742906880 1347211024 14.122 97 17 34 0 1359109552 8275264 3623264
19:19:32 44169 8192000000 10307467600 10759516160 1366884784 14.299 98 43 86 0 1390692512 14672704 9135024
19:21:16 35685 8192000000 10303380640 10757562368 1393687360 14.539 100 24 48 0 1409366448 10219952 5459136
19:22:55 163739 8192000000 10301228912 10753908736 1376243072 14.383 98 34 68 0 1394747344 11001616 7502656
19:26:48 84348 8192000000 10281220032 10748870656 1355924816 14.201 97 8 16 0 1367320080 9744224 1651040
19:29:13 108078 8192000000 10295821648 10762575872 1383567776 14.448 99 9 18 0 1397357120 11641904 2147440
19:32:06 67433 8192000000 10286525552 10737115136 1366341936 14.294 99 18 35 1 1381814336 11432672 4039728
19:34:28 57242 8192000000 10281883648 10731446272 1363955088 14.273 98 24 48 0 1378172224 9029760 5187376
19:36:31 36493 8192000000 10291182336 10741633024 1360646992 14.243 98 25 50 2 1378438272 12116144 5675136
19:38:22 14690 8192000000 9140741568 9389838336 297842464 3.508 22 5 8 0 307602768 8698160 1062144

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-06-2022 19:39:53 to 20-06-2022 19:40:07.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-06-2022 19:40:08 to 20-06-2022 19:40:10.

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          233111
MON$OLDEST_ACTIVE               233736
MON$OLDEST_SNAPSHOT             233736
MON$NEXT_TRANSACTION            233736
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                       2004352
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 ---
:183: 
:184: mscs-test-161.budzdorov.ru    Mon Jun 20 19:38:42 2022
:185:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:186: 
:187: 
:188: mscs-test-161.budzdorov.ru    Mon Jun 20 19:38:48 2022
:189:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:190: 
:191: 
:192: mscs-test-161.budzdorov.ru    Mon Jun 20 19:38:48 2022
:193:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:194: 
--- end of diff output ---

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

20.06.22 19:40:11. 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-20 19:40:14 No severe PSQL-related problems occured
20.06.22 19:40:15. End of report.