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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16426300 kB
MemAvailable 31218296 kB
Buffers 0 kB
Cached 15257436 kB
SwapCached 2460 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-06-18 07:37:12
DTS_END                         2022-06-18 07:37:12.8270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38182
TRN_ID                          222042
ATT_ID                          619
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: 8647.52 25936 1037702
customer order: creation 167.72 455 20126
customer order: refuse 14.63 387 1755
order to supplier: creation 44.41 1388 5329
order to supplier: removal 3.31 147255 397
invoice (draft): creation 44.35 2876 5322
invoice (draft): removal 48.02 951 5762
invoice accept: apply 41.76 27508 5011
invoice accept: cancel 42.78 1237 5133
customer reserve: creation 3798.53 272 455823
customer reserve: removal 4149.55 93 497946
realization accept: apply 81.53 249 9784
realization accept: cancel 82.03 173 9843
payment from customer: creation 58.86 157 7063
payment from customer: removal 5.41 97 649
payment to supplier: creation 48.73 171 5848
payment to supplier: removal 3.21 122 385
service: total inventory turnovers 4.19 3291 503
service: total monetary turnovers 7.47 293 896
service: aggregate perf. data 1.02 12364 122
service: refresh index statistics 0.04 319389 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-06-2022 07:38:25 to 18-06-2022 07:38:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4548
TEST_TIME 2 5117
TEST_TIME 3 5657
TEST_TIME 4 5611
TEST_TIME 5 5240
TEST_TIME 6 5295
TEST_TIME 7 5260
TEST_TIME 8 5244
TEST_TIME 9 5646
TEST_TIME 10 6312
TEST_TIME 11 6305
TEST_TIME 12 6324
TEST_TIME 13 6419
TEST_TIME 14 6730
TEST_TIME 15 6977
TEST_TIME 16 7021
TEST_TIME 17 7236
TEST_TIME 18 7360
TEST_TIME 19 7371
TEST_TIME 20 7717
TEST_TIME 21 7774
TEST_TIME 22 8397
TEST_TIME 23 8681
TEST_TIME 24 8861
TEST_TIME 25 8879
TEST_TIME 26 9553
TEST_TIME 27 9963
TEST_TIME 28 10302
TEST_TIME 29 10547
TEST_TIME 30 10881
TEST_TIME 31 10637
TEST_TIME 32 10459
TEST_TIME 33 10178
TEST_TIME 34 10032
TEST_TIME 35 9991
TEST_TIME 36 9896
TEST_TIME 37 9823
TEST_TIME 38 9824
TEST_TIME 39 9863
TEST_TIME 40 9717
TEST_TIME 41 9627
TEST_TIME 42 9576
TEST_TIME 43 9490
TEST_TIME 44 9341
TEST_TIME 45 9415
TEST_TIME 46 9251
TEST_TIME 47 9171
TEST_TIME 48 9134
TEST_TIME 49 9052
TEST_TIME 50 9064
TEST_TIME 51 9057
TEST_TIME 52 8958
TEST_TIME 53 9055
TEST_TIME 54 9069
TEST_TIME 55 9010
TEST_TIME 56 9132
TEST_TIME 57 9094
TEST_TIME 58 9043
TEST_TIME 59 9012
TEST_TIME 60 9091
TEST_TIME 61 9206
TEST_TIME 62 9233
TEST_TIME 63 9142
TEST_TIME 64 9021
TEST_TIME 65 8948
TEST_TIME 66 8878
TEST_TIME 67 9020
TEST_TIME 68 8961
TEST_TIME 69 8902
TEST_TIME 70 8847
TEST_TIME 71 8808
TEST_TIME 72 8832
TEST_TIME 73 8861
TEST_TIME 74 8879
TEST_TIME 75 8882
TEST_TIME 76 8929
TEST_TIME 77 8911
TEST_TIME 78 8946
TEST_TIME 79 9019
TEST_TIME 80 9052
TEST_TIME 81 9024
TEST_TIME 82 9002
TEST_TIME 83 9034
TEST_TIME 84 9018
TEST_TIME 85 8947
TEST_TIME 86 8919
TEST_TIME 87 8900
TEST_TIME 88 8866
TEST_TIME 89 8816
TEST_TIME 90 8806
TEST_TIME 91 8754
TEST_TIME 92 8728
TEST_TIME 93 8714
TEST_TIME 94 8717
TEST_TIME 95 8728
TEST_TIME 96 8744
TEST_TIME 97 8759
TEST_TIME 98 8771
TEST_TIME 99 8832
TEST_TIME 100 8814
TEST_TIME 101 8796
TEST_TIME 102 8782
TEST_TIME 103 8878
TEST_TIME 104 8929
TEST_TIME 105 8890
TEST_TIME 106 8821
TEST_TIME 107 8817
TEST_TIME 108 8778
TEST_TIME 109 8755
TEST_TIME 110 8717
TEST_TIME 111 8708
TEST_TIME 112 8679
TEST_TIME 113 8708
TEST_TIME 114 8691
TEST_TIME 115 8679
TEST_TIME 116 8734
TEST_TIME 117 8725
TEST_TIME 118 8717
TEST_TIME 119 8725
TEST_TIME 120 8666

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-06-2022 07:38:28 to 18-06-2022 07:38:32.

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 1457221 1457221 0 0.00 0 33591 14 0 0
doc_list_biud 1457209 1457209 0 0.00 0 40322 38 0 0
sp_add_invoice_to_stock 5107 5011 96 1.87 27 250934 27509 0 96
sp_cancel_adding_invoice 5133 5133 0 0.00 6 90015 1237 0 0
sp_cancel_client_order 1755 1755 0 0.00 6 20053 387 0 0
sp_cancel_customer_reserve 497946 497946 0 0.00 1 40330 93 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 4 1777 98 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 3 6734 122 0 0
sp_cancel_supplier_invoice 5773 5762 11 0.19 2 23261 952 0 11
sp_cancel_supplier_order 397 397 0 0.00 13 1281055 147256 0 0
sp_cancel_write_off 9843 9843 0 0.00 2 31145 173 0 0
sp_client_order 20126 20126 0 0.00 5 21646 455 0 0
sp_customer_reserve 455823 455823 0 0.00 1 60035 273 0 0
sp_fill_shopping_cart 30767 30767 0 0.00 2 13624 75 0 0
sp_fill_shopping_cart_clo_res 455797 455797 0 0.00 0 18527 22 0 0
sp_get_clo_for_invoice 4978 4978 0 0.00 0 40570 365 0 0
sp_kill_cost_storno 16010 16010 0 0.00 0 6888 67 0 0
sp_kill_qstorno_ret_qs2qd 1011894 1011894 0 0.00 0 40310 42 0 0
sp_kill_qty_storno 535596 535596 0 0.00 0 40322 95 0 0
sp_lock_selected_doc 20413 20413 0 0.00 0 12946 6 0 0
sp_make_cost_storno 27672 27672 0 0.00 0 16065 67 0 0
sp_multiply_rows_for_pdistr 27671 27671 0 0.00 0 12781 36 0 0
sp_multiply_rows_for_qdistr 20126 20126 0 0.00 0 20637 200 0 0
sp_pay_from_customer 7063 7063 0 0.00 5 12819 157 0 0
sp_pay_to_supplier 5848 5848 0 0.00 6 15410 171 0 0
sp_payment_common 12910 12910 0 0.00 2 6640 38 0 0
sp_qd_handle_on_cancel_clo 1755 1755 0 0.00 0 19967 325 0 0
sp_qd_handle_on_invoice_upd_sts 10110 10110 0 0.00 0 22656 487 0 0
sp_qd_handle_on_reserve_upd_sts 9783 9783 0 0.00 0 19917 64 0 0
sp_reserve_write_off 9784 9784 0 0.00 8 20247 249 0 0
sp_supplier_invoice 5322 5322 0 0.00 9 74304 2877 0 0
sp_supplier_order 5329 5329 0 0.00 7 35812 1389 0 0
srv_aggregate_perf_data 122 122 0 0.00 2347 110321 12365 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 744 148028 41285 0 0
srv_find_qd_qs_mism 989446 989446 0 0.00 0 33589 12 0 0
srv_make_invnt_saldo 503 503 0 0.00 81 45503 3291 0 0
srv_make_money_saldo 896 896 0 0.00 1 8322 293 0 0
srv_recalc_idx_stat 5 5 0 0.00 181223 386116 319389 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5018 5018 0 0.00 0 9860 802 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 1 5169 414 0 0
t$perf-norm:sp_cancel_client_order 1755 1755 0 0.00 0 20404 39 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 626 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 1466 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 1077 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 490 28 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 90825 5967 0 0
t$perf-norm:sp_cancel_write_off 944 944 0 0.00 0 500 14 0 0
t$perf-norm:sp_client_order 20126 20126 0 0.00 0 5544 29 0 0
t$perf-norm:sp_customer_reserve 994 994 0 0.00 0 3621 14 0 0
t$perf-norm:sp_pay_from_customer 7064 7064 0 0.00 0 4194 16 0 0
t$perf-norm:sp_pay_to_supplier 5848 5848 0 0.00 0 4426 18 0 0
t$perf-norm:sp_reserve_write_off 9784 9784 0 0.00 0 4163 18 0 0
t$perf-norm:sp_supplier_invoice 5322 5322 0 0.00 0 2346 24 0 0
t$perf-norm:sp_supplier_order 5329 5329 0 0.00 0 3099 28 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 755 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 91 7 0 0
t$perf-norm:srv_make_invnt_saldo 503 503 0 0.00 0 976 15 0 0
t$perf-norm:srv_make_money_saldo 896 896 0 0.00 0 5828 12 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 231 92 0 0
v_add_invoice_to_stock 10138 9946 192 1.89 0 5608 22 0 192
v_all_customers 37109 37109 0 0.00 0 75 3 0 0
v_all_suppliers 30021 30021 0 0.00 0 74 4 0 0
v_all_wares 40240 40240 0 0.00 0 51 3 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 4430 16 0 0
v_cancel_client_order 3510 3510 0 0.00 0 3188 9 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 1715 13 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 3240 11 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 546 9 0 0
v_cancel_supplier_invoice 834 812 22 2.63 0 680 21 0 22
v_cancel_supplier_order 794 794 0 0.00 0 1433 13 0 0
v_cancel_write_off 1888 1888 0 0.00 0 3558 22 0 0
v_max_id_clo_ord 5328 5328 0 0.00 0 1397 4 0 0
v_max_id_clo_res 994 994 0 0.00 0 2792 24 0 0
v_max_id_ord_sup 5316 5316 0 0.00 0 4636 11 0 0
v_max_non_paid_invoice 1479 1479 0 0.00 0 2687 8 0 0
v_max_non_paid_realizn 3515 3515 0 0.00 0 3750 8 0 0
v_min_id_clo_ord 5328 5328 0 0.00 0 11096 9 0 0
v_min_id_clo_res 994 994 0 0.00 0 6880 20 0 0
v_min_id_ord_sup 5316 5316 0 0.00 0 4566 10 0 0
v_min_non_paid_invoice 5847 5847 0 0.00 0 3916 11 0 0
v_min_non_paid_realizn 7063 7063 0 0.00 0 6624 16 0 0
v_our_firm 10224 10224 0 0.00 0 59 4 0 0
v_reserve_write_off 19563 19563 0 0.00 0 3303 9 0 0
x_lock_depdocs_on_canc_invoice 10489 10489 0 0.00 0 15221 125 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 3350 308 0 0
x_make_qstorno_1000_1200 5329 5329 0 0.00 2 35787 1239 0 0
x_make_qstorno_1200_2000 5322 5322 0 0.00 4 69259 2658 0 0
x_make_qstorno_2100_3300 455819 455819 0 0.00 0 59922 239 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-06-2022 07:38:33 to 18-06-2022 07:38:37.

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:07:16 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:17 29076 8192000000 9713204624 10007695360 786909680 8.763 100 92 184 0 817770560 7752464 23108416
05:10:00 14348 8192000000 9750617632 10054217728 822771664 9.126 100 91 181 1 858027536 13772384 21483488
05:11:27 21695 8192000000 9803701920 10125176832 867689616 9.577 100 86 171 1 913954416 26591280 19673520
05:12:55 8400 8192000000 9872895024 10208739328 925034736 10.146 100 81 162 1 987006368 44942112 17029520
05:14:05 5252 8192000000 9928577696 10275602432 970058640 10.587 100 84 160 2 1042130576 55432992 16638944
05:15:14 1574 8192000000 9981682704 10336915456 1000160512 10.880 100 94 179 1 1102923792 84890048 17873232
05:16:17 2010 8192000000 9991774816 10351919104 1022024256 11.092 100 88 164 0 1116513360 77710992 16778112
05:17:22 1333 8192000000 10038057872 10417254400 1050277328 11.363 100 92 183 2 1156889424 87501056 19111040
05:18:25 865 8192000000 10031930016 10409082880 1062666592 11.482 100 96 190 2 1153773568 70621408 20485568
05:19:27 1631 8192000000 10030031696 10408042496 1072943456 11.580 100 92 175 2 1154994896 64748144 17303296
05:20:30 1703 8192000000 10067488768 10444275712 1075834992 11.608 100 95 181 2 1185153856 91854864 17464000
05:21:34 1046 8192000000 10054164176 10437808128 1084307728 11.689 100 95 170 1 1172082752 69825408 17949616
05:22:36 3091 8192000000 10022276640 10406432768 1088584272 11.729 100 77 140 2 1139020832 35346160 15090400
05:23:40 1197 8192000000 10071578816 10460188672 1094492848 11.785 100 98 188 0 1192898608 78876192 19529568
05:24:43 1360 8192000000 10083929312 10473345024 1100280944 11.840 100 97 183 0 1207318368 88228576 18808848
05:25:46 1237 8192000000 10074860016 10462179328 1102473072 11.861 100 96 183 2 1194649200 73294192 18881936
05:26:49 1196 8192000000 10071255008 10461302784 1105748272 11.892 100 91 174 0 1188569808 64572352 18249184
05:27:51 1221 8192000000 10061367616 10451570688 1100959920 11.847 100 97 183 2 1184848144 65588688 18299536
05:28:53 2638 8192000000 10025218768 10409394176 1086444576 11.709 100 72 136 4 1143949472 44582592 12922304
05:29:57 1025 8192000000 10053415216 10438701056 1081477808 11.662 100 90 167 2 1170623424 72066976 17078640
05:30:59 1177 8192000000 10003532768 10383458304 1057205888 11.430 100 96 181 3 1125566144 49639728 18720528
05:32:01 1405 8192000000 9993089808 10362970112 1036605408 11.232 100 94 177 2 1112421360 58123968 17691984
05:33:04 1181 8192000000 10002753456 10383302656 1048937808 11.350 100 92 178 0 1127333168 59617712 18777648
05:34:06 1121 8192000000 10006400768 10388619264 1049517616 11.356 100 85 164 0 1130592336 64443648 16631072
05:35:08 893 8192000000 10014287968 10397966336 1054112736 11.400 100 87 170 2 1135310288 63953488 17244064
05:36:10 993 8192000000 9969384032 10344312832 1040916016 11.273 99 85 158 6 1092235440 33746976 17572448
05:37:13 2782 8192000000 9966491056 10350768128 1046544768 11.328 99 65 122 0 1094050656 35668128 11837760
05:38:33 32451 8192000000 10043013776 10428514304 1056205728 11.420 100 68 132 1 1145483008 76176672 13100608
05:40:14 25381 8192000000 10034552432 10424119296 1079148096 11.639 100 65 128 0 1163070928 71369520 12553312
05:41:44 2681 8192000000 10079529168 10467106816 1094399504 11.784 100 73 134 3 1198796336 89520304 14876528
05:42:49 6192 8192000000 10116463968 10516242432 1101314208 11.850 100 60 117 0 1235290720 120995440 12981072
05:43:59 6208 8192000000 10156601504 10552238080 1117973840 12.008 100 64 121 1 1272358544 141245888 13138816
05:45:09 4928 8192000000 10211504544 10610655232 1130159296 12.123 100 65 129 2 1322717184 178293808 14264080
05:46:18 1321 8192000000 10242421552 10634584064 1141620160 12.231 100 96 185 2 1354108832 192248960 20239712
05:47:22 1521 8192000000 10253309808 10675347456 1155046896 12.357 100 90 177 1 1370133056 195684816 19401344
05:48:29 1117 8192000000 10290517904 10688188416 1159778064 12.401 100 95 189 1 1406682624 225766016 21138544
05:49:34 9898 8192000000 10325472672 10729680896 1166366160 12.463 100 76 149 0 1437306048 254008704 16931184
05:50:49 2572 8192000000 10341491632 10768547840 1174241344 12.536 100 88 167 1 1455768880 261415376 20112160
05:51:57 2435 8192000000 10356096144 10773348352 1178050592 12.572 100 83 165 0 1460710048 264012544 18646912
05:53:02 1059 8192000000 10380792400 10783715328 1174456208 12.538 100 96 188 1 1487744816 291084128 22204480
05:54:07 1059 8192000000 10419643136 10843656192 1184088864 12.628 100 97 187 0 1529603968 323298384 22216720
05:55:10 735 8192000000 10451213008 10861293568 1180901568 12.599 100 98 194 2 1559063376 355091824 23069984
05:56:14 1316 8192000000 10485058560 10898763776 1179932048 12.590 100 94 184 0 1594115568 391207584 22975936
05:57:21 1607 8192000000 10454423712 10913656832 1186245136 12.648 100 83 161 0 1562900416 356688448 19966832
05:58:27 1023 8192000000 10469339440 10928496640 1191853104 12.701 100 93 186 0 1579023200 365190768 21979328
05:59:29 1677 8192000000 10443903200 10945990656 1188654272 12.671 100 77 154 1 1549845840 341802704 19388864
06:00:32 6934 8192000000 10437409888 10869055488 1186387568 12.650 100 47 91 0 1540537952 342644096 11506288
06:01:48 10156 8192000000 10398056400 10866982912 1164876240 12.449 100 43 82 0 1516316832 340720400 10720192
06:03:40 17779 8192000000 10269647168 10792087552 1141449184 12.229 99 31 60 0 1372486688 222825936 8211568
06:04:59 26744 8192000000 10220871040 10677760000 1120193504 12.029 98 26 50 0 1323611280 197536032 5881744
06:06:27 105371 8192000000 10043850128 10523934720 1077043888 11.619 95 15 26 2 1180540304 100278288 3218128
06:09:14 116998 8192000000 10127698080 10565058560 1120914496 12.036 99 59 110 1 1197154128 63761120 12478512
06:12:13 53879 8192000000 10105323456 10583281664 1122032736 12.046 99 21 42 1 1209340064 82398480 4908848
06:14:09 75880 8192000000 10102643360 10547314688 1072498992 11.576 95 16 31 2 1158497728 82165600 3833136
06:16:37 25902 8192000000 10027095808 10463870976 1070971840 11.561 96 24 45 1 1106473632 30159008 5342784
06:18:03 64385 8192000000 10066387328 10524520448 1091651792 11.758 98 13 25 0 1145033600 50570464 2811344
06:20:08 148028 8192000000 10068211008 10551406592 1099644816 11.834 98 22 44 0 1164190880 59857504 4688560
06:23:38 3556 8192000000 10040428608 10482057216 1105387664 11.889 99 40 79 0 1139224000 25058864 8777472
06:24:43 74432 8192000000 10093886384 10564390912 1118260816 12.011 99 16 32 0 1204923328 83414592 3247920
06:27:27 61208 8192000000 10065314368 10550984704 1114743808 11.977 99 11 22 0 1143194592 26460032 1990752
06:29:28 22226 8192000000 10112341936 10541555712 1137947664 12.196 100 52 103 0 1198800880 50518608 10334608
06:30:52 89544 8192000000 10105480688 10557911040 1113670944 11.967 97 22 42 1 1168153552 49845200 4637408
06:33:22 97831 8192000000 10078442992 10523246592 1090942080 11.752 95 20 40 0 1170171024 74944832 4284112
06:36:02 25024 8192000000 10160287648 10590945280 1143118144 12.245 99 39 77 2 1242419456 91419856 7881456
06:37:30 64136 8192000000 10149899744 10612883456 1104111008 11.877 97 20 38 1 1206139408 97248160 4780240
06:39:34 73061 8192000000 10080412320 10502864896 1096590896 11.805 97 26 52 0 1139845616 37681328 5573392
06:41:51 49250 8192000000 10119155584 10550874112 1082911264 11.675 96 50 98 0 1160351536 67220320 10219952
06:43:41 13971 8192000000 10144080832 10595291136 1123346848 12.059 100 40 81 1 1252264256 120076864 8840544
06:44:55 26435 8192000000 10067443200 10576310272 1122618736 12.052 100 3 6 0 1179936720 56815408 502576
06:46:33 56002 8192000000 10087719744 10538954752 1140079008 12.216 100 18 36 1 1194086416 49857152 4150256
06:48:32 90228 8192000000 10121053088 10606211072 1111257648 11.944 97 19 38 0 1214592816 98932432 4402736
06:51:12 14102 8192000000 10101668896 10532847616 1134063632 12.160 100 47 89 2 1206743600 62987200 9692768
06:52:26 65113 8192000000 10110843040 10580578304 1120865360 12.035 98 31 62 1 1215276704 87572320 6839024
06:54:32 75685 8192000000 10114236912 10593935360 1118404448 12.012 98 34 68 1 1217911312 92138816 7368048
06:57:22 58898 8192000000 10078809152 10519179264 1127870176 12.101 99 23 44 0 1171900304 39380896 4649232
06:59:21 30609 8192000000 10123520048 10567647232 1099769312 11.835 96 46 92 0 1177897200 69037184 9090704
07:01:27 88058 8192000000 10081100160 10513657856 1134182144 12.161 99 33 62 1 1197252016 55734608 7335264
07:03:56 23700 8192000000 10073826320 10502631424 1114865264 11.978 98 40 78 3 1151325712 27773552 8686896
07:05:21 73703 8192000000 10069937632 10536448000 1108792336 11.921 98 14 28 0 1152099744 39787968 3519440
07:07:35 42538 8192000000 10059183456 10500993024 1111933552 11.951 98 18 34 1 1154034368 38634000 3466816
07:09:18 110454 8192000000 10090938752 10567602176 1099952384 11.837 97 11 22 0 1167055568 64881216 2221968
07:12:08 54750 8192000000 10111629072 10553200640 1123972432 12.065 98 28 54 1 1197535584 67672944 5890208
07:14:08 43407 8192000000 10099186992 10532950016 1114567872 11.976 97 26 52 2 1166751792 46830016 5353904
07:15:55 39112 8192000000 10080631552 10556911616 1094619616 11.787 97 26 51 1 1165367568 65213104 5534848
07:17:35 33669 8192000000 10105397424 10543833088 1110235088 11.935 99 56 112 0 1176190848 54648496 11307264
07:19:10 31122 8192000000 10158707856 10597261312 1123686608 12.062 99 47 94 2 1252571344 119285552 9599184
07:20:45 30842 8192000000 10096540208 10561556480 1132943664 12.149 100 26 52 1 1201502656 63285072 5273920
07:22:19 119045 8192000000 10061484688 10507272192 1102361872 11.860 97 37 72 1 1140662880 31260528 7040480
07:25:20 87157 8192000000 10061473072 10533916672 1111981664 11.951 98 9 18 1 1146697184 32982592 1732928
07:29:12 90123 8192000000 10134888144 10609504256 1148620352 12.297 100 27 54 3 1221539808 67148864 5770592
07:32:15 46512 8192000000 10159189168 10596777984 1131136656 12.132 99 38 75 1 1247883712 108997216 7749840
07:34:03 49863 8192000000 10116589360 10569621504 1127519280 12.098 98 22 44 1 1202919568 70170640 5229648
07:35:53 50463 8192000000 10141452336 10586140672 1111363584 11.945 97 31 62 0 1241083440 122707840 7012016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-06-2022 07:38:39 to 18-06-2022 07:38:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-06-2022 07:38:55 to 18-06-2022 07: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          215365
MON$OLDEST_ACTIVE               222332
MON$OLDEST_SNAPSHOT             222332
MON$NEXT_TRANSACTION            222332
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                       2013312
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2780 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2780 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2780 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2780 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 ---
:150: 
:151: mscs-test-161.budzdorov.ru    Sat Jun 18 07:37:21 2022
:152:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:153: 
:154: 
:155: mscs-test-161.budzdorov.ru    Sat Jun 18 07:37:27 2022
:156:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:157: 
:158: 
:159: mscs-test-161.budzdorov.ru    Sat Jun 18 07:37:27 2022
:160:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:161: 
--- end of diff output ---

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

18.06.22 07: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-06-18 07:39:00 No severe PSQL-related problems occured
18.06.22 07:39:02. End of report.