Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.02.2023 07: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 15164120 kB
MemAvailable 30453712 kB
Buffers 0 kB
Cached 16563344 kB
SwapCached 5056 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-02-10 07:37:24
DTS_END                         2023-02-10 07:37:24.6520
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/43554
TRN_ID                          214991
ATT_ID                          592
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: 8939.75 24043 1072770
customer order: creation 163.03 475 19564
customer order: refuse 13.61 381 1633
order to supplier: creation 43.73 1432 5248
order to supplier: removal 3.25 169662 390
invoice (draft): creation 42.57 3189 5108
invoice (draft): removal 50.73 956 6088
invoice accept: apply 40.29 27145 4835
invoice accept: cancel 46.20 1080 5544
customer reserve: creation 3750.79 260 450095
customer reserve: removal 4506.33 95 540759
realization accept: apply 78.68 271 9442
realization accept: cancel 78.36 161 9403
payment from customer: creation 55.03 176 6604
payment from customer: removal 5.56 115 667
payment to supplier: creation 45.94 217 5513
payment to supplier: removal 3.32 129 398
service: total inventory turnovers 4.05 3752 486
service: total monetary turnovers 7.33 333 880
service: aggregate perf. data 0.90 14056 108
service: refresh index statistics 0.04 256979 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-02-2023 07:40:13 to 10-02-2023 07:40:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7325
TEST_TIME 2 5618
TEST_TIME 3 5800
TEST_TIME 4 6284
TEST_TIME 5 5931
TEST_TIME 6 5843
TEST_TIME 7 5827
TEST_TIME 8 5908
TEST_TIME 9 6178
TEST_TIME 10 6386
TEST_TIME 11 6351
TEST_TIME 12 6375
TEST_TIME 13 6411
TEST_TIME 14 6715
TEST_TIME 15 6996
TEST_TIME 16 7363
TEST_TIME 17 7570
TEST_TIME 18 7753
TEST_TIME 19 7970
TEST_TIME 20 7884
TEST_TIME 21 7762
TEST_TIME 22 7751
TEST_TIME 23 8044
TEST_TIME 24 8465
TEST_TIME 25 8520
TEST_TIME 26 9178
TEST_TIME 27 9622
TEST_TIME 28 10233
TEST_TIME 29 10811
TEST_TIME 30 11339
TEST_TIME 31 11574
TEST_TIME 32 11411
TEST_TIME 33 11179
TEST_TIME 34 11043
TEST_TIME 35 10845
TEST_TIME 36 10730
TEST_TIME 37 10605
TEST_TIME 38 10823
TEST_TIME 39 10665
TEST_TIME 40 10522
TEST_TIME 41 10342
TEST_TIME 42 10288
TEST_TIME 43 10339
TEST_TIME 44 10239
TEST_TIME 45 10176
TEST_TIME 46 10083
TEST_TIME 47 9966
TEST_TIME 48 9958
TEST_TIME 49 9895
TEST_TIME 50 9823
TEST_TIME 51 9861
TEST_TIME 52 9733
TEST_TIME 53 9661
TEST_TIME 54 9632
TEST_TIME 55 9561
TEST_TIME 56 9480
TEST_TIME 57 9388
TEST_TIME 58 9321
TEST_TIME 59 9234
TEST_TIME 60 9265
TEST_TIME 61 9372
TEST_TIME 62 9326
TEST_TIME 63 9300
TEST_TIME 64 9386
TEST_TIME 65 9480
TEST_TIME 66 9440
TEST_TIME 67 9411
TEST_TIME 68 9416
TEST_TIME 69 9488
TEST_TIME 70 9489
TEST_TIME 71 9441
TEST_TIME 72 9463
TEST_TIME 73 9491
TEST_TIME 74 9458
TEST_TIME 75 9352
TEST_TIME 76 9384
TEST_TIME 77 9327
TEST_TIME 78 9295
TEST_TIME 79 9279
TEST_TIME 80 9262
TEST_TIME 81 9178
TEST_TIME 82 9251
TEST_TIME 83 9212
TEST_TIME 84 9187
TEST_TIME 85 9214
TEST_TIME 86 9179
TEST_TIME 87 9155
TEST_TIME 88 9169
TEST_TIME 89 9163
TEST_TIME 90 9170
TEST_TIME 91 9228
TEST_TIME 92 9223
TEST_TIME 93 9316
TEST_TIME 94 9274
TEST_TIME 95 9342
TEST_TIME 96 9273
TEST_TIME 97 9206
TEST_TIME 98 9218
TEST_TIME 99 9211
TEST_TIME 100 9175
TEST_TIME 101 9122
TEST_TIME 102 9113
TEST_TIME 103 9060
TEST_TIME 104 9048
TEST_TIME 105 9028
TEST_TIME 106 9011
TEST_TIME 107 9013
TEST_TIME 108 8996
TEST_TIME 109 8962
TEST_TIME 110 9028
TEST_TIME 111 9049
TEST_TIME 112 9043
TEST_TIME 113 9052
TEST_TIME 114 9041
TEST_TIME 115 9031
TEST_TIME 116 9094
TEST_TIME 117 9094
TEST_TIME 118 9065
TEST_TIME 119 9013
TEST_TIME 120 8968

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-02-2023 07:40:17 to 10-02-2023 07:40:21.

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 1486304 1486304 0 0.00 0 35942 14 0 0
doc_list_biud 1486287 1486287 0 0.00 0 46056 40 0 0
sp_add_invoice_to_stock 4994 4835 159 3.18 46 200616 27146 0 159
sp_cancel_adding_invoice 5544 5544 0 0.00 6 91278 1081 0 0
sp_cancel_client_order 1633 1633 0 0.00 6 9671 381 0 0
sp_cancel_customer_reserve 540759 540759 0 0.00 1 33564 96 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 6285 115 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 4 7498 129 0 0
sp_cancel_supplier_invoice 6101 6088 13 0.21 2 46064 957 0 13
sp_cancel_supplier_order 390 390 0 0.00 7 1437612 169662 0 0
sp_cancel_write_off 9403 9403 0 0.00 2 6974 161 0 0
sp_client_order 19564 19564 0 0.00 5 36855 476 0 0
sp_customer_reserve 450095 450095 0 0.00 0 75634 260 0 0
sp_fill_shopping_cart 29907 29907 0 0.00 1 27521 82 0 0
sp_fill_shopping_cart_clo_res 450051 450051 0 0.00 0 14503 19 0 0
sp_get_clo_for_invoice 4786 4786 0 0.00 0 22483 313 0 0
sp_kill_cost_storno 16012 16012 0 0.00 0 6970 71 0 0
sp_kill_qstorno_ret_qs2qd 1097399 1097399 0 0.00 0 46055 41 0 0
sp_kill_qty_storno 578043 578043 0 0.00 0 46056 94 0 0
sp_lock_selected_doc 19672 19672 0 0.00 0 9256 7 0 0
sp_make_cost_storno 26344 26344 0 0.00 0 25894 86 0 0
sp_multiply_rows_for_pdistr 26341 26341 0 0.00 0 14873 39 0 0
sp_multiply_rows_for_qdistr 19564 19564 0 0.00 0 12829 169 0 0
sp_pay_from_customer 6604 6604 0 0.00 5 10884 176 0 0
sp_pay_to_supplier 5513 5513 0 0.00 7 15812 217 0 0
sp_payment_common 12115 12115 0 0.00 2 7149 46 0 0
sp_qd_handle_on_cancel_clo 1633 1633 0 0.00 1 9661 306 0 0
sp_qd_handle_on_invoice_upd_sts 10330 10330 0 0.00 0 22738 460 0 0
sp_qd_handle_on_reserve_upd_sts 9440 9440 0 0.00 0 11182 58 0 0
sp_reserve_write_off 9442 9442 0 0.00 8 17461 272 0 0
sp_supplier_invoice 5108 5108 0 0.00 10 94115 3189 0 0
sp_supplier_order 5248 5248 0 0.00 8 74990 1432 0 0
srv_aggregate_perf_data 108 108 0 0.00 2624 147002 14057 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 832 162239 40158 0 0
srv_find_qd_qs_mism 1025601 1025601 0 0.00 0 35940 11 0 0
srv_make_invnt_saldo 486 486 0 0.00 51 106709 3752 0 0
srv_make_money_saldo 880 880 0 0.00 1 16908 334 0 0
srv_recalc_idx_stat 5 5 0 0.00 25061 596706 256979 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 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 4841 4841 0 0.00 0 24796 820 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 1 7948 408 0 0
t$perf-norm:sp_cancel_client_order 1633 1633 0 0.00 0 6111 27 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 5609 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 419 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 1986 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 4971 41 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 104401 7040 0 0
t$perf-norm:sp_cancel_write_off 892 892 0 0.00 0 1563 19 0 0
t$perf-norm:sp_client_order 19564 19564 0 0.00 0 8040 32 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 8091 22 0 0
t$perf-norm:sp_pay_from_customer 6604 6604 0 0.00 0 8209 19 0 0
t$perf-norm:sp_pay_to_supplier 5513 5513 0 0.00 0 6913 20 0 0
t$perf-norm:sp_reserve_write_off 9443 9443 0 0.00 0 9013 25 0 0
t$perf-norm:sp_supplier_invoice 5108 5108 0 0.00 0 7100 27 0 0
t$perf-norm:sp_supplier_order 5248 5248 0 0.00 0 4543 30 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 466 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 88 4 0 0
t$perf-norm:srv_make_invnt_saldo 486 486 0 0.00 0 1536 16 0 0
t$perf-norm:srv_make_money_saldo 880 880 0 0.00 0 4792 12 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 315 90 0 0
v_add_invoice_to_stock 9884 9566 318 3.21 0 8226 26 0 318
v_all_customers 35704 35704 0 0.00 0 97 4 0 0
v_all_suppliers 29194 29194 0 0.00 0 97 4 0 0
v_all_wares 39126 39126 0 0.00 0 85 4 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 630 14 0 0
v_cancel_client_order 3266 3266 0 0.00 0 4597 14 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 4526 13 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 9951 26 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 7400 22 0 0
v_cancel_supplier_invoice 778 752 26 3.34 0 2896 25 0 26
v_cancel_supplier_order 780 780 0 0.00 0 2227 15 0 0
v_cancel_write_off 1784 1784 0 0.00 0 2889 28 0 0
v_max_id_clo_ord 5244 5244 0 0.00 0 345 4 0 0
v_max_id_clo_res 956 956 0 0.00 0 3004 17 0 0
v_max_id_ord_sup 5100 5100 0 0.00 0 7080 8 0 0
v_max_non_paid_invoice 1258 1258 0 0.00 0 5288 12 0 0
v_max_non_paid_realizn 3451 3451 0 0.00 0 4011 10 0 0
v_min_id_clo_ord 5244 5244 0 0.00 0 4560 8 0 0
v_min_id_clo_res 956 956 0 0.00 0 5391 20 0 0
v_min_id_ord_sup 5100 5100 0 0.00 0 5993 10 0 0
v_min_non_paid_invoice 5511 5511 0 0.00 0 7146 18 0 0
v_min_non_paid_realizn 6604 6604 0 0.00 0 7060 19 0 0
v_our_firm 9728 9728 0 0.00 0 69 4 0 0
v_reserve_write_off 18879 18879 0 0.00 0 10121 14 0 0
x_lock_depdocs_on_canc_invoice 11256 11256 0 0.00 0 12680 110 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 9012 385 0 0
x_make_qstorno_1000_1200 5248 5248 0 0.00 2 74970 1279 0 0
x_make_qstorno_1200_2000 5108 5108 0 0.00 3 93826 2952 0 0
x_make_qstorno_2100_3300 450090 450090 0 0.00 0 75625 230 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-02-2023 07:40:22 to 10-02-2023 07:40:26.

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:28 6 8192000000 8803665264 8816877568 7802880 0.095 3 2 2 0 7910688 72032 35776
05:08:29 27448 8192000000 9711785696 10006757376 786479968 8.759 100 91 180 0 817153600 7476608 23197024
05:10:02 22289 8192000000 9752639552 10057138176 824182848 9.141 100 88 176 0 857381904 11760864 21438192
05:11:29 15718 8192000000 9789845104 10108272640 863651488 9.537 100 86 172 0 902930384 19661136 19617760
05:12:51 8542 8192000000 9868849920 10201026560 906935680 9.967 100 79 159 0 977255840 52948320 17371840
05:14:11 4189 8192000000 9918765264 10262941696 954478304 10.435 100 87 172 1 1025544704 52924832 18141568
05:15:19 1880 8192000000 9964255024 10317434880 991819440 10.799 100 90 169 0 1085959008 76868736 17270832
05:16:23 1681 8192000000 9993445776 10354061312 1017825840 11.051 100 94 175 0 1111112848 75131376 18155632
05:17:26 926 8192000000 10015890896 10382798848 1043682160 11.300 100 97 188 4 1135060992 72494240 18884592
05:18:28 863 8192000000 10048160032 10424561664 1056651888 11.424 100 95 187 1 1170607088 95388528 18566672
05:19:30 964 8192000000 10021520496 10396495872 1067001296 11.523 100 92 175 1 1138017792 52808864 18207632
05:20:32 1369 8192000000 10017177456 10396983296 1072597296 11.577 100 83 148 0 1138771760 51581136 14593328
05:21:35 1208 8192000000 10059725744 10442235904 1078630832 11.634 100 99 192 3 1183189632 84822384 19736416
05:22:37 1056 8192000000 10041965040 10426646528 1084033824 11.686 100 95 177 1 1161591600 59357280 18200496
05:23:39 1344 8192000000 10067007232 10455179264 1087486992 11.719 100 80 161 1 1187749664 84891744 15370928
05:24:42 1241 8192000000 10054597264 10448982016 1091418416 11.756 100 87 163 0 1176178592 68240224 16519952
05:25:44 2253 8192000000 10060167104 10452606976 1094989664 11.790 100 75 136 4 1178672288 68655664 15026960
05:26:48 930 8192000000 10070652672 10462756864 1099074512 11.829 100 95 185 0 1192613872 74617424 18921936
05:27:50 1020 8192000000 10052735184 10441703424 1093859664 11.779 100 91 169 0 1178756352 66713408 18183280
05:28:52 1103 8192000000 10029990512 10417827840 1069947248 11.552 98 95 182 1 1149960832 61250560 18763024
05:29:53 1256 8192000000 10040248640 10421772288 1086334080 11.708 100 96 182 0 1162932928 56917808 19681040
05:30:56 1074 8192000000 10047217136 10427109376 1077563680 11.624 100 96 189 0 1166688688 69914032 19210976
05:31:58 830 8192000000 9991455536 10368356352 1044590816 11.309 100 89 168 1 1116028528 52370816 19066896
05:33:00 1380 8192000000 9990686128 10370293760 1054784128 11.407 100 72 138 2 1114948336 45883392 14280816
05:34:02 1148 8192000000 9986616720 10363871232 1041964416 11.284 100 90 171 3 1108682544 47838320 18879808
05:35:04 1198 8192000000 9980543264 10357043200 1039778544 11.263 100 91 174 3 1103555216 45796848 17979824
05:36:06 1114 8192000000 9995416240 10367844352 1036841824 11.234 100 95 181 0 1115179952 60461184 17876944
05:37:08 1175 8192000000 9987719760 10361753600 1032451536 11.192 100 78 150 0 1109727200 61600336 15675328
05:38:16 20644 8192000000 10017963552 10398285824 1047825408 11.340 99 76 141 1 1135084272 72806144 14452720
05:39:40 20981 8192000000 10055573776 10447912960 1066419760 11.518 99 53 101 0 1167045520 90260816 10364944
05:41:05 1120 8192000000 10099462576 10492821504 1098126864 11.820 100 93 182 1 1220917104 102716368 20073872
05:42:09 3161 8192000000 10148608720 10541608960 1103412752 11.870 100 73 136 1 1260801824 143553072 13836000
05:43:15 13497 8192000000 10128693648 10544955392 1099019744 11.828 99 49 93 0 1236957056 127255408 10681904
05:44:32 838 8192000000 10187782512 10576842752 1122284608 12.049 100 98 184 2 1298825296 158324544 18216144
05:45:36 2249 8192000000 10266235248 10656223232 1141886240 12.233 100 84 159 1 1368645264 209795728 16963296
05:46:44 3114 8192000000 10268870544 10668208128 1152080528 12.329 100 78 154 0 1385638768 216521504 17036736
05:47:49 966 8192000000 10317800560 10711805952 1152139584 12.330 100 97 192 3 1432755472 258395488 22220400
05:48:54 2695 8192000000 10371138320 10777075712 1169392544 12.491 100 78 150 1 1484678608 297279536 18006528
05:50:07 1619 8192000000 10440055952 10845892608 1188700576 12.671 100 91 179 1 1550965296 341397120 20867600
05:51:12 1241 8192000000 10472341616 10915368960 1202142784 12.796 100 96 184 0 1582091728 358701632 21247312
05:52:16 967 8192000000 10473875216 10901188608 1194320912 12.724 100 94 183 0 1579553216 363892368 21339936
05:53:20 821 8192000000 10504372032 10910220288 1187946368 12.664 100 99 193 2 1608432672 398374624 22111680
05:54:25 1526 8192000000 10528765104 10958450688 1202616544 12.801 100 91 179 0 1637235296 413593152 21025600
05:55:30 826 8192000000 10528151072 10959663104 1198912480 12.766 100 93 185 0 1635055200 414240112 21902608
05:56:34 3824 8192000000 10545400128 10968436736 1208453504 12.855 100 72 135 1 1658815424 433257504 17104416
05:57:42 1313 8192000000 10616527856 11034980352 1211234672 12.881 100 86 166 0 1717089600 486659568 19195360
05:58:47 2427 8192000000 10634076304 11064774656 1189967616 12.683 100 83 155 0 1742769600 533931952 18870032
05:59:51 2223 8192000000 10649170368 11098550272 1181222560 12.602 100 80 158 1 1753554416 551453904 20877952
06:01:01 3944 8192000000 10616464640 11105026048 1171427712 12.510 99 62 122 1 1720077184 532349264 16300208
06:02:13 6197 8192000000 10615986400 11084279808 1183944512 12.627 100 47 92 1 1739819008 543192848 12681648
06:03:48 11103 8192000000 10483503040 10997792768 1174508128 12.539 100 41 83 0 1606287824 421369296 10410400
06:05:19 33962 8192000000 10376292272 10932711424 1151452592 12.323 100 21 42 0 1503211584 345833232 5925760
06:07:10 47198 8192000000 10151912592 10686529536 1133784528 12.157 100 12 24 0 1328026736 191746864 2495344
06:09:02 65669 8192000000 10113713968 10552356864 1141471312 12.229 99 31 43 1 1200239440 54412096 4356032
06:11:10 73845 8192000000 10146454832 10590916608 1130569280 12.127 98 51 101 1 1242128256 100596496 10962480
06:13:26 106026 8192000000 10131624512 10638929920 1103162496 11.868 96 19 38 0 1249061088 141886368 4012224
06:16:13 145655 8192000000 10098391488 10547212288 1105506960 11.890 97 18 36 0 1162445648 53749920 3188768
06:19:53 54835 8192000000 10058817888 10522685440 1129907088 12.120 100 24 43 2 1185398320 50816656 4674576
06:21:48 64687 8192000000 10070780512 10539368448 1128286704 12.105 99 19 35 0 1176586560 44696656 3603200
06:23:54 81237 8192000000 10132320880 10601455616 1142481408 12.239 100 24 47 0 1204530944 57173648 4875888
06:26:16 47613 8192000000 10076066272 10540388352 1138666528 12.203 100 33 64 0 1173928096 28597008 6664560
06:28:05 70163 8192000000 10060793200 10570780672 1121031200 12.037 100 22 44 0 1175828384 50489264 4307920
06:30:23 25111 8192000000 10098289888 10546679808 1135745248 12.175 100 42 77 2 1197570288 53949200 7875840
06:31:56 74039 8192000000 10090503488 10553405440 1108614720 11.919 97 13 26 0 1163853008 52377520 2860768
06:34:10 87919 8192000000 10074203840 10558377984 1104830000 11.883 97 18 36 0 1190181744 81322832 4028912
06:36:43 76020 8192000000 10126523392 10604298240 1135718240 12.175 100 11 20 0 1213222592 75405360 2098992
06:38:59 24600 8192000000 10101067104 10540507136 1138235088 12.199 99 27 51 0 1186328800 42746512 5347200
06:40:24 43896 8192000000 10135486160 10598653952 1125449008 12.078 98 23 44 1 1229878416 99546128 4883280
06:42:09 26169 8192000000 10082559936 10542772224 1119044432 12.018 98 44 86 0 1181107104 52695504 9367168
06:43:41 29123 8192000000 10106117520 10539143168 1116015072 11.989 98 31 62 0 1173595680 51173504 6407104
06:45:16 31438 8192000000 10133682944 10592219136 1115389008 11.983 98 49 98 0 1224052224 98124720 10538496
06:46:48 29124 8192000000 10103162768 10536382464 1088562528 11.729 96 43 86 0 1160866368 63995680 8308160
06:48:17 16164 8192000000 10107812624 10541375488 1132324288 12.143 100 51 101 0 1198748144 56795008 9628848
06:49:37 39753 8192000000 10125122112 10575118336 1122166928 12.047 99 40 80 0 1216136432 85759888 8209616
06:51:17 60409 8192000000 10103915968 10545127424 1122941296 12.055 99 38 76 3 1189058064 58714336 7402432
06:54:02 46491 8192000000 10057297696 10504867840 1092986800 11.771 97 22 44 0 1145456448 48433408 4036240
06:55:49 57193 8192000000 10067225616 10538262528 1110904496 11.941 99 14 28 0 1175415968 61349392 3162080
06:57:46 63928 8192000000 10100415312 10564718592 1131389872 12.134 100 13 26 0 1214849408 80318960 3140576
06:59:51 80953 8192000000 10057206704 10498170880 1110133568 11.934 98 15 30 0 1153460480 40037520 3289392
07:02:12 42652 8192000000 10057105392 10514944000 1101638656 11.853 98 23 44 0 1165553056 59673216 4241184
07:03:55 56107 8192000000 10095037792 10526756864 1099703312 11.835 97 39 78 0 1158051216 50247088 8100816
07:05:51 46727 8192000000 10114705232 10568220672 1104563952 11.881 97 32 64 0 1185311344 73819488 6927904
07:07:39 65678 8192000000 10109064224 10539692032 1126408928 12.087 99 26 52 1 1220536704 88787104 5340672
07:09:51 42743 8192000000 10090098688 10569719808 1089928560 11.742 97 36 72 1 1195554400 97994432 7631408
07:11:34 17293 8192000000 10109800608 10525446144 1119069696 12.018 100 62 123 0 1190319888 59042672 12207520
07:12:54 162239 8192000000 10046483040 10535821312 1113335936 11.964 99 18 32 0 1146107520 28845792 3925792
07:16:47 81756 8192000000 10048788288 10525720576 1108269760 11.916 99 11 23 0 1166468432 55583696 2614976
07:19:10 92068 8192000000 10056337520 10543333376 1085398256 11.699 97 16 32 0 1119328160 30878944 3050960
07:21:47 51655 8192000000 10062794384 10514649088 1124520320 12.070 100 22 44 0 1164565456 35237840 4807296
07:23:41 61324 8192000000 10100078992 10543595520 1139692576 12.213 100 15 30 0 1187962768 44568640 3701552
07:25:42 46194 8192000000 10110968848 10548748288 1106344544 11.898 97 41 81 0 1166246240 51743648 8158048
07:27:34 54898 8192000000 10100625280 10543087616 1112563776 11.957 98 34 68 2 1199708624 80554352 6590496
07:29:42 30040 8192000000 10088078016 10547830784 1115755152 11.987 99 36 72 0 1199710304 76277120 7678032
07:31:13 27439 8192000000 10087854064 10514087936 1072978048 11.581 95 46 90 0 1121623328 39925872 8719408
07:32:43 38160 8192000000 10140744880 10584588288 1123598960 12.061 100 48 96 0 1239878368 106232736 10046672
07:34:23 102931 8192000000 10059045360 10568888320 1111485200 11.946 98 14 28 0 1174579296 60382016 2712080

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-02-2023 07:40:28 to 10-02-2023 07:40:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 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 10-02-2023 07:40:45 to 10-02-2023 07:40:47.

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          214088
MON$OLDEST_ACTIVE               215147
MON$OLDEST_SNAPSHOT             215147
MON$NEXT_TRANSACTION            215147
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                       2055136
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2899 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2899 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2899 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 ---
:58: 
:59: mscs-test-161.budzdorov.ru    Fri Feb 10 07:37:38 2023
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:61: 
:62: 
:63: mscs-test-161.budzdorov.ru    Fri Feb 10 07:37:44 2023
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Fri Feb 10 07:37:54 2023
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:69: 
--- end of diff output ---

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

10.02.23 07:40:48. 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-02-10 07:40:51 No severe PSQL-related problems occured
10.02.23 07:40:52. End of report.