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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16029460 kB
MemAvailable 31001776 kB
Buffers 0 kB
Cached 15651468 kB
SwapCached 2552 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-07-25 22:38:27
DTS_END                         2022-07-25 22:38:27.7830
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/60280
TRN_ID                          247960
ATT_ID                          727
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: 9294.05 20806 1115286
customer order: creation 174.89 332 20987
customer order: refuse 14.59 338 1751
order to supplier: creation 46.42 1195 5570
order to supplier: removal 3.48 98508 417
invoice (draft): creation 45.99 3385 5519
invoice (draft): removal 53.12 789 6374
invoice accept: apply 42.30 17897 5076
invoice accept: cancel 47.88 690 5745
customer reserve: creation 3888.09 180 466571
customer reserve: removal 4675.32 57 561038
realization accept: apply 85.12 169 10214
realization accept: cancel 86.63 102 10396
payment from customer: creation 60.11 101 7213
payment from customer: removal 5.56 96 667
payment to supplier: creation 48.93 120 5871
payment to supplier: removal 3.26 73 391
service: total inventory turnovers 4.88 986 586
service: total monetary turnovers 6.61 25 793
service: aggregate perf. data 0.86 19129 103
service: refresh index statistics 0.03 271947 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-07-2022 22:39:28 to 25-07-2022 22:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5004
TEST_TIME 2 4432
TEST_TIME 3 4253
TEST_TIME 4 4296
TEST_TIME 5 4733
TEST_TIME 6 5231
TEST_TIME 7 5015
TEST_TIME 8 4908
TEST_TIME 9 4919
TEST_TIME 10 5157
TEST_TIME 11 5148
TEST_TIME 12 5113
TEST_TIME 13 5224
TEST_TIME 14 5406
TEST_TIME 15 5503
TEST_TIME 16 5613
TEST_TIME 17 5856
TEST_TIME 18 6330
TEST_TIME 19 6911
TEST_TIME 20 7436
TEST_TIME 21 7427
TEST_TIME 22 7564
TEST_TIME 23 8511
TEST_TIME 24 9293
TEST_TIME 25 9856
TEST_TIME 26 9901
TEST_TIME 27 9817
TEST_TIME 28 9599
TEST_TIME 29 9584
TEST_TIME 30 9436
TEST_TIME 31 9439
TEST_TIME 32 9525
TEST_TIME 33 9317
TEST_TIME 34 9354
TEST_TIME 35 9324
TEST_TIME 36 9377
TEST_TIME 37 9277
TEST_TIME 38 9242
TEST_TIME 39 9203
TEST_TIME 40 9268
TEST_TIME 41 9160
TEST_TIME 42 9080
TEST_TIME 43 9124
TEST_TIME 44 9048
TEST_TIME 45 9114
TEST_TIME 46 9127
TEST_TIME 47 9070
TEST_TIME 48 9158
TEST_TIME 49 9378
TEST_TIME 50 9348
TEST_TIME 51 9396
TEST_TIME 52 9438
TEST_TIME 53 9492
TEST_TIME 54 9638
TEST_TIME 55 9711
TEST_TIME 56 9848
TEST_TIME 57 9739
TEST_TIME 58 9613
TEST_TIME 59 9590
TEST_TIME 60 9517
TEST_TIME 61 9551
TEST_TIME 62 9561
TEST_TIME 63 9493
TEST_TIME 64 9490
TEST_TIME 65 9406
TEST_TIME 66 9472
TEST_TIME 67 9588
TEST_TIME 68 9520
TEST_TIME 69 9571
TEST_TIME 70 9615
TEST_TIME 71 9557
TEST_TIME 72 9633
TEST_TIME 73 9595
TEST_TIME 74 9696
TEST_TIME 75 9720
TEST_TIME 76 9757
TEST_TIME 77 9680
TEST_TIME 78 9828
TEST_TIME 79 9807
TEST_TIME 80 9696
TEST_TIME 81 9686
TEST_TIME 82 9652
TEST_TIME 83 9624
TEST_TIME 84 9572
TEST_TIME 85 9550
TEST_TIME 86 9503
TEST_TIME 87 9517
TEST_TIME 88 9454
TEST_TIME 89 9416
TEST_TIME 90 9389
TEST_TIME 91 9480
TEST_TIME 92 9435
TEST_TIME 93 9487
TEST_TIME 94 9502
TEST_TIME 95 9469
TEST_TIME 96 9513
TEST_TIME 97 9553
TEST_TIME 98 9518
TEST_TIME 99 9532
TEST_TIME 100 9515
TEST_TIME 101 9518
TEST_TIME 102 9508
TEST_TIME 103 9508
TEST_TIME 104 9529
TEST_TIME 105 9550
TEST_TIME 106 9524
TEST_TIME 107 9484
TEST_TIME 108 9437
TEST_TIME 109 9447
TEST_TIME 110 9423
TEST_TIME 111 9386
TEST_TIME 112 9356
TEST_TIME 113 9331
TEST_TIME 114 9323
TEST_TIME 115 9289
TEST_TIME 116 9297
TEST_TIME 117 9289
TEST_TIME 118 9323
TEST_TIME 119 9300
TEST_TIME 120 9296

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-07-2022 22:39:32 to 25-07-2022 22:39:35.

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 1549965 1549965 0 0.00 0 19224 6 0 0
doc_list_biud 1549964 1549964 0 0.00 0 66176 26 0 0
sp_add_invoice_to_stock 5188 5076 112 2.15 51 186601 17897 0 112
sp_cancel_adding_invoice 5745 5745 0 0.00 5 74951 690 0 0
sp_cancel_client_order 1751 1751 0 0.00 4 12342 338 0 0
sp_cancel_customer_reserve 561038 561038 0 0.00 0 66177 57 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 2 11494 97 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 2 3262 74 0 0
sp_cancel_supplier_invoice 6385 6374 11 0.17 2 18998 789 0 11
sp_cancel_supplier_order 417 417 0 0.00 5 949982 98508 0 0
sp_cancel_write_off 10396 10396 0 0.00 1 12754 103 0 0
sp_client_order 20987 20987 0 0.00 3 17032 333 0 0
sp_customer_reserve 466571 466571 0 0.00 0 37252 180 0 0
sp_fill_shopping_cart 32074 32074 0 0.00 1 10777 37 0 0
sp_fill_shopping_cart_clo_res 466569 466569 0 0.00 0 19789 13 0 0
sp_get_clo_for_invoice 5074 5074 0 0.00 0 34083 312 0 0
sp_kill_cost_storno 17199 17199 0 0.00 0 11481 49 0 0
sp_kill_qstorno_ret_qs2qd 1139263 1139263 0 0.00 0 66175 31 0 0
sp_kill_qty_storno 601008 601008 0 0.00 0 66176 67 0 0
sp_lock_selected_doc 21029 21029 0 0.00 0 8874 8 0 0
sp_make_cost_storno 28372 28372 0 0.00 0 19203 51 0 0
sp_multiply_rows_for_pdistr 28371 28371 0 0.00 0 19198 28 0 0
sp_multiply_rows_for_qdistr 20987 20987 0 0.00 0 17000 156 0 0
sp_pay_from_customer 7213 7213 0 0.00 4 16505 102 0 0
sp_pay_to_supplier 5871 5871 0 0.00 4 19323 121 0 0
sp_payment_common 13084 13084 0 0.00 1 18172 33 0 0
sp_qd_handle_on_cancel_clo 1751 1751 0 0.00 0 12250 302 0 0
sp_qd_handle_on_invoice_upd_sts 10819 10819 0 0.00 0 23308 295 0 0
sp_qd_handle_on_reserve_upd_sts 10213 10213 0 0.00 0 19815 53 0 0
sp_reserve_write_off 10214 10214 0 0.00 6 19868 169 0 0
sp_supplier_invoice 5519 5519 0 0.00 6 86579 3386 0 0
sp_supplier_order 5570 5570 0 0.00 5 24542 1195 0 0
srv_aggregate_perf_data 103 103 0 0.00 2896 247445 19129 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1724 148714 41022 0 0
srv_find_qd_qs_mism 1068416 1068416 0 0.00 0 16645 6 0 0
srv_make_invnt_saldo 586 586 0 0.00 38 57177 987 0 0
srv_make_money_saldo 793 793 0 0.00 1 4113 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 86267 403241 271947 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5077 5077 0 0.00 0 10187 146 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 4664 83 0 0
t$perf-norm:sp_cancel_client_order 1751 1751 0 0.00 0 5962 12 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 3207 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 1612 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 888 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 1942 15 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 20945 1234 0 0
t$perf-norm:sp_cancel_write_off 1027 1027 0 0.00 0 2030 7 0 0
t$perf-norm:sp_client_order 20987 20987 0 0.00 0 13445 17 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 760 3 0 0
t$perf-norm:sp_pay_from_customer 7213 7213 0 0.00 0 9171 13 0 0
t$perf-norm:sp_pay_to_supplier 5871 5871 0 0.00 0 5141 9 0 0
t$perf-norm:sp_reserve_write_off 10214 10214 0 0.00 0 9317 10 0 0
t$perf-norm:sp_supplier_invoice 5519 5519 0 0.00 0 8046 20 0 0
t$perf-norm:sp_supplier_order 5570 5570 0 0.00 0 7335 12 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 529 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 2240 45 0 0
t$perf-norm:srv_make_invnt_saldo 586 586 0 0.00 0 4657 39 0 0
t$perf-norm:srv_make_money_saldo 793 793 0 0.00 0 6190 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10368 10144 224 2.16 0 9434 14 0 224
v_all_customers 38186 38186 0 0.00 0 84 0 0 0
v_all_suppliers 30544 30544 0 0.00 0 137 1 0 0
v_all_wares 41974 41974 0 0.00 0 83 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 946 6 0 0
v_cancel_client_order 3502 3502 0 0.00 0 2140 4 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 5437 8 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 2103 7 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 3187 7 0 0
v_cancel_supplier_invoice 872 850 22 2.52 0 517 11 0 22
v_cancel_supplier_order 834 834 0 0.00 0 2138 8 0 0
v_cancel_write_off 2054 2054 0 0.00 0 1134 13 0 0
v_max_id_clo_ord 5570 5570 0 0.00 0 846 1 0 0
v_max_id_clo_res 994 994 0 0.00 0 2626 17 0 0
v_max_id_ord_sup 5517 5517 0 0.00 0 4290 4 0 0
v_max_non_paid_invoice 1686 1686 0 0.00 0 1251 4 0 0
v_max_non_paid_realizn 3742 3742 0 0.00 0 3561 5 0 0
v_min_id_clo_ord 5570 5570 0 0.00 0 3359 9 0 0
v_min_id_clo_res 994 994 0 0.00 0 7935 13 0 0
v_min_id_ord_sup 5517 5517 0 0.00 0 2879 5 0 0
v_min_non_paid_invoice 5871 5871 0 0.00 0 18162 29 0 0
v_min_non_paid_realizn 7213 7213 0 0.00 0 11708 25 0 0
v_our_firm 10730 10730 0 0.00 0 12 1 0 0
v_reserve_write_off 20426 20426 0 0.00 0 2809 4 0 0
x_lock_depdocs_on_canc_invoice 11694 11694 0 0.00 0 11348 103 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 6153 311 0 0
x_make_qstorno_1000_1200 5570 5570 0 0.00 1 24529 1123 0 0
x_make_qstorno_1200_2000 5519 5519 0 0.00 3 86526 3249 0 0
x_make_qstorno_2100_3300 466571 466571 0 0.00 0 37251 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-07-2022 22:39:36 to 25-07-2022 22:39:40.

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:08:31 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:31 27065 8192000000 9905714208 10215952384 981808816 10.702 100 79 158 0 1008510560 5718944 20982800
20:11:05 21941 8192000000 9961010288 10285592576 1041148464 11.276 100 88 177 0 1071495680 7753584 22593632
20:12:34 19459 8192000000 9987267360 10324430848 1072288800 11.574 100 79 158 0 1101317216 9369632 19658784
20:13:57 10422 8192000000 10047260832 10397511680 1138102528 12.198 100 82 164 0 1167942608 10532336 19307744
20:15:12 4587 8192000000 10096561408 10459570176 1185820464 12.644 100 70 139 0 1213130656 10925168 16385024
20:16:18 3141 8192000000 10133707728 10503364608 1217062960 12.935 100 80 159 1 1248396336 13815904 17517472
20:17:22 1532 8192000000 10161305456 10539773952 1250244240 13.240 100 74 146 0 1278413792 12544912 15624640
20:18:25 1028 8192000000 10189907152 10567667712 1271865872 13.439 100 88 176 2 1305933872 15120272 18947728
20:19:28 777 8192000000 10210835200 10595528704 1297375296 13.671 100 93 186 0 1332327936 14477184 20475456
20:20:30 29933 8192000000 10182296480 10600837120 1302227344 13.715 100 36 70 1 1317349392 6990624 8131424
20:22:01 11081 8192000000 10194751504 10613256192 1313468384 13.818 100 18 35 0 1322679664 5670736 3540544
20:23:16 13064 8192000000 10203943664 10624512000 1317422768 13.853 100 15 28 0 1325892848 5464160 3005920
20:24:30 69811 8192000000 10217808880 10638458880 1329140704 13.959 100 41 82 1 1344226336 4967424 10118208
20:26:41 8413 8192000000 10221934560 10640814080 1320570992 13.882 99 18 35 0 1329615536 5468752 3575792
20:27:53 7301 8192000000 10193625328 10609750016 1295939376 13.658 99 24 50 0 1307564736 5915808 5709552
20:29:02 27486 8192000000 10111562624 10509324288 1155647824 12.362 90 19 38 1 1168897072 8707728 4541520
20:30:41 8056 8192000000 10151269984 10557399040 1254751776 13.282 99 20 40 0 1264508672 5326176 4430720
20:31:50 7190 8192000000 10170266608 10568839168 1247829856 13.218 98 24 47 2 1258240992 5072064 5339072
20:32:58 11805 8192000000 10163847232 10565189632 1259310256 13.324 99 24 48 0 1269969056 5165952 5492848
20:34:13 47625 8192000000 10176787600 10594455552 1290120800 13.605 100 27 54 1 1301641168 4458672 7061696
20:36:05 6601 8192000000 10196231920 10607771648 1299056304 13.687 100 29 58 0 1312497600 7436400 6004896
20:37:12 7497 8192000000 10203652768 10619084800 1312121840 13.805 100 20 39 0 1322035968 5584320 4329808
20:38:22 12550 8192000000 10232574624 10646712320 1338568752 14.045 100 19 38 0 1346977200 4567792 3840656
20:39:52 80483 8192000000 10247256064 10673553408 1354595888 14.189 100 29 58 0 1367894128 6906032 6392208
20:42:18 15179 8192000000 10275455424 10691489792 1356947744 14.210 99 53 106 0 1378776528 9956400 11872384
20:43:37 22553 8192000000 10276050880 10693312512 1345980448 14.111 98 51 104 1 1369899136 12486416 11432272
20:45:13 15772 8192000000 10278592096 10702561280 1376096736 14.382 100 35 68 2 1394850912 10847568 7906608
20:46:40 5707 8192000000 10281409024 10699091968 1362317408 14.258 99 49 96 0 1387105168 13206432 11581328
20:48:14 26500 8192000000 10292447040 10714988544 1360585088 14.243 99 42 84 0 1392774448 21454160 10735200
20:49:53 2216 8192000000 10306533536 10720530432 1382326176 14.437 100 53 103 0 1415648144 20408880 12913088
20:51:03 10504 8192000000 10298707872 10712883200 1360781616 14.244 99 48 94 0 1396326896 22678784 12866496
20:52:20 12888 8192000000 10330382352 10746998784 1387191600 14.481 99 54 108 0 1430303632 28436112 14675920
20:53:45 3152 8192000000 10304284448 10700468224 1353198016 14.176 98 77 152 0 1403657776 30990944 19468816
20:54:53 3759 8192000000 10320873088 10726846464 1368204800 14.311 100 63 126 0 1422808864 37566848 17037216
20:56:03 1724 8192000000 10334269680 10743287808 1380654816 14.422 100 68 133 1 1434217040 36222576 17339648
20:57:11 7614 8192000000 10328804096 10741329920 1376071152 14.381 100 62 124 3 1432133632 39268992 16793488
20:58:25 7694 8192000000 10314576368 10736496640 1370946864 14.336 100 49 98 2 1418607584 34646112 13014608
20:59:36 12047 8192000000 10301822752 10720129024 1338889360 14.047 98 42 84 0 1392092288 41306224 11896704
21:00:53 5272 8192000000 10316847936 10734579712 1350963504 14.156 99 46 92 0 1411395744 47767424 12664816
21:02:00 22395 8192000000 10287510976 10723323904 1345589632 14.108 99 25 50 0 1392722016 40212352 6920032
21:03:35 31735 8192000000 10273253840 10722029568 1360939392 14.246 100 7 14 0 1384888880 22195056 1754432
21:05:12 36437 8192000000 10270276288 10703515648 1333335472 13.997 98 39 78 3 1351727552 9213728 9178352
21:06:54 115749 8192000000 10284569712 10721079296 1355946464 14.201 99 42 80 1 1376925440 11935424 9043552
21:09:52 69060 8192000000 10288832928 10748280832 1368008480 14.309 98 19 38 0 1387473616 15477936 3987200
21:12:44 11037 8192000000 10291754432 10723758080 1358534736 14.224 98 50 100 1 1377579152 7869088 11175328
21:13:57 1732 8192000000 10318065600 10747596800 1390230784 14.508 100 74 148 1 1418529344 11989200 16309360
21:15:04 44180 8192000000 10305505440 10756128768 1399393872 14.590 100 9 19 0 1410290192 8625024 2271296
21:16:50 44752 8192000000 10300583632 10761261056 1402765664 14.620 100 12 24 0 1417098608 11492736 2840208
21:18:39 36999 8192000000 10303576912 10761076736 1402278528 14.615 100 23 46 2 1415717392 7813840 5625024
21:20:40 34419 8192000000 10289374416 10756001792 1396977696 14.568 100 14 28 0 1410150800 10165264 3007840
21:23:08 63659 8192000000 10292876912 10754732032 1358061728 14.220 97 15 30 0 1374421408 13004496 3355184
21:25:12 70393 8192000000 10312196544 10773450752 1372406736 14.349 98 22 44 1 1395508464 17713360 5388368
21:27:32 27691 8192000000 10306663568 10750468096 1390927696 14.514 100 24 47 1 1405202608 9100576 5174336
21:29:02 31287 8192000000 10309599712 10767945728 1388342720 14.491 99 20 38 0 1409411632 16837584 4231328
21:30:34 45977 8192000000 10298566624 10750779392 1370294864 14.330 99 21 42 0 1394239408 19019824 4924720
21:32:31 69142 8192000000 10294902832 10743934976 1337901344 14.038 96 12 24 0 1366377728 25622480 2853904
21:34:41 117711 8192000000 10282001792 10723569664 1329391184 13.962 96 15 30 0 1346673952 13859488 3423280
21:37:41 75590 8192000000 10283225984 10738839552 1360987136 14.246 98 24 48 0 1383793008 16932912 5872960
21:39:59 50164 8192000000 10265299792 10725060608 1342396560 14.079 97 17 35 0 1360589952 14522960 3670432
21:42:08 61962 8192000000 10276419072 10736455680 1376410656 14.384 99 10 20 0 1389049808 10625280 2013872
21:44:10 49181 8192000000 10280249648 10736193536 1335318784 14.015 96 19 38 0 1351282080 11392640 4570656
21:46:01 24747 8192000000 10269820064 10705977344 1332008256 13.985 98 37 70 0 1348135936 7246080 8881600
21:47:26 81091 8192000000 10270992352 10719698944 1307266736 13.761 96 19 38 0 1322784640 11401728 4116176
21:49:53 11304 8192000000 10284774400 10719940608 1344707392 14.100 98 40 79 0 1364942080 11697120 8537568
21:51:06 18824 8192000000 10270877648 10701635584 1329053232 13.959 98 43 85 1 1348977360 9950800 9973328
21:52:38 34678 8192000000 10277545952 10739965952 1365826736 14.290 100 20 40 1 1388447056 18164656 4455664
21:54:23 115972 8192000000 10289719056 10733125632 1314960416 13.831 96 20 40 0 1334371552 15098576 4312560
21:57:21 148714 8192000000 10275584096 10711560192 1323614176 13.909 97 17 34 0 1343898576 16103968 4180432
22:00:54 19798 8192000000 10281315504 10717917184 1362422720 14.259 100 30 54 4 1378652192 9199856 7029616
22:02:23 36645 8192000000 10274059040 10736902144 1358196768 14.221 99 6 12 0 1376385456 16957408 1231280
22:04:00 64625 8192000000 10281725680 10737827840 1380230656 14.419 100 20 40 0 1395576672 10719200 4626816
22:06:43 79151 8192000000 10294430016 10756689920 1377265120 14.392 99 16 32 0 1397474112 16586736 3622256
22:10:01 48950 8192000000 10283522176 10739367936 1371781696 14.343 99 8 13 0 1379258768 6766048 711024
22:11:56 74075 8192000000 10293996928 10749583360 1351214144 14.158 97 15 29 1 1363955632 10020768 2720720
22:14:50 54547 8192000000 10287225232 10740068352 1367551536 14.305 99 23 46 0 1384006752 11207856 5247360
22:16:45 31237 8192000000 10315428240 10765230080 1396055248 14.560 100 33 66 0 1414849120 11623920 7169952
22:18:21 17818 8192000000 10327416128 10769850368 1405712464 14.646 100 47 94 1 1425930144 10217168 10000512
22:19:40 26247 8192000000 10292845408 10726260736 1319616592 13.873 95 41 80 0 1338663024 10605152 8441280
22:21:07 19374 8192000000 10319903520 10759581696 1389666256 14.503 100 56 112 0 1417785760 16279840 11839664
22:22:28 18910 8192000000 10289276848 10731077632 1367308128 14.303 99 35 69 0 1385251744 10462688 7480928
22:23:47 99881 8192000000 10262591264 10712240128 1325252896 13.924 97 11 22 0 1342570176 14987760 2329520
22:27:04 7382 8192000000 10274265856 10720485376 1366896736 14.299 100 37 74 1 1384054464 9197472 7960256
22:28:12 104638 8192000000 10277819600 10739941376 1371600720 14.341 99 14 28 0 1385878896 10599312 3678864
22:31:04 66324 8192000000 10257384016 10713772032 1338626640 14.045 97 13 26 1 1347305520 6303712 2375168
22:33:20 21051 8192000000 10263697824 10707644416 1328055616 13.950 97 22 44 0 1339248080 6663280 4529184
22:34:45 22475 8192000000 10273725264 10728194048 1347419088 14.124 98 32 64 1 1368275968 13718416 7138464
22:36:32 61060 8192000000 10273544848 10715926528 1331645520 13.982 97 31 63 0 1347654576 8905488 7103568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-07-2022 22:39:42 to 25-07-2022 22:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-07-2022 22:39:58 to 25-07-2022 22:40:00.

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          247472
MON$OLDEST_ACTIVE               248146
MON$OLDEST_SNAPSHOT             248146
MON$NEXT_TRANSACTION            248146
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                       2027232
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.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:188: 
:189: mscs-test-161.budzdorov.ru    Mon Jul 25 22:38:35 2022
:190:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:191: 
:192: 
:193: mscs-test-161.budzdorov.ru    Mon Jul 25 22:38:36 2022
:194:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:195: 
:196: 
:197: mscs-test-161.budzdorov.ru    Mon Jul 25 22:38:38 2022
:198:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:199: 
--- end of diff output ---

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

25.07.22 22:40:01. 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-07-25 22:40:03 No severe PSQL-related problems occured
25.07.22 22:40:05. End of report.