Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.10.2022 16:43
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 15278288 kB
MemAvailable 30518560 kB
Buffers 0 kB
Cached 16456636 kB
SwapCached 2060 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-10-17 16:42:26
DTS_END                         2022-10-17 16:42:26.1680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58502
TRN_ID                          236128
ATT_ID                          619
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: 9536.28 25233 1144353
customer order: creation 164.98 388 19798
customer order: refuse 14.32 380 1718
order to supplier: creation 43.41 1324 5209
order to supplier: removal 3.73 107051 447
invoice (draft): creation 44.54 3058 5345
invoice (draft): removal 57.11 787 6853
invoice accept: apply 39.31 17002 4717
invoice accept: cancel 51.60 712 6192
customer reserve: creation 3735.18 164 448221
customer reserve: removal 5086.61 61 610393
realization accept: apply 83.60 187 10032
realization accept: cancel 86.85 101 10422
payment from customer: creation 57.41 126 6889
payment from customer: removal 5.57 86 668
payment to supplier: creation 46.85 138 5622
payment to supplier: removal 3.68 75 442
service: total inventory turnovers 4.33 2167 519
service: total monetary turnovers 6.45 40 774
service: aggregate perf. data 0.73 24829 88
service: refresh index statistics 0.03 345988 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-10-2022 16:43:52 to 17-10-2022 16:43:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6074
TEST_TIME 2 6329
TEST_TIME 3 5536
TEST_TIME 4 4841
TEST_TIME 5 5074
TEST_TIME 6 4897
TEST_TIME 7 5429
TEST_TIME 8 5690
TEST_TIME 9 5339
TEST_TIME 10 5095
TEST_TIME 11 5111
TEST_TIME 12 5003
TEST_TIME 13 4931
TEST_TIME 14 5253
TEST_TIME 15 5441
TEST_TIME 16 5661
TEST_TIME 17 5888
TEST_TIME 18 6326
TEST_TIME 19 6824
TEST_TIME 20 7311
TEST_TIME 21 7689
TEST_TIME 22 7746
TEST_TIME 23 8223
TEST_TIME 24 9316
TEST_TIME 25 9179
TEST_TIME 26 9770
TEST_TIME 27 10776
TEST_TIME 28 11019
TEST_TIME 29 11062
TEST_TIME 30 10905
TEST_TIME 31 10717
TEST_TIME 32 10731
TEST_TIME 33 10516
TEST_TIME 34 10677
TEST_TIME 35 10742
TEST_TIME 36 10509
TEST_TIME 37 10324
TEST_TIME 38 10270
TEST_TIME 39 10203
TEST_TIME 40 10206
TEST_TIME 41 10103
TEST_TIME 42 10115
TEST_TIME 43 10041
TEST_TIME 44 9924
TEST_TIME 45 10086
TEST_TIME 46 10025
TEST_TIME 47 10160
TEST_TIME 48 10174
TEST_TIME 49 10034
TEST_TIME 50 9919
TEST_TIME 51 9895
TEST_TIME 52 9885
TEST_TIME 53 9935
TEST_TIME 54 9842
TEST_TIME 55 9986
TEST_TIME 56 9993
TEST_TIME 57 9973
TEST_TIME 58 9893
TEST_TIME 59 10019
TEST_TIME 60 10114
TEST_TIME 61 10070
TEST_TIME 62 10052
TEST_TIME 63 10313
TEST_TIME 64 10322
TEST_TIME 65 10257
TEST_TIME 66 10170
TEST_TIME 67 10097
TEST_TIME 68 10114
TEST_TIME 69 10030
TEST_TIME 70 9948
TEST_TIME 71 9861
TEST_TIME 72 9944
TEST_TIME 73 9991
TEST_TIME 74 9959
TEST_TIME 75 10008
TEST_TIME 76 9994
TEST_TIME 77 9959
TEST_TIME 78 9917
TEST_TIME 79 9931
TEST_TIME 80 10005
TEST_TIME 81 10027
TEST_TIME 82 10007
TEST_TIME 83 10034
TEST_TIME 84 9990
TEST_TIME 85 9932
TEST_TIME 86 9901
TEST_TIME 87 9820
TEST_TIME 88 9894
TEST_TIME 89 9852
TEST_TIME 90 9792
TEST_TIME 91 9773
TEST_TIME 92 9711
TEST_TIME 93 9649
TEST_TIME 94 9686
TEST_TIME 95 9703
TEST_TIME 96 9726
TEST_TIME 97 9699
TEST_TIME 98 9703
TEST_TIME 99 9810
TEST_TIME 100 9769
TEST_TIME 101 9779
TEST_TIME 102 9784
TEST_TIME 103 9776
TEST_TIME 104 9809
TEST_TIME 105 9899
TEST_TIME 106 9914
TEST_TIME 107 9876
TEST_TIME 108 9838
TEST_TIME 109 9774
TEST_TIME 110 9757
TEST_TIME 111 9698
TEST_TIME 112 9697
TEST_TIME 113 9683
TEST_TIME 114 9668
TEST_TIME 115 9626
TEST_TIME 116 9634
TEST_TIME 117 9581
TEST_TIME 118 9566
TEST_TIME 119 9574
TEST_TIME 120 9541

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-10-2022 16:43:55 to 17-10-2022 16:43:59.

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 1560417 1560417 0 0.00 0 17845 6 0 0
doc_list_biud 1560417 1560417 0 0.00 0 22587 30 0 0
sp_add_invoice_to_stock 4794 4717 77 1.60 52 163525 17003 0 77
sp_cancel_adding_invoice 6192 6192 0 0.00 4 95173 713 0 0
sp_cancel_client_order 1718 1718 0 0.00 4 9304 380 0 0
sp_cancel_customer_reserve 610393 610393 0 0.00 0 19117 62 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 7059 86 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 2 4217 76 0 0
sp_cancel_supplier_invoice 6858 6853 5 0.07 1 22599 787 0 5
sp_cancel_supplier_order 447 447 0 0.00 6 1277313 107051 0 0
sp_cancel_write_off 10422 10422 0 0.00 1 10956 102 0 0
sp_client_order 19798 19798 0 0.00 3 32440 388 0 0
sp_customer_reserve 448221 448221 0 0.00 0 99334 164 0 0
sp_fill_shopping_cart 30352 30352 0 0.00 1 8318 38 0 0
sp_fill_shopping_cart_clo_res 448221 448221 0 0.00 0 19825 14 0 0
sp_get_clo_for_invoice 4707 4707 0 0.00 0 20885 282 0 0
sp_kill_cost_storno 17724 17724 0 0.00 0 11605 55 0 0
sp_kill_qstorno_ret_qs2qd 1238508 1238508 0 0.00 0 22584 33 0 0
sp_kill_qty_storno 650764 650764 0 0.00 0 22586 71 0 0
sp_lock_selected_doc 20359 20359 0 0.00 0 7314 6 0 0
sp_make_cost_storno 27250 27250 0 0.00 0 14843 64 0 0
sp_multiply_rows_for_pdistr 27250 27250 0 0.00 0 14538 27 0 0
sp_multiply_rows_for_qdistr 19798 19798 0 0.00 0 12580 148 0 0
sp_pay_from_customer 6889 6889 0 0.00 4 13618 127 0 0
sp_pay_to_supplier 5622 5622 0 0.00 4 13783 138 0 0
sp_payment_common 12511 12511 0 0.00 1 12184 43 0 0
sp_qd_handle_on_cancel_clo 1718 1718 0 0.00 0 9299 358 0 0
sp_qd_handle_on_invoice_upd_sts 10899 10899 0 0.00 0 16279 334 0 0
sp_qd_handle_on_reserve_upd_sts 10032 10032 0 0.00 0 19640 61 0 0
sp_reserve_write_off 10032 10032 0 0.00 6 19656 188 0 0
sp_supplier_invoice 5345 5345 0 0.00 4 75364 3059 0 0
sp_supplier_order 5209 5209 0 0.00 4 42582 1325 0 0
srv_aggregate_perf_data 88 88 0 0.00 2934 397030 24829 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2220 159044 48646 0 0
srv_find_qd_qs_mism 1098780 1098780 0 0.00 0 17843 5 0 0
srv_make_invnt_saldo 519 519 0 0.00 33 497240 2167 0 0
srv_make_money_saldo 774 774 0 0.00 1 4388 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 207119 573794 345989 0 0
t$perf-abend:sp_add_invoice_to_stock 77 77 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 4717 4717 0 0.00 0 20360 206 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 7471 123 0 0
t$perf-norm:sp_cancel_client_order 1718 1718 0 0.00 0 20350 38 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 4544 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 3968 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 6062 32 0 0
t$perf-norm:sp_cancel_supplier_invoice 366 366 0 0.00 0 4177 25 0 0
t$perf-norm:sp_cancel_supplier_order 447 447 0 0.00 0 16749 1371 0 0
t$perf-norm:sp_cancel_write_off 917 917 0 0.00 0 4989 9 0 0
t$perf-norm:sp_client_order 19798 19798 0 0.00 0 10268 19 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 927 6 0 0
t$perf-norm:sp_pay_from_customer 6889 6889 0 0.00 0 5092 12 0 0
t$perf-norm:sp_pay_to_supplier 5622 5622 0 0.00 0 5227 11 0 0
t$perf-norm:sp_reserve_write_off 10032 10032 0 0.00 0 11210 17 0 0
t$perf-norm:sp_supplier_invoice 5345 5345 0 0.00 0 7489 23 0 0
t$perf-norm:sp_supplier_order 5209 5209 0 0.00 0 10227 19 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 1143 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 2486 43 0 0
t$perf-norm:srv_make_invnt_saldo 519 519 0 0.00 0 6565 34 0 0
t$perf-norm:srv_make_money_saldo 774 774 0 0.00 0 2488 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9558 9404 154 1.61 0 7277 18 0 154
v_all_customers 35898 35898 0 0.00 0 42 1 0 0
v_all_suppliers 29582 29582 0 0.00 0 18 1 0 0
v_all_wares 39596 39596 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 957 7 0 0
v_cancel_client_order 3436 3436 0 0.00 0 1786 6 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 3208 9 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 1324 5 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 1852 7 0 0
v_cancel_supplier_invoice 742 732 10 1.34 0 1720 17 0 10
v_cancel_supplier_order 894 894 0 0.00 0 1714 6 0 0
v_cancel_write_off 1834 1834 0 0.00 0 739 12 0 0
v_max_id_clo_ord 5209 5209 0 0.00 0 1440 1 0 0
v_max_id_clo_res 984 984 0 0.00 0 1771 9 0 0
v_max_id_ord_sup 5345 5345 0 0.00 0 4378 4 0 0
v_max_non_paid_invoice 1383 1383 0 0.00 0 4763 11 0 0
v_max_non_paid_realizn 3761 3761 0 0.00 0 1504 4 0 0
v_min_id_clo_ord 5209 5209 0 0.00 0 6217 9 0 0
v_min_id_clo_res 984 984 0 0.00 0 3806 13 0 0
v_min_id_ord_sup 5345 5345 0 0.00 0 6812 10 0 0
v_min_non_paid_invoice 5620 5620 0 0.00 0 11577 31 0 0
v_min_non_paid_realizn 6889 6889 0 0.00 0 11690 35 0 0
v_our_firm 9954 9954 0 0.00 0 43 1 0 0
v_reserve_write_off 20062 20062 0 0.00 0 3698 5 0 0
x_lock_depdocs_on_canc_invoice 12679 12679 0 0.00 0 49294 110 0 0
x_lock_depdocs_on_canc_sup_ord 447 447 0 0.00 0 10086 318 0 0
x_make_qstorno_1000_1200 5209 5209 0 0.00 1 42572 1251 0 0
x_make_qstorno_1200_2000 5345 5345 0 0.00 2 71103 2922 0 0
x_make_qstorno_2100_3300 448221 448221 0 0.00 0 99334 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-10-2022 16:44:00 to 17-10-2022 16:44:04.

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
14:12:30 11 8192000000 8804759280 8817905664 10342656 0.126 3 2 3 0 10485104 67504 74944
14:13:31 28328 8192000000 9894029616 10204532736 981590208 10.700 100 89 178 0 1009906832 5326592 22990032
14:15:06 18246 8192000000 9940162896 10259898368 1021764672 11.089 100 88 175 0 1052269552 8281824 22223056
14:16:28 10272 8192000000 9969937360 10297008128 1050853504 11.369 100 86 172 0 1082578688 9864864 21860320
14:17:42 14470 8192000000 10027542128 10369560576 1107176016 11.906 100 76 152 0 1135794784 10433360 18185408
14:19:03 7127 8192000000 10078083440 10431287296 1156247280 12.368 100 83 165 0 1187546464 12477008 18822176
14:20:12 5093 8192000000 10108031952 10476589056 1199524688 12.772 100 66 132 1 1227013328 13011344 14477296
14:21:20 2526 8192000000 10156503136 10527744000 1243698656 13.180 100 86 167 1 1274689104 12951328 18039120
14:22:24 1162 8192000000 10184617136 10563190784 1274130640 13.459 100 88 174 1 1305852608 12719504 19002464
14:23:29 1703 8192000000 10203259376 10590154752 1293696896 13.638 100 79 154 3 1323159552 11447728 18014928
14:24:34 22738 8192000000 10199717984 10605047808 1307144704 13.760 100 54 108 0 1330696592 11441456 12110432
14:25:57 5163 8192000000 10212530736 10619428864 1316883696 13.848 100 25 50 0 1332414752 10191152 5339904
14:27:03 9132 8192000000 10205252992 10619940864 1320304944 13.879 100 17 32 1 1329698912 5224256 4169712
14:28:16 65755 8192000000 10216792656 10637836288 1329033312 13.958 100 45 90 1 1344524304 4609936 10881056
14:30:32 6160 8192000000 10230788144 10646745088 1334904848 14.011 100 19 36 0 1344030176 5319808 3805520
14:31:40 10614 8192000000 10228794272 10648489984 1333469696 13.998 100 19 38 0 1347202592 9773536 3959360
14:32:51 52314 8192000000 10221807456 10632921088 1309310912 13.780 98 42 81 1 1324060960 5436000 9314048
14:35:02 11231 8192000000 10156198944 10563555328 1231459232 13.068 96 19 38 0 1241060768 5146128 4455408
14:36:16 10621 8192000000 10127297696 10524303360 1188241312 12.667 94 22 42 0 1197819776 5282256 4296208
14:37:26 45366 8192000000 10148543216 10556682240 1261540320 13.344 100 31 60 0 1274769888 5514816 7714752
14:39:20 4031 8192000000 10185152864 10588999680 1286032960 13.568 100 29 58 0 1299378688 6808240 6537488
14:40:25 8358 8192000000 10191969120 10602250240 1299420752 13.690 100 18 36 0 1308837584 4876480 4540352
14:41:36 10339 8192000000 10196768384 10611441664 1305846112 13.748 100 21 43 1 1316005584 5313120 4846352
14:42:50 159044 8192000000 10269212208 10691178496 1348968960 14.138 100 33 66 1 1366658976 10536208 7153808
14:46:37 52211 8192000000 10290482640 10708643840 1355275488 14.195 98 50 101 1 1385281600 18104176 11901936
14:48:38 21563 8192000000 10323991472 10743762944 1394650160 14.547 100 43 86 0 1431112880 25371584 11091136
14:50:22 51214 8192000000 10339708080 10765385728 1395265488 14.553 99 50 100 1 1436027856 27472272 13290096
14:52:21 11556 8192000000 10347000016 10774896640 1416498272 14.742 100 51 99 0 1460445072 31528512 12418288
14:53:45 3636 8192000000 10358743120 10783584256 1423229568 14.801 100 51 99 0 1468663648 33049280 12384800
14:54:58 7167 8192000000 10375410192 10798514176 1424853232 14.816 99 61 120 0 1485098736 44248144 15997360
14:56:08 2214 8192000000 10385598160 10805690368 1431404576 14.874 100 65 128 0 1497215648 49702816 16108256
14:57:19 3432 8192000000 10380022064 10789335040 1410480960 14.688 100 78 154 1 1481998048 52300432 19216656
14:58:30 5218 8192000000 10386704336 10798465024 1415608912 14.734 100 73 146 1 1492542880 58458352 18475616
14:59:42 2748 8192000000 10390032912 10800418816 1412993760 14.711 100 73 144 2 1496884544 65574272 18316512
15:00:50 5638 8192000000 10377811456 10789957632 1401063056 14.604 100 65 130 1 1484314928 65828432 17423440
15:02:04 4308 8192000000 10371601984 10786779136 1395442160 14.554 100 66 132 0 1478925984 65581760 17902064
15:03:18 10032 8192000000 10376645792 10797121536 1400911120 14.603 100 46 84 3 1477605552 64886848 11807584
15:04:33 24042 8192000000 10379703312 10811727872 1406048288 14.649 100 41 79 0 1482934624 65728848 11157488
15:06:01 14682 8192000000 10357916080 10804965376 1376559584 14.386 99 29 58 0 1458969792 73581008 8829200
15:07:35 30081 8192000000 10334881808 10766290944 1359040560 14.229 99 33 61 1 1425188752 56901600 9246592
15:09:14 33568 8192000000 10285456272 10738589696 1356169200 14.203 99 14 27 1 1386632400 26850144 3613056
15:10:54 135392 8192000000 10275986704 10729230336 1295305664 13.653 94 20 35 1 1312624384 13484416 3834304
15:14:12 28199 8192000000 10304108480 10749427712 1374916048 14.371 99 32 65 0 1393881888 12182096 6783744
15:15:52 6519 8192000000 10306690544 10739748864 1377607536 14.395 100 50 92 3 1402802288 14201776 10992976
15:17:05 130435 8192000000 10283998912 10720194560 1350234656 14.150 98 18 36 2 1368384528 13981488 4168384
15:20:30 80865 8192000000 10275379792 10720256000 1332885936 13.993 97 33 67 0 1349420544 8705792 7828816
15:22:56 34117 8192000000 10278915328 10719698944 1361576752 14.252 100 17 34 0 1371792128 6440240 3775136
15:24:30 31812 8192000000 10278075856 10722938880 1353134720 14.176 98 31 62 0 1371696672 11772192 6789760
15:26:07 53003 8192000000 10302496736 10737491968 1378929440 14.407 100 38 75 1 1401732544 14245936 8557168
15:28:03 23428 8192000000 10302116928 10752581632 1389853216 14.505 100 11 22 0 1405547296 13214416 2479664
15:29:51 41560 8192000000 10291464176 10756943872 1394668608 14.548 100 8 16 0 1405447888 9033872 1745408
15:32:31 21618 8192000000 10299729792 10757980160 1396310512 14.562 100 23 45 0 1410635936 9239040 5086384
15:33:55 42909 8192000000 10306486272 10776436736 1402535712 14.618 100 12 24 1 1418144512 13191632 2417168
15:35:38 28463 8192000000 10298634352 10760245248 1386206880 14.472 99 7 14 0 1394265824 6362736 1696208
15:37:07 39263 8192000000 10304842688 10778251264 1389105264 14.498 99 20 40 0 1409238960 15538752 4594944
15:38:47 95635 8192000000 10310116368 10765692928 1349189968 14.140 97 15 28 0 1363491424 11222032 3079424
15:41:32 89941 8192000000 10294756784 10757906432 1367441888 14.304 98 9 18 0 1384911968 15780160 1689920
15:44:03 49322 8192000000 10290856496 10737844224 1345508016 14.107 97 19 38 1 1361619984 12166480 3945488
15:45:53 108849 8192000000 10288018272 10750697472 1343678784 14.091 97 8 16 1 1357276064 12076704 1520576
15:49:24 11824 8192000000 10272610416 10712563712 1340325456 14.060 98 39 78 0 1357944768 8794784 8824528
15:50:43 49755 8192000000 10249581776 10710921216 1337242848 14.033 98 3 6 0 1345391008 7600848 547312
15:52:33 62206 8192000000 10305066528 10746597376 1355722944 14.199 98 38 77 0 1383134000 18871280 8539776
15:54:40 72568 8192000000 10256935504 10704826368 1331021312 13.976 97 15 28 1 1348918272 14752832 3144128
15:56:55 102578 8192000000 10263064480 10711207936 1308206624 13.770 95 8 16 0 1318807648 8997184 1603840
15:59:38 35082 8192000000 10260201424 10698641408 1315782176 13.839 97 24 48 0 1328890432 7748640 5359616
16:01:14 16088 8192000000 10275956080 10712694784 1342839248 14.083 99 34 67 0 1366174176 16144112 7190816
16:02:40 101473 8192000000 10254015600 10698944512 1299214112 13.688 96 7 14 0 1311146864 10674624 1258128
16:05:22 64221 8192000000 10257448896 10716696576 1343605888 14.090 99 17 34 0 1360201872 12616704 3979280
16:07:38 111304 8192000000 10273499376 10719846400 1316393568 13.844 96 24 46 0 1334762960 13243440 5125952
16:11:24 65607 8192000000 10252539760 10715762688 1355628752 14.198 100 11 22 0 1366018624 8002672 2387200
16:13:38 60254 8192000000 10275775424 10737119232 1378707776 14.405 100 10 20 0 1390675808 9553824 2414208
16:16:20 60378 8192000000 10275841792 10742116352 1380734016 14.423 100 14 29 0 1391720720 7957280 3029424
16:18:54 40028 8192000000 10304772576 10756042752 1379594224 14.413 99 27 54 0 1397651872 11970464 6087184
16:20:38 64228 8192000000 10295603616 10758914048 1378357632 14.402 99 16 32 0 1394267056 12438688 3470736
16:22:47 13397 8192000000 10317469216 10762960896 1401493312 14.608 100 41 82 0 1419907568 8664912 9749344
16:24:10 30778 8192000000 10301959216 10760138752 1395998976 14.559 100 25 50 0 1409752992 8350416 5403600
16:25:41 55973 8192000000 10323975312 10790297600 1412934800 14.710 100 31 62 1 1432545088 12956928 6653360
16:27:37 104167 8192000000 10281182480 10747961344 1285720144 13.565 92 3 6 1 1302145232 15479696 945392
16:30:22 25734 8192000000 10313262064 10754387968 1389450672 14.501 100 61 120 1 1413201216 10505680 13244864
16:31:48 63632 8192000000 10273321488 10736934912 1335985808 14.021 96 26 52 0 1349394464 7571488 5837168
16:34:18 72085 8192000000 10269168400 10740707328 1342999024 14.084 97 14 28 0 1359147344 12949216 3199104
16:36:31 59301 8192000000 10271327200 10726260736 1339044064 14.049 97 17 34 0 1350278912 7503088 3731760
16:38:31 68417 8192000000 10275667552 10732998656 1364046512 14.274 99 23 45 1 1380933776 10717216 6170048
16:40:48 72467 8192000000 10266395408 10733621248 1356437744 14.205 99 10 20 0 1368067696 9356624 2273328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-10-2022 16:44:06 to 17-10-2022 16:44:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 77 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-10-2022 16:44:22 to 17-10-2022 16:44:24.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          236190
MON$OLDEST_ACTIVE               236495
MON$OLDEST_SNAPSHOT             236495
MON$NEXT_TRANSACTION            236495
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-03 16:41:55.3470
MON$PAGES                       2050976
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33635 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33635 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33635 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33635 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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    Mon Oct 17 16:42:32 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
--- end of diff output ---

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

17.10.22 16:44:25. There are 3 logs in /var/tmp/logs-oltp30 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-10-17 16:44:27 No severe PSQL-related problems occured
17.10.22 16:44:29. End of report.