Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.12.2021 13: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 16606644 kB
MemAvailable 31434808 kB
Buffers 0 kB
Cached 15114932 kB
SwapCached 440 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.1
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 2021-12-24 13:37:12
DTS_END                         2021-12-24 13:37:12.5490
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48260
TRN_ID                          228072
ATT_ID                          664
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: 8969.88 25450 1076385
customer order: creation 164.35 458 19722
customer order: refuse 13.77 325 1652
order to supplier: creation 43.90 1491 5268
order to supplier: removal 3.42 123527 410
invoice (draft): creation 43.30 3663 5196
invoice (draft): removal 52.55 997 6306
invoice accept: apply 39.94 19279 4793
invoice accept: cancel 47.18 849 5661
customer reserve: creation 3662.19 190 439463
customer reserve: removal 4620.63 72 554475
realization accept: apply 78.46 186 9415
realization accept: cancel 74.94 122 8993
payment from customer: creation 59.42 135 7130
payment from customer: removal 5.57 74 668
payment to supplier: creation 46.32 154 5558
payment to supplier: removal 3.11 94 373
service: total inventory turnovers 3.86 3464 463
service: total monetary turnovers 5.96 506 715
service: aggregate perf. data 1.00 14197 120
service: refresh index statistics 0.03 339220 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-12-2021 13:39:38 to 24-12-2021 13:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6771
TEST_TIME 2 6113
TEST_TIME 3 5642
TEST_TIME 4 5728
TEST_TIME 5 5300
TEST_TIME 6 5402
TEST_TIME 7 5402
TEST_TIME 8 6020
TEST_TIME 9 5807
TEST_TIME 10 6161
TEST_TIME 11 6642
TEST_TIME 12 6698
TEST_TIME 13 6910
TEST_TIME 14 7373
TEST_TIME 15 7466
TEST_TIME 16 7383
TEST_TIME 17 7732
TEST_TIME 18 8057
TEST_TIME 19 8428
TEST_TIME 20 8645
TEST_TIME 21 8939
TEST_TIME 22 9391
TEST_TIME 23 9249
TEST_TIME 24 9469
TEST_TIME 25 10659
TEST_TIME 26 11321
TEST_TIME 27 11286
TEST_TIME 28 11054
TEST_TIME 29 10880
TEST_TIME 30 10911
TEST_TIME 31 10760
TEST_TIME 32 10592
TEST_TIME 33 10409
TEST_TIME 34 10256
TEST_TIME 35 10295
TEST_TIME 36 10233
TEST_TIME 37 10115
TEST_TIME 38 9926
TEST_TIME 39 9824
TEST_TIME 40 9658
TEST_TIME 41 9690
TEST_TIME 42 9587
TEST_TIME 43 9624
TEST_TIME 44 9486
TEST_TIME 45 9316
TEST_TIME 46 9389
TEST_TIME 47 9291
TEST_TIME 48 9425
TEST_TIME 49 9376
TEST_TIME 50 9337
TEST_TIME 51 9336
TEST_TIME 52 9393
TEST_TIME 53 9349
TEST_TIME 54 9288
TEST_TIME 55 9284
TEST_TIME 56 9394
TEST_TIME 57 9509
TEST_TIME 58 9514
TEST_TIME 59 9550
TEST_TIME 60 9443
TEST_TIME 61 9295
TEST_TIME 62 9334
TEST_TIME 63 9279
TEST_TIME 64 9271
TEST_TIME 65 9203
TEST_TIME 66 9160
TEST_TIME 67 9066
TEST_TIME 68 8999
TEST_TIME 69 8973
TEST_TIME 70 8934
TEST_TIME 71 8921
TEST_TIME 72 8906
TEST_TIME 73 9019
TEST_TIME 74 9250
TEST_TIME 75 9346
TEST_TIME 76 9279
TEST_TIME 77 9256
TEST_TIME 78 9356
TEST_TIME 79 9306
TEST_TIME 80 9268
TEST_TIME 81 9168
TEST_TIME 82 9177
TEST_TIME 83 9125
TEST_TIME 84 9110
TEST_TIME 85 9050
TEST_TIME 86 8986
TEST_TIME 87 8932
TEST_TIME 88 8942
TEST_TIME 89 8929
TEST_TIME 90 8912
TEST_TIME 91 8915
TEST_TIME 92 8910
TEST_TIME 93 8942
TEST_TIME 94 9002
TEST_TIME 95 9018
TEST_TIME 96 9046
TEST_TIME 97 9103
TEST_TIME 98 9084
TEST_TIME 99 9085
TEST_TIME 100 9106
TEST_TIME 101 9063
TEST_TIME 102 9006
TEST_TIME 103 9020
TEST_TIME 104 8992
TEST_TIME 105 8935
TEST_TIME 106 8913
TEST_TIME 107 8882
TEST_TIME 108 8905
TEST_TIME 109 8894
TEST_TIME 110 8918
TEST_TIME 111 8928
TEST_TIME 112 8951
TEST_TIME 113 8917
TEST_TIME 114 8923
TEST_TIME 115 8981
TEST_TIME 116 9054
TEST_TIME 117 9052
TEST_TIME 118 9039
TEST_TIME 119 9048
TEST_TIME 120 8974

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-12-2021 13:39:42 to 24-12-2021 13:39:45.

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 1484247 1484247 0 0.00 0 20827 7 0 0
doc_list_biud 1484245 1484245 0 0.00 0 85547 34 0 0
sp_add_invoice_to_stock 4915 4793 122 2.48 36 238146 19279 0 122
sp_cancel_adding_invoice 5661 5661 0 0.00 3 67475 850 0 0
sp_cancel_client_order 1652 1652 0 0.00 4 6939 325 0 0
sp_cancel_customer_reserve 554475 554475 0 0.00 1 26906 73 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 5352 75 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 3 3457 94 0 0
sp_cancel_supplier_invoice 6324 6306 18 0.28 3 85552 998 0 18
sp_cancel_supplier_order 410 410 0 0.00 7 1095588 123527 0 0
sp_cancel_write_off 8993 8993 0 0.00 1 8097 122 0 0
sp_client_order 19722 19722 0 0.00 4 24478 459 0 0
sp_customer_reserve 439463 439463 0 0.00 0 60477 191 0 0
sp_fill_shopping_cart 30171 30171 0 0.00 1 36242 49 0 0
sp_fill_shopping_cart_clo_res 439453 439453 0 0.00 0 15148 19 0 0
sp_get_clo_for_invoice 4783 4783 0 0.00 0 31716 418 0 0
sp_kill_cost_storno 15695 15695 0 0.00 0 20816 61 0 0
sp_kill_qstorno_ret_qs2qd 1124659 1124659 0 0.00 0 85546 39 0 0
sp_kill_qty_storno 591694 591694 0 0.00 0 85547 84 0 0
sp_lock_selected_doc 19724 19724 0 0.00 0 10730 7 0 0
sp_make_cost_storno 26886 26886 0 0.00 0 15211 52 0 0
sp_multiply_rows_for_pdistr 26885 26885 0 0.00 0 10830 38 0 0
sp_multiply_rows_for_qdistr 19722 19722 0 0.00 0 15788 245 0 0
sp_pay_from_customer 7130 7130 0 0.00 3 19223 136 0 0
sp_pay_to_supplier 5558 5558 0 0.00 4 19535 155 0 0
sp_payment_common 12688 12688 0 0.00 1 8615 33 0 0
sp_qd_handle_on_cancel_clo 1652 1652 0 0.00 0 6502 298 0 0
sp_qd_handle_on_invoice_upd_sts 10444 10444 0 0.00 0 28187 348 0 0
sp_qd_handle_on_reserve_upd_sts 9414 9414 0 0.00 0 18359 59 0 0
sp_reserve_write_off 9415 9415 0 0.00 7 18441 187 0 0
sp_supplier_invoice 5196 5196 0 0.00 7 104901 3663 0 0
sp_supplier_order 5268 5268 0 0.00 5 31908 1491 0 0
srv_aggregate_perf_data 120 120 0 0.00 2721 180175 14197 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1567 118052 48494 0 0
srv_find_qd_qs_mism 1031114 1031114 0 0.00 0 12165 6 0 0
srv_make_invnt_saldo 463 463 0 0.00 55 125193 3464 0 0
srv_make_money_saldo 715 715 0 0.00 1 18138 507 0 0
srv_recalc_idx_stat 4 4 0 0.00 149888 481375 339221 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4793 4793 0 0.00 0 11356 162 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 3270 80 0 0
t$perf-norm:sp_cancel_client_order 1652 1652 0 0.00 0 4784 14 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 270 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 5225 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 1878 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 447 11 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 25747 1612 0 0
t$perf-norm:sp_cancel_write_off 964 964 0 0.00 0 5531 8 0 0
t$perf-norm:sp_client_order 19722 19722 0 0.00 0 19144 15 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 3874 12 0 0
t$perf-norm:sp_pay_from_customer 7130 7130 0 0.00 0 10392 13 0 0
t$perf-norm:sp_pay_to_supplier 5558 5558 0 0.00 0 3334 7 0 0
t$perf-norm:sp_reserve_write_off 9415 9415 0 0.00 0 9631 11 0 0
t$perf-norm:sp_supplier_invoice 5196 5196 0 0.00 0 10652 26 0 0
t$perf-norm:sp_supplier_order 5268 5268 0 0.00 0 8268 16 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 234 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2994 50 0 0
t$perf-norm:srv_make_invnt_saldo 463 463 0 0.00 0 671 7 0 0
t$perf-norm:srv_make_money_saldo 715 715 0 0.00 0 298 2 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 5 0 0
v_add_invoice_to_stock 9804 9560 244 2.48 0 8039 17 0 244
v_all_customers 36412 36412 0 0.00 0 47 1 0 0
v_all_suppliers 28956 28956 0 0.00 0 58 1 0 0
v_all_wares 39444 39444 0 0.00 0 147 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 270 5 0 0
v_cancel_client_order 3304 3304 0 0.00 0 1545 4 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 2285 6 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 1393 4 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 972 9 0 0
v_cancel_supplier_invoice 804 768 36 4.47 0 820 16 0 36
v_cancel_supplier_order 820 820 0 0.00 0 2449 10 0 0
v_cancel_write_off 1928 1928 0 0.00 0 2529 12 0 0
v_max_id_clo_ord 5267 5267 0 0.00 0 384 1 0 0
v_max_id_clo_res 962 962 0 0.00 0 2150 14 0 0
v_max_id_ord_sup 5182 5182 0 0.00 0 5252 10 0 0
v_max_non_paid_invoice 1529 1529 0 0.00 0 147 2 0 0
v_max_non_paid_realizn 3687 3687 0 0.00 0 2524 3 0 0
v_min_id_clo_ord 5267 5267 0 0.00 0 2479 5 0 0
v_min_id_clo_res 962 962 0 0.00 0 2317 6 0 0
v_min_id_ord_sup 5182 5182 0 0.00 0 8406 5 0 0
v_min_non_paid_invoice 5558 5558 0 0.00 0 7946 23 0 0
v_min_non_paid_realizn 7130 7130 0 0.00 0 8413 28 0 0
v_our_firm 9918 9918 0 0.00 0 133 1 0 0
v_reserve_write_off 18828 18828 0 0.00 0 5996 4 0 0
x_lock_depdocs_on_canc_invoice 11583 11583 0 0.00 0 31926 118 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 25475 526 0 0
x_make_qstorno_1000_1200 5268 5268 0 0.00 2 31896 1418 0 0
x_make_qstorno_1200_2000 5196 5196 0 0.00 3 94125 3461 0 0
x_make_qstorno_2100_3300 439463 439463 0 0.00 0 60476 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-12-2021 13:39:46 to 24-12-2021 13:39:50.

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:15 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:16 17904 8192000000 9707219952 10001551360 782215328 8.716 100 86 172 0 810625568 7451744 20958496
11:09:43 24183 8192000000 9764392624 10070003712 832830992 9.228 100 88 176 0 867621568 13203424 21587152
11:11:15 17844 8192000000 9803589344 10122768384 871205488 9.612 100 83 166 0 908956704 18386960 19364256
11:12:38 14089 8192000000 9864550592 10198724608 917552672 10.072 100 81 162 0 976449264 40999136 17897456
11:13:54 9950 8192000000 9926456256 10272096256 964716976 10.535 100 80 159 1 1034915952 53541408 16657568
11:15:07 5591 8192000000 9962776384 10316910592 999699840 10.876 100 82 165 1 1077998160 61774704 16523616
11:16:16 3706 8192000000 10009282080 10367664128 1025165952 11.122 100 89 178 0 1121392800 78342496 17884352
11:17:21 1756 8192000000 10038005600 10401968128 1047541488 11.337 100 91 182 0 1148066128 82189264 18335376
11:18:24 2103 8192000000 10060574800 10433146880 1065396880 11.508 100 93 186 1 1162426944 78113456 18916608
11:19:27 9796 8192000000 9990289760 10389479424 1069058192 11.543 100 33 66 1 1116435072 40408224 6968656
11:20:39 4381 8192000000 10008045520 10396631040 1080652544 11.654 100 53 102 4 1126650112 34889616 11107952
11:21:46 43724 8192000000 9986102496 10387525632 1087855920 11.722 100 27 54 0 1121648464 26472496 7320048
11:23:33 5349 8192000000 10007251936 10411634688 1093757472 11.778 100 40 78 1 1117684784 15508864 8418448
11:24:39 6703 8192000000 10004433072 10415575040 1099099760 11.829 100 48 97 3 1121974096 11705872 11168464
11:25:48 8094 8192000000 10029504080 10435096576 1105384304 11.889 100 35 69 0 1131474224 19127568 6962352
11:26:59 61045 8192000000 9987853904 10384482304 1052618016 11.386 96 38 75 3 1079577712 18828032 8131664
11:29:02 2886 8192000000 10031440592 10421960704 1081319696 11.660 100 60 120 2 1141206448 47579280 12307472
11:30:06 6154 8192000000 9964889520 10346106880 1033331392 11.201 98 44 89 3 1066702384 23516528 9854464
11:31:13 37843 8192000000 9914722208 10300620800 978694368 10.671 96 32 65 0 1012835840 27161152 6980320
11:33:09 6249 8192000000 9924655168 10310389760 995190512 10.832 98 38 76 2 1018642720 14581216 8870992
11:34:17 7242 8192000000 9961069536 10354827264 1039880800 11.264 100 46 94 1 1071454304 20945440 10628064
11:35:27 65149 8192000000 9959013152 10369245184 1053988144 11.399 100 26 52 0 1091043472 31378432 5676896
11:37:43 13975 8192000000 10017529328 10410115072 1084721904 11.692 100 60 117 0 1119977120 22543936 12711280
11:39:01 22806 8192000000 10060788288 10465071104 1105578304 11.891 100 43 86 0 1168637392 53653056 9406032
11:40:29 30832 8192000000 10117077712 10531831808 1121524768 12.041 100 48 95 2 1218346208 86200608 10620832
11:42:06 21050 8192000000 10138779488 10549993472 1139543600 12.211 100 43 86 1 1257038672 107639344 9855728
11:43:33 8394 8192000000 10183804512 10600165376 1152757696 12.335 100 49 96 0 1287084208 123345600 10980912
11:44:46 3223 8192000000 10222900432 10622652416 1150955296 12.318 100 72 143 0 1327051904 159954576 16142032
11:45:52 16539 8192000000 10226299632 10643382272 1157365024 12.379 100 49 98 1 1327484624 158765088 11354512
11:47:12 10473 8192000000 10263607296 10686443520 1175351616 12.547 100 57 111 2 1369355648 180787072 13216960
11:48:32 2641 8192000000 10306057168 10717229056 1182139648 12.610 100 69 136 1 1410561152 212236016 16185488
11:49:41 3956 8192000000 10361370960 10770636800 1194188528 12.722 100 85 170 0 1469290016 256066192 19035296
11:50:49 4669 8192000000 10413140352 10819100672 1187203200 12.657 100 90 180 3 1510200016 302449440 20547376
11:51:59 2190 8192000000 10398325440 10802278400 1189776672 12.681 100 77 154 0 1498625120 291686288 17162160
11:53:09 7124 8192000000 10409170256 10824118272 1167432336 12.473 99 55 108 0 1504218608 323231984 13554288
11:54:18 1565 8192000000 10438726704 10863915008 1158553600 12.390 99 80 159 0 1538884400 361071808 19258992
11:55:24 15663 8192000000 10413104608 10836881408 1152580352 12.334 100 41 81 1 1506015616 343305504 10129760
11:56:46 11129 8192000000 10389485808 10871238656 1145411632 12.266 99 48 97 1 1523174160 365011104 12751424
11:58:04 3064 8192000000 10426929456 10835685376 1162126496 12.423 100 76 152 0 1523509392 342982144 18400752
11:59:11 14547 8192000000 10376200944 10874032128 1166655152 12.466 100 34 68 1 1530630896 355088240 8887504
12:00:28 21186 8192000000 10401145136 10838253568 1127335712 12.096 98 27 55 0 1462799584 327962000 7501872
12:01:51 19379 8192000000 10404683360 10912235520 1137337888 12.190 100 28 56 1 1535555872 390089824 8128160
12:03:16 21685 8192000000 10155906416 10697519104 1135376192 12.172 100 12 24 1 1303019456 164574336 3068928
12:04:39 32954 8192000000 10054435888 10486771712 1114803456 11.978 99 41 83 0 1158182832 34209712 9169664
12:06:17 92903 8192000000 10063056272 10556424192 1116522304 11.994 99 16 32 0 1170592144 50772864 3296976
12:08:59 67733 8192000000 10062240064 10509438976 1087107728 11.715 96 20 40 0 1123599792 32790752 3701312
12:11:15 115982 8192000000 10104330800 10575876096 1112010016 11.951 98 15 30 0 1201118816 85399664 3709136
12:14:13 67154 8192000000 10035198112 10472595456 1105530400 11.890 98 32 63 0 1129157776 17410736 6216640
12:16:48 41513 8192000000 10119997776 10562854912 1146922288 12.281 100 23 46 0 1214973360 63438096 4612976
12:18:33 94982 8192000000 10104568640 10541805568 1109467568 11.927 97 25 47 1 1177546864 63362528 4716768
12:21:08 89398 8192000000 10095925616 10555170816 1117977968 12.008 98 12 24 0 1182183696 61732752 2472976
12:24:05 77322 8192000000 10125352464 10609238016 1122289216 12.049 98 7 14 0 1208581600 84904864 1387520
12:26:26 85525 8192000000 10079433792 10531663872 1085827312 11.703 95 13 26 0 1133665872 44792448 3046112
12:28:52 87578 8192000000 10059574688 10500313088 1139633216 12.212 100 21 42 0 1174776416 30439120 4704080
12:31:20 40862 8192000000 10115338016 10556076032 1148870144 12.299 100 23 45 0 1192618416 39530576 4217696
12:33:08 46635 8192000000 10130384224 10604343296 1156089360 12.367 100 15 30 1 1239871264 79344416 4437488
12:35:03 118051 8192000000 10073636800 10524835840 1083351632 11.679 94 10 20 0 1117693888 32362752 1979504
12:38:03 99628 8192000000 10080897680 10523639808 1121891840 12.045 98 28 56 1 1188361232 60651392 5818000
12:40:44 19974 8192000000 10049417136 10489708544 1105676528 11.891 98 30 60 0 1143137376 31205472 6255376
12:42:05 43833 8192000000 10038588176 10476011520 1087107456 11.715 96 13 26 1 1119675856 30084624 2483776
12:43:50 73653 8192000000 10085243536 10530697216 1115579264 11.985 98 10 20 0 1169759968 51878864 2301840
12:46:04 61340 8192000000 10168650688 10611425280 1149229312 12.302 100 24 48 1 1297025632 142192464 5603856
12:48:12 34868 8192000000 10228884912 10666528768 1161313600 12.416 100 29 58 1 1327499168 159135008 7050560
12:49:53 56733 8192000000 10234435088 10716274688 1147985072 12.291 99 27 55 1 1332067968 177331840 6751056
12:52:26 65932 8192000000 10046720880 10478841856 1100681488 11.844 96 21 42 2 1129674272 24588656 4404128
12:54:49 83781 8192000000 10060865488 10513358848 1102129056 11.858 97 16 32 0 1137132064 31761920 3241088
12:57:15 84435 8192000000 10091255024 10515173376 1105431008 11.889 99 28 56 0 1185458432 74502256 5525168
13:00:07 25470 8192000000 10037821488 10466197504 1086572224 11.710 97 26 52 0 1117891248 25315904 6003120
13:01:59 66069 8192000000 10050590000 10511663104 1104096208 11.876 98 9 18 0 1136046624 29935456 2014960
13:04:13 91477 8192000000 10070979536 10556461056 1131543328 12.136 100 14 28 0 1208880400 74432944 2904128
13:06:59 45344 8192000000 10103515760 10540990464 1106186992 11.896 96 38 74 1 1163513264 49407152 7919120
13:08:51 51106 8192000000 10153839120 10585812992 1133932960 12.158 99 29 58 0 1218669712 79044464 5692288
13:10:56 60351 8192000000 10105748384 10593431552 1111620464 11.948 97 29 58 1 1205149312 87647040 5881808
13:13:00 83551 8192000000 10128309616 10575491072 1115495808 11.984 97 27 55 1 1198752832 77827184 5429840
13:15:28 33334 8192000000 10110970656 10540756992 1138595744 12.202 99 46 92 0 1200773008 53165504 9011760
13:17:02 39396 8192000000 10097289664 10530828288 1089673600 11.740 95 41 82 0 1161022624 63325312 8023712
13:18:43 81906 8192000000 10096261728 10525126656 1124540752 12.070 99 43 84 3 1191893856 58698944 8654160
13:21:05 46474 8192000000 10058329696 10543812608 1124559840 12.070 99 5 10 0 1161150480 35752112 838528
13:24:36 28736 8192000000 10119092704 10562891776 1141558688 12.230 99 15 30 0 1198271632 53552000 3160944
13:26:12 101474 8192000000 10116295680 10599837696 1115788240 11.987 97 24 48 0 1178925584 58127504 5009840
13:28:57 94289 8192000000 10122024448 10570354688 1107705920 11.911 97 18 36 0 1184073696 72216704 4151072
13:31:39 50258 8192000000 10152187776 10637430784 1112523616 11.956 98 18 36 1 1251374768 134847536 4003616
13:33:37 86526 8192000000 10076808880 10508341248 1108249472 11.916 98 27 54 0 1161930640 48259568 5421600
13:36:09 81849 8192000000 9585398304 9954672640 673385552 7.595 60 9 18 0 716939376 41544320 2009504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-12-2021 13:39:52 to 24-12-2021 13:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 24-12-2021 13:40:08 to 24-12-2021 13:40:09.

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          228071
MON$OLDEST_ACTIVE               228647
MON$OLDEST_SNAPSHOT             228647
MON$NEXT_TRANSACTION            228647
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                       2036960
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2696 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2696 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2696 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2696 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 ---
:100: 
:101: mscs-test-161.budzdorov.ru    Fri Dec 24 13:37:36 2021
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:103: 
:104: 
:105: mscs-test-161.budzdorov.ru    Fri Dec 24 13:37:48 2021
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:107: 
--- end of diff output ---

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

24.12.21 13:40:10. 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
2021-12-24 13:40:13 No severe PSQL-related problems occured
24.12.21 13:40:14. End of report.