Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.03.2023 10:39
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 15005308 kB
MemAvailable 30456780 kB
Buffers 0 kB
Cached 16717656 kB
SwapCached 3084 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.3
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 2023-03-01 10:37:29
DTS_END                         2023-03-01 10:37:29.0650
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55622
TRN_ID                          229663
ATT_ID                          612
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: 9144.48 24282 1097338
customer order: creation 180.87 432 21704
customer order: refuse 15.53 381 1864
order to supplier: creation 47.38 1262 5686
order to supplier: removal 3.32 155606 398
invoice (draft): creation 48.59 2918 5831
invoice (draft): removal 49.96 957 5995
invoice accept: apply 43.43 23332 5212
invoice accept: cancel 44.87 1195 5384
customer reserve: creation 4001.38 224 480165
customer reserve: removal 4399.65 93 527958
realization accept: apply 88.19 255 10583
realization accept: cancel 84.13 143 10096
payment from customer: creation 61.83 153 7420
payment from customer: removal 6.16 117 739
payment to supplier: creation 52.51 175 6301
payment to supplier: removal 3.68 115 442
service: total inventory turnovers 4.23 3634 508
service: total monetary turnovers 7.86 363 943
service: aggregate perf. data 0.88 16914 105
service: refresh index statistics 0.03 277378 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-03-2023 10:39:42 to 01-03-2023 10:39:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7279
TEST_TIME 2 7168
TEST_TIME 3 6733
TEST_TIME 4 6500
TEST_TIME 5 6836
TEST_TIME 6 6579
TEST_TIME 7 6413
TEST_TIME 8 6720
TEST_TIME 9 6607
TEST_TIME 10 6815
TEST_TIME 11 6959
TEST_TIME 12 7010
TEST_TIME 13 7311
TEST_TIME 14 7690
TEST_TIME 15 7763
TEST_TIME 16 7947
TEST_TIME 17 8162
TEST_TIME 18 8260
TEST_TIME 19 8530
TEST_TIME 20 8647
TEST_TIME 21 8550
TEST_TIME 22 8541
TEST_TIME 23 8526
TEST_TIME 24 8701
TEST_TIME 25 8594
TEST_TIME 26 9050
TEST_TIME 27 9459
TEST_TIME 28 9707
TEST_TIME 29 10132
TEST_TIME 30 10147
TEST_TIME 31 10643
TEST_TIME 32 10448
TEST_TIME 33 10631
TEST_TIME 34 10649
TEST_TIME 35 11035
TEST_TIME 36 11303
TEST_TIME 37 11090
TEST_TIME 38 10997
TEST_TIME 39 10890
TEST_TIME 40 10901
TEST_TIME 41 10836
TEST_TIME 42 10744
TEST_TIME 43 10648
TEST_TIME 44 10727
TEST_TIME 45 10632
TEST_TIME 46 10540
TEST_TIME 47 10421
TEST_TIME 48 10266
TEST_TIME 49 10266
TEST_TIME 50 10248
TEST_TIME 51 10185
TEST_TIME 52 10177
TEST_TIME 53 10110
TEST_TIME 54 10115
TEST_TIME 55 10029
TEST_TIME 56 9965
TEST_TIME 57 9909
TEST_TIME 58 9797
TEST_TIME 59 9672
TEST_TIME 60 9696
TEST_TIME 61 9722
TEST_TIME 62 9706
TEST_TIME 63 9650
TEST_TIME 64 9654
TEST_TIME 65 9670
TEST_TIME 66 9719
TEST_TIME 67 9897
TEST_TIME 68 9850
TEST_TIME 69 9806
TEST_TIME 70 9829
TEST_TIME 71 9994
TEST_TIME 72 9945
TEST_TIME 73 9960
TEST_TIME 74 9900
TEST_TIME 75 9823
TEST_TIME 76 9749
TEST_TIME 77 9665
TEST_TIME 78 9665
TEST_TIME 79 9582
TEST_TIME 80 9545
TEST_TIME 81 9493
TEST_TIME 82 9492
TEST_TIME 83 9435
TEST_TIME 84 9456
TEST_TIME 85 9422
TEST_TIME 86 9389
TEST_TIME 87 9507
TEST_TIME 88 9527
TEST_TIME 89 9513
TEST_TIME 90 9467
TEST_TIME 91 9547
TEST_TIME 92 9543
TEST_TIME 93 9604
TEST_TIME 94 9551
TEST_TIME 95 9465
TEST_TIME 96 9467
TEST_TIME 97 9410
TEST_TIME 98 9375
TEST_TIME 99 9361
TEST_TIME 100 9341
TEST_TIME 101 9317
TEST_TIME 102 9306
TEST_TIME 103 9265
TEST_TIME 104 9233
TEST_TIME 105 9233
TEST_TIME 106 9238
TEST_TIME 107 9250
TEST_TIME 108 9250
TEST_TIME 109 9297
TEST_TIME 110 9405
TEST_TIME 111 9480
TEST_TIME 112 9440
TEST_TIME 113 9364
TEST_TIME 114 9307
TEST_TIME 115 9343
TEST_TIME 116 9304
TEST_TIME 117 9275
TEST_TIME 118 9274
TEST_TIME 119 9240
TEST_TIME 120 9177

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-03-2023 10:39:45 to 01-03-2023 10:39:49.

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 1541267 1541267 0 0.00 0 15981 12 0 0
doc_list_biud 1541254 1541254 0 0.00 0 25474 38 0 0
sp_add_invoice_to_stock 5297 5212 85 1.60 40 202574 23332 0 85
sp_cancel_adding_invoice 5384 5384 0 0.00 7 81865 1195 0 0
sp_cancel_client_order 1864 1864 0 0.00 6 11411 381 0 0
sp_cancel_customer_reserve 527958 527958 0 0.00 1 25487 94 0 0
sp_cancel_pay_from_customer 739 739 0 0.00 3 5550 117 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 4 2852 115 0 0
sp_cancel_supplier_invoice 6008 5995 13 0.21 2 16214 957 0 13
sp_cancel_supplier_order 398 398 0 0.00 10 1336145 155606 0 0
sp_cancel_write_off 10096 10096 0 0.00 2 21095 143 0 0
sp_client_order 21704 21704 0 0.00 6 22795 432 0 0
sp_customer_reserve 480165 480165 0 0.00 0 61512 224 0 0
sp_fill_shopping_cart 33217 33217 0 0.00 1 16839 74 0 0
sp_fill_shopping_cart_clo_res 480128 480128 0 0.00 0 19926 17 0 0
sp_get_clo_for_invoice 5168 5168 0 0.00 0 26486 250 0 0
sp_kill_cost_storno 16661 16661 0 0.00 0 5380 60 0 0
sp_kill_qstorno_ret_qs2qd 1072405 1072405 0 0.00 0 25460 42 0 0
sp_kill_qty_storno 567445 567445 0 0.00 0 25470 95 0 0
sp_lock_selected_doc 21818 21818 0 0.00 0 8262 8 0 0
sp_make_cost_storno 29472 29472 0 0.00 0 11813 73 0 0
sp_multiply_rows_for_pdistr 29471 29471 0 0.00 0 8213 33 0 0
sp_multiply_rows_for_qdistr 21704 21704 0 0.00 0 11258 167 0 0
sp_pay_from_customer 7420 7420 0 0.00 5 10849 153 0 0
sp_pay_to_supplier 6301 6301 0 0.00 6 12550 176 0 0
sp_payment_common 13721 13721 0 0.00 2 10843 43 0 0
sp_qd_handle_on_cancel_clo 1864 1864 0 0.00 1 11403 313 0 0
sp_qd_handle_on_invoice_upd_sts 10551 10551 0 0.00 0 20316 470 0 0
sp_qd_handle_on_reserve_upd_sts 10583 10583 0 0.00 0 24228 58 0 0
sp_reserve_write_off 10583 10583 0 0.00 8 24305 256 0 0
sp_supplier_invoice 5831 5831 0 0.00 10 91474 2918 0 0
sp_supplier_order 5686 5686 0 0.00 7 18170 1263 0 0
srv_aggregate_perf_data 105 105 0 0.00 2362 184209 16915 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 790 128842 35667 0 0
srv_find_qd_qs_mism 1046912 1046912 0 0.00 0 15980 9 0 0
srv_make_invnt_saldo 508 508 0 0.00 58 92107 3634 0 0
srv_make_money_saldo 943 943 0 0.00 1 11526 363 0 0
srv_recalc_idx_stat 4 4 0 0.00 80419 442134 277378 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5220 5220 0 0.00 0 15021 815 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 1 6608 417 0 0
t$perf-norm:sp_cancel_client_order 1864 1864 0 0.00 0 1852 24 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 2867 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 739 739 0 0.00 0 2822 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 1230 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 1146 29 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 1 85458 6317 0 0
t$perf-norm:sp_cancel_write_off 1065 1065 0 0.00 0 1241 16 0 0
t$perf-norm:sp_client_order 21704 21704 0 0.00 0 16397 37 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 3122 21 0 0
t$perf-norm:sp_pay_from_customer 7420 7420 0 0.00 0 2785 17 0 0
t$perf-norm:sp_pay_to_supplier 6301 6301 0 0.00 0 9051 22 0 0
t$perf-norm:sp_reserve_write_off 10583 10583 0 0.00 0 7111 21 0 0
t$perf-norm:sp_supplier_invoice 5831 5831 0 0.00 0 10572 28 0 0
t$perf-norm:sp_supplier_order 5687 5687 0 0.00 0 7193 30 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 1058 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 20 2 0 0
t$perf-norm:srv_make_invnt_saldo 508 508 0 0.00 0 2375 22 0 0
t$perf-norm:srv_make_money_saldo 943 943 0 0.00 0 370 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 302 81 0 0
v_add_invoice_to_stock 10500 10330 170 1.61 0 3724 22 0 170
v_all_customers 39755 39755 0 0.00 0 66 3 0 0
v_all_suppliers 32754 32754 0 0.00 0 66 4 0 0
v_all_wares 43406 43406 0 0.00 0 52 4 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 979 14 0 0
v_cancel_client_order 3728 3728 0 0.00 0 4124 10 0 0
v_cancel_customer_prepayment 1478 1478 0 0.00 0 2092 12 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 1 4026 13 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 2757 13 0 0
v_cancel_supplier_invoice 854 828 26 3.04 0 1131 21 0 26
v_cancel_supplier_order 796 796 0 0.00 0 2747 14 0 0
v_cancel_write_off 2130 2130 0 0.00 0 2835 26 0 0
v_max_id_clo_ord 5685 5685 0 0.00 0 3340 5 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 1252 12 0 0
v_max_id_ord_sup 5829 5829 0 0.00 0 4177 7 0 0
v_max_non_paid_invoice 1437 1437 0 0.00 0 156 7 0 0
v_max_non_paid_realizn 3864 3864 0 0.00 0 5301 8 0 0
v_min_id_clo_ord 5685 5685 0 0.00 0 5138 8 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 7075 16 0 0
v_min_id_ord_sup 5829 5829 0 0.00 0 7228 13 0 0
v_min_non_paid_invoice 6301 6301 0 0.00 0 7861 20 0 0
v_min_non_paid_realizn 7420 7420 0 0.00 0 7089 14 0 0
v_our_firm 10760 10760 0 0.00 0 70 4 0 0
v_reserve_write_off 21166 21166 0 0.00 0 5458 10 0 0
x_lock_depdocs_on_canc_invoice 10965 10965 0 0.00 0 10424 116 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 7934 345 0 0
x_make_qstorno_1000_1200 5686 5686 0 0.00 2 17442 1113 0 0
x_make_qstorno_1200_2000 5831 5831 0 0.00 5 85305 2716 0 0
x_make_qstorno_2100_3300 480163 480163 0 0.00 0 61489 196 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-03-2023 10:39:50 to 01-03-2023 10:39:54.

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:32 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:33 24456 8192000000 9727586672 10028249088 799041072 8.887 100 82 164 0 826601648 7359600 20200976
08:10:07 17698 8192000000 9759687728 10068013056 833410864 9.234 100 83 166 0 865749376 12940240 19398272
08:11:30 8153 8192000000 9797852448 10113286144 863826048 9.538 100 90 180 0 907629360 22957824 20845488
08:12:40 9217 8192000000 9855416880 10187849728 908114400 9.979 100 79 156 0 964502080 39055760 17331920
08:13:54 6429 8192000000 9900871488 10244567040 950372256 10.395 100 79 157 0 1022028768 55138768 16517744
08:15:05 1987 8192000000 9959395072 10309902336 989431824 10.776 100 89 174 1 1072687312 65357360 17898128
08:16:08 1391 8192000000 9990406384 10351226880 1013558752 11.010 100 94 182 2 1115536736 84355952 17622032
08:17:12 1220 8192000000 10013070256 10382618624 1038694496 11.252 100 95 183 0 1133505248 76154176 18656576
08:18:14 1019 8192000000 10025484048 10401824768 1053415312 11.393 100 95 181 1 1150042992 79290656 17337024
08:19:16 1087 8192000000 10039877008 10414776320 1065190528 11.506 100 92 178 1 1156947792 73546816 18210448
08:20:19 1806 8192000000 10017816992 10393145344 1074810176 11.598 100 78 148 2 1136439776 47196000 14433600
08:21:22 1236 8192000000 10037122064 10416455680 1080215552 11.650 100 89 166 1 1156271696 59109776 16946368
08:22:24 3333 8192000000 10032725392 10425597952 1081706000 11.664 100 67 127 0 1156574608 62427248 12441360
08:23:28 4502 8192000000 10030869024 10433658880 1089624736 11.739 100 64 108 2 1163206048 62397456 11183856
08:24:36 941 8192000000 10036890368 10432225280 1090319632 11.746 100 80 155 3 1162495216 55944192 16231392
08:25:40 1168 8192000000 10053540064 10445250560 1096218656 11.802 100 90 170 2 1173595680 58480128 18896896
08:26:42 1397 8192000000 10092126576 10484240384 1097929712 11.818 100 88 171 1 1215768704 99509920 18329072
08:27:44 997 8192000000 10053023072 10443517952 1098876928 11.827 100 84 153 2 1174450480 59937136 15636416
08:28:46 1480 8192000000 10039172352 10431016960 1089310128 11.736 100 85 161 0 1158900832 52268224 17322480
08:29:49 1334 8192000000 10034670000 10415935488 1071643712 11.568 100 94 176 2 1155492400 64938448 18910240
08:30:51 1333 8192000000 10023956640 10407948288 1071703312 11.568 100 76 143 4 1155473280 69412160 14357808
08:31:53 1709 8192000000 9999013840 10380824576 1051138720 11.372 100 75 141 3 1115550832 49757936 14654176
08:32:56 1757 8192000000 9991566256 10368565248 1052189840 11.382 99 84 162 2 1119426960 51143536 16093584
08:34:00 1211 8192000000 9984064832 10357010432 1033012192 11.197 99 92 176 4 1106603056 55513024 18077840
08:35:02 887 8192000000 9978471200 10354782208 1044341968 11.306 100 87 167 1 1104942768 41245152 19355648
08:36:04 873 8192000000 9946875168 10321104896 1025123184 11.121 100 81 154 2 1076190192 33870816 17196192
08:37:07 1441 8192000000 10021618976 10402553856 1048034160 11.342 100 84 160 5 1142225504 78127104 16064240
08:38:16 12235 8192000000 9996071296 10377854976 1057961648 11.437 99 59 104 3 1114769568 46696512 10111408
08:39:31 4851 8192000000 10056772112 10437586944 1087543984 11.719 100 74 138 5 1168873264 66786464 14542816
08:40:40 11674 8192000000 10066092112 10459152384 1096359856 11.803 100 56 102 0 1180916960 73683392 10873712
08:42:03 1824 8192000000 10113791664 10506739712 1111840352 11.950 100 89 163 0 1229344304 100634672 16869280
08:43:11 9377 8192000000 10140654304 10546860032 1120839392 12.035 100 62 118 0 1258645392 123970768 13835232
08:44:27 2276 8192000000 10148265680 10553880576 1124360800 12.068 100 77 145 1 1267738128 127942000 15435328
08:45:34 4182 8192000000 10168021296 10574909440 1139598464 12.212 100 71 137 0 1291123040 136658336 14866240
08:46:41 4643 8192000000 10222714032 10631225344 1157530080 12.380 100 70 138 0 1336528848 163070352 15928416
08:47:50 1516 8192000000 10269483264 10670751744 1169337264 12.491 100 92 180 1 1382580064 192669072 20573728
08:48:56 1047 8192000000 10299806320 10702991360 1170338400 12.500 100 89 173 2 1408729440 218747264 19643776
08:50:01 1069 8192000000 10317254944 10738446336 1180281792 12.593 100 96 188 4 1429855328 227414176 22159360
08:51:05 776 8192000000 10328227136 10761162752 1184583136 12.633 100 96 183 1 1436881248 231362784 20935328
08:52:07 1866 8192000000 10327631008 10737111040 1172992432 12.525 99 85 158 0 1434656720 243925504 17738784
08:53:12 3932 8192000000 10354625920 10793402368 1185524032 12.642 100 73 140 0 1468794080 267277792 15992256
08:54:21 970 8192000000 10400663008 10830213120 1197703568 12.755 100 97 188 2 1503801904 284018560 22079776
08:55:25 1044 8192000000 10411127296 10842103808 1204710640 12.820 100 95 183 2 1520453728 294147744 21595344
08:56:29 1272 8192000000 10444817440 10866139136 1206503552 12.837 100 90 177 0 1551580048 323364544 21711952
08:57:36 3689 8192000000 10396839632 10833600512 1193284368 12.714 99 41 82 0 1503874640 300355776 10234496
08:58:42 21478 8192000000 10471777472 10927050752 1211866112 12.886 100 52 101 0 1581059104 355549088 13643904
09:00:07 15404 8192000000 10486538096 10917982208 1181768544 12.607 99 50 97 1 1577568800 383725424 12074832
09:01:33 8659 8192000000 10476316928 10956079104 1175851024 12.551 99 40 79 0 1577166944 390890688 10425232
09:02:49 13495 8192000000 10491393936 10971406336 1167877760 12.477 99 43 84 1 1602474560 423187696 11409104
09:04:04 14598 8192000000 10419329888 10911637504 1158173872 12.386 99 37 72 0 1540052256 372562288 9316096
09:05:29 15676 8192000000 10331408864 10815692800 1143877312 12.252 100 29 58 0 1446705984 295438464 7390208
09:06:47 31226 8192000000 10242635168 10735259648 1122668576 12.052 98 33 64 1 1356195744 226181760 7345408
09:08:34 15920 8192000000 10244207072 10703040512 1170504320 12.502 100 49 98 0 1347860768 165876976 11479472
09:09:53 32815 8192000000 10284375776 10772086784 1167418992 12.473 100 23 46 0 1411377744 237653792 6304960
09:12:32 82995 8192000000 10106231168 10671398912 1118277504 12.011 97 13 26 0 1251958336 129907840 3772992
09:14:57 68611 8192000000 10129965744 10594369536 1116079024 11.990 97 25 49 2 1188986784 68156000 4751760
09:17:28 30050 8192000000 10087128400 10511581184 1133729472 12.157 100 35 60 0 1178653056 38963504 5960080
09:19:00 99716 8192000000 10156836592 10615189504 1131458736 12.135 98 17 30 0 1225072448 90083008 3530704
09:21:54 128839 8192000000 10084397344 10529214464 1101865968 11.855 97 36 66 0 1163024656 54653824 6504864
09:25:08 83539 8192000000 10062939952 10499448832 1094427632 11.785 97 31 61 0 1144053552 43490000 6135920
09:27:33 11217 8192000000 10088980560 10523471872 1130792528 12.129 100 38 74 0 1186919024 48929392 7197104
09:28:46 21329 8192000000 10104404240 10530750464 1122981904 12.055 99 48 93 0 1181190448 48453744 9754800
09:30:12 49610 8192000000 10083112016 10543144960 1100901552 11.846 98 24 43 1 1163616240 57378768 5335920
09:32:05 16270 8192000000 10047945648 10486910976 1110335104 11.936 100 42 75 1 1137688336 18792608 8560624
09:33:32 44390 8192000000 10079471168 10527854592 1125490368 12.079 100 8 12 0 1178753920 52031712 1231840
09:35:17 113164 8192000000 10086951168 10573307904 1122929872 12.055 99 26 46 1 1220667120 93216928 4520320
09:38:11 34300 8192000000 10034046928 10481893376 1103965008 11.875 98 16 32 0 1132334032 25123648 3245376
09:39:46 43515 8192000000 10105190736 10546917376 1145456112 12.267 100 24 46 0 1185372688 35234128 4682448
09:41:33 20208 8192000000 10148418144 10594205696 1151439568 12.323 100 34 68 0 1249030560 89774304 7816688
09:42:58 32646 8192000000 10138304512 10594394112 1150770080 12.317 100 28 53 0 1261571712 104743024 6058608
09:44:43 65013 8192000000 10102153776 10545180672 1104831088 11.883 96 18 36 0 1147873456 39434512 3607856
09:47:04 24803 8192000000 10150703296 10610974720 1144897008 12.262 100 29 56 1 1246533088 95337984 6298096
09:48:30 59111 8192000000 10062501312 10506403840 1089316896 11.736 96 22 40 0 1162461712 68922864 4221952
09:50:33 9117 8192000000 10098193472 10525581312 1121529264 12.041 100 37 74 0 1192084576 63353776 7201536
09:51:44 95027 8192000000 10040555648 10471456768 1110260080 11.935 99 42 80 0 1161235472 42513184 8462208
09:54:22 5256 8192000000 10055289376 10483007488 1116409328 11.993 100 59 106 1 1163093184 34866384 11817472
09:55:32 58922 8192000000 10037855488 10499088384 1080009088 11.648 97 9 19 0 1132035328 50243792 1782448
09:57:31 39817 8192000000 10048770896 10482806784 1082633952 11.673 98 14 28 0 1128035888 42368288 3033648
09:59:38 46142 8192000000 10048159120 10492936192 1094664320 11.787 99 21 42 0 1140557440 41220736 4672384
10:01:26 23352 8192000000 10065404000 10515353600 1104046224 11.876 99 31 63 0 1168299472 57687600 6565648
10:02:51 28223 8192000000 10099763904 10527354880 1066336320 11.517 96 42 80 0 1154128784 80101072 7691392
10:04:28 36275 8192000000 10057914480 10537152512 1092069152 11.762 99 29 56 0 1180178944 82135504 5974288
10:06:08 39260 8192000000 10044853552 10478403584 1076998960 11.619 97 32 56 2 1127234400 42715760 7519680
10:07:50 76907 8192000000 10088340928 10543345664 1079031472 11.638 96 30 58 1 1138654160 53915536 5707152
10:10:11 62009 8192000000 10077539136 10503409664 1092539904 11.767 97 27 53 1 1139387728 42020672 4827152
10:12:13 64990 8192000000 10049180688 10502901760 1088777040 11.731 97 46 87 0 1155428896 57893808 8758048
10:14:32 43907 8192000000 10038923616 10477666304 1105700864 11.892 99 17 29 3 1129518704 19451152 4366688
10:16:29 76778 8192000000 10060126624 10502639616 1091621952 11.758 97 18 36 1 1120325952 24972928 3731072
10:18:45 47397 8192000000 10099207504 10540920832 1103342672 11.869 97 30 60 0 1185689584 75784400 6562512
10:20:34 34537 8192000000 10154161296 10596028416 1144120160 12.254 100 30 55 0 1243420816 93173600 6127056
10:22:18 22400 8192000000 10207869808 10663849984 1155631136 12.362 100 41 80 0 1306348480 141872464 8844880
10:24:03 30829 8192000000 10176111488 10627383296 1124809840 12.072 98 32 63 1 1264031696 132134960 7086896
10:25:42 58881 8192000000 10179156000 10651496448 1123660720 12.062 98 26 53 1 1283741456 153888688 6192048
10:27:42 90150 8192000000 10104336048 10557452288 1116383888 11.993 98 29 58 0 1199655168 76880368 6390912
10:30:17 74391 8192000000 10108826064 10563526656 1134553264 12.164 100 16 32 0 1198085248 60180752 3351232
10:32:35 92482 8192000000 10089213216 10558603264 1126406576 12.087 99 16 32 0 1181532160 51497536 3628048
10:35:22 72494 8192000000 10072226480 10559840256 1123988464 12.065 99 11 20 0 1182204224 55796752 2419008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 01-03-2023 10:39:56 to 01-03-2023 10:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-03-2023 10:40:13 to 01-03-2023 10:40:15.

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          228808
MON$OLDEST_ACTIVE               230021
MON$OLDEST_SNAPSHOT             230021
MON$NEXT_TRANSACTION            230021
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                       2067360
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2904 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2904 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2904 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2904 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 ---
--- end of diff output ---

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

01.03.23 10:40:17. 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
2023-03-01 10:40:19 No severe PSQL-related problems occured
01.03.23 10:40:21. End of report.