Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.04.2022 04: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 16544436 kB
MemAvailable 31502040 kB
Buffers 20 kB
Cached 15082288 kB
SwapCached 2260 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.2
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 2022-04-06 04:37:15
DTS_END                         2022-04-06 04:37:15.5780
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50404
TRN_ID                          216244
ATT_ID                          579
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: 8944.95 28226 1073394
customer order: creation 158.87 490 19064
customer order: refuse 13.98 417 1677
order to supplier: creation 43.58 1325 5230
order to supplier: removal 3.53 164080 424
invoice (draft): creation 43.53 3102 5223
invoice (draft): removal 52.16 970 6259
invoice accept: apply 39.62 25971 4754
invoice accept: cancel 47.18 1113 5662
customer reserve: creation 3717.09 246 446051
customer reserve: removal 4539.05 102 544686
realization accept: apply 78.29 263 9395
realization accept: cancel 84.11 161 10093
payment from customer: creation 56.93 179 6831
payment from customer: removal 5.69 106 683
payment to supplier: creation 45.89 196 5507
payment to supplier: removal 3.51 152 421
service: total inventory turnovers 3.73 4206 448
service: total monetary turnovers 7.33 357 879
service: aggregate perf. data 0.85 16302 102
service: refresh index statistics 0.04 344773 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-04-2022 04:38:33 to 06-04-2022 04:38:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8764
TEST_TIME 2 6320
TEST_TIME 3 5830
TEST_TIME 4 6359
TEST_TIME 5 6113
TEST_TIME 6 6161
TEST_TIME 7 5994
TEST_TIME 8 6376
TEST_TIME 9 6339
TEST_TIME 10 6405
TEST_TIME 11 6561
TEST_TIME 12 6490
TEST_TIME 13 6774
TEST_TIME 14 6861
TEST_TIME 15 7062
TEST_TIME 16 7432
TEST_TIME 17 7314
TEST_TIME 18 7315
TEST_TIME 19 7364
TEST_TIME 20 7654
TEST_TIME 21 8104
TEST_TIME 22 8104
TEST_TIME 23 8327
TEST_TIME 24 8495
TEST_TIME 25 9162
TEST_TIME 26 9549
TEST_TIME 27 9917
TEST_TIME 28 10495
TEST_TIME 29 11192
TEST_TIME 30 11331
TEST_TIME 31 11245
TEST_TIME 32 11111
TEST_TIME 33 10950
TEST_TIME 34 10812
TEST_TIME 35 10580
TEST_TIME 36 10486
TEST_TIME 37 10489
TEST_TIME 38 10344
TEST_TIME 39 10207
TEST_TIME 40 10164
TEST_TIME 41 10020
TEST_TIME 42 9947
TEST_TIME 43 9990
TEST_TIME 44 9881
TEST_TIME 45 9855
TEST_TIME 46 9876
TEST_TIME 47 9932
TEST_TIME 48 9903
TEST_TIME 49 10025
TEST_TIME 50 9949
TEST_TIME 51 9940
TEST_TIME 52 9854
TEST_TIME 53 9791
TEST_TIME 54 9988
TEST_TIME 55 9926
TEST_TIME 56 9866
TEST_TIME 57 9906
TEST_TIME 58 9853
TEST_TIME 59 9872
TEST_TIME 60 10062
TEST_TIME 61 10055
TEST_TIME 62 9936
TEST_TIME 63 9819
TEST_TIME 64 9744
TEST_TIME 65 9677
TEST_TIME 66 9716
TEST_TIME 67 9693
TEST_TIME 68 9664
TEST_TIME 69 9617
TEST_TIME 70 9519
TEST_TIME 71 9485
TEST_TIME 72 9402
TEST_TIME 73 9396
TEST_TIME 74 9339
TEST_TIME 75 9264
TEST_TIME 76 9231
TEST_TIME 77 9231
TEST_TIME 78 9193
TEST_TIME 79 9205
TEST_TIME 80 9191
TEST_TIME 81 9199
TEST_TIME 82 9242
TEST_TIME 83 9300
TEST_TIME 84 9347
TEST_TIME 85 9367
TEST_TIME 86 9390
TEST_TIME 87 9378
TEST_TIME 88 9411
TEST_TIME 89 9435
TEST_TIME 90 9354
TEST_TIME 91 9316
TEST_TIME 92 9280
TEST_TIME 93 9242
TEST_TIME 94 9170
TEST_TIME 95 9130
TEST_TIME 96 9179
TEST_TIME 97 9159
TEST_TIME 98 9142
TEST_TIME 99 9089
TEST_TIME 100 9088
TEST_TIME 101 9059
TEST_TIME 102 9048
TEST_TIME 103 9015
TEST_TIME 104 9035
TEST_TIME 105 9056
TEST_TIME 106 9262
TEST_TIME 107 9248
TEST_TIME 108 9207
TEST_TIME 109 9177
TEST_TIME 110 9160
TEST_TIME 111 9124
TEST_TIME 112 9107
TEST_TIME 113 9084
TEST_TIME 114 9065
TEST_TIME 115 9023
TEST_TIME 116 9020
TEST_TIME 117 8988
TEST_TIME 118 8987
TEST_TIME 119 9049
TEST_TIME 120 8980

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-04-2022 04:38:37 to 06-04-2022 04:38:40.

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 1483872 1483872 0 0.00 0 37977 13 0 0
doc_list_biud 1483862 1483862 0 0.00 0 73157 43 0 0
sp_add_invoice_to_stock 4895 4754 141 2.88 33 227653 25971 0 141
sp_cancel_adding_invoice 5662 5662 0 0.00 6 97220 1113 0 0
sp_cancel_client_order 1677 1677 0 0.00 6 16203 417 0 0
sp_cancel_customer_reserve 544686 544686 0 0.00 1 24324 102 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 4 2267 106 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 4 6761 153 0 0
sp_cancel_supplier_invoice 6275 6259 16 0.25 3 73214 971 0 16
sp_cancel_supplier_order 424 424 0 0.00 19 1310585 164080 0 0
sp_cancel_write_off 10093 10093 0 0.00 2 8977 162 0 0
sp_client_order 19064 19064 0 0.00 5 34402 491 0 0
sp_customer_reserve 446051 446051 0 0.00 1 43075 246 0 0
sp_fill_shopping_cart 29505 29505 0 0.00 1 15061 79 0 0
sp_fill_shopping_cart_clo_res 446025 446025 0 0.00 0 12247 18 0 0
sp_get_clo_for_invoice 4722 4722 0 0.00 0 16093 286 0 0
sp_kill_cost_storno 16859 16859 0 0.00 0 8218 70 0 0
sp_kill_qstorno_ret_qs2qd 1106148 1106148 0 0.00 0 73135 45 0 0
sp_kill_qty_storno 582918 582918 0 0.00 0 73150 101 0 0
sp_lock_selected_doc 19738 19738 0 0.00 0 9030 8 0 0
sp_make_cost_storno 26455 26455 0 0.00 0 10904 78 0 0
sp_multiply_rows_for_pdistr 26452 26452 0 0.00 0 7330 40 0 0
sp_multiply_rows_for_qdistr 19064 19064 0 0.00 0 11421 188 0 0
sp_pay_from_customer 6831 6831 0 0.00 6 11829 180 0 0
sp_pay_to_supplier 5507 5507 0 0.00 7 11572 196 0 0
sp_payment_common 12335 12335 0 0.00 2 4806 43 0 0
sp_qd_handle_on_cancel_clo 1677 1677 0 0.00 0 16164 340 0 0
sp_qd_handle_on_invoice_upd_sts 10384 10384 0 0.00 0 12963 468 0 0
sp_qd_handle_on_reserve_upd_sts 9395 9395 0 0.00 0 11878 62 0 0
sp_reserve_write_off 9395 9395 0 0.00 8 13509 263 0 0
sp_supplier_invoice 5223 5223 0 0.00 11 91054 3103 0 0
sp_supplier_order 5230 5230 0 0.00 7 41297 1325 0 0
srv_aggregate_perf_data 102 102 0 0.00 2805 162846 16302 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 827 108921 36449 0 0
srv_find_qd_qs_mism 1026688 1026688 0 0.00 0 37976 10 0 0
srv_make_invnt_saldo 448 448 0 0.00 50 108779 4207 0 0
srv_make_money_saldo 879 879 0 0.00 0 16936 358 0 0
srv_recalc_idx_stat 5 5 0 0.00 270798 414987 344773 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 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4762 4762 0 0.00 1 13441 815 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 2 13648 463 0 0
t$perf-norm:sp_cancel_client_order 1677 1677 0 0.00 0 5089 23 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 7324 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 2659 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 8609 35 0 0
t$perf-norm:sp_cancel_supplier_invoice 357 357 0 0.00 0 637 28 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 98635 5355 0 0
t$perf-norm:sp_cancel_write_off 967 967 0 0.00 0 1411 17 0 0
t$perf-norm:sp_client_order 19064 19064 0 0.00 0 8585 32 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 386 11 0 0
t$perf-norm:sp_pay_from_customer 6831 6831 0 0.00 0 6355 20 0 0
t$perf-norm:sp_pay_to_supplier 5507 5507 0 0.00 0 5247 20 0 0
t$perf-norm:sp_reserve_write_off 9395 9395 0 0.00 0 16473 25 0 0
t$perf-norm:sp_supplier_invoice 5223 5223 0 0.00 0 6092 30 0 0
t$perf-norm:sp_supplier_order 5230 5230 0 0.00 0 2360 26 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 734 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 357 11 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 748 13 0 0
t$perf-norm:srv_make_money_saldo 879 879 0 0.00 0 3053 14 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 231 90 0 0
v_add_invoice_to_stock 9726 9444 282 2.89 0 9533 27 0 282
v_all_customers 35346 35346 0 0.00 0 91 3 0 0
v_all_suppliers 28848 28848 0 0.00 0 152 4 0 0
v_all_wares 38123 38123 0 0.00 0 150 3 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 3059 23 0 0
v_cancel_client_order 3354 3354 0 0.00 0 1805 9 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 1278 11 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 2189 11 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 6739 25 0 0
v_cancel_supplier_invoice 746 714 32 4.28 0 2196 25 0 32
v_cancel_supplier_order 848 848 0 0.00 0 1441 12 0 0
v_cancel_write_off 1934 1934 0 0.00 0 4268 27 0 0
v_max_id_clo_ord 5227 5227 0 0.00 0 373 4 0 0
v_max_id_clo_res 952 952 0 0.00 0 1906 13 0 0
v_max_id_ord_sup 5215 5215 0 0.00 0 6176 9 0 0
v_max_non_paid_invoice 1525 1525 0 0.00 0 1311 8 0 0
v_max_non_paid_realizn 3498 3498 0 0.00 0 566 7 0 0
v_min_id_clo_ord 5227 5227 0 0.00 0 1635 7 0 0
v_min_id_clo_res 952 952 0 0.00 0 2274 9 0 0
v_min_id_ord_sup 5215 5215 0 0.00 0 6716 11 0 0
v_min_non_paid_invoice 5507 5507 0 0.00 0 4416 15 0 0
v_min_non_paid_realizn 6828 6828 0 0.00 0 4770 18 0 0
v_our_firm 9436 9436 0 0.00 0 89 4 0 0
v_reserve_write_off 18790 18790 0 0.00 0 9655 11 0 0
x_lock_depdocs_on_canc_invoice 11564 11564 0 0.00 0 20173 122 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 12486 381 0 0
x_make_qstorno_1000_1200 5230 5230 0 0.00 2 40749 1161 0 0
x_make_qstorno_1200_2000 5223 5223 0 0.00 5 90265 2895 0 0
x_make_qstorno_2100_3300 446050 446050 0 0.00 0 43072 218 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-04-2022 04:38:41 to 06-04-2022 04:38:45.

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
02:07:19 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:20 29724 8192000000 9723533840 10024472576 805697232 8.954 100 89 179 1 835810704 8484416 21629056
02:09:58 18905 8192000000 9761934992 10072047616 835089392 9.250 100 86 172 0 869029120 13220864 20718864
02:11:22 22568 8192000000 9810127232 10136190976 876510512 9.665 100 82 165 0 919284784 23874928 18899344
02:12:48 9963 8192000000 9863371152 10203463680 916002864 10.057 100 80 158 0 980570400 46835600 17731936
02:14:05 5827 8192000000 9923938304 10274430976 971437424 10.601 100 81 161 0 1039556992 51677984 16441584
02:15:14 2068 8192000000 9988367648 10349289472 1000524976 10.884 100 91 180 0 1112338112 93868736 17944400
02:16:18 5804 8192000000 9969515888 10334068736 1022898576 11.100 100 78 155 0 1085044000 46809536 15335888
02:17:28 1143 8192000000 10006862416 10376593408 1052877840 11.388 100 91 176 0 1131735488 60668096 18189552
02:18:31 723 8192000000 10053825264 10429386752 1065798432 11.512 100 94 189 0 1169702032 85599136 18304464
02:19:33 11578 8192000000 10035034560 10414243840 1072072784 11.572 100 77 148 1 1157808304 71479488 14256032
02:20:46 899 8192000000 10040649664 10421747712 1080050688 11.648 100 88 171 1 1150900080 53422336 17427056
02:21:47 3014 8192000000 10024176400 10421313536 1082213584 11.669 100 71 136 1 1145412784 50050848 13148352
02:22:51 1404 8192000000 10044249920 10435538944 1086980496 11.714 100 78 149 2 1172987216 71242432 14764288
02:23:54 1138 8192000000 10046494176 10430021632 1093936720 11.780 100 97 184 0 1171077920 56570816 20570384
02:24:56 1341 8192000000 10095461616 10486767616 1099345696 11.831 100 99 185 0 1213053600 95080976 18626928
02:25:58 1354 8192000000 10054684960 10442424320 1102166832 11.858 100 95 181 1 1173139872 53278480 17694560
02:27:01 1037 8192000000 10057944544 10448347136 1103656544 11.872 100 92 179 0 1182706544 60827600 18222400
02:28:03 7349 8192000000 10047501152 10444804096 1100278944 11.840 100 61 113 1 1169109616 57740656 11090016
02:29:12 4517 8192000000 10041525488 10433228800 1089105728 11.734 100 66 130 4 1168816272 66369664 13340880
02:30:17 1281 8192000000 10044572912 10426126336 1069933632 11.551 99 90 175 1 1164898496 76476560 18488304
02:31:20 1660 8192000000 10032251904 10418929664 1079203360 11.640 100 78 148 3 1152920288 58842096 14874832
02:32:22 1478 8192000000 9975564368 10354454528 1036953648 11.235 97 72 133 2 1103233664 51309104 14970912
02:33:25 1255 8192000000 10006487216 10380697600 1041316224 11.277 100 96 186 2 1128986672 68474304 19196144
02:34:28 4864 8192000000 9971446432 10352664576 1030313264 11.171 100 63 124 1 1090598800 46904144 13381392
02:35:36 903 8192000000 9971190480 10344251392 1030535376 11.174 100 94 174 0 1091040896 43051216 17454304
02:36:38 984 8192000000 9978255392 10355564544 1048863728 11.350 100 87 170 1 1099791056 32771568 18155760
02:37:41 27247 8192000000 10001560432 10395480064 1058621392 11.443 100 47 79 1 1114060608 47680400 7758816
02:39:17 13829 8192000000 10022142912 10410733568 1077021648 11.619 100 69 129 2 1146761568 56936048 12803872
02:40:35 2527 8192000000 10061511360 10440667136 1077361584 11.622 99 82 160 3 1180391280 86917456 16112240
02:41:47 9587 8192000000 10101731120 10494570496 1105324096 11.888 100 65 125 2 1222431600 103568128 13539376
02:43:06 4468 8192000000 10155209920 10549702656 1111128096 11.943 100 72 143 0 1265519536 138176640 16214800
02:44:15 2164 8192000000 10172507840 10564513792 1131093328 12.132 100 77 148 0 1291696576 144425616 16177632
02:45:22 2033 8192000000 10218549280 10615611392 1136774448 12.185 100 88 169 0 1333589200 178217808 18596944
02:46:27 1508 8192000000 10272863232 10666258432 1156408624 12.370 100 93 179 1 1382701296 205813840 20478832
02:47:34 2280 8192000000 10298848112 10692730880 1172026352 12.516 100 89 166 0 1408661616 219133232 17502032
02:48:42 1404 8192000000 10337278192 10738331648 1181606096 12.605 100 91 177 1 1449396656 248399120 19391440
02:49:48 948 8192000000 10377702896 10790273024 1191943264 12.701 100 92 183 0 1487224416 274498240 20782912
02:50:53 978 8192000000 10425808032 10826190848 1188990720 12.674 100 97 186 0 1533993120 322831952 22170448
02:51:57 1822 8192000000 10440467344 10859118592 1198747696 12.765 100 99 186 0 1543545456 322497504 22300256
02:53:01 817 8192000000 10461261904 10893496320 1202979840 12.804 100 85 168 0 1568199680 345362928 19856912
02:54:06 991 8192000000 10479143280 10885734400 1200360992 12.780 100 99 196 2 1587245376 364050608 22833776
02:55:10 1234 8192000000 10530282640 10937942016 1202901232 12.803 100 97 191 3 1635999296 411134720 21963344
02:56:14 1360 8192000000 10575836352 10990825472 1193390624 12.715 100 96 189 1 1674942912 458907376 22644912
02:57:19 1117 8192000000 10575652000 11045281792 1206296576 12.835 100 97 194 0 1688041296 459161808 22582912
02:58:23 2779 8192000000 10588904752 11055280128 1212076800 12.888 100 75 148 1 1698356400 467320880 18958720
02:59:38 11251 8192000000 10603179104 11041050624 1202585296 12.800 100 60 118 0 1711493584 492288544 16619744
03:01:11 5830 8192000000 10578368560 11040878592 1196400624 12.743 100 51 102 0 1691331792 481201264 13729904
03:02:27 16269 8192000000 10498805840 11015147520 1183933040 12.627 100 40 80 0 1627642624 432033776 11675808
03:03:51 27396 8192000000 10419002368 10933260288 1171993952 12.515 100 23 46 0 1537501136 359093072 6414112
03:05:19 51636 8192000000 10198153760 10713710592 1137853184 12.195 98 21 42 0 1353031488 209892064 5286240
03:07:11 36071 8192000000 10076276928 10528665600 1121605008 12.042 98 15 30 1 1167975712 42293536 4077168
03:08:52 65462 8192000000 10091434032 10538635264 1105575520 11.891 97 27 54 1 1172037664 61406080 5056064
03:11:03 48352 8192000000 10100873248 10526683136 1131823456 12.139 99 46 89 1 1195701152 53955104 9922592
03:12:54 20112 8192000000 10110970800 10539970560 1137580128 12.193 100 68 124 3 1194455920 42606080 14269712
03:14:21 6307 8192000000 10070223504 10508410880 1125759216 12.081 99 52 80 3 1158378512 24421632 8197664
03:15:31 36410 8192000000 10079060816 10526838784 1123700512 12.062 99 35 70 1 1185230624 54215088 7315024
03:17:31 41657 8192000000 10099880688 10539937792 1099608144 11.834 97 25 50 1 1190598320 84915440 6074736
03:19:14 1885 8192000000 10158972880 10581479424 1121797952 12.044 99 60 117 1 1251558832 116743904 13016976
03:20:17 92154 8192000000 10167474656 10627465216 1128064512 12.103 99 22 44 1 1281627552 147713568 5849472
03:22:49 49384 8192000000 10187665632 10669740032 1141591792 12.231 99 21 42 1 1293738976 147085968 5061216
03:24:39 69200 8192000000 10103001744 10557771776 1111734528 11.949 97 17 34 0 1197577040 81876720 3965792
03:26:49 87351 8192000000 10088271488 10547056640 1086677552 11.711 95 16 32 0 1160061920 70163312 3221056
03:29:17 34599 8192000000 10135509728 10566930432 1115907872 11.988 97 26 52 0 1209565488 88241264 5416352
03:30:55 52998 8192000000 10085799296 10553946112 1130937488 12.130 99 21 42 0 1209975392 74550016 4487888
03:33:00 20690 8192000000 10131958496 10566946816 1151391568 12.323 100 58 116 0 1220718144 57647664 11678912
03:34:25 31105 8192000000 10135434928 10572820480 1138660464 12.203 99 31 62 0 1208509488 63381024 6468000
03:35:57 47894 8192000000 10174396992 10651672576 1122654800 12.052 98 28 55 0 1256456080 128027024 5774256
03:39:11 103160 8192000000 10130544912 10567225344 1135339232 12.172 99 31 60 5 1200284624 58629088 6316304
03:42:01 46419 8192000000 10124857328 10552369152 1135097296 12.169 99 58 105 2 1207156528 61992976 10066256
03:43:48 34165 8192000000 10088829440 10544574464 1130562000 12.127 99 25 50 1 1198149056 62264288 5322768
03:45:22 44256 8192000000 10059237568 10521612288 1101490320 11.852 96 22 44 0 1161036992 54766096 4780576
03:47:07 43076 8192000000 10073548096 10516701184 1128299680 12.105 99 11 23 0 1162184592 31738640 2146272
03:48:55 70762 8192000000 10075179904 10543259648 1129941056 12.121 99 22 44 0 1178379104 43155824 5282224
03:51:21 48189 8192000000 10092609888 10541473792 1137063984 12.188 99 26 52 2 1174518576 32186384 5268208
03:53:09 83978 8192000000 10104784752 10634358784 1142350416 12.238 99 8 16 0 1194477056 50820480 1306160
03:55:56 41820 8192000000 10135303728 10575589376 1076898560 11.618 93 19 38 1 1163845840 82976928 3970352
03:57:38 47308 8192000000 10152921872 10599325696 1102035920 11.857 96 25 46 2 1212667184 105509088 5122176
03:59:37 37952 8192000000 10164631968 10647203840 1142142064 12.236 100 30 60 0 1287323136 138656768 6524304
04:01:21 18869 8192000000 10122249504 10558910464 1135434064 12.173 100 31 62 2 1216970992 75225296 6311632
04:02:42 8035 8192000000 10098632832 10519834624 1117865248 12.007 99 53 105 0 1168203968 40077904 10260816
04:04:03 10790 8192000000 10094087296 10518609920 1104683792 11.882 98 49 96 1 1173423104 59334352 9404960
04:05:18 12076 8192000000 10150428064 10568658944 1121566848 12.042 100 62 124 1 1230236864 96415488 12254528
04:06:30 95301 8192000000 10076611792 10531840000 1119955440 12.027 99 26 52 1 1163490192 37724768 5809984
04:09:52 55671 8192000000 10085513312 10532249600 1099336832 11.831 97 22 44 1 1156372496 52371520 4664144
04:12:00 108921 8192000000 10072412736 10613886976 1134087264 12.160 100 9 18 1 1218678000 82416000 2174736
04:15:00 73898 8192000000 10045390672 10491408384 1074207296 11.592 95 16 31 0 1112627936 35109168 3311472
04:17:14 54441 8192000000 10138170944 10587971584 1114639408 11.976 98 29 58 1 1225902416 104920976 6342032
04:19:24 19253 8192000000 10193390016 10628837376 1137488256 12.192 99 44 88 2 1286023584 138497568 10037760
04:20:47 45405 8192000000 10198233040 10673594368 1111177920 11.944 98 37 73 1 1296063536 175241440 9644176
04:22:41 57133 8192000000 10122173168 10679554048 1133787392 12.157 100 26 51 3 1312322288 172852608 5682288
04:24:40 63745 8192000000 10100297280 10520555520 1086517488 11.710 95 44 85 0 1138862832 43448432 8896912
04:26:46 81355 8192000000 10076772688 10548088832 1134069472 12.160 100 31 62 0 1201616800 61574640 5972688
04:29:08 68194 8192000000 10099163728 10602786816 1145175424 12.264 100 12 24 1 1204641584 56913328 2552832
04:31:22 62921 8192000000 10090041776 10524995584 1141923120 12.234 99 37 72 1 1194474928 44448032 8103776
04:33:28 88860 8192000000 10117102464 10574909440 1095718608 11.797 95 11 21 2 1162984864 64364656 2901600
04:35:57 92171 8192000000 9181743360 9520537600 301235360 3.546 27 5 10 0 363667104 61391296 1040448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-04-2022 04:38:47 to 06-04-2022 04:39:03.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-04-2022 04:39:04 to 06-04-2022 04:39:06.

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          217202
MON$OLDEST_ACTIVE               217559
MON$OLDEST_SNAPSHOT             217559
MON$NEXT_TRANSACTION            217559
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                       2036768
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2746 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2746 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2746 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2746 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 ---
:49: 
:50: mscs-test-161.budzdorov.ru    Wed Apr  6 04:37:47 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Wed Apr  6 04:37:52 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Wed Apr  6 04:37:52 2022
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
--- end of diff output ---

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

06.04.22 04:39:07. 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
2022-04-06 04:39:09 No severe PSQL-related problems occured
06.04.22 04:39:11. End of report.