Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.04.2022 07:47
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 16055408 kB
MemAvailable 31577744 kB
Buffers 0 kB
Cached 15669276 kB
SwapCached 2280 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-08 07:46:20
DTS_END                         2022-04-08 07:46:20.0860
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/55098
TRN_ID                          227551
ATT_ID                          615
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9240.00 29306 1108800
customer order: creation 173.14 471 20777
customer order: refuse 15.62 457 1874
order to supplier: creation 47.02 1450 5642
order to supplier: removal 3.56 177346 427
invoice (draft): creation 46.11 3391 5533
invoice (draft): removal 51.06 1083 6127
invoice accept: apply 42.77 29838 5132
invoice accept: cancel 46.27 1327 5552
customer reserve: creation 3978.76 282 477451
customer reserve: removal 4523.20 108 542784
realization accept: apply 85.34 291 10241
realization accept: cancel 94.16 182 11299
payment from customer: creation 60.93 184 7312
payment from customer: removal 5.72 157 686
payment to supplier: creation 49.63 217 5956
payment to supplier: removal 3.53 130 423
service: total inventory turnovers 4.08 4803 490
service: total monetary turnovers 8.12 395 974
service: aggregate perf. data 0.96 18594 115
service: refresh index statistics 0.04 345415 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-04-2022 07:47:52 to 08-04-2022 07:47:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7228
TEST_TIME 2 6838
TEST_TIME 3 5871
TEST_TIME 4 5630
TEST_TIME 5 6063
TEST_TIME 6 5900
TEST_TIME 7 6275
TEST_TIME 8 6716
TEST_TIME 9 6767
TEST_TIME 10 6945
TEST_TIME 11 7035
TEST_TIME 12 7048
TEST_TIME 13 6956
TEST_TIME 14 7113
TEST_TIME 15 7114
TEST_TIME 16 7174
TEST_TIME 17 7251
TEST_TIME 18 7407
TEST_TIME 19 7830
TEST_TIME 20 7853
TEST_TIME 21 7789
TEST_TIME 22 8094
TEST_TIME 23 8139
TEST_TIME 24 8178
TEST_TIME 25 8284
TEST_TIME 26 8321
TEST_TIME 27 8716
TEST_TIME 28 8863
TEST_TIME 29 8801
TEST_TIME 30 8731
TEST_TIME 31 8934
TEST_TIME 32 9066
TEST_TIME 33 9109
TEST_TIME 34 9215
TEST_TIME 35 9279
TEST_TIME 36 9405
TEST_TIME 37 9735
TEST_TIME 38 9976
TEST_TIME 39 10647
TEST_TIME 40 10649
TEST_TIME 41 10855
TEST_TIME 42 10737
TEST_TIME 43 10593
TEST_TIME 44 10568
TEST_TIME 45 10577
TEST_TIME 46 10410
TEST_TIME 47 10359
TEST_TIME 48 10268
TEST_TIME 49 10150
TEST_TIME 50 10197
TEST_TIME 51 10160
TEST_TIME 52 10072
TEST_TIME 53 10114
TEST_TIME 54 10111
TEST_TIME 55 10071
TEST_TIME 56 10012
TEST_TIME 57 9994
TEST_TIME 58 10056
TEST_TIME 59 9985
TEST_TIME 60 9936
TEST_TIME 61 9924
TEST_TIME 62 9966
TEST_TIME 63 9958
TEST_TIME 64 9912
TEST_TIME 65 9873
TEST_TIME 66 9846
TEST_TIME 67 9980
TEST_TIME 68 9917
TEST_TIME 69 9946
TEST_TIME 70 9952
TEST_TIME 71 9946
TEST_TIME 72 9922
TEST_TIME 73 9854
TEST_TIME 74 9741
TEST_TIME 75 9731
TEST_TIME 76 9699
TEST_TIME 77 9650
TEST_TIME 78 9603
TEST_TIME 79 9586
TEST_TIME 80 9518
TEST_TIME 81 9473
TEST_TIME 82 9487
TEST_TIME 83 9470
TEST_TIME 84 9480
TEST_TIME 85 9465
TEST_TIME 86 9477
TEST_TIME 87 9434
TEST_TIME 88 9405
TEST_TIME 89 9526
TEST_TIME 90 9556
TEST_TIME 91 9542
TEST_TIME 92 9513
TEST_TIME 93 9509
TEST_TIME 94 9575
TEST_TIME 95 9585
TEST_TIME 96 9516
TEST_TIME 97 9501
TEST_TIME 98 9465
TEST_TIME 99 9438
TEST_TIME 100 9423
TEST_TIME 101 9376
TEST_TIME 102 9324
TEST_TIME 103 9314
TEST_TIME 104 9340
TEST_TIME 105 9347
TEST_TIME 106 9317
TEST_TIME 107 9304
TEST_TIME 108 9315
TEST_TIME 109 9315
TEST_TIME 110 9314
TEST_TIME 111 9325
TEST_TIME 112 9330
TEST_TIME 113 9360
TEST_TIME 114 9381
TEST_TIME 115 9408
TEST_TIME 116 9426
TEST_TIME 117 9456
TEST_TIME 118 9391
TEST_TIME 119 9327
TEST_TIME 120 9260

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-04-2022 07:47:55 to 08-04-2022 07:47:59.

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 1547578 1547578 0 0.00 0 28244 16 0 0
doc_list_biud 1547572 1547572 0 0.00 0 35180 44 0 0
sp_add_invoice_to_stock 5226 5132 94 1.79 51 209608 29838 0 94
sp_cancel_adding_invoice 5552 5552 0 0.00 8 108314 1328 0 0
sp_cancel_client_order 1874 1874 0 0.00 5 14461 458 0 0
sp_cancel_customer_reserve 542784 542784 0 0.00 1 35183 109 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 4 6232 157 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 4 1668 130 0 0
sp_cancel_supplier_invoice 6132 6127 5 0.08 4 27906 1084 0 5
sp_cancel_supplier_order 427 427 0 0.00 19 1367773 177347 0 0
sp_cancel_write_off 11299 11299 0 0.00 2 13268 183 0 0
sp_client_order 20777 20777 0 0.00 6 29220 472 0 0
sp_customer_reserve 477451 477451 0 0.00 0 150338 283 0 0
sp_fill_shopping_cart 31951 31951 0 0.00 1 37800 87 0 0
sp_fill_shopping_cart_clo_res 477417 477417 0 0.00 0 20835 19 0 0
sp_get_clo_for_invoice 5086 5086 0 0.00 0 32536 266 0 0
sp_kill_cost_storno 17960 17960 0 0.00 0 6146 72 0 0
sp_kill_qstorno_ret_qs2qd 1103421 1103421 0 0.00 0 35168 46 0 0
sp_kill_qty_storno 583389 583389 0 0.00 0 35179 108 0 0
sp_lock_selected_doc 21316 21316 0 0.00 0 8337 9 0 0
sp_make_cost_storno 28594 28594 0 0.00 0 9673 82 0 0
sp_multiply_rows_for_pdistr 28594 28594 0 0.00 0 6320 39 0 0
sp_multiply_rows_for_qdistr 20777 20777 0 0.00 0 12132 177 0 0
sp_pay_from_customer 7312 7312 0 0.00 6 11207 185 0 0
sp_pay_to_supplier 5956 5956 0 0.00 7 11466 217 0 0
sp_payment_common 13268 13268 0 0.00 2 5679 47 0 0
sp_qd_handle_on_cancel_clo 1874 1874 0 0.00 1 14448 369 0 0
sp_qd_handle_on_invoice_upd_sts 10637 10637 0 0.00 0 15181 614 0 0
sp_qd_handle_on_reserve_upd_sts 10241 10241 0 0.00 0 15630 69 0 0
sp_reserve_write_off 10241 10241 0 0.00 9 15745 292 0 0
sp_supplier_invoice 5533 5533 0 0.00 8 104452 3392 0 0
sp_supplier_order 5642 5642 0 0.00 7 38527 1450 0 0
srv_aggregate_perf_data 115 115 0 0.00 2677 104091 18594 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 786 137232 32176 0 0
srv_find_qd_qs_mism 1058305 1058305 0 0.00 0 28243 13 0 0
srv_make_invnt_saldo 490 490 0 0.00 104 71972 4803 0 0
srv_make_money_saldo 974 974 0 0.00 2 9777 396 0 0
srv_recalc_idx_stat 5 5 0 0.00 276159 470552 345416 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5132 5132 0 0.00 1 12473 1099 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 1 6231 454 0 0
t$perf-norm:sp_cancel_client_order 1874 1874 0 0.00 0 1855 31 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 783 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 1456 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 526 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 1012 40 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 120166 8432 0 0
t$perf-norm:sp_cancel_write_off 1046 1046 0 0.00 0 2309 25 0 0
t$perf-norm:sp_client_order 20777 20777 0 0.00 0 18943 44 0 0
t$perf-norm:sp_customer_reserve 1003 1003 0 0.00 0 1163 17 0 0
t$perf-norm:sp_pay_from_customer 7312 7312 0 0.00 0 5419 22 0 0
t$perf-norm:sp_pay_to_supplier 5956 5956 0 0.00 0 21197 30 0 0
t$perf-norm:sp_reserve_write_off 10241 10241 0 0.00 0 5240 25 0 0
t$perf-norm:sp_supplier_invoice 5533 5533 0 0.00 0 11606 41 0 0
t$perf-norm:sp_supplier_order 5642 5642 0 0.00 0 9466 39 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 398 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 1271 25 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 866 20 0 0
t$perf-norm:srv_make_money_saldo 974 974 0 0.00 0 365 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 27 720 207 0 0
v_add_invoice_to_stock 10358 10170 188 1.81 0 3147 27 0 188
v_all_customers 38316 38316 0 0.00 0 124 4 0 0
v_all_suppliers 31122 31122 0 0.00 0 194 5 0 0
v_all_wares 41554 41554 0 0.00 0 59 4 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 5939 24 0 0
v_cancel_client_order 3748 3748 0 0.00 0 3797 11 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 3664 19 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 1094 11 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 447 10 0 0
v_cancel_supplier_invoice 846 836 10 1.18 0 3671 35 0 10
v_cancel_supplier_order 854 854 0 0.00 0 1076 13 0 0
v_cancel_write_off 2092 2092 0 0.00 0 3007 27 0 0
v_max_id_clo_ord 5642 5642 0 0.00 0 551 6 0 0
v_max_id_clo_res 1003 1003 0 0.00 0 3381 19 0 0
v_max_id_ord_sup 5532 5532 0 0.00 0 3326 7 0 0
v_max_non_paid_invoice 1569 1569 0 0.00 0 162 9 0 0
v_max_non_paid_realizn 3650 3650 0 0.00 0 2365 9 0 0
v_min_id_clo_ord 5642 5642 0 0.00 0 862 8 0 0
v_min_id_clo_res 1003 1003 0 0.00 0 2041 13 0 0
v_min_id_ord_sup 5532 5532 0 0.00 0 5635 10 0 0
v_min_non_paid_invoice 5956 5956 0 0.00 0 4018 13 0 0
v_min_non_paid_realizn 7312 7312 0 0.00 0 5667 16 0 0
v_our_firm 10562 10562 0 0.00 0 134 5 0 0
v_reserve_write_off 20482 20482 0 0.00 0 3193 11 0 0
x_lock_depdocs_on_canc_invoice 11261 11261 0 0.00 0 28153 141 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 12443 410 0 0
x_make_qstorno_1000_1200 5642 5642 0 0.00 3 35591 1245 0 0
x_make_qstorno_1200_2000 5533 5533 0 0.00 3 101362 3183 0 0
x_make_qstorno_2100_3300 477450 477450 0 0.00 0 150330 249 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-04-2022 07:48:00 to 08-04-2022 07:48:04.

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
05:16:23 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:17:24 33045 8192000000 9726481808 10026971136 801349712 8.910 100 87 174 0 830857424 7969920 21537792
05:19:02 22408 8192000000 9775191168 10085793792 843466560 9.335 100 88 176 0 877495200 13235920 20792720
05:20:30 20378 8192000000 9819439296 10143166464 877282176 9.673 100 88 176 0 924594768 27191120 20121472
05:21:51 6940 8192000000 9871269968 10204897280 917652464 10.073 100 80 158 0 989451792 54592288 17207040
05:23:00 3982 8192000000 9933596576 10281095168 963474560 10.523 100 90 176 1 1052880960 71246464 18159936
05:24:07 2329 8192000000 9953310272 10307293184 994711872 10.827 100 87 170 0 1076114800 64287184 17115744
05:25:12 817 8192000000 9999615568 10362228736 1029614384 11.165 100 97 184 0 1118161392 69107920 19439088
05:26:15 1108 8192000000 10019827872 10393157632 1043306560 11.296 100 93 178 3 1133574224 73078464 17189200
05:27:18 950 8192000000 10022197536 10394230784 1056220752 11.420 100 96 187 0 1145463728 70451920 18791056
05:28:22 8476 8192000000 10019298624 10394464256 1062809840 11.483 100 80 150 1 1143187184 65943440 14433904
05:29:31 1180 8192000000 10050847888 10432647168 1069209552 11.545 100 96 188 0 1175516912 87861200 18446160
05:30:34 1219 8192000000 10051827424 10435641344 1076344368 11.613 100 99 192 0 1172179104 77141072 18693664
05:31:36 1144 8192000000 10053829600 10437697536 1084654992 11.692 100 93 179 0 1173555888 71072656 17828240
05:32:38 1368 8192000000 10048072640 10431946752 1087427136 11.718 100 97 182 0 1168376992 62443920 18505936
05:33:40 1191 8192000000 10054507520 10441531392 1089949424 11.742 100 82 157 1 1173594048 67223104 16421520
05:34:42 1174 8192000000 10052238080 10439208960 1093843312 11.779 100 95 188 1 1171426944 58688720 18894912
05:35:44 1157 8192000000 10050572640 10441990144 1093534240 11.776 100 93 173 2 1173360224 60971888 18854096
05:36:46 2860 8192000000 10055623824 10446843904 1095904448 11.799 100 76 141 1 1172941728 63398496 13638784
05:37:50 2813 8192000000 10007471024 10395525120 1076545952 11.615 99 69 131 1 1125571168 36136352 12888864
05:38:54 1187 8192000000 10050058512 10434506752 1073684560 11.587 99 94 186 2 1184301920 91886304 18731056
05:39:57 1161 8192000000 10042534208 10419888128 1069079680 11.543 100 96 182 3 1163210416 75158112 18972624
05:40:59 1223 8192000000 10030599808 10412683264 1065319008 11.507 99 95 186 1 1154758240 70528528 18910704
05:42:01 1756 8192000000 9990494800 10376114176 1049859440 11.359 100 68 131 1 1116016944 52557056 13600448
05:43:04 1294 8192000000 9984713456 10358595584 1024035168 11.111 99 83 158 1 1106090592 64846992 17208432
05:44:07 993 8192000000 9986422608 10366857216 1046092720 11.323 100 84 158 2 1112740592 49919440 16728432
05:45:09 1271 8192000000 9991796688 10372694016 1053373792 11.393 100 68 132 1 1112247312 46324720 12548800
05:46:10 1870 8192000000 9966668032 10346057728 1043526976 11.299 100 79 151 0 1089996640 30623088 15846576
05:47:23 4552 8192000000 10030452416 10408898560 1067661552 11.530 100 85 156 1 1146750128 63271504 15817072
05:48:32 9143 8192000000 10053085200 10441641984 1080375520 11.651 100 87 170 2 1175899488 76705904 18818064
05:49:47 10693 8192000000 10069931232 10469896192 1092204432 11.764 100 61 118 1 1200214128 95204144 12805552
05:51:03 1637 8192000000 10119048176 10508480512 1095567936 11.796 100 88 170 0 1230176688 116618576 17990176
05:52:08 11245 8192000000 10111976080 10512961536 1108540800 11.919 100 54 106 1 1237221952 117412240 11268912
05:53:25 1635 8192000000 10158048368 10554773504 1119105408 12.019 100 85 166 1 1275499648 137213392 19180848
05:54:31 1184 8192000000 10163872048 10560950272 1130684240 12.128 100 94 176 0 1285951392 135381056 19886096
05:55:36 868 8192000000 10216602528 10610475008 1144233440 12.255 100 93 182 2 1327530592 163059456 20237696
05:56:40 1148 8192000000 10231934832 10638983168 1149631968 12.306 100 93 179 1 1346722976 176436416 20654592
05:57:44 1890 8192000000 10245417968 10646421504 1161296960 12.415 100 87 169 0 1346429600 166854080 18278560
05:58:51 1023 8192000000 10235606128 10656432128 1170619216 12.503 100 89 171 0 1345103920 156000464 18484240
05:59:57 1343 8192000000 10258501504 10679992320 1183241968 12.620 100 94 188 0 1372516624 167861920 21412736
06:01:03 1382 8192000000 10294757264 10703568896 1180741792 12.597 100 92 175 0 1410713072 209488208 20483072
06:02:09 1399 8192000000 10317337520 10731286528 1166761584 12.467 100 87 168 1 1433085984 246122208 20202192
06:03:17 1038 8192000000 10326096240 10752241664 1180273200 12.593 100 95 185 0 1428126576 226042464 21810912
06:04:21 774 8192000000 10341291360 10785153024 1190568752 12.689 100 98 188 0 1445846016 234353408 20923856
06:05:24 1072 8192000000 10329454432 10767060992 1186558320 12.651 100 96 184 3 1438465616 229466064 22441232
06:06:28 2586 8192000000 10382223680 10781843456 1184107776 12.628 100 99 197 0 1482286160 277030176 21148208
06:07:36 892 8192000000 10355095824 10776080384 1180644544 12.596 100 96 187 4 1463322304 259977264 22700496
06:08:40 1872 8192000000 10377857552 10782568448 1179281232 12.583 100 92 182 1 1491957632 292088704 20587696
06:09:44 1182 8192000000 10395169920 10822377472 1191177472 12.694 100 89 176 0 1521381936 308767600 21436864
06:10:48 1031 8192000000 10400668288 10840268800 1181856720 12.608 99 95 191 1 1507798272 303540704 22400848
06:11:53 883 8192000000 10455726832 10875138048 1189937408 12.683 100 98 194 0 1560386432 346913312 23535712
06:12:58 1037 8192000000 10428317856 10896482304 1195555216 12.735 100 98 195 0 1530942080 311685552 23701312
06:14:03 1223 8192000000 10435583968 10849308672 1190005376 12.683 100 98 195 0 1536733840 323649104 23079360
06:15:05 6077 8192000000 10467003664 10889211904 1193278784 12.714 100 65 127 0 1570261536 362112800 14869952
06:16:15 2306 8192000000 10496391792 10928046080 1182089808 12.610 100 71 133 0 1599893056 400267232 17536016
06:17:20 14115 8192000000 10522595440 10976858112 1184073360 12.628 100 54 104 0 1627268560 429725136 13470064
06:18:38 12465 8192000000 10476772080 10927063040 1165068480 12.451 100 45 88 1 1590853184 414403872 11380832
06:19:59 7844 8192000000 10468788464 10938028032 1172614688 12.521 100 58 107 2 1583172272 396550336 14007248
06:21:10 21748 8192000000 10487769552 10923962368 1157303232 12.378 98 43 86 1 1553860304 385802448 10754624
06:22:43 22392 8192000000 10431633856 10908516352 1152836672 12.336 100 37 73 0 1574569456 411561232 10171552
06:24:14 33943 8192000000 10322748848 10814353408 1136980912 12.187 100 27 55 1 1464387632 320414144 6992576
06:25:52 37121 8192000000 10102673856 10538422272 1125800832 12.082 100 54 90 2 1206413584 71809040 8803712
06:27:41 37772 8192000000 10037154048 10497875968 1078451344 11.633 96 27 50 1 1112159856 28345568 5362944
06:29:24 137232 8192000000 10107957744 10606092288 1131133632 12.132 100 18 36 2 1213352544 78254720 3964192
06:32:56 67749 8192000000 10123288208 10554171392 1100485152 11.842 97 36 72 2 1190737680 83318128 6934400
06:35:15 98596 8192000000 10097250352 10519420928 1083658800 11.682 95 74 137 3 1138923584 39791680 15473104
06:37:56 11541 8192000000 10128360448 10556043264 1122476624 12.050 99 58 113 1 1219502224 84524256 12501344
06:39:11 20125 8192000000 10159647792 10617352192 1133994400 12.159 100 58 116 0 1255574896 108769600 12810896
06:40:42 52006 8192000000 10165242544 10662064128 1147984384 12.291 100 18 36 1 1275579264 123415056 4179824
06:43:31 52324 8192000000 10163343088 10608422912 1108751488 11.921 97 21 40 3 1215219088 101779552 4688048
06:46:08 85138 8192000000 10128028640 10595123200 1121282272 12.039 98 19 38 0 1200470608 74632288 4556048
06:48:39 38667 8192000000 10058536624 10517323776 1095610800 11.796 97 35 69 1 1155041040 51903120 7527120
06:50:19 23155 8192000000 10104466528 10535874560 1133894592 12.158 100 35 69 1 1196294832 55436352 6963888
06:51:45 93487 8192000000 10129233088 10617413632 1123881728 12.064 99 20 40 1 1227017424 98820816 4314880
06:54:35 45436 8192000000 10081984832 10538713088 1130424704 12.125 99 28 55 0 1196275408 59624640 6226064
06:56:24 14601 8192000000 10116936208 10563096576 1142624208 12.240 100 41 80 1 1213899584 63586784 7688592
06:57:42 81050 8192000000 10117883072 10560733184 1128131008 12.104 99 32 64 0 1170290016 35960384 6198624
07:00:16 85524 8192000000 10106908768 10596143104 1134613312 12.165 99 43 85 1 1216866688 73170432 9082944
07:02:49 38865 8192000000 10063987904 10517721088 1127691664 12.100 100 14 28 0 1192788144 62005280 3091200
07:04:30 66697 8192000000 10044456272 10534948864 1101797424 11.855 98 10 20 2 1151004720 47127488 2079808
07:06:37 99139 8192000000 10092270080 10534617088 1091047664 11.753 96 18 31 1 1134989152 41122816 2818672
07:09:17 33339 8192000000 10065582784 10497286144 1106448416 11.899 98 21 38 1 1138160880 27890400 3822064
07:10:56 62814 8192000000 10105727936 10563502080 1138993344 12.206 100 30 60 0 1188109104 42351440 6764320
07:13:04 28316 8192000000 10127858464 10562621440 1121412272 12.040 98 41 82 1 1211707008 82048112 8246624
07:14:32 19779 8192000000 10169167872 10601431040 1134495200 12.164 99 54 106 2 1254090128 108010352 11584576
07:15:53 58276 8192000000 10078911968 10525798400 1113036400 11.961 98 28 56 0 1169240816 50358912 5845504
07:17:57 15405 8192000000 10110315600 10544177152 1121297152 12.039 99 59 117 0 1193272144 59964544 12010448
07:19:18 40874 8192000000 10142750544 10581725184 1124919952 12.073 100 36 72 0 1228731616 96791552 7020112
07:21:09 29007 8192000000 10079932288 10517909504 1126396528 12.087 99 25 48 1 1188357968 56690288 5271152
07:22:38 117040 8192000000 10100412240 10594136064 1130847280 12.129 99 18 36 0 1213572480 79037888 3687312
07:25:54 59150 8192000000 10112910096 10577371136 1139647456 12.212 99 19 38 0 1211421312 67402096 4371760
07:27:54 61734 8192000000 10105826256 10556542976 1116118224 11.990 97 18 37 1 1189987232 69882976 3986032
07:29:56 59797 8192000000 10082361200 10566524928 1125527888 12.079 99 8 16 0 1213499456 85683968 2287600
07:31:56 28798 8192000000 10068934080 10522968064 1124275104 12.067 99 18 36 2 1173494128 44504064 4714960
07:33:28 25809 8192000000 10126177520 10567352320 1141295648 12.228 100 34 68 0 1213650432 65475952 6878832
07:35:00 77594 8192000000 10115506688 10605580288 1097729856 11.816 97 16 32 1 1173429744 72484320 3215568
07:37:25 70414 8192000000 10117813584 10573938688 1115511376 11.985 98 43 86 1 1239884880 116028880 8344624
07:39:37 12283 8192000000 10115350896 10543726592 1123616272 12.061 99 42 82 2 1192799472 61335536 7847664
07:40:53 46696 8192000000 10134692272 10573893632 1115294640 11.983 98 34 68 1 1211575584 89367056 6913888
07:42:44 124604 8192000000 10057212064 10517610496 1107492816 11.909 98 14 28 0 1151137056 41157648 2486592
07:46:10 6945 8192000000 10085255568 10510172160 1136965664 12.187 100 76 130 2 1181875632 30298512 14611456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-04-2022 07:48:06 to 08-04-2022 07:48:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-04-2022 07:48:23 to 08-04-2022 07:48:25.

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          226186
MON$OLDEST_ACTIVE               227848
MON$OLDEST_SNAPSHOT             227848
MON$NEXT_TRANSACTION            227848
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                       2102400
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             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2757 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2757 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2757 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2757 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Apr  8 07:46:21 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Apr  8 07:46:21 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Apr  8 07:46:22 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Fri Apr  8 07:46:22 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

08.04.22 07:48:26. 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-08 07:48:28 No severe PSQL-related problems occured
08.04.22 07:48:30. End of report.