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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14514076 kB
MemAvailable 30451372 kB
Buffers 0 kB
Cached 17197744 kB
SwapCached 3712 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2023-01-31 10:37:26
DTS_END                         2023-01-31 10:37:26.1010
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33040
TRN_ID                          217399
ATT_ID                          652
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: 8978.07 28400 1077368
customer order: creation 165.78 485 19893
customer order: refuse 14.36 476 1723
order to supplier: creation 43.55 1462 5226
order to supplier: removal 3.36 174022 403
invoice (draft): creation 43.53 3285 5223
invoice (draft): removal 50.94 1092 6113
invoice accept: apply 40.79 27797 4895
invoice accept: cancel 45.52 1374 5462
customer reserve: creation 3809.63 260 457156
customer reserve: removal 4481.24 100 537749
realization accept: apply 81.08 310 9730
realization accept: cancel 73.72 180 8846
payment from customer: creation 56.97 180 6836
payment from customer: removal 5.52 160 662
payment to supplier: creation 46.83 198 5619
payment to supplier: removal 3.13 155 376
service: total inventory turnovers 3.67 4833 440
service: total monetary turnovers 7.58 419 909
service: aggregate perf. data 0.85 16960 102
service: refresh index statistics 0.04 334244 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-01-2023 10:39:08 to 31-01-2023 10:39:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6428
TEST_TIME 2 5171
TEST_TIME 3 5183
TEST_TIME 4 5733
TEST_TIME 5 5699
TEST_TIME 6 5703
TEST_TIME 7 6313
TEST_TIME 8 6858
TEST_TIME 9 6822
TEST_TIME 10 6644
TEST_TIME 11 6835
TEST_TIME 12 6996
TEST_TIME 13 7096
TEST_TIME 14 7154
TEST_TIME 15 7288
TEST_TIME 16 7378
TEST_TIME 17 7567
TEST_TIME 18 7525
TEST_TIME 19 7692
TEST_TIME 20 7727
TEST_TIME 21 7959
TEST_TIME 22 8165
TEST_TIME 23 8241
TEST_TIME 24 8443
TEST_TIME 25 8344
TEST_TIME 26 8240
TEST_TIME 27 8462
TEST_TIME 28 8455
TEST_TIME 29 8517
TEST_TIME 30 8644
TEST_TIME 31 9062
TEST_TIME 32 9330
TEST_TIME 33 9444
TEST_TIME 34 9444
TEST_TIME 35 9608
TEST_TIME 36 9870
TEST_TIME 37 9987
TEST_TIME 38 9988
TEST_TIME 39 10241
TEST_TIME 40 10234
TEST_TIME 41 10436
TEST_TIME 42 10233
TEST_TIME 43 10434
TEST_TIME 44 10676
TEST_TIME 45 10798
TEST_TIME 46 10744
TEST_TIME 47 10636
TEST_TIME 48 10640
TEST_TIME 49 10512
TEST_TIME 50 10395
TEST_TIME 51 10203
TEST_TIME 52 10082
TEST_TIME 53 10041
TEST_TIME 54 9926
TEST_TIME 55 9831
TEST_TIME 56 9796
TEST_TIME 57 9866
TEST_TIME 58 9855
TEST_TIME 59 9764
TEST_TIME 60 9727
TEST_TIME 61 9658
TEST_TIME 62 9617
TEST_TIME 63 9593
TEST_TIME 64 9458
TEST_TIME 65 9507
TEST_TIME 66 9391
TEST_TIME 67 9387
TEST_TIME 68 9339
TEST_TIME 69 9279
TEST_TIME 70 9201
TEST_TIME 71 9195
TEST_TIME 72 9193
TEST_TIME 73 9194
TEST_TIME 74 9169
TEST_TIME 75 9201
TEST_TIME 76 9224
TEST_TIME 77 9285
TEST_TIME 78 9307
TEST_TIME 79 9399
TEST_TIME 80 9436
TEST_TIME 81 9472
TEST_TIME 82 9531
TEST_TIME 83 9474
TEST_TIME 84 9471
TEST_TIME 85 9570
TEST_TIME 86 9582
TEST_TIME 87 9484
TEST_TIME 88 9412
TEST_TIME 89 9340
TEST_TIME 90 9378
TEST_TIME 91 9326
TEST_TIME 92 9314
TEST_TIME 93 9277
TEST_TIME 94 9238
TEST_TIME 95 9207
TEST_TIME 96 9180
TEST_TIME 97 9170
TEST_TIME 98 9108
TEST_TIME 99 9136
TEST_TIME 100 9162
TEST_TIME 101 9116
TEST_TIME 102 9164
TEST_TIME 103 9162
TEST_TIME 104 9176
TEST_TIME 105 9200
TEST_TIME 106 9199
TEST_TIME 107 9179
TEST_TIME 108 9223
TEST_TIME 109 9288
TEST_TIME 110 9256
TEST_TIME 111 9267
TEST_TIME 112 9217
TEST_TIME 113 9199
TEST_TIME 114 9146
TEST_TIME 115 9127
TEST_TIME 116 9086
TEST_TIME 117 9124
TEST_TIME 118 9090
TEST_TIME 119 9075
TEST_TIME 120 9006

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-01-2023 10:39:12 to 31-01-2023 10:39:16.

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 1497003 1497003 0 0.00 0 20041 14 0 0
doc_list_biud 1496999 1496999 0 0.00 0 37540 43 0 0
sp_add_invoice_to_stock 5036 4895 141 2.79 56 243858 27797 0 141
sp_cancel_adding_invoice 5462 5462 0 0.00 7 80746 1374 0 0
sp_cancel_client_order 1723 1723 0 0.00 5 14329 476 0 0
sp_cancel_customer_reserve 537749 537749 0 0.00 1 29409 101 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 4 4457 161 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 4 7284 156 0 0
sp_cancel_supplier_invoice 6126 6113 13 0.21 2 35126 1093 0 13
sp_cancel_supplier_order 403 403 0 0.00 9 1503892 174022 0 0
sp_cancel_write_off 8846 8846 0 0.00 1 27207 181 0 0
sp_client_order 19893 19893 0 0.00 5 23975 485 0 0
sp_customer_reserve 457156 457156 0 0.00 0 53729 261 0 0
sp_fill_shopping_cart 30331 30331 0 0.00 1 12420 89 0 0
sp_fill_shopping_cart_clo_res 457135 457135 0 0.00 0 15802 18 0 0
sp_get_clo_for_invoice 4860 4860 0 0.00 0 15599 211 0 0
sp_kill_cost_storno 15346 15346 0 0.00 0 8683 84 0 0
sp_kill_qstorno_ret_qs2qd 1090860 1090860 0 0.00 0 35120 44 0 0
sp_kill_qty_storno 574885 574885 0 0.00 0 37539 103 0 0
sp_lock_selected_doc 20150 20150 0 0.00 0 7185 9 0 0
sp_make_cost_storno 27045 27045 0 0.00 0 12786 84 0 0
sp_multiply_rows_for_pdistr 27044 27044 0 0.00 0 6933 38 0 0
sp_multiply_rows_for_qdistr 19893 19893 0 0.00 0 11618 159 0 0
sp_pay_from_customer 6836 6836 0 0.00 5 9565 180 0 0
sp_pay_to_supplier 5619 5619 0 0.00 6 7434 198 0 0
sp_payment_common 12455 12455 0 0.00 2 6222 47 0 0
sp_qd_handle_on_cancel_clo 1723 1723 0 0.00 0 14299 400 0 0
sp_qd_handle_on_invoice_upd_sts 10321 10321 0 0.00 0 37538 605 0 0
sp_qd_handle_on_reserve_upd_sts 9730 9730 0 0.00 0 14364 74 0 0
sp_reserve_write_off 9730 9730 0 0.00 9 14617 311 0 0
sp_supplier_invoice 5223 5223 0 0.00 8 90498 3286 0 0
sp_supplier_order 5226 5226 0 0.00 8 39860 1463 0 0
srv_aggregate_perf_data 102 102 0 0.00 2588 152343 16961 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 640 106412 32051 0 0
srv_find_qd_qs_mism 1029206 1029206 0 0.00 0 20040 10 0 0
srv_make_invnt_saldo 440 440 0 0.00 62 64464 4833 0 0
srv_make_money_saldo 909 909 0 0.00 1 14356 420 0 0
srv_recalc_idx_stat 5 5 0 0.00 123942 454542 334244 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4905 4905 0 0.00 1 26950 1098 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 6454 565 0 0
t$perf-norm:sp_cancel_client_order 1723 1723 0 0.00 0 2525 30 0 0
t$perf-norm:sp_cancel_customer_reserve 611 611 0 0.00 0 1576 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 2065 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 2359 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 1275 43 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 110656 8501 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 1837 21 0 0
t$perf-norm:sp_client_order 19893 19893 0 0.00 0 5929 42 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 2211 19 0 0
t$perf-norm:sp_pay_from_customer 6836 6836 0 0.00 0 16562 25 0 0
t$perf-norm:sp_pay_to_supplier 5619 5619 0 0.00 0 3876 22 0 0
t$perf-norm:sp_reserve_write_off 9731 9731 0 0.00 0 16563 28 0 0
t$perf-norm:sp_supplier_invoice 5223 5223 0 0.00 0 5672 34 0 0
t$perf-norm:sp_supplier_order 5227 5227 0 0.00 0 25787 40 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 1232 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 1460 30 0 0
t$perf-norm:srv_make_invnt_saldo 440 440 0 0.00 0 1573 16 0 0
t$perf-norm:srv_make_money_saldo 909 909 0 0.00 0 2821 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 180 54 0 0
v_add_invoice_to_stock 9995 9713 282 2.82 0 5302 28 0 282
v_all_customers 36542 36542 0 0.00 0 72 4 0 0
v_all_suppliers 29252 29252 0 0.00 0 85 5 0 0
v_all_wares 39782 39782 0 0.00 0 84 4 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 238 16 0 0
v_cancel_client_order 3446 3446 0 0.00 0 2982 12 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 2745 17 0 0
v_cancel_customer_reserve 1222 1222 0 0.00 0 2144 14 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 262 12 0 0
v_cancel_supplier_invoice 854 828 26 3.04 0 1098 24 0 26
v_cancel_supplier_order 806 806 0 0.00 0 569 12 0 0
v_cancel_write_off 1950 1950 0 0.00 0 6062 30 0 0
v_max_id_clo_ord 5226 5226 0 0.00 0 489 5 0 0
v_max_id_clo_res 971 971 0 0.00 0 10820 31 0 0
v_max_id_ord_sup 5214 5214 0 0.00 0 2672 6 0 0
v_max_non_paid_invoice 1433 1433 0 0.00 0 166 9 0 0
v_max_non_paid_realizn 3555 3555 0 0.00 0 388 8 0 0
v_min_id_clo_ord 5226 5226 0 0.00 0 3539 11 0 0
v_min_id_clo_res 971 971 0 0.00 0 328 8 0 0
v_min_id_ord_sup 5214 5214 0 0.00 0 3635 7 0 0
v_min_non_paid_invoice 5619 5619 0 0.00 0 6197 16 0 0
v_min_non_paid_realizn 6836 6836 0 0.00 0 5160 17 0 0
v_our_firm 9802 9802 0 0.00 0 74 5 0 0
v_reserve_write_off 19460 19460 0 0.00 0 3853 12 0 0
x_lock_depdocs_on_canc_invoice 11161 11161 0 0.00 0 32476 140 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 9576 409 0 0
x_make_qstorno_1000_1200 5226 5226 0 0.00 3 39834 1268 0 0
x_make_qstorno_1200_2000 5223 5223 0 0.00 4 87119 3044 0 0
x_make_qstorno_2100_3300 457155 457155 0 0.00 0 53680 228 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-01-2023 10:39:17 to 31-01-2023 10:39:21.

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
08:07:30 5 8192000000 8798996576 8810319872 4473472 0.054 2 1 2 0 4572864 63616 35776
08:08:31 20383 8192000000 9724794288 10022387712 801401360 8.910 100 89 178 0 831273920 7654288 22218272
08:09:57 28954 8192000000 9770851616 10080202752 843433808 9.334 100 91 182 0 877753776 12795568 21524400
08:11:34 21658 8192000000 9799999520 10120040448 860508400 9.505 100 76 152 0 903592208 25432016 17651792
08:12:59 6586 8192000000 9865924592 10199920640 911479472 10.012 100 74 146 0 976929184 49059024 16390688
08:14:14 3885 8192000000 9923020608 10266644480 961821136 10.507 100 93 185 0 1039018448 57574640 19622672
08:15:20 2051 8192000000 9980166240 10331828224 995172192 10.832 100 91 174 0 1090512720 77909648 17430880
08:16:24 1501 8192000000 9996730976 10359365632 1019657712 11.069 100 86 171 0 1115575136 79026032 16891392
08:17:27 1031 8192000000 10012977616 10381516800 1047009888 11.332 100 96 183 1 1131096608 65576688 18510032
08:18:30 1111 8192000000 10033263168 10406313984 1059668800 11.453 100 100 192 1 1155345424 76465376 19211248
08:19:33 940 8192000000 10024095696 10400178176 1068282880 11.536 100 94 184 1 1145281232 58496688 18501664
08:20:36 933 8192000000 10053598464 10438209536 1069031376 11.543 100 94 186 0 1178362992 89450848 19880768
08:21:41 1251 8192000000 10038272112 10418069504 1077127984 11.620 100 95 181 1 1157862336 62855312 17879040
08:22:44 2896 8192000000 10041996336 10430926848 1081568512 11.662 100 67 128 4 1157381552 63186112 12626928
08:23:48 4094 8192000000 10046807376 10434719744 1089479568 11.738 100 70 135 0 1182665088 79427440 13758080
08:24:54 1429 8192000000 10044512512 10437689344 1093823184 11.779 100 78 142 3 1161762400 53543360 14395856
08:25:58 1028 8192000000 10026740864 10423812096 1084224304 11.688 99 68 132 1 1155314144 57908768 13181072
08:27:00 1011 8192000000 10047533616 10435813376 1100827152 11.845 100 91 174 2 1170977552 52598016 17552384
08:28:02 1201 8192000000 10054308192 10449731584 1102765600 11.864 100 78 148 0 1177193744 57413184 17014960
08:29:04 1077 8192000000 10066429216 10455932928 1104432592 11.880 100 98 182 0 1188026208 65394736 18198880
08:30:06 1248 8192000000 10072387280 10461143040 1091098240 11.753 100 94 179 3 1195714304 87587696 17028368
08:31:09 1232 8192000000 10043132992 10432458752 1084842640 11.694 100 79 146 2 1154004448 54862144 14299664
08:32:13 6750 8192000000 10000244512 10384859136 1048304048 11.344 99 57 114 2 1117278496 57742816 11231632
08:33:21 1023 8192000000 10017981600 10398748672 1055714960 11.415 99 90 176 2 1141470208 67435664 18319584
08:34:24 4944 8192000000 10006126464 10390306816 1047444864 11.336 100 70 134 0 1120545376 59531472 13569040
08:35:30 1268 8192000000 10007142416 10384584704 1049497552 11.356 100 80 155 1 1131722192 67032656 15191984
08:36:32 983 8192000000 9984422960 10355957760 1034547088 11.212 100 96 176 2 1107493744 53696496 19250160
08:37:39 13701 8192000000 9989131984 10367643648 1049093552 11.352 100 64 118 0 1110606704 50581024 10932128
08:39:03 5489 8192000000 10012009312 10392805376 1067770672 11.531 100 77 145 0 1134570512 51350480 15449360
08:40:17 4299 8192000000 10062650880 10440581120 1078705872 11.635 100 85 159 2 1180092496 86648400 14738224
08:41:24 8456 8192000000 10083672624 10477150208 1094809424 11.788 100 67 126 0 1201424512 93650784 12964304
08:42:38 2723 8192000000 10116282352 10505662464 1105552528 11.890 100 73 142 0 1230512368 110110192 14849648
08:43:46 3134 8192000000 10150189504 10537025536 1113526528 11.966 100 88 169 10 1260565168 128215392 18823248
08:44:52 1296 8192000000 10179506272 10597175296 1124722992 12.072 100 93 177 1 1300305984 156320496 19262496
08:45:57 6333 8192000000 10150554384 10548731904 1142153296 12.236 100 70 126 2 1277443664 121036816 14253552
08:47:08 4382 8192000000 10232288672 10631901184 1161571504 12.418 100 74 142 1 1338360080 161216512 15572064
08:48:16 2862 8192000000 10262754848 10673979392 1167683088 12.475 100 89 160 1 1374495696 188840384 17972224
08:49:27 2348 8192000000 10256373440 10681585664 1165885328 12.458 99 89 174 1 1362390912 176121248 20384336
08:50:33 1226 8192000000 10279975712 10686349312 1184860048 12.636 100 92 181 2 1390033376 183896576 21276752
08:51:38 938 8192000000 10304840336 10711777280 1188368656 12.668 100 86 164 0 1416017632 209134000 18514976
08:52:43 5901 8192000000 10334896704 10750889984 1190142672 12.685 100 82 155 0 1445741904 237980800 17618432
08:53:54 1609 8192000000 10366176896 10777812992 1192088544 12.703 100 95 186 2 1477138480 262206064 22843872
08:54:58 2420 8192000000 10356241040 10774601728 1189765360 12.681 100 72 143 0 1473849984 267707120 16377504
08:56:05 940 8192000000 10386327904 10813489152 1197920192 12.757 100 100 200 2 1494656048 273390544 23345312
08:57:09 1159 8192000000 10417155072 10825793536 1191692928 12.699 100 91 178 1 1521103152 308006608 21403616
08:58:15 848 8192000000 10409883984 10868056064 1182817968 12.616 100 100 198 0 1514977536 308955488 23204080
08:59:20 1008 8192000000 10418404928 10859692032 1202470144 12.799 100 97 189 0 1527163552 301696832 22996576
09:00:25 2686 8192000000 10428960208 10865565696 1194366592 12.724 100 74 143 0 1531605472 319397472 17841408
09:01:35 13147 8192000000 10429326912 10875383808 1194046656 12.721 100 60 114 1 1518285264 309222576 15016032
09:03:00 625 8192000000 10479212672 10890993664 1200466832 12.781 100 92 180 0 1585601024 362788624 22345568
09:04:06 1111 8192000000 10533995792 10972368896 1208316832 12.854 100 97 187 0 1631321216 400592928 22411456
09:05:11 3863 8192000000 10477653936 10933727232 1188716864 12.671 99 65 125 0 1584268944 379342192 16209888
09:06:22 4112 8192000000 10493439216 10913636352 1191913072 12.701 100 79 151 4 1591879936 381421984 18544880
09:07:33 6382 8192000000 10474286448 10978910208 1183042064 12.619 100 66 132 1 1600706736 400611056 17053616
09:09:08 3909 8192000000 10408801424 10917203968 1188601840 12.670 100 46 92 0 1509730544 309370160 11758544
09:10:28 34013 8192000000 10384993216 10879401984 1186342672 12.649 100 27 53 0 1509325696 316066848 6916176
09:12:06 41950 8192000000 10344583008 10799616000 1143986672 12.253 98 26 52 0 1434745152 283298816 7459664
09:13:54 36391 8192000000 10281322592 10730762240 1165553104 12.455 100 29 57 1 1387498448 215121536 6823808
09:15:32 16997 8192000000 10252705808 10741178368 1142306832 12.237 100 48 92 0 1338272240 184081328 11884080
09:16:54 10878 8192000000 10214836496 10662166528 1144554720 12.258 99 49 93 1 1311129872 156548288 10026864
09:18:07 20724 8192000000 10208764000 10696818688 1160092384 12.404 100 19 38 1 1338406784 173729984 4584416
09:19:36 33567 8192000000 10254078944 10684243968 1118742016 12.015 97 60 113 2 1324851888 193389280 12720592
09:21:10 106411 8192000000 10077190192 10554482688 1139393872 12.210 98 12 24 0 1243729456 102029536 2306048
09:23:57 68509 8192000000 10102448704 10529378304 1101733184 11.854 96 14 28 0 1125517360 20986832 2797344
09:26:07 93354 8192000000 10083821232 10528968704 1114979264 11.980 97 44 81 2 1164703664 41145776 8578624
09:29:52 76393 8192000000 10080793904 10565046272 1131389984 12.134 99 26 49 0 1194002080 57661920 4950176
09:32:30 58654 8192000000 10133723968 10573008896 1134003408 12.159 99 36 68 1 1216519264 75242288 7273568
09:34:33 37615 8192000000 10091260176 10574782464 1133279456 12.152 100 18 34 0 1195052000 58020480 3752064
09:36:53 58403 8192000000 10085493888 10562768896 1126325376 12.087 99 13 26 0 1193456400 64441840 2689184
09:39:37 65387 8192000000 10082873376 10559741952 1109826032 11.931 97 18 34 0 1176639088 63132080 3680976
09:41:42 57105 8192000000 10085581808 10572902400 1134430800 12.163 100 17 35 0 1190995584 52953280 3611504
09:43:43 18346 8192000000 10083252384 10528542720 1123276384 12.058 98 41 79 1 1163565168 32208800 8079984
09:45:02 90331 8192000000 10047349840 10521370624 1100471520 11.842 97 12 23 0 1143096480 40435856 2189104
09:47:33 55430 8192000000 10063418880 10532651008 1116043200 11.990 100 16 32 0 1158502128 38351120 4107808
09:49:28 47718 8192000000 10096455200 10539819008 1111065552 11.943 98 26 50 0 1166244512 50071632 5107328
09:51:16 44967 8192000000 10118939552 10565427200 1109333360 11.926 98 32 61 0 1196049648 79554240 7162048
09:53:04 58208 8192000000 10152236592 10595565568 1103978512 11.875 97 16 32 0 1206983856 99569168 3436176
09:55:09 32704 8192000000 10144239984 10588368896 1143723408 12.251 100 27 55 0 1236943120 87768368 5451344
09:56:42 42007 8192000000 10106573840 10547281920 1129677120 12.118 99 22 44 1 1188743936 54416592 4650224
09:58:27 75200 8192000000 10064144336 10537017344 1116818624 11.997 97 24 44 0 1178109552 56568800 4722128
10:00:44 9335 8192000000 10134091328 10566901760 1145394928 12.266 100 64 122 0 1225496352 67156960 12944464
10:01:54 38805 8192000000 10136995664 10615291904 1123184688 12.057 98 34 66 1 1240886656 110891808 6810160
10:03:36 103470 8192000000 10072458128 10556452864 1095341792 11.793 97 15 29 1 1143348592 45041280 2965520
10:07:43 48703 8192000000 10079731216 10569396224 1124897296 12.073 99 15 26 0 1184686368 56824272 2964800
10:09:33 57738 8192000000 10065472752 10524299264 1135435824 12.173 100 17 34 0 1179846496 41116880 3293792
10:11:36 85935 8192000000 10102175808 10578718720 1144025248 12.253 100 13 25 0 1222793680 76063792 2704640
10:14:02 53727 8192000000 10107741568 10573230080 1146923184 12.281 100 41 82 0 1223265056 66607760 9734112
10:16:45 68744 8192000000 10115946672 10608230400 1133397536 12.153 99 12 24 1 1212216240 75886000 2932704
10:18:55 22824 8192000000 10105830528 10572406784 1132962528 12.149 100 60 120 1 1229117360 83721104 12433728
10:20:29 37423 8192000000 10116895808 10573516800 1112602336 11.957 98 39 78 1 1188316400 67638400 8075664
10:22:07 6734 8192000000 10069334928 10500165632 1095092192 11.791 98 56 111 0 1146271392 39899664 11279536
10:23:15 11072 8192000000 10093397760 10532024320 1122361888 12.049 100 51 101 0 1182378128 50408784 9607456
10:24:28 24200 8192000000 10136686672 10578747392 1112880256 11.960 99 57 110 1 1223465824 99612000 10973568
10:25:53 63951 8192000000 10047496832 10512924672 1090644128 11.749 97 23 46 0 1165351424 70251568 4455728
10:28:03 34617 8192000000 10093255728 10548772864 1117189632 12.000 99 37 70 1 1184075728 60068016 6818080
10:29:38 98445 8192000000 10057547216 10508607488 1128891680 12.111 99 26 52 0 1187960736 53692944 5376112
10:32:33 75154 8192000000 10118887712 10577293312 1114207824 11.972 98 15 30 1 1197936656 80506848 3221984
10:34:59 35782 8192000000 10037359600 10495148032 1119022704 12.018 100 11 22 0 1154435632 33497296 1915632
10:36:37 42610 8192000000 10041931760 10507001856 1105557264 11.890 99 6 12 0 1138359296 32050048 751984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 31-01-2023 10:39:23 to 31-01-2023 10:39:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-01-2023 10:39:40 to 31-01-2023 10:39:42.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          217583
MON$OLDEST_ACTIVE               217584
MON$OLDEST_SNAPSHOT             217584
MON$NEXT_TRANSACTION            217584
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-30 07:30:08.6030 GMT
MON$PAGES                       2132992
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2894 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2894 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:251: 
:252: mscs-test-161.budzdorov.ru    Tue Jan 31 10:37:34 2023
:253:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:254: 
:255: 
:256: mscs-test-161.budzdorov.ru    Tue Jan 31 10:37:36 2023
:257:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:258: 
--- end of diff output ---

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

31.01.23 10:39:44. There are 3 logs in /var/tmp/logs-oltp40 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
2023-01-31 10:39:46 No severe PSQL-related problems occured
31.01.23 10:39:48. End of report.