Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.06.2022 07:47
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 15814796 kB
MemAvailable 31249464 kB
Buffers 0 kB
Cached 15871592 kB
SwapCached 2452 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-16 07:46:43
DTS_END                         2022-06-16 07:46:43.7970
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/35764
TRN_ID                          213450
ATT_ID                          622
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: 9257.63 28764 1110915
customer order: creation 156.48 456 18777
customer order: refuse 13.10 400 1572
order to supplier: creation 43.28 1343 5194
order to supplier: removal 3.40 170699 408
invoice (draft): creation 43.33 2978 5200
invoice (draft): removal 55.13 884 6615
invoice accept: apply 39.41 23859 4729
invoice accept: cancel 49.73 1102 5968
customer reserve: creation 3703.90 222 444468
customer reserve: removal 4859.70 93 583164
realization accept: apply 79.16 275 9499
realization accept: cancel 90.81 168 10897
payment from customer: creation 54.39 184 6527
payment from customer: removal 4.88 158 586
payment to supplier: creation 46.09 197 5531
payment to supplier: removal 3.58 147 430
service: total inventory turnovers 3.53 4367 424
service: total monetary turnovers 6.83 353 819
service: aggregate perf. data 0.86 17373 103
service: refresh index statistics 0.03 350018 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-06-2022 07:48:05 to 16-06-2022 07:48:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7207
TEST_TIME 2 7617
TEST_TIME 3 6485
TEST_TIME 4 6066
TEST_TIME 5 5854
TEST_TIME 6 5729
TEST_TIME 7 6047
TEST_TIME 8 5935
TEST_TIME 9 5791
TEST_TIME 10 5715
TEST_TIME 11 5931
TEST_TIME 12 5855
TEST_TIME 13 5930
TEST_TIME 14 6128
TEST_TIME 15 6207
TEST_TIME 16 6505
TEST_TIME 17 6488
TEST_TIME 18 6563
TEST_TIME 19 7192
TEST_TIME 20 7464
TEST_TIME 21 8088
TEST_TIME 22 8560
TEST_TIME 23 8501
TEST_TIME 24 8653
TEST_TIME 25 8754
TEST_TIME 26 8765
TEST_TIME 27 8708
TEST_TIME 28 9368
TEST_TIME 29 9885
TEST_TIME 30 10511
TEST_TIME 31 10410
TEST_TIME 32 10377
TEST_TIME 33 10537
TEST_TIME 34 10401
TEST_TIME 35 10241
TEST_TIME 36 10455
TEST_TIME 37 10472
TEST_TIME 38 10708
TEST_TIME 39 10546
TEST_TIME 40 10448
TEST_TIME 41 10358
TEST_TIME 42 10303
TEST_TIME 43 10179
TEST_TIME 44 10095
TEST_TIME 45 10207
TEST_TIME 46 10241
TEST_TIME 47 10229
TEST_TIME 48 10125
TEST_TIME 49 10140
TEST_TIME 50 10076
TEST_TIME 51 9986
TEST_TIME 52 10080
TEST_TIME 53 9915
TEST_TIME 54 9748
TEST_TIME 55 9750
TEST_TIME 56 9684
TEST_TIME 57 9592
TEST_TIME 58 9556
TEST_TIME 59 9511
TEST_TIME 60 9474
TEST_TIME 61 9455
TEST_TIME 62 9452
TEST_TIME 63 9358
TEST_TIME 64 9401
TEST_TIME 65 9381
TEST_TIME 66 9294
TEST_TIME 67 9548
TEST_TIME 68 9458
TEST_TIME 69 9433
TEST_TIME 70 9465
TEST_TIME 71 9523
TEST_TIME 72 9562
TEST_TIME 73 9506
TEST_TIME 74 9538
TEST_TIME 75 9550
TEST_TIME 76 9515
TEST_TIME 77 9561
TEST_TIME 78 9659
TEST_TIME 79 9682
TEST_TIME 80 9738
TEST_TIME 81 9826
TEST_TIME 82 9794
TEST_TIME 83 9686
TEST_TIME 84 9667
TEST_TIME 85 9644
TEST_TIME 86 9596
TEST_TIME 87 9513
TEST_TIME 88 9560
TEST_TIME 89 9501
TEST_TIME 90 9539
TEST_TIME 91 9507
TEST_TIME 92 9471
TEST_TIME 93 9402
TEST_TIME 94 9425
TEST_TIME 95 9403
TEST_TIME 96 9355
TEST_TIME 97 9324
TEST_TIME 98 9336
TEST_TIME 99 9309
TEST_TIME 100 9320
TEST_TIME 101 9306
TEST_TIME 102 9420
TEST_TIME 103 9444
TEST_TIME 104 9420
TEST_TIME 105 9604
TEST_TIME 106 9549
TEST_TIME 107 9599
TEST_TIME 108 9552
TEST_TIME 109 9485
TEST_TIME 110 9461
TEST_TIME 111 9420
TEST_TIME 112 9389
TEST_TIME 113 9369
TEST_TIME 114 9359
TEST_TIME 115 9341
TEST_TIME 116 9292
TEST_TIME 117 9287
TEST_TIME 118 9248
TEST_TIME 119 9300
TEST_TIME 120 9287

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-06-2022 07:48:08 to 16-06-2022 07:48:12.

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 1519543 1519543 0 0.00 0 25968 12 0 0
doc_list_biud 1519526 1519526 0 0.00 0 47644 41 0 0
sp_add_invoice_to_stock 4831 4729 102 2.11 40 181334 23860 0 102
sp_cancel_adding_invoice 5968 5968 0 0.00 7 90922 1102 0 0
sp_cancel_client_order 1572 1572 0 0.00 6 13952 400 0 0
sp_cancel_customer_reserve 583164 583164 0 0.00 1 37626 94 0 0
sp_cancel_pay_from_customer 586 586 0 0.00 4 15424 159 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 4 5897 148 0 0
sp_cancel_supplier_invoice 6623 6615 8 0.12 3 47703 885 0 8
sp_cancel_supplier_order 408 408 0 0.00 10 1234908 170699 0 0
sp_cancel_write_off 10897 10897 0 0.00 2 20524 169 0 0
sp_client_order 18777 18777 0 0.00 6 22910 457 0 0
sp_customer_reserve 444468 444468 0 0.00 0 99353 222 0 0
sp_fill_shopping_cart 29159 29159 0 0.00 2 14304 76 0 0
sp_fill_shopping_cart_clo_res 444430 444430 0 0.00 0 16568 18 0 0
sp_get_clo_for_invoice 4679 4679 0 0.00 0 35467 311 0 0
sp_kill_cost_storno 17881 17881 0 0.00 0 25963 79 0 0
sp_kill_qstorno_ret_qs2qd 1184248 1184248 0 0.00 0 47640 41 0 0
sp_kill_qty_storno 622801 622801 0 0.00 0 47643 94 0 0
sp_lock_selected_doc 19562 19562 0 0.00 0 7511 6 0 0
sp_make_cost_storno 26235 26235 0 0.00 0 14970 90 0 0
sp_multiply_rows_for_pdistr 26235 26235 0 0.00 0 9047 38 0 0
sp_multiply_rows_for_qdistr 18777 18777 0 0.00 0 13716 174 0 0
sp_pay_from_customer 6527 6527 0 0.00 5 16850 184 0 0
sp_pay_to_supplier 5531 5531 0 0.00 6 17667 198 0 0
sp_payment_common 12057 12057 0 0.00 2 10149 42 0 0
sp_qd_handle_on_cancel_clo 1572 1572 0 0.00 1 13939 329 0 0
sp_qd_handle_on_invoice_upd_sts 10646 10646 0 0.00 0 24127 494 0 0
sp_qd_handle_on_reserve_upd_sts 9499 9499 0 0.00 0 20690 71 0 0
sp_reserve_write_off 9499 9499 0 0.00 9 20912 275 0 0
sp_supplier_invoice 5200 5200 0 0.00 10 94770 2979 0 0
sp_supplier_order 5194 5194 0 0.00 7 30941 1344 0 0
srv_aggregate_perf_data 103 103 0 0.00 2639 187421 17373 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 752 139169 43127 0 0
srv_find_qd_qs_mism 1064645 1064645 0 0.00 0 24049 8 0 0
srv_make_invnt_saldo 424 424 0 0.00 77 70970 4368 0 0
srv_make_money_saldo 819 819 0 0.00 2 9481 353 0 0
srv_recalc_idx_stat 4 4 0 0.00 246840 498420 350019 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4735 4735 0 0.00 1 30175 888 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 1 8116 419 0 0
t$perf-norm:sp_cancel_client_order 1572 1572 0 0.00 0 23904 44 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 909 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 586 586 0 0.00 0 3744 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 3392 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 368 368 0 0.00 0 1633 36 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 119931 7657 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 13629 38 0 0
t$perf-norm:sp_client_order 18777 18777 0 0.00 0 27693 37 0 0
t$perf-norm:sp_customer_reserve 948 948 0 0.00 0 5228 20 0 0
t$perf-norm:sp_pay_from_customer 6527 6527 0 0.00 0 3616 17 0 0
t$perf-norm:sp_pay_to_supplier 5531 5531 0 0.00 0 5367 21 0 0
t$perf-norm:sp_reserve_write_off 9499 9499 0 0.00 0 15805 26 0 0
t$perf-norm:sp_supplier_invoice 5201 5201 0 0.00 0 25432 33 0 0
t$perf-norm:sp_supplier_order 5195 5195 0 0.00 0 2118 28 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 330 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 142 7 0 0
t$perf-norm:srv_make_invnt_saldo 424 424 0 0.00 0 748 16 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 539 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 9 181 61 0 0
v_add_invoice_to_stock 9554 9350 204 2.13 0 6048 26 0 204
v_all_customers 34480 34480 0 0.00 0 127 3 0 0
v_all_suppliers 29036 29036 0 0.00 0 116 4 0 0
v_all_wares 37550 37550 0 0.00 0 126 3 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 3726 23 0 0
v_cancel_client_order 3144 3144 0 0.00 0 3013 12 0 0
v_cancel_customer_prepayment 1172 1172 0 0.00 0 15349 34 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 15404 20 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 5836 26 0 0
v_cancel_supplier_invoice 752 736 16 2.12 0 2306 21 0 16
v_cancel_supplier_order 816 816 0 0.00 0 8585 28 0 0
v_cancel_write_off 1974 1974 0 0.00 0 13025 30 0 0
v_max_id_clo_ord 5191 5191 0 0.00 0 433 4 0 0
v_max_id_clo_res 948 948 0 0.00 0 1142 10 0 0
v_max_id_ord_sup 5193 5193 0 0.00 0 6974 8 0 0
v_max_non_paid_invoice 1396 1396 0 0.00 0 70 7 0 0
v_max_non_paid_realizn 3313 3313 0 0.00 0 233 6 0 0
v_min_id_clo_ord 5191 5191 0 0.00 0 5140 10 0 0
v_min_id_clo_res 948 948 0 0.00 0 4151 15 0 0
v_min_id_ord_sup 5193 5193 0 0.00 0 3451 6 0 0
v_min_non_paid_invoice 5531 5531 0 0.00 0 5179 15 0 0
v_min_non_paid_realizn 6526 6526 0 0.00 0 6775 18 0 0
v_our_firm 9494 9494 0 0.00 0 50 4 0 0
v_reserve_write_off 18996 18996 0 0.00 0 14805 14 0 0
x_lock_depdocs_on_canc_invoice 12215 12215 0 0.00 0 13531 114 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 7014 340 0 0
x_make_qstorno_1000_1200 5194 5194 0 0.00 2 30922 1182 0 0
x_make_qstorno_1200_2000 5199 5199 0 0.00 4 94752 2780 0 0
x_make_qstorno_2100_3300 444466 444466 0 0.00 0 99349 194 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-06-2022 07:48:13 to 16-06-2022 07:48:17.

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:16:47 2 8192000000 8797228592 8807960576 3189120 0.038 1 1 2 0 3286736 61840 35776
05:17:48 19347 8192000000 9717616432 10017886208 797914720 8.875 100 83 165 1 825655952 7412496 20328736
05:19:11 17411 8192000000 9763270432 10073251840 840562016 9.305 100 88 174 2 872799792 11492880 20744896
05:20:38 14347 8192000000 9796486752 10112761856 866980416 9.570 100 92 184 1 907288544 18761936 21546192
05:22:01 16838 8192000000 9862941120 10194046976 913545584 10.032 100 85 170 0 975387456 43353408 18488464
05:23:21 6982 8192000000 9922034576 10266365952 953700112 10.427 100 87 173 0 1035933696 64202240 18031344
05:24:31 3223 8192000000 9952131408 10306252800 995260736 10.833 100 86 172 0 1073771408 61206448 17304224
05:25:39 2473 8192000000 10010719824 10371125248 1017609040 11.049 100 90 176 1 1120762368 86012288 17141040
05:26:43 1169 8192000000 10009517424 10375753728 1042623952 11.290 100 92 177 1 1129829600 69750848 17454800
05:27:47 1334 8192000000 10015488336 10392186880 1053300224 11.392 100 96 179 2 1141818816 70829712 17688880
05:28:49 1276 8192000000 10035970992 10412765184 1065944336 11.513 100 92 174 1 1156543552 73089456 17509760
05:29:52 1119 8192000000 10066905440 10444931072 1071670480 11.568 100 90 178 0 1183078096 93968848 17438768
05:30:54 1005 8192000000 10064493920 10450022400 1078078192 11.629 100 97 192 1 1188954048 91882688 18993168
05:31:57 1313 8192000000 10043801776 10428928000 1082067568 11.667 100 98 186 1 1162329680 62120128 18141984
05:32:59 1366 8192000000 10059893136 10443112448 1091427456 11.756 100 95 183 3 1181662656 71986784 18248416
05:34:01 1810 8192000000 10060069216 10446135296 1090171168 11.744 100 82 156 0 1177897856 72016112 15710576
05:35:04 2133 8192000000 10074565072 10466287616 1096060880 11.800 100 94 178 3 1199963472 86210944 17691648
05:36:07 946 8192000000 10072946608 10464485376 1098733248 11.826 100 89 172 3 1193766080 76880832 18152000
05:37:09 1103 8192000000 10063517376 10454159360 1100713296 11.844 100 85 161 0 1184677824 67895744 16068784
05:38:12 7515 8192000000 10073314992 10463625216 1098971008 11.828 100 66 130 1 1197985152 85946112 13068032
05:39:23 1353 8192000000 10038196928 10422992896 1072760176 11.578 100 89 165 0 1160916192 70568496 17587520
05:40:25 10641 8192000000 10000582640 10387673088 1051338928 11.374 98 61 120 1 1129206272 64702160 13165184
05:41:36 1335 8192000000 10001631072 10378674176 1047169584 11.334 100 90 174 2 1126229888 61422704 17637600
05:42:38 5276 8192000000 9998693952 10379554816 1043262976 11.296 100 66 126 2 1125535136 68447120 13825040
05:43:46 1567 8192000000 10011051312 10387492864 1049004224 11.351 100 89 171 2 1135291216 68596400 17690592
05:44:49 1344 8192000000 9979273312 10345312256 1019402112 11.066 100 96 188 0 1099270832 59544016 20324704
05:45:51 4164 8192000000 9995182576 10368778240 1038013360 11.246 99 72 140 2 1105480032 53692512 13774160
05:47:00 2471 8192000000 9998222256 10369556480 1042454912 11.288 99 89 163 2 1118698544 61223872 15019760
05:48:09 12283 8192000000 10017691056 10401812480 1062020912 11.476 100 69 127 1 1149558480 74754096 12783472
05:49:24 1150 8192000000 10063501728 10444505088 1088772240 11.731 100 95 174 1 1180055424 75384048 15899136
05:50:38 5147 8192000000 10084250832 10478059520 1095211552 11.792 100 78 148 2 1196313392 85000592 16101248
05:51:54 1035 8192000000 10115499648 10505093120 1106297472 11.897 100 83 157 0 1238927568 115635216 16994880
05:52:59 14704 8192000000 10169359872 10570477568 1120787328 12.034 100 72 135 1 1295005616 159545648 14672640
05:54:18 1734 8192000000 10211467008 10606280704 1131699984 12.137 100 80 153 1 1329851280 180831040 17320256
05:55:25 3105 8192000000 10238792912 10638000128 1148162000 12.292 100 78 152 0 1352920752 188059616 16699136
05:56:35 2101 8192000000 10279609232 10684862464 1166068528 12.460 100 84 166 1 1395124752 209859344 19196880
05:57:40 1438 8192000000 10318644400 10721046528 1182451344 12.613 100 75 141 0 1431432336 232938416 16042576
05:58:47 1001 8192000000 10375019632 10772746240 1185666384 12.643 100 93 180 2 1486468272 280696000 20105888
05:59:53 1183 8192000000 10426992768 10838114304 1196608192 12.745 100 96 188 1 1535154848 315872080 22674576
06:01:01 1840 8192000000 10460683632 10868510720 1201780960 12.793 100 87 174 0 1567527296 345201216 20545120
06:02:06 886 8192000000 10477456896 10907725824 1199862832 12.775 100 97 188 1 1582941584 361232640 21846112
06:03:10 740 8192000000 10505058736 10913665024 1208357616 12.854 100 99 195 1 1613350080 382099872 22892592
06:04:15 1465 8192000000 10521119936 10956857344 1201754624 12.793 100 93 179 0 1628382336 405259280 21368432
06:05:21 1006 8192000000 10511929280 11003265024 1209093648 12.861 100 97 188 2 1619460928 387683952 22683328
06:06:23 1530 8192000000 10532740912 10990141440 1205238544 12.825 100 97 190 0 1636177136 407659040 23279552
06:07:28 1310 8192000000 10517873760 10963861504 1206600752 12.838 100 97 181 1 1626046480 397414432 22031296
06:08:31 3841 8192000000 10445853376 10915684352 1200952352 12.785 100 54 105 2 1556566672 342585888 13028432
06:09:39 12022 8192000000 10435077408 10884628480 1181621616 12.605 99 44 84 0 1552017552 359907648 10488288
06:10:53 12059 8192000000 10452178720 10896912384 1185908080 12.645 100 45 90 0 1554923408 358015360 10999968
06:12:26 33236 8192000000 10448826352 10899152896 1136217248 12.180 97 31 60 1 1529312464 384401888 8693328
06:14:03 12003 8192000000 10401119632 10912681984 1137294272 12.190 98 30 60 0 1492005488 346561424 8149792
06:15:19 4732 8192000000 10270364320 10742939648 1147347504 12.285 100 45 88 1 1374727216 217016848 10362864
06:16:25 38728 8192000000 10138884928 10587074560 1143560000 12.249 99 27 54 0 1283098032 132706688 6831344
06:18:05 2451 8192000000 10199856736 10619408384 1152662944 12.334 100 71 139 0 1304305744 136858272 14784528
06:19:11 4003 8192000000 10201813872 10664079360 1154462512 12.351 99 46 90 1 1296898144 131424608 11011024
06:20:16 55078 8192000000 10262652608 10719576064 1167936224 12.478 100 39 71 1 1362021696 185280032 8805440
06:22:23 49318 8192000000 10235628816 10768162816 1129243616 12.114 98 15 30 0 1323359024 190664384 3451024
06:24:23 133344 8192000000 10097323264 10578378752 1107956288 11.913 97 10 18 1 1152628160 43146288 1525584
06:27:37 137153 8192000000 10132589936 10576674816 1106691264 11.901 95 30 57 4 1154452176 41061776 6699136
06:30:54 103060 8192000000 10077922016 10569437184 1115625440 11.986 97 15 28 0 1245395456 126336016 3434000
06:33:42 44366 8192000000 10060436496 10483568640 1092593984 11.767 96 41 74 3 1123955936 24081040 7280912
06:35:30 131423 8192000000 10092169920 10536779776 1096929616 11.808 97 10 20 0 1124945200 25616256 2399328
06:38:51 139169 8192000000 10094895248 10534285312 1090199472 11.745 97 18 36 0 1133046032 39228160 3618400
06:42:18 99397 8192000000 10069247296 10503331840 1096514064 11.805 97 39 74 2 1148016976 43812528 7690384
06:44:59 54713 8192000000 10119781040 10546819072 1120683232 12.033 98 40 79 1 1199071728 70129136 8259360
06:46:54 66119 8192000000 10129424464 10583654400 1126470880 12.088 99 21 42 0 1221398608 89714688 5213040
06:49:08 91868 8192000000 10134001408 10615087104 1119428960 12.022 98 17 34 1 1245205536 121657296 4119280
06:51:59 104619 8192000000 10157325664 10653069312 1105583520 11.891 98 16 32 0 1238094128 128577584 3933024
06:55:24 86001 8192000000 10069555248 10516406272 1074148480 11.592 95 23 42 1 1126656528 48412816 4095232
06:57:50 64217 8192000000 10053212416 10524672000 1088764592 11.731 96 11 19 1 1131905168 41064528 2076048
06:59:58 43078 8192000000 10142823680 10590990336 1096602048 11.805 97 31 60 0 1180202320 76783280 6816992
07:01:42 35648 8192000000 10146042880 10575773696 1127182208 12.095 99 39 78 0 1208734512 73617856 7934448
07:04:14 61448 8192000000 10101979760 10565402624 1111300224 11.945 97 18 35 1 1185544800 70527536 3717040
07:06:16 107081 8192000000 10087034160 10565046272 1099208880 11.830 96 9 18 0 1165874576 64625296 2040400
07:09:06 79732 8192000000 10115241152 10547281920 1145529504 12.268 100 52 104 0 1217219696 60165648 11524544
07:11:26 66085 8192000000 10109735760 10566627328 1152025808 12.329 100 18 36 2 1204104224 47762448 4315968
07:13:33 66606 8192000000 10130430736 10626768896 1140662272 12.222 100 21 43 1 1234799216 89447568 4689376
07:15:41 50644 8192000000 10118180816 10594701312 1126802416 12.091 98 21 42 2 1235303120 103960448 4540256
07:17:36 65380 8192000000 10096970384 10569633792 1123684320 12.062 98 6 12 1 1186610432 61481728 1444384
07:19:42 116509 8192000000 10079301040 10527313920 1136391488 12.182 99 17 34 2 1200992848 60596432 4004928
07:22:39 31177 8192000000 10121470528 10556108800 1107675264 11.910 96 27 52 4 1184105648 70399232 6031152
07:24:21 34196 8192000000 10147951808 10601033728 1131742240 12.138 99 46 92 3 1257281040 115323120 10215680
07:25:55 55556 8192000000 10194514240 10633842688 1152124944 12.329 100 26 52 0 1290100080 132093664 5881472
07:27:56 32749 8192000000 10158980320 10642227200 1132446784 12.144 99 38 73 3 1267149056 127039536 7662736
07:29:32 16800 8192000000 10121985024 10553102336 1114292768 11.973 98 39 77 0 1189436128 67388112 7755248
07:30:49 51391 8192000000 10162000672 10648543232 1140237424 12.218 100 19 38 0 1278762080 133962192 4562464
07:32:50 36712 8192000000 10084641936 10576400384 1123916640 12.064 99 22 44 0 1197379680 68959136 4503904
07:34:28 9286 8192000000 10084498192 10501844992 1125463696 12.079 99 56 105 1 1162707424 27307392 9936336
07:35:44 77503 8192000000 10089264704 10543968256 1120853664 12.035 98 32 64 0 1193365872 66085680 6426528
07:39:16 56379 8192000000 10121222640 10596847616 1153358848 12.341 100 16 32 2 1237435504 80777328 3299328
07:41:15 97425 8192000000 10131825040 10605015040 1112926336 11.960 97 15 30 2 1174171840 57981536 3263968
07:44:50 72608 8192000000 10083378224 10589261824 1098488688 11.823 96 11 22 1 1184696224 83608032 2599504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-06-2022 07:48:19 to 16-06-2022 07:48:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-06-2022 07:48:35 to 16-06-2022 07:48:37.

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          213335
MON$OLDEST_ACTIVE               213561
MON$OLDEST_SNAPSHOT             213561
MON$NEXT_TRANSACTION            213561
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                       2088800
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             760
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2780 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2780 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2780 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2780 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:25: 
:26: 
:27: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
:28:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:29: 
:30: 
:31: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
:32:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:33: 
:34: 
:35: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
:36:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:37: 
:38: 
:39: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
:40:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:41: 
:42: 
:43: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
:44:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:45: 
:46: 
:47: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
:54: 
:55: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:57: 
:58: 
:59: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:61: 
:62: 
:63: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Thu Jun 16 07:46:53 2022
: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'

16.06.22 07:48:38. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-06-16 07:48:40 No severe PSQL-related problems occured
16.06.22 07:48:42. End of report.