Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.04.2022 04: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 16229448 kB
MemAvailable 31452256 kB
Buffers 20 kB
Cached 15381236 kB
SwapCached 2688 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-13 04:37:16
DTS_END                         2022-04-13 04:37:16.2920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44972
TRN_ID                          224302
ATT_ID                          634
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: 9122.87 20966 1094744
customer order: creation 174.12 485 20894
customer order: refuse 14.41 419 1729
order to supplier: creation 47.15 1369 5658
order to supplier: removal 3.43 145328 412
invoice (draft): creation 44.71 3210 5365
invoice (draft): removal 50.83 907 6099
invoice accept: apply 41.65 26106 4998
invoice accept: cancel 46.06 1164 5527
customer reserve: creation 3839.88 249 460785
customer reserve: removal 4558.15 85 546978
realization accept: apply 85.08 287 10209
realization accept: cancel 86.13 148 10335
payment from customer: creation 60.15 173 7218
payment from customer: removal 6.03 141 724
payment to supplier: creation 49.48 186 5937
payment to supplier: removal 3.48 116 418
service: total inventory turnovers 3.73 4092 448
service: total monetary turnovers 7.44 382 893
service: aggregate perf. data 0.93 15011 112
service: refresh index statistics 0.04 219456 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-04-2022 04:38:01 to 13-04-2022 04:38:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7964
TEST_TIME 2 8031
TEST_TIME 3 6899
TEST_TIME 4 6571
TEST_TIME 5 5941
TEST_TIME 6 5961
TEST_TIME 7 6054
TEST_TIME 8 6588
TEST_TIME 9 7065
TEST_TIME 10 7128
TEST_TIME 11 7184
TEST_TIME 12 7469
TEST_TIME 13 7392
TEST_TIME 14 7331
TEST_TIME 15 7422
TEST_TIME 16 7577
TEST_TIME 17 7867
TEST_TIME 18 8022
TEST_TIME 19 8399
TEST_TIME 20 8304
TEST_TIME 21 8392
TEST_TIME 22 8484
TEST_TIME 23 8768
TEST_TIME 24 8977
TEST_TIME 25 8915
TEST_TIME 26 9096
TEST_TIME 27 9299
TEST_TIME 28 9249
TEST_TIME 29 9401
TEST_TIME 30 9596
TEST_TIME 31 9667
TEST_TIME 32 10024
TEST_TIME 33 10515
TEST_TIME 34 10757
TEST_TIME 35 10727
TEST_TIME 36 10766
TEST_TIME 37 10595
TEST_TIME 38 10390
TEST_TIME 39 10216
TEST_TIME 40 10239
TEST_TIME 41 10059
TEST_TIME 42 9995
TEST_TIME 43 10136
TEST_TIME 44 10144
TEST_TIME 45 10084
TEST_TIME 46 10103
TEST_TIME 47 10037
TEST_TIME 48 9968
TEST_TIME 49 9960
TEST_TIME 50 9970
TEST_TIME 51 9949
TEST_TIME 52 9801
TEST_TIME 53 9755
TEST_TIME 54 9666
TEST_TIME 55 9535
TEST_TIME 56 9451
TEST_TIME 57 9479
TEST_TIME 58 9362
TEST_TIME 59 9235
TEST_TIME 60 9138
TEST_TIME 61 9242
TEST_TIME 62 9279
TEST_TIME 63 9224
TEST_TIME 64 9276
TEST_TIME 65 9325
TEST_TIME 66 9322
TEST_TIME 67 9437
TEST_TIME 68 9376
TEST_TIME 69 9339
TEST_TIME 70 9471
TEST_TIME 71 9439
TEST_TIME 72 9403
TEST_TIME 73 9498
TEST_TIME 74 9570
TEST_TIME 75 9449
TEST_TIME 76 9430
TEST_TIME 77 9351
TEST_TIME 78 9367
TEST_TIME 79 9338
TEST_TIME 80 9271
TEST_TIME 81 9194
TEST_TIME 82 9179
TEST_TIME 83 9113
TEST_TIME 84 9117
TEST_TIME 85 9166
TEST_TIME 86 9128
TEST_TIME 87 9130
TEST_TIME 88 9143
TEST_TIME 89 9115
TEST_TIME 90 9154
TEST_TIME 91 9145
TEST_TIME 92 9231
TEST_TIME 93 9184
TEST_TIME 94 9223
TEST_TIME 95 9278
TEST_TIME 96 9240
TEST_TIME 97 9257
TEST_TIME 98 9305
TEST_TIME 99 9312
TEST_TIME 100 9280
TEST_TIME 101 9244
TEST_TIME 102 9185
TEST_TIME 103 9165
TEST_TIME 104 9122
TEST_TIME 105 9085
TEST_TIME 106 9104
TEST_TIME 107 9083
TEST_TIME 108 9059
TEST_TIME 109 9092
TEST_TIME 110 9098
TEST_TIME 111 9073
TEST_TIME 112 9090
TEST_TIME 113 9052
TEST_TIME 114 9063
TEST_TIME 115 9041
TEST_TIME 116 9028
TEST_TIME 117 9159
TEST_TIME 118 9226
TEST_TIME 119 9205
TEST_TIME 120 9141

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-04-2022 04:38:04 to 13-04-2022 04:38:08.

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 1518605 1518605 0 0.00 0 69721 13 0 0
doc_list_biud 1518605 1518605 0 0.00 0 29914 37 0 0
sp_add_invoice_to_stock 5126 4998 128 2.49 66 174847 26106 0 128
sp_cancel_adding_invoice 5527 5527 0 0.00 7 77018 1165 0 0
sp_cancel_client_order 1729 1729 0 0.00 6 18966 420 0 0
sp_cancel_customer_reserve 546978 546978 0 0.00 1 29177 86 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 3 6950 142 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 1058 116 0 0
sp_cancel_supplier_invoice 6108 6099 9 0.14 3 29921 907 0 9
sp_cancel_supplier_order 412 412 0 0.00 17 1368043 145329 0 0
sp_cancel_write_off 10335 10335 0 0.00 2 9583 149 0 0
sp_client_order 20894 20894 0 0.00 6 40178 485 0 0
sp_customer_reserve 460785 460785 0 0.00 1 106438 250 0 0
sp_fill_shopping_cart 31913 31913 0 0.00 2 20894 84 0 0
sp_fill_shopping_cart_clo_res 460774 460774 0 0.00 0 21045 18 0 0
sp_get_clo_for_invoice 4956 4956 0 0.00 0 29910 284 0 0
sp_kill_cost_storno 17004 17004 0 0.00 0 13907 63 0 0
sp_kill_qstorno_ret_qs2qd 1110802 1110802 0 0.00 0 29913 38 0 0
sp_kill_qty_storno 586243 586243 0 0.00 0 29913 88 0 0
sp_lock_selected_doc 20993 20993 0 0.00 0 6931 7 0 0
sp_make_cost_storno 28318 28318 0 0.00 0 12083 79 0 0
sp_multiply_rows_for_pdistr 28318 28318 0 0.00 0 6263 38 0 0
sp_multiply_rows_for_qdistr 20894 20894 0 0.00 0 10162 173 0 0
sp_pay_from_customer 7218 7218 0 0.00 5 7562 174 0 0
sp_pay_to_supplier 5937 5937 0 0.00 6 10586 186 0 0
sp_payment_common 13155 13155 0 0.00 2 5688 44 0 0
sp_qd_handle_on_cancel_clo 1729 1729 0 0.00 0 18956 350 0 0
sp_qd_handle_on_invoice_upd_sts 10481 10481 0 0.00 0 27446 491 0 0
sp_qd_handle_on_reserve_upd_sts 10209 10209 0 0.00 0 9854 62 0 0
sp_reserve_write_off 10209 10209 0 0.00 7 69750 288 0 0
sp_supplier_invoice 5365 5365 0 0.00 9 165676 3210 0 0
sp_supplier_order 5658 5658 0 0.00 7 92704 1369 0 0
srv_aggregate_perf_data 112 112 0 0.00 2791 150857 15012 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 924 162637 38165 0 0
srv_find_qd_qs_mism 1045283 1045283 0 0.00 0 20898 10 0 0
srv_make_invnt_saldo 448 448 0 0.00 39 58980 4093 0 0
srv_make_money_saldo 893 893 0 0.00 1 20744 382 0 0
srv_recalc_idx_stat 5 5 0 0.00 57799 437119 219456 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4998 4998 0 0.00 0 11988 945 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 7391 472 0 0
t$perf-norm:sp_cancel_client_order 1729 1729 0 0.00 0 1507 30 0 0
t$perf-norm:sp_cancel_customer_reserve 693 693 0 0.00 0 558 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 742 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 487 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 1476 42 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 78759 5889 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 2906 19 0 0
t$perf-norm:sp_client_order 20894 20894 0 0.00 0 7837 38 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 3330 17 0 0
t$perf-norm:sp_pay_from_customer 7218 7218 0 0.00 0 6724 20 0 0
t$perf-norm:sp_pay_to_supplier 5937 5937 0 0.00 0 6527 22 0 0
t$perf-norm:sp_reserve_write_off 10209 10209 0 0.00 0 9690 24 0 0
t$perf-norm:sp_supplier_invoice 5365 5365 0 0.00 0 8972 37 0 0
t$perf-norm:sp_supplier_order 5658 5658 0 0.00 0 3267 32 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 1546 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 100 7 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 1233 16 0 0
t$perf-norm:srv_make_money_saldo 893 893 0 0.00 0 3086 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 193 107 0 0
v_add_invoice_to_stock 10164 9908 256 2.51 0 3953 24 0 256
v_all_customers 38094 38094 0 0.00 0 124 4 0 0
v_all_suppliers 30662 30662 0 0.00 0 121 4 0 0
v_all_wares 41788 41788 0 0.00 0 121 4 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 593 16 0 0
v_cancel_client_order 3458 3458 0 0.00 0 4436 11 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 6615 22 0 0
v_cancel_customer_reserve 1386 1386 0 0.00 0 2301 14 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 621 11 0 0
v_cancel_supplier_invoice 886 868 18 2.03 0 508 23 0 18
v_cancel_supplier_order 824 824 0 0.00 0 622 10 0 0
v_cancel_write_off 2046 2046 0 0.00 0 5994 27 0 0
v_max_id_clo_ord 5658 5658 0 0.00 0 269 5 0 0
v_max_id_clo_res 997 997 0 0.00 0 4088 23 0 0
v_max_id_ord_sup 5361 5361 0 0.00 0 8136 11 0 0
v_max_non_paid_invoice 1625 1625 0 0.00 0 2427 9 0 0
v_max_non_paid_realizn 3777 3777 0 0.00 0 2031 7 0 0
v_min_id_clo_ord 5658 5658 0 0.00 0 4037 9 0 0
v_min_id_clo_res 997 997 0 0.00 0 2104 10 0 0
v_min_id_ord_sup 5361 5361 0 0.00 0 20846 17 0 0
v_min_non_paid_invoice 5937 5937 0 0.00 0 2543 14 0 0
v_min_non_paid_realizn 7218 7218 0 0.00 0 3221 14 0 0
v_our_firm 10576 10576 0 0.00 0 63 5 0 0
v_reserve_write_off 20418 20418 0 0.00 0 6905 11 0 0
x_lock_depdocs_on_canc_invoice 11192 11192 0 0.00 0 18754 131 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 10195 345 0 0
x_make_qstorno_1000_1200 5658 5658 0 0.00 2 92557 1187 0 0
x_make_qstorno_1200_2000 5365 5365 0 0.00 4 165547 2992 0 0
x_make_qstorno_2100_3300 460785 460785 0 0.00 0 106427 219 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-04-2022 04:38:09 to 13-04-2022 04:38:13.

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:20 4 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:21 29621 8192000000 9731922064 10032943104 813404128 9.032 100 89 178 0 842445856 8028384 21013344
02:09:54 29995 8192000000 9774751056 10084737024 846896992 9.369 100 90 180 1 880813728 12845120 21071616
02:11:28 16249 8192000000 9803179760 10128576512 871896608 9.619 100 71 143 0 913507968 25513248 16098112
02:12:51 7068 8192000000 9884637760 10218053632 926750192 10.163 100 87 174 0 997538384 52091024 18697168
02:14:01 4518 8192000000 9934457696 10281209856 962100000 10.510 100 88 174 0 1048953664 68532352 18321312
02:15:08 2261 8192000000 9974322576 10330673152 1000477824 10.883 100 93 181 0 1091698832 72385392 18835616
02:16:12 1384 8192000000 10001233168 10365255680 1025691216 11.127 100 94 176 0 1115260128 73009616 16559296
02:17:16 1013 8192000000 10025311328 10395078656 1043202576 11.295 100 95 182 1 1142275632 80926288 18146768
02:18:18 763 8192000000 10012261200 10387087360 1064159600 11.496 100 96 187 1 1130718000 47652768 18905632
02:19:20 1193 8192000000 10054685792 10433589248 1072237920 11.573 100 98 191 0 1176074032 85279600 18556512
02:20:22 3621 8192000000 10023294416 10413981696 1079089024 11.639 100 71 142 0 1145988368 52864704 14034640
02:21:26 1871 8192000000 10022353200 10411479040 1080048048 11.648 100 77 150 0 1149262768 53738768 15475952
02:22:35 1294 8192000000 10048416736 10437664768 1087733104 11.721 100 82 159 1 1170470432 67005936 15731392
02:23:37 974 8192000000 10059109184 10445946880 1091759184 11.759 100 94 186 1 1172953296 62787488 18406624
02:24:39 1045 8192000000 10052226512 10443329536 1096268448 11.802 100 89 175 0 1171351344 56997104 18085792
02:25:41 1251 8192000000 10084907008 10475814912 1103620320 11.872 100 96 192 1 1212349936 88256208 20473408
02:26:44 1790 8192000000 10059306944 10448121856 1096051056 11.800 100 80 151 1 1178389344 67161904 15176384
02:27:48 1125 8192000000 10076349728 10466025472 1096488976 11.804 100 97 187 1 1198156272 82810080 18857216
02:28:50 1000 8192000000 10018644240 10398810112 1073277568 11.583 100 91 166 1 1140324768 48924704 18122496
02:29:51 855 8192000000 10026202752 10411646976 1073469248 11.585 100 86 167 0 1146678576 55393264 17816064
02:30:53 1439 8192000000 10023070720 10405879808 1075129664 11.601 100 88 165 1 1147953232 55735424 17088144
02:31:56 1506 8192000000 9984126752 10358202368 1043923728 11.302 100 96 177 7 1107803424 45533760 18345936
02:32:58 936 8192000000 9996572688 10374086656 1038844464 11.254 100 86 163 1 1120899584 62788096 19267024
02:34:00 1781 8192000000 10020918832 10404610048 1047823296 11.340 100 75 139 1 1141102320 80863152 12415872
02:35:03 5628 8192000000 9981160880 10364022784 1038384784 11.249 99 68 125 1 1113982416 62625968 12971664
02:36:10 1527 8192000000 9975865008 10350903296 1036309360 11.229 100 71 132 5 1086516704 36220144 13987200
02:37:12 4886 8192000000 10000203728 10396291072 1053553984 11.395 100 81 153 2 1136066128 66608736 15903408
02:38:31 13855 8192000000 10039065520 10419130368 1078689280 11.635 100 71 129 1 1155366208 64519040 12157888
02:39:50 1188 8192000000 10051222800 10436755456 1080609904 11.653 100 85 161 1 1170096144 74245536 15240704
02:40:58 6668 8192000000 10065434448 10461167616 1093020624 11.771 100 62 119 3 1189895072 84092768 12781680
02:42:12 18682 8192000000 10095254720 10489962496 1104288880 11.878 100 66 126 0 1202143760 84602576 13252304
02:43:39 1048 8192000000 10138978000 10528063488 1123562800 12.061 100 85 169 0 1255658608 114467152 17628656
02:44:44 7460 8192000000 10154544544 10557001728 1122279136 12.049 100 65 125 2 1280913872 145013568 13621168
02:45:56 970 8192000000 10173781744 10583076864 1135618240 12.174 100 87 159 1 1289643152 137519472 16505440
02:47:00 3824 8192000000 10174234256 10586845184 1141672544 12.231 100 62 120 0 1291891360 137544880 12673936
02:48:08 1765 8192000000 10212713088 10611494912 1158926976 12.393 100 89 176 0 1330968064 153257040 18784048
02:49:12 1178 8192000000 10246704592 10650398720 1162253808 12.424 100 94 183 1 1356108112 174180112 19674192
02:50:16 2598 8192000000 10245980128 10649190400 1161149760 12.414 100 87 171 1 1363281856 182390496 19741600
02:51:24 1084 8192000000 10295002976 10694660096 1174292848 12.537 100 92 172 1 1407985728 214660448 19032432
02:52:28 981 8192000000 10322120672 10732773376 1171448576 12.510 100 93 177 0 1430374144 239282464 19643104
02:53:31 927 8192000000 10339170896 10750926848 1174926832 12.543 100 96 188 2 1452249888 255862672 21460384
02:54:33 1337 8192000000 10332885552 10747064320 1175104576 12.545 100 88 172 1 1441843232 246086128 20652528
02:55:36 919 8192000000 10331906608 10785898496 1175778352 12.551 100 91 180 1 1437596912 241516880 20301680
02:56:39 1959 8192000000 10321346208 10725007360 1166809136 12.467 100 85 167 0 1428356336 241788544 19758656
02:57:48 1136 8192000000 10356621232 10762764288 1167049792 12.469 100 91 179 0 1464544080 275307040 22187248
02:58:52 1611 8192000000 10332079824 10762539008 1157368832 12.379 100 85 159 0 1432990064 257268240 18352992
02:59:55 3393 8192000000 10323748432 10751524864 1150836032 12.317 100 68 132 1 1433094000 265783088 16474880
03:01:01 3355 8192000000 10304609696 10746445824 1151580384 12.324 100 65 127 1 1413440048 245787824 16071840
03:02:08 1088 8192000000 10331356560 10734379008 1157609712 12.381 100 94 175 3 1430683904 253079792 19994400
03:03:11 6485 8192000000 10343997152 10785181696 1153671456 12.344 99 62 120 0 1448332912 280437568 14223888
03:04:28 4182 8192000000 10361413360 10810785792 1168001936 12.478 100 53 106 0 1461609344 280676480 12930928
03:05:42 15818 8192000000 10398860720 10832719872 1171219872 12.508 100 47 94 0 1524699728 341862032 11617824
03:07:05 25198 8192000000 10345103584 10818486272 1155912224 12.365 100 40 78 0 1484705312 319025648 9767440
03:08:46 18588 8192000000 10270494336 10773934080 1114896128 11.979 98 29 56 1 1372039584 248634528 8508928
03:10:06 53136 8192000000 10122515168 10582761472 1107615168 11.910 98 18 36 0 1255182832 143481920 4085744
03:12:00 57151 8192000000 10089699376 10515193856 1108673824 11.920 97 16 32 4 1163629056 52055984 2899248
03:13:58 60186 8192000000 10148302912 10565681152 1101905136 11.856 97 23 44 2 1228575760 120643488 6027136
03:16:00 29897 8192000000 10227604944 10644619264 1137529088 12.192 100 55 108 1 1316987616 166329024 13129504
03:17:34 162636 8192000000 10151447296 10659708928 1137758368 12.194 100 40 80 2 1307239616 158251328 11229920
03:21:18 54948 8192000000 10114559312 10580324352 1134904896 12.168 100 44 75 4 1194636880 51823344 7908640
03:23:13 63671 8192000000 10128595856 10604306432 1125541504 12.079 98 22 43 3 1236982560 106280240 5160816
03:25:18 70671 8192000000 10119745968 10601680896 1127499296 12.098 99 15 28 1 1228110592 97385808 3225488
03:27:34 79482 8192000000 10086447456 10565148672 1139682208 12.213 100 33 64 0 1195887696 49736720 6468768
03:30:12 56058 8192000000 10066915440 10547703808 1111498912 11.947 98 17 34 0 1148973040 33788224 3685904
03:32:54 85198 8192000000 10070677344 10582302720 1137765472 12.195 100 17 34 0 1217749616 76330624 3653520
03:35:20 55172 8192000000 10111251568 10568687616 1142036416 12.235 99 33 64 1 1195889824 47092080 6761328
03:37:31 57639 8192000000 10118467536 10640588800 1131763472 12.138 99 25 50 2 1245279296 108113232 5402592
03:40:04 23641 8192000000 10104495232 10538459136 1130688320 12.128 99 47 91 1 1191005376 50867680 9449376
03:41:37 64335 8192000000 10127840800 10598801408 1128308656 12.105 98 29 58 1 1214423344 79960016 6154672
03:43:41 56557 8192000000 10088573168 10577039360 1109221248 11.925 97 18 36 0 1191562912 78632848 3708816
03:45:38 9375 8192000000 10095818704 10516586496 1132964560 12.149 99 69 130 1 1183428016 36440528 14022928
03:46:51 50487 8192000000 10080843792 10572271616 1095868672 11.798 96 30 60 0 1189202528 87292816 6041040
03:48:44 29609 8192000000 10099316208 10534981632 1087023008 11.714 96 33 66 0 1164750656 70852224 6875424
03:50:31 152174 8192000000 10041544672 10460528640 1089019232 11.733 97 29 56 0 1134272992 38884672 6369088
03:54:08 56245 8192000000 10073637296 10534068224 1116133024 11.990 99 37 74 1 1178951088 55374432 7443632
03:56:05 69935 8192000000 10065746976 10532241408 1123498320 12.060 99 15 28 1 1167172048 39814864 3858864
03:59:29 83423 8192000000 10108801696 10579652608 1107741856 11.911 97 17 34 0 1196589840 84898000 3949984
04:01:53 80903 8192000000 10051504192 10545348608 1103202480 11.868 97 14 28 0 1152752704 46804976 2745248
04:04:17 43951 8192000000 10067447744 10528096256 1118812000 12.016 99 26 52 1 1171251360 46250896 6188464
04:06:02 16625 8192000000 10072150688 10506366976 1102014928 11.857 98 42 78 2 1153793776 43729952 8048896
04:07:24 74236 8192000000 10085335360 10547511296 1066289776 11.517 95 30 60 1 1163160416 90699216 6171424
04:09:40 41644 8192000000 10063307088 10494918656 1108398368 11.917 99 34 68 1 1162894128 48231760 6264000
04:11:23 10467 8192000000 10109062864 10527162368 1111758912 11.949 99 49 98 2 1188655568 67271712 9624944
04:12:37 33347 8192000000 10066434752 10511712256 1079769296 11.645 96 31 60 2 1142734928 56583872 6381760
04:14:14 33826 8192000000 10163877584 10606415872 1138853392 12.205 100 39 78 2 1254421856 107430752 8137712
04:15:49 32555 8192000000 10097718000 10561294336 1111772768 11.949 98 30 58 2 1162461776 44830192 5858816
04:17:26 118846 8192000000 10081274608 10521391104 1111814016 11.950 98 25 50 2 1169479840 52710288 4955536
04:20:25 71144 8192000000 10118875824 10590842880 1131000448 12.131 99 18 36 3 1222719088 87949280 3769360
04:22:37 66203 8192000000 10095161488 10564243456 1141544832 12.230 100 11 22 1 1244258336 100188544 2524960
04:25:12 48010 8192000000 10116649296 10588950528 1125968240 12.083 100 21 41 0 1210721584 80044896 4708448
04:27:10 69481 8192000000 10083049808 10520068096 1120542208 12.032 99 50 96 2 1164302928 33348768 10411952
04:29:22 46123 8192000000 10091188080 10521640960 1097562032 11.815 97 39 76 1 1147562912 41723296 8277584
04:31:15 89508 8192000000 10172157328 10640011264 1133344624 12.153 99 23 44 1 1240200384 101416896 5438864
04:33:58 62005 8192000000 10136158816 10712035328 1133123216 12.151 99 15 30 0 1311721552 174576368 4021968
04:36:08 60422 8192000000 10103845360 10550861824 1116555424 11.994 98 18 35 1 1156981664 37210720 3215520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-04-2022 04:38:15 to 13-04-2022 04:38:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-04-2022 04:38:31 to 13-04-2022 04:38:33.

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          223807
MON$OLDEST_ACTIVE               225245
MON$OLDEST_SNAPSHOT             225245
MON$NEXT_TRANSACTION            225245
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                       2068064
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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

13.04.22 04:38:34. 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-13 04:38:37 No severe PSQL-related problems occured
13.04.22 04:38:38. End of report.