Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.08.2022 22:42
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 15545672 kB
MemAvailable 30936128 kB
Buffers 12 kB
Cached 16145540 kB
SwapCached 2512 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.11
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-08-08 22:42:14
DTS_END                         2022-08-08 22:42:14.6740
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33188
TRN_ID                          244184
ATT_ID                          615
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: 9724.89 23431 1166987
customer order: creation 175.03 370 21003
customer order: refuse 14.77 326 1772
order to supplier: creation 46.37 1331 5564
order to supplier: removal 3.67 111218 440
invoice (draft): creation 47.68 3290 5722
invoice (draft): removal 56.08 804 6729
invoice accept: apply 42.72 16130 5126
invoice accept: cancel 51.19 727 6143
customer reserve: creation 3945.73 158 473487
customer reserve: removal 5027.04 64 603245
realization accept: apply 86.91 203 10429
realization accept: cancel 95.49 98 11459
payment from customer: creation 60.54 143 7265
payment from customer: removal 6.33 113 760
payment to supplier: creation 49.53 207 5943
payment to supplier: removal 3.51 72 421
service: total inventory turnovers 4.78 1207 573
service: total monetary turnovers 6.76 39 811
service: aggregate perf. data 0.76 21316 91
service: refresh index statistics 0.03 310811 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-08-2022 22:43:21 to 08-08-2022 22:43:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2220
TEST_TIME 2 1518
TEST_TIME 3 3099
TEST_TIME 4 4013
TEST_TIME 5 4000
TEST_TIME 6 4530
TEST_TIME 7 4509
TEST_TIME 8 4774
TEST_TIME 9 4671
TEST_TIME 10 4764
TEST_TIME 11 5178
TEST_TIME 12 5326
TEST_TIME 13 5659
TEST_TIME 14 6014
TEST_TIME 15 6124
TEST_TIME 16 6237
TEST_TIME 17 6665
TEST_TIME 18 7051
TEST_TIME 19 7359
TEST_TIME 20 7831
TEST_TIME 21 8070
TEST_TIME 22 8296
TEST_TIME 23 8604
TEST_TIME 24 9004
TEST_TIME 25 9121
TEST_TIME 26 9743
TEST_TIME 27 9777
TEST_TIME 28 9683
TEST_TIME 29 10827
TEST_TIME 30 11478
TEST_TIME 31 11343
TEST_TIME 32 11476
TEST_TIME 33 11560
TEST_TIME 34 11601
TEST_TIME 35 11503
TEST_TIME 36 11473
TEST_TIME 37 11486
TEST_TIME 38 11291
TEST_TIME 39 11138
TEST_TIME 40 10943
TEST_TIME 41 10821
TEST_TIME 42 10857
TEST_TIME 43 10846
TEST_TIME 44 10837
TEST_TIME 45 10726
TEST_TIME 46 10692
TEST_TIME 47 10640
TEST_TIME 48 10491
TEST_TIME 49 10494
TEST_TIME 50 10405
TEST_TIME 51 10406
TEST_TIME 52 10678
TEST_TIME 53 10765
TEST_TIME 54 10714
TEST_TIME 55 10663
TEST_TIME 56 10541
TEST_TIME 57 10530
TEST_TIME 58 10379
TEST_TIME 59 10329
TEST_TIME 60 10270
TEST_TIME 61 10223
TEST_TIME 62 10220
TEST_TIME 63 10157
TEST_TIME 64 10139
TEST_TIME 65 10144
TEST_TIME 66 10119
TEST_TIME 67 10060
TEST_TIME 68 10159
TEST_TIME 69 10182
TEST_TIME 70 10124
TEST_TIME 71 10293
TEST_TIME 72 10248
TEST_TIME 73 10344
TEST_TIME 74 10309
TEST_TIME 75 10288
TEST_TIME 76 10308
TEST_TIME 77 10291
TEST_TIME 78 10202
TEST_TIME 79 10223
TEST_TIME 80 10163
TEST_TIME 81 10150
TEST_TIME 82 10136
TEST_TIME 83 10069
TEST_TIME 84 10012
TEST_TIME 85 9933
TEST_TIME 86 9928
TEST_TIME 87 9921
TEST_TIME 88 9961
TEST_TIME 89 9941
TEST_TIME 90 9908
TEST_TIME 91 9966
TEST_TIME 92 9963
TEST_TIME 93 9971
TEST_TIME 94 10009
TEST_TIME 95 10002
TEST_TIME 96 10048
TEST_TIME 97 10128
TEST_TIME 98 10102
TEST_TIME 99 10082
TEST_TIME 100 10139
TEST_TIME 101 10153
TEST_TIME 102 10060
TEST_TIME 103 9997
TEST_TIME 104 9981
TEST_TIME 105 9949
TEST_TIME 106 9942
TEST_TIME 107 9912
TEST_TIME 108 9870
TEST_TIME 109 9870
TEST_TIME 110 9832
TEST_TIME 111 9795
TEST_TIME 112 9776
TEST_TIME 113 9739
TEST_TIME 114 9718
TEST_TIME 115 9705
TEST_TIME 116 9750
TEST_TIME 117 9805
TEST_TIME 118 9809
TEST_TIME 119 9769
TEST_TIME 120 9725

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-08-2022 22:43:24 to 08-08-2022 22:43:28.

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 1607966 1607966 0 0.00 0 32942 6 0 0
doc_list_biud 1607943 1607943 0 0.00 0 23493 30 0 0
sp_add_invoice_to_stock 5249 5126 123 2.34 48 195556 16131 0 123
sp_cancel_adding_invoice 6143 6143 0 0.00 4 55764 727 0 0
sp_cancel_client_order 1772 1772 0 0.00 4 9026 327 0 0
sp_cancel_customer_reserve 603245 603245 0 0.00 0 15674 64 0 0
sp_cancel_pay_from_customer 760 760 0 0.00 2 19471 114 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 6235 72 0 0
sp_cancel_supplier_invoice 6744 6729 15 0.22 1 23499 804 0 15
sp_cancel_supplier_order 440 440 0 0.00 8 1180512 111219 0 0
sp_cancel_write_off 11459 11459 0 0.00 1 10479 99 0 0
sp_client_order 21003 21003 0 0.00 3 48549 370 0 0
sp_customer_reserve 473487 473487 0 0.00 0 137354 159 0 0
sp_fill_shopping_cart 32281 32281 0 0.00 1 13216 37 0 0
sp_fill_shopping_cart_clo_res 473483 473483 0 0.00 0 19514 15 0 0
sp_get_clo_for_invoice 5122 5122 0 0.00 0 24246 294 0 0
sp_kill_cost_storno 18783 18783 0 0.00 0 19456 58 0 0
sp_kill_qstorno_ret_qs2qd 1225118 1225118 0 0.00 0 23491 34 0 0
sp_kill_qty_storno 645339 645339 0 0.00 0 23492 73 0 0
sp_lock_selected_doc 21425 21425 0 0.00 0 8414 6 0 0
sp_make_cost_storno 28759 28759 0 0.00 0 26699 86 0 0
sp_multiply_rows_for_pdistr 28759 28759 0 0.00 0 16791 31 0 0
sp_multiply_rows_for_qdistr 21003 21003 0 0.00 0 14631 160 0 0
sp_pay_from_customer 7265 7265 0 0.00 4 26785 144 0 0
sp_pay_to_supplier 5943 5943 0 0.00 4 35464 207 0 0
sp_payment_common 13197 13197 0 0.00 1 26659 32 0 0
sp_qd_handle_on_cancel_clo 1772 1772 0 0.00 0 8421 298 0 0
sp_qd_handle_on_invoice_upd_sts 11265 11265 0 0.00 0 21655 321 0 0
sp_qd_handle_on_reserve_upd_sts 10429 10429 0 0.00 0 23131 60 0 0
sp_reserve_write_off 10429 10429 0 0.00 5 32899 204 0 0
sp_supplier_invoice 5722 5722 0 0.00 6 107517 3290 0 0
sp_supplier_order 5564 5564 0 0.00 5 39925 1332 0 0
srv_aggregate_perf_data 91 91 0 0.00 2309 182157 21316 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1129 159835 44014 0 0
srv_find_qd_qs_mism 1119458 1119458 0 0.00 0 12475 4 0 0
srv_make_invnt_saldo 573 573 0 0.00 27 114816 1207 0 0
srv_make_money_saldo 811 811 0 0.00 1 5150 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 205295 443585 310812 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5126 5126 0 0.00 0 23329 171 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 12761 169 0 0
t$perf-norm:sp_cancel_client_order 1772 1772 0 0.00 0 1935 12 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 973 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 760 760 0 0.00 0 5314 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 3119 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 868 15 0 0
t$perf-norm:sp_cancel_supplier_order 440 440 0 0.00 0 24954 1618 0 0
t$perf-norm:sp_cancel_write_off 964 964 0 0.00 0 9663 26 0 0
t$perf-norm:sp_client_order 21003 21003 0 0.00 0 8322 17 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 2265 7 0 0
t$perf-norm:sp_pay_from_customer 7265 7265 0 0.00 0 5942 10 0 0
t$perf-norm:sp_pay_to_supplier 5943 5943 0 0.00 0 6138 11 0 0
t$perf-norm:sp_reserve_write_off 10429 10429 0 0.00 0 7472 12 0 0
t$perf-norm:sp_supplier_invoice 5722 5722 0 0.00 0 9029 19 0 0
t$perf-norm:sp_supplier_order 5564 5564 0 0.00 0 5732 14 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 452 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 113 2 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 2705 23 0 0
t$perf-norm:srv_make_money_saldo 811 811 0 0.00 0 4550 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10490 10244 246 2.34 0 8382 21 0 246
v_all_customers 37970 37970 0 0.00 0 23 1 0 0
v_all_suppliers 31324 31324 0 0.00 0 22 1 0 0
v_all_wares 42002 42002 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 5132 13 0 0
v_cancel_client_order 3544 3544 0 0.00 0 4550 7 0 0
v_cancel_customer_prepayment 1520 1520 0 0.00 0 3592 5 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 2124 6 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 6227 14 0 0
v_cancel_supplier_invoice 810 780 30 3.70 0 279 14 0 30
v_cancel_supplier_order 880 880 0 0.00 0 957 7 0 0
v_cancel_write_off 1928 1928 0 0.00 0 2729 14 0 0
v_max_id_clo_ord 5559 5559 0 0.00 0 842 2 0 0
v_max_id_clo_res 979 979 0 0.00 0 4890 17 0 0
v_max_id_ord_sup 5721 5721 0 0.00 0 7011 4 0 0
v_max_non_paid_invoice 1557 1557 0 0.00 0 1896 4 0 0
v_max_non_paid_realizn 4033 4033 0 0.00 0 1735 4 0 0
v_min_id_clo_ord 5559 5559 0 0.00 0 12517 13 0 0
v_min_id_clo_res 979 979 0 0.00 0 2975 6 0 0
v_min_id_ord_sup 5721 5721 0 0.00 0 3874 3 0 0
v_min_non_paid_invoice 5939 5939 0 0.00 0 9057 27 0 0
v_min_non_paid_realizn 7258 7258 0 0.00 0 7578 19 0 0
v_our_firm 10482 10482 0 0.00 0 21 1 0 0
v_reserve_write_off 20858 20858 0 0.00 0 6431 7 0 0
x_lock_depdocs_on_canc_invoice 12482 12482 0 0.00 0 19064 115 0 0
x_lock_depdocs_on_canc_sup_ord 440 440 0 0.00 0 5217 340 0 0
x_make_qstorno_1000_1200 5564 5564 0 0.00 2 39363 1247 0 0
x_make_qstorno_1200_2000 5722 5722 0 0.00 2 107496 3168 0 0
x_make_qstorno_2100_3300 473487 473487 0 0.00 0 137353 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-08-2022 22:43:29 to 08-08-2022 22:43:33.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:12:18 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:13:19 30115 8192000000 9902632320 10210455552 975783584 10.643 100 87 174 0 1005450592 5965392 23701616
20:14:56 26771 8192000000 9951837888 10273173504 1026918736 11.139 100 89 178 0 1058517648 8062384 23536528
20:16:33 12549 8192000000 9995294960 10329243648 1075071600 11.600 100 88 176 0 1106666672 9654688 21940384
20:17:50 8702 8192000000 10053515888 10401554432 1128126128 12.104 100 75 150 0 1157012368 11326160 17560080
20:19:02 4934 8192000000 10095994160 10457894912 1180349984 12.593 100 79 155 2 1210951488 12034768 18566736
20:20:10 2397 8192000000 10133299088 10505936896 1223110912 12.990 100 76 152 0 1254231152 13843664 17276576
20:21:13 1839 8192000000 10168218128 10543177728 1253415232 13.270 100 86 171 0 1284829840 13205664 18208944
20:22:16 1140 8192000000 10196911536 10576740352 1279481936 13.508 100 93 187 0 1315372064 15052768 20837360
20:23:19 31132 8192000000 10205766208 10597502976 1296131104 13.660 100 58 117 1 1317784976 8265648 13388224
20:24:51 4355 8192000000 10203997824 10602688512 1303253248 13.725 100 37 71 1 1319074544 6261232 9560064
20:25:56 6540 8192000000 10189124928 10607140864 1309165056 13.778 100 23 48 0 1321571504 7073856 5332592
20:27:06 6470 8192000000 10204219808 10620293120 1318854736 13.866 100 25 51 0 1330362464 5568720 5939008
20:28:15 54712 8192000000 10217572912 10636926976 1324823040 13.920 100 41 80 2 1340264528 5601872 9839616
20:30:21 6448 8192000000 10223227184 10640650240 1330932704 13.976 100 29 59 0 1343770384 5706384 7131296
20:31:28 5793 8192000000 10192545600 10601562112 1288431952 13.590 99 23 46 0 1299408960 5839776 5137232
20:32:34 13736 8192000000 10152903312 10561495040 1213072832 12.898 94 7 14 0 1219958016 5099376 1785808
20:33:50 12936 8192000000 10135631568 10538999808 1195209568 12.732 94 24 48 0 1205581456 5684304 4687584
20:35:05 4193 8192000000 10144273024 10546085888 1243969840 13.183 99 25 50 0 1255848416 6715632 5162944
20:36:11 7033 8192000000 10146129072 10548506624 1256266832 13.296 100 26 52 1 1267514000 4829680 6417488
20:37:23 15616 8192000000 10155420304 10560200704 1263988256 13.367 100 28 55 1 1276023232 5324704 6710272
20:38:39 15297 8192000000 10168613568 10578853888 1276761936 13.483 100 27 52 0 1288832864 5949616 6121312
20:39:55 4777 8192000000 10185033072 10599014400 1292921728 13.631 100 26 52 1 1305206416 6434096 5850592
20:41:01 9654 8192000000 10182382528 10601545728 1300112896 13.696 100 12 24 0 1308064640 5360096 2591648
20:42:11 54092 8192000000 10234905408 10642472960 1322578896 13.900 100 41 79 2 1337246880 4856784 9811200
20:44:12 87355 8192000000 10248706624 10669400064 1347513728 14.125 100 49 97 0 1368575024 9770544 11290752
20:46:46 44084 8192000000 10253443328 10680127488 1358581280 14.225 100 29 58 0 1378530032 13609248 6339504
20:48:37 16413 8192000000 10296829120 10719285248 1376575472 14.386 100 45 86 1 1403866192 17061280 10229440
20:49:58 8869 8192000000 10285100240 10707927040 1363432224 14.268 98 39 78 0 1392341472 18824736 10084512
20:51:12 30543 8192000000 10312610960 10737786880 1379793792 14.415 99 50 98 0 1416929504 24580064 12555648
20:52:52 6703 8192000000 10328497072 10746658816 1394587600 14.547 100 55 110 0 1440149920 31887888 13674432
20:54:05 9296 8192000000 10345737952 10772287488 1414451808 14.723 100 45 90 1 1457264224 29849568 12962848
20:55:20 6459 8192000000 10360090480 10783338496 1419736912 14.770 100 64 127 0 1470972016 35752288 15482816
20:56:37 1129 8192000000 10343660352 10749095936 1385848768 14.469 99 77 150 2 1445864896 41218096 18798032
20:57:42 1868 8192000000 10381744880 10786734080 1420192800 14.774 100 86 172 1 1487846928 45786640 21867488
20:58:51 1339 8192000000 10367881856 10770821120 1394223024 14.544 100 78 153 0 1467677872 53925440 19529408
20:59:59 4336 8192000000 10382311200 10789421056 1407481248 14.662 100 73 146 1 1487357648 61223136 18653264
21:01:07 2004 8192000000 10389531584 10795266048 1402614256 14.618 100 87 174 0 1490450496 65624896 22211344
21:02:16 2946 8192000000 10390533248 10805366784 1408433168 14.670 100 67 134 0 1492212880 65930192 17849520
21:03:26 7489 8192000000 10377018912 10799525888 1395166720 14.552 99 54 108 0 1480681376 70492672 15021984
21:04:39 25492 8192000000 10347975904 10783854592 1378227040 14.401 98 38 76 1 1456939008 67561776 11150192
21:06:08 8325 8192000000 10368427056 10799439872 1398501152 14.582 100 37 74 0 1473282752 64108176 10673424
21:07:24 13101 8192000000 10365081072 10805161984 1403463360 14.626 100 28 56 0 1474269344 62415552 8390432
21:08:38 32839 8192000000 10341161616 10799345664 1389689536 14.503 100 25 50 0 1456643680 59638832 7315312
21:10:40 50112 8192000000 10344213872 10790125568 1372441200 14.349 100 20 40 0 1446315504 67249840 6624464
21:13:20 61786 8192000000 10308960128 10761433088 1361364784 14.250 98 12 25 0 1379260368 15261152 2634432
21:15:28 156715 8192000000 10298480192 10754252800 1361864224 14.254 98 3 6 0 1382769200 20698368 206608
21:19:05 64832 8192000000 10309850784 10748784640 1367995168 14.309 98 24 45 1 1383005840 8963376 6047296
21:21:10 116787 8192000000 10303410352 10753146880 1369766832 14.325 98 29 56 0 1386863968 10460976 6636160
21:24:54 50362 8192000000 10312594192 10755309568 1365511200 14.287 98 41 82 1 1387071040 12538624 9021216
21:26:51 43765 8192000000 10317245104 10764505088 1393907536 14.541 100 27 54 0 1410353040 10181984 6263520
21:28:38 159834 8192000000 10305924720 10749116416 1319942896 13.876 94 17 31 0 1340169568 16503552 3723120
21:32:19 36203 8192000000 10319752688 10758291456 1373001280 14.354 98 40 82 1 1400708416 18194240 9512896
21:34:05 36144 8192000000 10309804336 10763038720 1395049696 14.551 100 50 98 0 1434655024 27243408 12361920
21:35:42 35530 8192000000 10294948848 10739511296 1381167440 14.427 100 12 23 0 1392814192 9547056 2099696
21:37:19 32519 8192000000 10269641344 10726469632 1343606128 14.090 97 9 17 0 1354167200 8829488 1731584
21:38:52 52018 8192000000 10270804768 10734104576 1373546448 14.359 100 23 46 0 1391406112 12788864 5070800
21:40:48 64416 8192000000 10277497536 10726240256 1346586304 14.117 98 12 24 0 1360102512 11009088 2507120
21:42:59 27933 8192000000 10280379504 10731651072 1378103552 14.400 100 20 40 1 1397218560 15127792 3987216
21:44:27 42370 8192000000 10254775328 10720100352 1342037312 14.076 98 14 28 0 1362200112 16736784 3426016
21:46:10 59664 8192000000 10259643808 10731593728 1346588096 14.117 98 8 16 0 1357921072 9640960 1692016
21:48:30 16191 8192000000 10258287856 10703740928 1348365920 14.133 100 27 54 0 1365664064 10974672 6323472
21:49:52 67873 8192000000 10261616672 10713931776 1348298288 14.132 100 14 28 1 1372929088 21270864 3359936
21:52:02 17203 8192000000 10262994992 10700374016 1338652784 14.045 99 27 54 0 1352708848 8520768 5535296
21:53:24 55072 8192000000 10260918800 10693513216 1315702864 13.838 97 25 48 0 1335256400 13828240 5725296
21:55:22 127684 8192000000 10276425520 10719772672 1310722288 13.793 96 11 22 0 1322994832 10016096 2256448
21:58:39 62175 8192000000 10277390336 10729570304 1354074128 14.184 99 27 54 1 1373765600 13479952 6211520
22:00:43 51405 8192000000 10276925392 10737008640 1376962544 14.389 100 33 66 1 1395696032 10527456 8206032
22:02:38 23276 8192000000 10277024704 10730643456 1355209056 14.194 99 21 42 0 1373974432 14307840 4457536
22:04:03 51998 8192000000 10238664688 10700640256 1335236960 14.014 97 9 18 0 1347945648 10531024 2177664
22:05:56 49458 8192000000 10261470848 10710003712 1327171520 13.942 97 11 20 1 1338093264 8454896 2466848
22:07:45 33276 8192000000 10259127872 10714992640 1348001808 14.129 99 18 36 0 1366105360 13689568 4413984
22:09:19 60408 8192000000 10264177392 10719625216 1352462224 14.170 99 15 30 0 1367742224 11832384 3447616
22:11:23 32348 8192000000 10293032192 10742136832 1377236688 14.392 100 36 72 0 1393497968 7683904 8577376
22:12:59 51436 8192000000 10293236976 10764439552 1403314016 14.624 100 20 40 1 1420885760 12802240 4769504
22:14:56 76366 8192000000 10289938912 10751950848 1342312704 14.078 96 13 26 0 1360216048 15274064 2629280
22:17:13 20761 8192000000 10294917552 10739355648 1359712784 14.235 98 32 65 2 1375113408 8026112 7374512
22:18:40 90067 8192000000 10282802672 10740363264 1340033776 14.058 97 12 24 0 1360869104 18127984 2707344
22:21:12 38877 8192000000 10306656704 10746974208 1359647280 14.234 98 24 45 0 1373961712 9056912 5257520
22:24:20 66947 8192000000 10270195136 10721955840 1345738464 14.109 98 16 32 0 1364127616 14680896 3708256
22:26:31 28022 8192000000 10257863328 10714005504 1358583600 14.225 99 15 24 2 1367030880 5331776 3115504
22:28:08 53523 8192000000 10252669360 10716934144 1337253504 14.033 98 10 20 0 1350298720 10693056 2352160
22:30:04 44920 8192000000 10261753248 10714435584 1327361584 13.943 97 20 40 0 1344217136 12379520 4476032
22:31:51 62858 8192000000 10256067632 10715250688 1338445088 14.043 98 17 34 1 1350040368 7751792 3843488
22:34:38 67266 8192000000 10239801648 10695622656 1306960720 13.758 96 4 8 0 1314843824 6790832 1092272
22:36:46 27803 8192000000 10274752352 10725449728 1355950432 14.201 99 32 64 0 1376978192 13840352 7187408
22:38:14 66554 8192000000 10265547104 10719547392 1318892000 13.867 97 29 58 0 1340645200 15142144 6611056
22:40:23 40207 8192000000 10261711456 10714783744 1348126560 14.131 99 21 42 0 1362969552 10123200 4719792
22:42:03 21257 8192000000 9055554928 9284968448 208643600 2.483 16 5 10 0 215027664 5138448 1245616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-08-2022 22:43:34 to 08-08-2022 22:43:50.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 08-08-2022 22:43:51 to 08-08-2022 22:43:52.

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          244183
MON$OLDEST_ACTIVE               247247
MON$OLDEST_SNAPSHOT             247247
MON$NEXT_TRANSACTION            247247
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                       2074272
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33615 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33615 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33615 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33615 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Mon Aug  8 22:42:26 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Mon Aug  8 22:42:27 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Mon Aug  8 22:42:32 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Mon Aug  8 22:42:32 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Mon Aug  8 22:42:37 2022
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:25: 
--- end of diff output ---

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

08.08.22 22:43:53. 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-08-08 22:43:56 No severe PSQL-related problems occured
08.08.22 22:43:57. End of report.