Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.05.2022 10:37
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 16369152 kB
MemAvailable 31356068 kB
Buffers 0 kB
Cached 15313508 kB
SwapCached 2544 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-05-26 10:37:17
DTS_END                         2022-05-26 10:37:17.0610
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53576
TRN_ID                          222281
ATT_ID                          675
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: 9009.26 26717 1081111
customer order: creation 168.27 470 20192
customer order: refuse 14.58 379 1749
order to supplier: creation 45.23 1357 5427
order to supplier: removal 3.28 174904 393
invoice (draft): creation 46.38 2943 5565
invoice (draft): removal 49.49 970 5939
invoice accept: apply 42.15 26600 5058
invoice accept: cancel 45.40 1207 5448
customer reserve: creation 3885.84 256 466301
customer reserve: removal 4416.83 103 530020
realization accept: apply 82.93 265 9951
realization accept: cancel 80.93 173 9711
payment from customer: creation 58.68 159 7042
payment from customer: removal 5.59 115 671
payment to supplier: creation 47.33 173 5680
payment to supplier: removal 3.45 115 414
service: total inventory turnovers 4.41 3179 529
service: total monetary turnovers 7.50 367 900
service: aggregate perf. data 0.98 14458 117
service: refresh index statistics 0.03 306154 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-05-2022 10:38:24 to 26-05-2022 10:38:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8867
TEST_TIME 2 7155
TEST_TIME 3 7048
TEST_TIME 4 6193
TEST_TIME 5 6401
TEST_TIME 6 6150
TEST_TIME 7 6029
TEST_TIME 8 6086
TEST_TIME 9 6204
TEST_TIME 10 6192
TEST_TIME 11 6328
TEST_TIME 12 6501
TEST_TIME 13 6851
TEST_TIME 14 7026
TEST_TIME 15 7008
TEST_TIME 16 7120
TEST_TIME 17 7085
TEST_TIME 18 7610
TEST_TIME 19 7809
TEST_TIME 20 7752
TEST_TIME 21 7711
TEST_TIME 22 8043
TEST_TIME 23 8172
TEST_TIME 24 8390
TEST_TIME 25 8918
TEST_TIME 26 9088
TEST_TIME 27 9187
TEST_TIME 28 10195
TEST_TIME 29 10609
TEST_TIME 30 10988
TEST_TIME 31 11460
TEST_TIME 32 11232
TEST_TIME 33 11100
TEST_TIME 34 11186
TEST_TIME 35 10872
TEST_TIME 36 10707
TEST_TIME 37 10724
TEST_TIME 38 10681
TEST_TIME 39 10489
TEST_TIME 40 10448
TEST_TIME 41 10421
TEST_TIME 42 10339
TEST_TIME 43 10374
TEST_TIME 44 10207
TEST_TIME 45 10121
TEST_TIME 46 9998
TEST_TIME 47 9918
TEST_TIME 48 9978
TEST_TIME 49 9956
TEST_TIME 50 10253
TEST_TIME 51 10235
TEST_TIME 52 10148
TEST_TIME 53 10139
TEST_TIME 54 10120
TEST_TIME 55 10198
TEST_TIME 56 10175
TEST_TIME 57 10132
TEST_TIME 58 10114
TEST_TIME 59 10127
TEST_TIME 60 10077
TEST_TIME 61 9970
TEST_TIME 62 9942
TEST_TIME 63 9893
TEST_TIME 64 9820
TEST_TIME 65 9748
TEST_TIME 66 9705
TEST_TIME 67 9624
TEST_TIME 68 9514
TEST_TIME 69 9551
TEST_TIME 70 9456
TEST_TIME 71 9462
TEST_TIME 72 9413
TEST_TIME 73 9464
TEST_TIME 74 9441
TEST_TIME 75 9550
TEST_TIME 76 9562
TEST_TIME 77 9519
TEST_TIME 78 9469
TEST_TIME 79 9540
TEST_TIME 80 9562
TEST_TIME 81 9563
TEST_TIME 82 9607
TEST_TIME 83 9510
TEST_TIME 84 9466
TEST_TIME 85 9421
TEST_TIME 86 9390
TEST_TIME 87 9347
TEST_TIME 88 9259
TEST_TIME 89 9289
TEST_TIME 90 9333
TEST_TIME 91 9301
TEST_TIME 92 9274
TEST_TIME 93 9277
TEST_TIME 94 9286
TEST_TIME 95 9254
TEST_TIME 96 9281
TEST_TIME 97 9320
TEST_TIME 98 9311
TEST_TIME 99 9310
TEST_TIME 100 9282
TEST_TIME 101 9329
TEST_TIME 102 9350
TEST_TIME 103 9270
TEST_TIME 104 9235
TEST_TIME 105 9219
TEST_TIME 106 9162
TEST_TIME 107 9119
TEST_TIME 108 9093
TEST_TIME 109 9058
TEST_TIME 110 9077
TEST_TIME 111 9042
TEST_TIME 112 9017
TEST_TIME 113 9001
TEST_TIME 114 9000
TEST_TIME 115 9005
TEST_TIME 116 8957
TEST_TIME 117 8982
TEST_TIME 118 9027
TEST_TIME 119 9022
TEST_TIME 120 9041

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-05-2022 10:38:27 to 26-05-2022 10:38:31.

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 1509607 1509607 0 0.00 0 14123 14 0 0
doc_list_biud 1509600 1509600 0 0.00 0 33581 42 0 0
sp_add_invoice_to_stock 5172 5058 114 2.20 46 255193 26601 0 114
sp_cancel_adding_invoice 5448 5448 0 0.00 7 92827 1207 0 0
sp_cancel_client_order 1749 1749 0 0.00 6 14983 379 0 0
sp_cancel_customer_reserve 530020 530020 0 0.00 1 33593 104 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 4 4375 116 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 4 4478 115 0 0
sp_cancel_supplier_invoice 5945 5939 6 0.10 3 13131 970 0 6
sp_cancel_supplier_order 393 393 0 0.00 9 1424443 174905 0 0
sp_cancel_write_off 9711 9711 0 0.00 2 9294 173 0 0
sp_client_order 20192 20192 0 0.00 5 40599 470 0 0
sp_customer_reserve 466301 466301 0 0.00 0 206159 257 0 0
sp_fill_shopping_cart 31171 31171 0 0.00 2 23944 80 0 0
sp_fill_shopping_cart_clo_res 466270 466270 0 0.00 0 22067 20 0 0
sp_get_clo_for_invoice 5011 5011 0 0.00 0 28349 281 0 0
sp_kill_cost_storno 16244 16244 0 0.00 0 8524 67 0 0
sp_kill_qstorno_ret_qs2qd 1076083 1076083 0 0.00 0 33557 45 0 0
sp_kill_qty_storno 568221 568221 0 0.00 0 33578 102 0 0
sp_lock_selected_doc 20598 20598 0 0.00 0 6430 8 0 0
sp_make_cost_storno 27683 27683 0 0.00 0 10915 73 0 0
sp_multiply_rows_for_pdistr 27682 27682 0 0.00 0 5967 35 0 0
sp_multiply_rows_for_qdistr 20192 20192 0 0.00 0 12693 194 0 0
sp_pay_from_customer 7042 7042 0 0.00 5 10116 160 0 0
sp_pay_to_supplier 5680 5680 0 0.00 7 11724 174 0 0
sp_payment_common 12721 12721 0 0.00 2 8081 40 0 0
sp_qd_handle_on_cancel_clo 1749 1749 0 0.00 0 9791 296 0 0
sp_qd_handle_on_invoice_upd_sts 10458 10458 0 0.00 0 21970 468 0 0
sp_qd_handle_on_reserve_upd_sts 9951 9951 0 0.00 0 33489 71 0 0
sp_reserve_write_off 9951 9951 0 0.00 8 33644 265 0 0
sp_supplier_invoice 5565 5565 0 0.00 8 76136 2944 0 0
sp_supplier_order 5427 5427 0 0.00 9 22322 1358 0 0
srv_aggregate_perf_data 117 117 0 0.00 2583 131267 14458 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 812 156929 35735 0 0
srv_find_qd_qs_mism 1032020 1032020 0 0.00 0 14052 11 0 0
srv_make_invnt_saldo 529 529 0 0.00 69 66455 3180 0 0
srv_make_money_saldo 900 900 0 0.00 1 8742 367 0 0
srv_recalc_idx_stat 4 4 0 0.00 135214 446352 306155 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5064 5064 0 0.00 1 26728 896 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 1 10301 439 0 0
t$perf-norm:sp_cancel_client_order 1749 1749 0 0.00 0 1664 24 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 5753 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 958 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 1189 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 363 363 0 0.00 0 790 34 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 106175 6491 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 3925 22 0 0
t$perf-norm:sp_client_order 20192 20192 0 0.00 0 16164 37 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 1858 17 0 0
t$perf-norm:sp_pay_from_customer 7042 7042 0 0.00 0 7946 23 0 0
t$perf-norm:sp_pay_to_supplier 5680 5680 0 0.00 0 36283 25 0 0
t$perf-norm:sp_reserve_write_off 9951 9951 0 0.00 0 8224 24 0 0
t$perf-norm:sp_supplier_invoice 5565 5565 0 0.00 0 10001 28 0 0
t$perf-norm:sp_supplier_order 5428 5428 0 0.00 0 5600 29 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 304 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 375 10 0 0
t$perf-norm:srv_make_invnt_saldo 529 529 0 0.00 0 440 12 0 0
t$perf-norm:srv_make_money_saldo 900 900 0 0.00 0 3937 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 163 49 0 0
v_add_invoice_to_stock 10244 10016 228 2.22 0 3665 22 0 228
v_all_customers 37200 37200 0 0.00 0 67 3 0 0
v_all_suppliers 30491 30491 0 0.00 0 211 4 0 0
v_all_wares 40376 40376 0 0.00 0 132 3 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 1917 17 0 0
v_cancel_client_order 3498 3498 0 0.00 0 14794 17 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 2069 11 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 2098 11 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 4443 16 0 0
v_cancel_supplier_invoice 738 726 12 1.62 0 466 17 0 12
v_cancel_supplier_order 786 786 0 0.00 0 650 10 0 0
v_cancel_write_off 1958 1958 0 0.00 0 4680 26 0 0
v_max_id_clo_ord 5422 5422 0 0.00 0 4260 6 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 1578 11 0 0
v_max_id_ord_sup 5559 5559 0 0.00 0 5984 8 0 0
v_max_non_paid_invoice 1414 1414 0 0.00 0 58 7 0 0
v_max_non_paid_realizn 3604 3604 0 0.00 0 2894 7 0 0
v_min_id_clo_ord 5422 5422 0 0.00 0 1458 7 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 4067 18 0 0
v_min_id_ord_sup 5559 5559 0 0.00 0 4907 11 0 0
v_min_non_paid_invoice 5679 5679 0 0.00 0 3570 11 0 0
v_min_non_paid_realizn 7042 7042 0 0.00 0 8072 15 0 0
v_our_firm 10050 10050 0 0.00 0 130 4 0 0
v_reserve_write_off 19902 19902 0 0.00 0 3465 9 0 0
x_lock_depdocs_on_canc_invoice 11024 11024 0 0.00 0 14548 138 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 3008 349 0 0
x_make_qstorno_1000_1200 5427 5427 0 0.00 3 22296 1193 0 0
x_make_qstorno_1200_2000 5565 5565 0 0.00 4 70684 2717 0 0
x_make_qstorno_2100_3300 466299 466299 0 0.00 0 206135 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-05-2022 10:38:32 to 26-05-2022 10:38:36.

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:20 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:21 34620 8192000000 9736319696 10039713792 811235408 9.010 100 85 170 2 839939904 7423808 21280688
08:10:03 24260 8192000000 9771251392 10082078720 845343296 9.353 100 90 180 0 880299984 13331808 21624880
08:11:35 16858 8192000000 9807788944 10131042304 876038304 9.660 100 84 168 0 919780608 24095376 19646928
08:12:53 6131 8192000000 9874390992 10207367168 926736976 10.162 100 82 163 3 985088640 40692640 17659024
08:14:06 3931 8192000000 9935080528 10286227456 977541504 10.660 100 81 159 1 1053547056 59355296 16650256
08:15:16 2570 8192000000 9984453120 10343116800 1012733872 11.002 100 89 171 0 1094997072 65267360 16995840
08:16:20 1224 8192000000 10010341808 10378498048 1036193376 11.228 100 95 184 0 1130087616 75148016 18746224
08:17:23 836 8192000000 10039835600 10412990464 1058108736 11.438 100 94 181 0 1158514512 81601312 18804464
08:18:25 1053 8192000000 10058695920 10432389120 1066561024 11.519 100 96 188 0 1177669840 92233376 18875440
08:19:27 1495 8192000000 10050860544 10429075456 1073641072 11.587 100 94 175 4 1173041344 81265216 18135056
08:20:30 883 8192000000 10043661376 10430042112 1075488352 11.604 100 92 180 3 1164173616 69847728 18837536
08:21:33 1326 8192000000 10039648800 10423009280 1081175488 11.659 100 93 181 2 1160975952 61332656 18467808
08:22:36 1502 8192000000 10065480928 10448637952 1083794016 11.684 100 90 172 5 1189063248 88295616 16973616
08:23:38 1240 8192000000 10083893872 10477297664 1091191808 11.754 100 93 180 0 1210089456 100318560 18579088
08:24:40 1543 8192000000 10048404128 10437464064 1093727104 11.778 100 95 181 1 1172966992 60866352 18373536
08:25:43 1334 8192000000 10049786624 10445172736 1094287600 11.783 100 79 149 2 1172275344 63413536 14574208
08:26:46 1663 8192000000 10068217072 10457452544 1097354368 11.813 100 78 143 3 1192270688 81344576 13571744
08:27:49 1403 8192000000 10043901712 10432536576 1090607536 11.748 100 96 179 1 1164566832 54738496 19220800
08:28:51 1258 8192000000 10056249904 10440130560 1076511616 11.614 100 98 190 0 1181821728 85973328 19336784
08:29:53 1201 8192000000 10007913920 10390241280 1053518736 11.394 100 88 159 2 1131724624 62065808 16140080
08:30:55 15811 8192000000 10004931184 10399875072 1054892528 11.408 99 58 116 1 1139943408 73428592 11622288
08:32:11 1630 8192000000 10033954336 10425204736 1071321104 11.565 100 68 126 1 1159181264 73486080 14374080
08:33:15 1184 8192000000 10012028128 10392322048 1043927792 11.302 100 81 157 0 1133501632 72977872 16595968
08:34:17 1146 8192000000 10015170416 10397323264 1052247504 11.382 99 85 159 1 1139959104 71549440 16162160
08:35:18 963 8192000000 10008033184 10384826368 1046345360 11.326 100 94 180 0 1133347648 67139712 19862576
08:36:20 1435 8192000000 9992054064 10364739584 1026242688 11.132 100 94 180 2 1111327488 67717616 17367184
08:37:23 1142 8192000000 10025304080 10404384768 1048906880 11.350 100 90 177 0 1151475920 82983696 19585344
08:38:33 9285 8192000000 10004865376 10384867328 1057445344 11.432 100 67 127 1 1136755856 66558864 12751648
08:39:51 10344 8192000000 10041874144 10428715008 1068723760 11.540 99 68 128 3 1155239072 73156032 13359280
08:41:07 1954 8192000000 10070963232 10456719360 1100011616 11.838 100 82 153 2 1194439936 78898144 15530176
08:42:11 1170 8192000000 10128904992 10521710592 1110829184 11.940 100 75 144 0 1251670912 125119440 15722288
08:43:18 17491 8192000000 10140638512 10546737152 1118810608 12.016 100 57 109 0 1259019632 128550208 11658816
08:44:38 2580 8192000000 10183422432 10582319104 1131877344 12.139 100 71 136 1 1303351232 156598320 14875568
08:45:45 1251 8192000000 10220716800 10612551680 1149181504 12.302 100 93 171 0 1331465440 164259408 18024528
08:46:49 3801 8192000000 10263250176 10661847040 1159135376 12.395 100 84 165 0 1376447200 199604416 17707408
08:47:58 960 8192000000 10308772544 10708123648 1157020144 12.375 100 95 183 1 1423605392 245178112 21407136
08:49:02 3167 8192000000 10317728096 10740137984 1166488032 12.464 100 89 176 1 1434338288 248079664 19770592
08:50:10 4383 8192000000 10353958016 10766876672 1178300304 12.574 100 88 173 0 1479044880 279436080 21308496
08:51:17 963 8192000000 10385187312 10805825536 1186772256 12.653 100 96 187 1 1495066672 286687792 21606624
08:52:23 996 8192000000 10393020288 10793267200 1175993632 12.553 99 94 183 2 1504441040 307406128 21041280
08:53:27 1767 8192000000 10458366368 10878017536 1190089536 12.684 100 94 182 0 1562937408 352830896 20016976
08:54:32 1188 8192000000 10466583200 10905677824 1179575424 12.586 99 94 180 0 1571606320 369797920 22232976
08:55:36 1555 8192000000 10495602144 10939936768 1195036368 12.730 100 94 187 1 1606876608 389163424 22676816
08:56:41 990 8192000000 10510260928 10922881024 1192070224 12.703 99 95 189 1 1620014368 405308592 22635552
08:57:45 1281 8192000000 10554341632 10958405632 1200884704 12.785 100 95 189 1 1656954624 434587584 21482336
08:58:48 1204 8192000000 10567736704 11010887680 1189218672 12.676 100 93 178 1 1670892784 460647984 21026128
08:59:52 809 8192000000 10591512048 11060019200 1198893808 12.766 100 96 188 1 1698266784 475317648 24055328
09:00:55 2460 8192000000 10585534160 11040964608 1193188560 12.713 100 70 139 1 1692173968 481301936 17683472
09:02:04 12712 8192000000 10571402160 11057303552 1189839360 12.682 100 50 100 0 1677132320 473622192 13670768
09:03:17 25457 8192000000 10507264960 10969088000 1160649040 12.409 99 31 62 1 1627064720 457637792 8777888
09:05:03 16376 8192000000 10386307712 10938740736 1118410864 12.012 97 29 58 0 1507279712 380604752 8264096
09:06:49 71696 8192000000 10109135072 10628558848 1134008288 12.159 99 12 24 1 1343717760 206528432 3181040
09:09:03 33299 8192000000 10053171504 10475687936 1083710608 11.683 95 55 96 2 1109405712 15877504 9817600
09:10:37 88777 8192000000 10072766000 10577027072 1092097472 11.763 97 14 28 0 1164395264 68925968 3371824
09:13:52 77744 8192000000 10072950288 10523168768 1059480448 11.452 96 25 50 2 1146488704 81318272 5689984
09:16:16 37156 8192000000 10052666064 10464731136 1047934864 11.341 94 25 44 2 1093025584 39950064 5140656
09:17:55 15751 8192000000 10104257920 10537086976 1113479744 11.965 99 48 94 1 1194352064 70727264 10145056
09:19:15 12798 8192000000 10142393232 10565808128 1143718720 12.250 100 65 122 3 1241294512 84888144 12687648
09:20:37 156928 8192000000 10188323808 10649124864 1150352992 12.313 100 26 50 2 1264893552 108948752 5591808
09:24:15 77947 8192000000 10191143728 10689212416 1155663968 12.363 100 25 48 1 1291046528 129280656 6101904
09:26:49 84145 8192000000 10091165280 10618146816 1127582240 12.099 98 7 14 0 1237983760 108611456 1790064
09:29:20 36880 8192000000 10106399568 10550104064 1128166304 12.104 98 25 49 2 1176273296 43125296 4981696
09:30:57 64334 8192000000 10136480784 10604167168 1125484800 12.079 98 25 50 1 1217147200 86634288 5028112
09:33:08 64440 8192000000 10072104336 10524327936 1121079968 12.037 98 37 75 0 1177234400 48584656 7569776
09:35:13 35568 8192000000 10138415040 10580983808 1141956704 12.234 100 50 98 0 1220466048 68467200 10042144
09:36:50 61650 8192000000 10130818080 10566705152 1114948160 11.979 97 32 64 0 1184276720 62797936 6530624
09:38:54 115164 8192000000 10058161840 10512809984 1125219056 12.076 100 41 73 3 1204931408 72522320 7190032
09:41:50 42174 8192000000 10070295616 10529476608 1120146640 12.028 98 21 41 1 1179758144 55496400 4115104
09:45:11 29561 8192000000 10095644160 10552598528 1132192496 12.142 100 15 28 1 1203350208 68233264 2924448
09:46:45 46118 8192000000 10134402256 10632146944 1129068992 12.113 99 9 18 1 1202737824 71658384 2010448
09:48:56 42504 8192000000 10147188592 10584936448 1100198496 11.840 96 18 36 0 1196897776 92873472 3825808
09:50:39 53024 8192000000 10117358464 10575728640 1119533920 12.023 99 27 53 1 1225418064 100095968 5788176
09:52:42 28540 8192000000 10055941648 10483191808 1092071184 11.762 97 33 64 1 1151243040 52739008 6432848
09:54:11 20001 8192000000 10074599152 10521681920 1091644800 11.758 97 42 81 0 1150632976 50242400 8745776
09:55:37 9512 8192000000 10153328640 10573164544 1128761984 12.110 100 74 141 2 1241750208 96928640 16059584
09:56:47 17538 8192000000 10099308000 10527952896 1132962560 12.149 100 43 80 0 1203610384 62011024 8636800
09:58:08 22010 8192000000 10079840272 10502066176 1115413504 11.984 98 43 81 4 1152499344 28177440 8908400
09:59:30 89487 8192000000 10079029456 10515255296 1109770592 11.930 98 32 64 0 1160619536 44296192 6552752
10:02:05 46454 8192000000 10085895520 10539061248 1128153168 12.104 100 30 58 2 1196952640 62893648 5905824
10:03:52 83314 8192000000 10113202592 10588213248 1113686560 11.967 98 9 18 0 1198593120 82774752 2131808
10:06:18 50749 8192000000 10082077824 10547466240 1127020624 12.093 100 20 38 2 1226777232 94844960 4911648
10:08:12 39366 8192000000 10047559616 10491129856 1099463504 11.833 97 14 28 1 1122846192 20226512 3156176
10:09:52 27921 8192000000 10085955568 10532933632 1136502384 12.183 100 20 40 1 1193588048 52794000 4291664
10:11:28 39207 8192000000 10108309312 10561224704 1133087632 12.150 99 26 52 2 1200627952 61875184 5665136
10:13:15 46666 8192000000 10104457424 10557218816 1112610560 11.957 98 27 54 1 1227624752 109102912 5911280
10:15:03 60402 8192000000 10050714352 10491215872 1084082704 11.686 96 22 44 0 1126184896 37633424 4468768
10:17:04 62965 8192000000 10127086560 10576662528 1114406496 11.974 98 37 74 1 1224201088 102244480 7550112
10:19:10 38597 8192000000 10109052864 10553618432 1131928000 12.140 99 38 76 1 1196897824 57689904 7279920
10:20:53 55652 8192000000 10101683856 10547920896 1133777104 12.157 100 46 87 1 1207926000 65304800 8844096
10:22:57 92248 8192000000 10087918880 10544099328 1147185216 12.283 100 11 22 1 1198761824 49006016 2570592
10:25:32 96427 8192000000 10098391360 10553573376 1129255280 12.114 98 8 16 0 1184494976 53206560 2033136
10:28:09 63724 8192000000 10111134352 10557747200 1143017504 12.244 100 32 64 1 1214942576 64855792 7069280
10:30:22 27353 8192000000 10141536352 10577002496 1112053600 11.952 97 30 58 2 1204383488 85164768 7165120
10:31:50 20696 8192000000 10175739168 10606260224 1141746688 12.232 99 50 98 1 1266028672 112900928 11381056
10:33:15 30517 8192000000 10206177440 10635870208 1127412000 12.097 98 42 84 1 1273597680 136430256 9755424
10:34:49 57987 8192000000 10170967136 10644172800 1126577472 12.089 98 25 49 2 1277019088 144147712 6293904
10:36:58 36530 8192000000 9274046976 9640796160 389771376 4.541 35 7 14 0 461784080 70809504 1203200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-05-2022 10:38:38 to 26-05-2022 10:38:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-05-2022 10:38:55 to 26-05-2022 10:38:57.

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          222280
MON$OLDEST_ACTIVE               222549
MON$OLDEST_SNAPSHOT             222549
MON$NEXT_TRANSACTION            222549
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                       2035936
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2770 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2770 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2770 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 ---
:35: 
:36: mscs-test-161.budzdorov.ru    Thu May 26 10:37:38 2022
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
:39: 
:40: mscs-test-161.budzdorov.ru    Thu May 26 10:37:50 2022
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:42: 
:43: 
:44: mscs-test-161.budzdorov.ru    Thu May 26 10:37:54 2022
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:46: 
:47: 
:48: mscs-test-161.budzdorov.ru    Thu May 26 10:37:55 2022
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:50: 
--- end of diff output ---

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

26.05.22 10:38:58. 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-05-26 10:39:00 No severe PSQL-related problems occured
26.05.22 10:39:02. End of report.