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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15003788 kB
MemAvailable 30373988 kB
Buffers 0 kB
Cached 16674660 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.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-01-17 13:37:25
DTS_END                         2023-01-17 13:37:25.3750
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/46400
TRN_ID                          223300
ATT_ID                          615
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: 9232.21 20223 1107865
customer order: creation 171.33 453 20559
customer order: refuse 14.18 413 1701
order to supplier: creation 46.00 1359 5520
order to supplier: removal 3.59 140406 431
invoice (draft): creation 45.58 3243 5469
invoice (draft): removal 51.20 922 6144
invoice accept: apply 41.98 26381 5037
invoice accept: cancel 46.01 1122 5521
customer reserve: creation 3960.47 248 475256
customer reserve: removal 4557.76 87 546931
realization accept: apply 82.98 268 9957
realization accept: cancel 80.85 157 9702
payment from customer: creation 59.41 172 7129
payment from customer: removal 6.04 115 725
payment to supplier: creation 48.91 191 5869
payment to supplier: removal 3.35 112 402
service: total inventory turnovers 4.13 3632 495
service: total monetary turnovers 7.38 347 886
service: aggregate perf. data 1.05 13486 126
service: refresh index statistics 0.04 211340 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-01-2023 13:38:08 to 17-01-2023 13:38:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6887
TEST_TIME 2 6015
TEST_TIME 3 5746
TEST_TIME 4 5459
TEST_TIME 5 5598
TEST_TIME 6 5824
TEST_TIME 7 6117
TEST_TIME 8 6565
TEST_TIME 9 6871
TEST_TIME 10 6953
TEST_TIME 11 7449
TEST_TIME 12 7526
TEST_TIME 13 7528
TEST_TIME 14 7617
TEST_TIME 15 7530
TEST_TIME 16 7407
TEST_TIME 17 7361
TEST_TIME 18 7536
TEST_TIME 19 7580
TEST_TIME 20 7730
TEST_TIME 21 7918
TEST_TIME 22 8113
TEST_TIME 23 8105
TEST_TIME 24 8508
TEST_TIME 25 8669
TEST_TIME 26 8913
TEST_TIME 27 9066
TEST_TIME 28 9506
TEST_TIME 29 9819
TEST_TIME 30 9909
TEST_TIME 31 10491
TEST_TIME 32 10700
TEST_TIME 33 11136
TEST_TIME 34 10958
TEST_TIME 35 11001
TEST_TIME 36 10876
TEST_TIME 37 10886
TEST_TIME 38 10916
TEST_TIME 39 10936
TEST_TIME 40 10759
TEST_TIME 41 10536
TEST_TIME 42 10408
TEST_TIME 43 10397
TEST_TIME 44 10419
TEST_TIME 45 10352
TEST_TIME 46 10238
TEST_TIME 47 10166
TEST_TIME 48 10114
TEST_TIME 49 10061
TEST_TIME 50 10127
TEST_TIME 51 10090
TEST_TIME 52 10101
TEST_TIME 53 10052
TEST_TIME 54 10088
TEST_TIME 55 9937
TEST_TIME 56 9891
TEST_TIME 57 9822
TEST_TIME 58 9775
TEST_TIME 59 9726
TEST_TIME 60 9660
TEST_TIME 61 9702
TEST_TIME 62 9680
TEST_TIME 63 9713
TEST_TIME 64 9777
TEST_TIME 65 9726
TEST_TIME 66 9801
TEST_TIME 67 9773
TEST_TIME 68 9795
TEST_TIME 69 9778
TEST_TIME 70 9801
TEST_TIME 71 9786
TEST_TIME 72 9815
TEST_TIME 73 9919
TEST_TIME 74 9870
TEST_TIME 75 9773
TEST_TIME 76 9769
TEST_TIME 77 9756
TEST_TIME 78 9672
TEST_TIME 79 9634
TEST_TIME 80 9633
TEST_TIME 81 9587
TEST_TIME 82 9558
TEST_TIME 83 9509
TEST_TIME 84 9441
TEST_TIME 85 9439
TEST_TIME 86 9427
TEST_TIME 87 9429
TEST_TIME 88 9398
TEST_TIME 89 9426
TEST_TIME 90 9432
TEST_TIME 91 9395
TEST_TIME 92 9464
TEST_TIME 93 9461
TEST_TIME 94 9452
TEST_TIME 95 9468
TEST_TIME 96 9509
TEST_TIME 97 9526
TEST_TIME 98 9519
TEST_TIME 99 9605
TEST_TIME 100 9533
TEST_TIME 101 9477
TEST_TIME 102 9428
TEST_TIME 103 9429
TEST_TIME 104 9381
TEST_TIME 105 9355
TEST_TIME 106 9330
TEST_TIME 107 9290
TEST_TIME 108 9249
TEST_TIME 109 9201
TEST_TIME 110 9217
TEST_TIME 111 9215
TEST_TIME 112 9202
TEST_TIME 113 9190
TEST_TIME 114 9176
TEST_TIME 115 9196
TEST_TIME 116 9236
TEST_TIME 117 9271
TEST_TIME 118 9283
TEST_TIME 119 9302
TEST_TIME 120 9248

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-01-2023 13:38:12 to 17-01-2023 13:38:15.

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 1544426 1544426 0 0.00 0 13344 13 0 0
doc_list_biud 1544413 1544413 0 0.00 0 53016 37 0 0
sp_add_invoice_to_stock 5181 5037 144 2.77 36 185717 26382 0 144
sp_cancel_adding_invoice 5521 5521 0 0.00 5 99257 1123 0 0
sp_cancel_client_order 1701 1701 0 0.00 5 10732 413 0 0
sp_cancel_customer_reserve 546931 546931 0 0.00 1 25602 88 0 0
sp_cancel_pay_from_customer 725 725 0 0.00 4 2605 115 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 1606 113 0 0
sp_cancel_supplier_invoice 6149 6144 5 0.08 3 53025 922 0 5
sp_cancel_supplier_order 431 431 0 0.00 15 1355380 140407 0 0
sp_cancel_write_off 9702 9702 0 0.00 2 9046 158 0 0
sp_client_order 20559 20559 0 0.00 5 39862 453 0 0
sp_customer_reserve 475256 475256 0 0.00 0 105860 248 0 0
sp_fill_shopping_cart 31539 31539 0 0.00 1 20170 84 0 0
sp_fill_shopping_cart_clo_res 475224 475224 0 0.00 0 13065 17 0 0
sp_get_clo_for_invoice 4993 4993 0 0.00 0 30031 273 0 0
sp_kill_cost_storno 16350 16350 0 0.00 0 6427 64 0 0
sp_kill_qstorno_ret_qs2qd 1110139 1110139 0 0.00 0 53014 39 0 0
sp_kill_qty_storno 585379 585379 0 0.00 0 53016 89 0 0
sp_lock_selected_doc 20712 20712 0 0.00 0 7930 6 0 0
sp_make_cost_storno 27948 27948 0 0.00 0 13253 80 0 0
sp_multiply_rows_for_pdistr 27946 27946 0 0.00 0 8559 37 0 0
sp_multiply_rows_for_qdistr 20559 20559 0 0.00 0 14560 188 0 0
sp_pay_from_customer 7129 7129 0 0.00 5 10186 173 0 0
sp_pay_to_supplier 5869 5869 0 0.00 7 11932 191 0 0
sp_payment_common 12995 12995 0 0.00 2 10180 45 0 0
sp_qd_handle_on_cancel_clo 1701 1701 0 0.00 0 10722 346 0 0
sp_qd_handle_on_invoice_upd_sts 10514 10514 0 0.00 0 23150 463 0 0
sp_qd_handle_on_reserve_upd_sts 9956 9956 0 0.00 0 15939 65 0 0
sp_reserve_write_off 9957 9957 0 0.00 8 15972 268 0 0
sp_supplier_invoice 5469 5469 0 0.00 9 93888 3244 0 0
sp_supplier_order 5520 5520 0 0.00 8 36954 1359 0 0
srv_aggregate_perf_data 126 126 0 0.00 2333 157095 13486 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 798 152979 37473 0 0
srv_find_qd_qs_mism 1057852 1057852 0 0.00 0 8643 10 0 0
srv_make_invnt_saldo 495 495 0 0.00 47 49423 3632 0 0
srv_make_money_saldo 886 886 0 0.00 1 17363 347 0 0
srv_recalc_idx_stat 5 5 0 0.00 49950 445250 211341 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5041 5041 0 0.00 1 10104 884 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 1 6259 530 0 0
t$perf-norm:sp_cancel_client_order 1701 1701 0 0.00 0 3598 28 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 5165 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 725 725 0 0.00 0 1053 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 651 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 4057 46 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 68400 5698 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 16732 34 0 0
t$perf-norm:sp_client_order 20559 20559 0 0.00 0 6835 36 0 0
t$perf-norm:sp_customer_reserve 950 950 0 0.00 0 1784 14 0 0
t$perf-norm:sp_pay_from_customer 7129 7129 0 0.00 0 3469 19 0 0
t$perf-norm:sp_pay_to_supplier 5869 5869 0 0.00 0 5128 18 0 0
t$perf-norm:sp_reserve_write_off 9957 9957 0 0.00 0 11636 23 0 0
t$perf-norm:sp_supplier_invoice 5469 5469 0 0.00 0 5866 33 0 0
t$perf-norm:sp_supplier_order 5520 5520 0 0.00 0 6839 31 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 173 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 3522 57 0 0
t$perf-norm:srv_make_invnt_saldo 495 495 0 0.00 0 2262 16 0 0
t$perf-norm:srv_make_money_saldo 886 886 0 0.00 0 467 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 209 83 0 0
v_add_invoice_to_stock 10258 9970 288 2.80 0 6458 26 0 288
v_all_customers 37934 37934 0 0.00 0 67 4 0 0
v_all_suppliers 30686 30686 0 0.00 0 285 4 0 0
v_all_wares 41112 41112 0 0.00 0 100 4 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 358 14 0 0
v_cancel_client_order 3402 3402 0 0.00 0 2247 12 0 0
v_cancel_customer_prepayment 1450 1450 0 0.00 0 2541 14 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 2206 15 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 973 12 0 0
v_cancel_supplier_invoice 808 798 10 1.23 0 1449 24 0 10
v_cancel_supplier_order 862 862 0 0.00 0 593 11 0 0
v_cancel_write_off 2042 2042 0 0.00 0 1407 22 0 0
v_max_id_clo_ord 5520 5520 0 0.00 0 72 4 0 0
v_max_id_clo_res 950 950 0 0.00 0 1069 10 0 0
v_max_id_ord_sup 5462 5462 0 0.00 0 12759 11 0 0
v_max_non_paid_invoice 1508 1508 0 0.00 0 327 7 0 0
v_max_non_paid_realizn 3690 3690 0 0.00 0 4356 9 0 0
v_min_id_clo_ord 5520 5520 0 0.00 0 5734 12 0 0
v_min_id_clo_res 950 950 0 0.00 0 4814 16 0 0
v_min_id_ord_sup 5462 5462 0 0.00 0 3337 10 0 0
v_min_non_paid_invoice 5869 5869 0 0.00 0 4774 17 0 0
v_min_non_paid_realizn 7126 7126 0 0.00 0 10174 19 0 0
v_our_firm 10048 10048 0 0.00 0 63 4 0 0
v_reserve_write_off 19910 19910 0 0.00 0 3103 11 0 0
x_lock_depdocs_on_canc_invoice 11266 11266 0 0.00 0 13635 123 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 10384 287 0 0
x_make_qstorno_1000_1200 5520 5520 0 0.00 3 31691 1200 0 0
x_make_qstorno_1200_2000 5469 5469 0 0.00 4 88840 2997 0 0
x_make_qstorno_2100_3300 475256 475256 0 0.00 0 105856 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-01-2023 13:38:16 to 17-01-2023 13:38:20.

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
11:07:29 5 8192000000 8804130768 8817471488 8893696 0.108 3 3 4 0 9113312 132656 86960
11:08:30 40242 8192000000 9743035840 10046451712 813964400 9.038 100 88 174 0 842407552 7716624 20726528
11:10:15 13157 8192000000 9777005840 10086608896 850471840 9.405 100 92 184 0 885432496 12985520 21975136
11:11:31 11560 8192000000 9799735360 10120708096 873651600 9.636 100 84 167 0 912643808 20168368 18823840
11:12:52 8720 8192000000 9882160928 10218467328 928315248 10.178 100 86 167 2 993371744 46751696 18304800
11:14:07 2707 8192000000 9928027136 10274533376 965445104 10.542 100 89 175 0 1046706304 62759664 18501536
11:15:12 3387 8192000000 9988325696 10345529344 1002127616 10.899 100 85 166 0 1110531744 91595040 16809088
11:16:17 1518 8192000000 9990368496 10353360896 1027115328 11.141 100 84 161 0 1102582528 59182112 16285088
11:17:21 941 8192000000 10016278496 10385117184 1051711728 11.377 100 98 187 2 1138442496 67092016 19638752
11:18:23 1041 8192000000 10022304352 10401259520 1060507792 11.461 100 98 187 3 1142005024 62311584 19185648
11:19:25 1150 8192000000 10044253872 10425380864 1065437344 11.508 100 94 176 3 1165005024 81997760 17569920
11:20:27 1327 8192000000 10028489856 10406760448 1071720880 11.569 100 90 173 0 1153734208 63892128 18121200
11:21:29 1302 8192000000 10045156000 10429329408 1076181728 11.611 100 92 174 0 1166842016 71882048 18778240
11:22:32 1045 8192000000 10039063792 10425565184 1078076704 11.629 100 80 151 0 1160614016 65802896 16734416
11:23:33 2346 8192000000 10029968784 10422902784 1086059504 11.705 100 78 144 5 1146794560 45790336 14944720
11:24:37 1156 8192000000 10053214560 10441932800 1088878112 11.732 100 97 183 0 1176058544 69223040 17957392
11:25:40 1135 8192000000 10063646688 10452275200 1092655712 11.768 100 94 180 1 1188512400 77573904 18282784
11:26:42 1016 8192000000 10068875024 10456956928 1097957424 11.818 100 96 185 2 1187232912 69464048 19811440
11:27:43 884 8192000000 10056547168 10446995456 1095480672 11.795 100 91 174 0 1174412976 60965168 17967136
11:28:45 1204 8192000000 10062024976 10445811712 1086308400 11.708 100 93 182 0 1182833616 77162496 19362720
11:29:47 1303 8192000000 10032147184 10416816128 1071371568 11.565 100 92 172 2 1157641824 68805648 17464608
11:30:49 4380 8192000000 10050660480 10438451200 1083545120 11.681 100 80 155 6 1171230688 73205856 14479712
11:31:54 1787 8192000000 10020483776 10397200384 1047541728 11.337 100 88 163 3 1142681008 79191312 15947968
11:32:57 1550 8192000000 9990129360 10371198976 1042709200 11.291 100 82 153 1 1115563792 58085088 14769504
11:34:00 1171 8192000000 9996915488 10368135168 1025406720 11.124 100 89 164 1 1120549280 76512608 18629952
11:35:02 1581 8192000000 10002026896 10376658944 1034770464 11.214 100 88 170 2 1123091872 71658896 16662512
11:36:04 2716 8192000000 9973232016 10346885120 1019644880 11.069 99 76 131 4 1091573936 59044592 12884464
11:37:08 4192 8192000000 9979330544 10366263296 1045499920 11.317 100 68 124 0 1097256160 37569360 14186880
11:38:20 2551 8192000000 10032631952 10407923712 1066010288 11.514 100 82 157 3 1153862720 72901664 14950768
11:39:30 24503 8192000000 10048119568 10437050368 1074137712 11.592 100 64 126 1 1156576384 69809728 12628944
11:41:03 806 8192000000 10053793488 10436235264 1084064016 11.686 99 84 153 1 1175540960 73848864 17628080
11:42:06 1672 8192000000 10128769232 10509979648 1105232064 11.887 100 87 164 0 1243100528 121480192 16388272
11:43:11 2809 8192000000 10133352656 10526416896 1115122624 11.981 100 76 139 1 1253432880 123311200 14999056
11:44:19 1729 8192000000 10178892848 10575544320 1129019408 12.112 100 90 172 3 1291190528 143811088 18360032
11:45:26 2911 8192000000 10189080048 10595229696 1138126416 12.198 100 96 180 2 1304555904 145800512 20628976
11:46:32 1634 8192000000 10201035968 10600415232 1143881536 12.252 100 82 159 2 1313563184 152490800 17190848
11:47:39 1776 8192000000 10199401360 10620231680 1148909792 12.299 100 97 185 0 1314526224 143804544 21811888
11:48:45 1216 8192000000 10224200000 10635399168 1168931728 12.487 100 89 165 0 1334789552 146294272 19563552
11:49:49 1496 8192000000 10238372848 10644221952 1177624272 12.568 100 86 166 1 1351101856 155820112 17657472
11:50:55 1202 8192000000 10256656160 10673750016 1179211712 12.583 100 96 186 2 1365299264 165441232 20646320
11:52:02 1192 8192000000 10289987120 10694017024 1186512400 12.651 100 93 183 0 1398048512 189832976 21703136
11:53:06 4623 8192000000 10345626544 10759548928 1199979920 12.776 100 84 163 0 1456884720 236718736 20186064
11:54:17 793 8192000000 10406782640 10812563456 1203306944 12.807 100 82 162 1 1514725712 292513696 18905072
11:55:21 968 8192000000 10454602736 10872287232 1211476016 12.883 100 98 193 0 1561287824 326092032 23719776
11:56:25 884 8192000000 10477427984 10886901760 1207052368 12.842 100 98 185 0 1583606192 354775776 21778048
11:57:29 1763 8192000000 10483420912 10920062976 1206147440 12.833 100 93 178 2 1592558432 365734864 20676128
11:58:33 809 8192000000 10517789648 10937909248 1203996592 12.813 100 95 185 1 1621221968 395953360 21272016
11:59:36 3973 8192000000 10489062640 10942787584 1196405264 12.743 100 58 116 2 1597060480 385922080 14733136
12:00:46 2002 8192000000 10482515792 10940260352 1188920288 12.673 99 98 187 2 1580454896 367655456 23879152
12:01:49 5903 8192000000 10468339712 10919960576 1190072960 12.684 100 55 107 1 1594026800 389835904 14117936
12:03:02 12900 8192000000 10434146672 10903969792 1173565584 12.530 100 45 88 0 1573299424 387816848 11916992
12:04:18 22131 8192000000 10438384128 10911780864 1168412176 12.482 100 31 60 0 1549590176 372723472 8454528
12:05:45 28071 8192000000 10365768672 10820067328 1134702112 12.166 97 32 62 0 1451658912 309007840 7948960
12:07:21 15195 8192000000 10310930912 10771410944 1141227872 12.227 99 26 52 0 1408775200 260953856 6593472
12:08:55 48047 8192000000 10166249328 10683113472 1139565200 12.211 99 15 30 0 1302271184 159268144 3437840
12:10:45 53933 8192000000 10060097936 10487140352 1098120064 11.820 95 42 78 1 1132316656 26139984 8056608
12:12:40 96354 8192000000 10092230736 10560520192 1090033424 11.743 95 19 37 0 1155423040 61598048 3791568
12:15:22 140259 8192000000 10064013232 10502139904 1070864288 11.560 94 14 28 1 1097672368 23926912 2881168
12:18:45 46878 8192000000 10115843200 10543005696 1109530336 11.928 98 53 102 1 1164444560 44144560 10769664
12:20:35 87928 8192000000 10096363552 10560847872 1089648640 11.739 96 26 52 0 1190521392 94906960 5965792
12:23:11 66079 8192000000 10088805632 10526212096 1112303600 11.954 98 31 60 0 1168305104 50008096 5993408
12:25:20 17801 8192000000 10112212944 10550767616 1127733040 12.100 98 24 44 0 1209251952 75700016 5818896
12:26:39 18145 8192000000 10189998992 10639486976 1157213824 12.377 100 64 122 1 1285809696 115587680 13008192
12:28:14 30355 8192000000 10145462128 10651127808 1147478048 12.286 100 11 22 0 1254436848 104222256 2736544
12:31:01 83910 8192000000 10083083920 10568314880 1126229760 12.086 99 18 36 0 1181494592 51249856 4014976
12:33:31 81529 8192000000 10083041200 10567376896 1135685392 12.175 99 12 22 0 1206375040 68649120 2040528
12:36:58 47874 8192000000 10109471248 10587836416 1136008560 12.178 99 17 34 0 1205584928 65963552 3612816
12:38:53 35855 8192000000 10078400800 10540748800 1113270864 11.963 97 16 32 0 1145973664 29732848 2969952
12:40:29 89341 8192000000 10092984816 10589036544 1125817680 12.082 98 21 39 0 1190810336 61240832 3751824
12:43:01 62850 8192000000 10075220848 10530316288 1097295360 11.812 95 22 44 1 1113701744 12084496 4321888
12:45:07 23173 8192000000 10121556720 10591436800 1130447232 12.126 99 34 68 0 1207782240 70388704 6946304
12:46:35 12920 8192000000 10126492272 10581913600 1141628176 12.231 99 41 82 0 1212886400 62963584 8294640
12:48:17 31900 8192000000 10117636832 10564804608 1133387728 12.153 98 27 54 0 1209651328 70944240 5319360
12:49:52 106532 8192000000 10104974128 10562764800 1103849024 11.874 96 18 34 0 1192094208 84260224 3984960
12:52:42 105306 8192000000 10082905472 10550480896 1088061008 11.724 95 17 32 0 1131352448 39691872 3599568
12:55:33 5417 8192000000 10056235568 10525319168 1131250464 12.133 100 24 48 0 1162996912 27295824 4450624
12:56:40 77634 8192000000 10056913296 10528845824 1120694976 12.034 99 21 40 1 1163710480 39134608 3880896
12:59:06 63987 8192000000 10079972928 10545352704 1130857168 12.129 99 12 24 0 1182126256 48693904 2575184
13:01:10 67614 8192000000 10089539344 10559471616 1124860080 12.073 100 10 20 0 1195592448 68498608 2233760
13:03:49 34536 8192000000 10049665136 10494545920 1085231504 11.697 97 26 52 0 1138642576 47488592 5922480
13:05:25 20201 8192000000 10075500464 10512531456 1097848720 11.817 98 42 82 1 1143869520 37693728 8327072
13:06:51 31232 8192000000 10080562640 10545930240 1123194432 12.057 100 35 64 1 1205081072 74590816 7295824
13:08:24 41799 8192000000 10111248384 10562990080 1106087008 11.895 98 34 64 1 1200683296 88653888 5942400
13:10:06 40184 8192000000 10101510976 10547601408 1087119712 11.715 96 43 84 0 1160940736 65596000 8225024
13:12:07 25121 8192000000 10109473904 10552373248 1117167616 12.000 99 30 60 0 1191511680 68751488 5592576
13:13:32 69280 8192000000 10069178768 10522120192 1122020272 12.046 99 18 36 0 1172632048 47471296 3140480
13:15:42 15542 8192000000 10144645584 10580787200 1142498160 12.239 100 63 124 1 1225192784 70127328 12567296
13:17:02 152979 8192000000 10080338912 10540355584 1104696256 11.882 97 28 54 0 1160219376 48767424 6755696
13:20:38 25950 8192000000 10062208864 10531696640 1124700080 12.071 99 11 22 1 1165906240 38492496 2713664
13:22:51 62055 8192000000 10066735744 10504441856 1108957376 11.923 98 11 22 0 1139980224 28589536 2433312
13:25:03 102725 8192000000 10114406176 10542546944 1129523344 12.117 99 20 42 1 1187761744 53577552 4660848
13:28:01 65028 8192000000 10104600688 10555244544 1133860848 12.158 98 23 46 0 1221942720 82817520 5264352
13:30:15 32938 8192000000 10144574208 10596384768 1144032960 12.253 99 28 54 1 1236360400 85984000 6343440
13:31:57 75821 8192000000 10159418752 10640551936 1147623744 12.287 99 19 38 0 1282266224 130427392 4215088
13:34:38 30481 8192000000 10108770720 10564833280 1136335472 12.181 100 42 83 0 1211595872 67126576 8133824
13:36:11 74929 8192000000 10098173168 10553880576 1070743616 11.559 94 38 76 1 1124976736 46772080 7461040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-01-2023 13:38:22 to 17-01-2023 13:38:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-01-2023 13:38:39 to 17-01-2023 13:38:41.

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          220717
MON$OLDEST_ACTIVE               223450
MON$OLDEST_SNAPSHOT             223450
MON$NEXT_TRANSACTION            223450
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                       2067104
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 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 ---
:798: 
:799: mscs-test-161.budzdorov.ru    Tue Jan 17 13:37:31 2023
:800:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:801: 
--- end of diff output ---

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

17.01.23 13:38:43. 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-01-17 13:38:45 No severe PSQL-related problems occured
17.01.23 13:38:47. End of report.