Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.10.2022 16: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 15697124 kB
MemAvailable 30435864 kB
Buffers 0 kB
Cached 16018176 kB
SwapCached 1968 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-26 16:38:31
DTS_END                         2022-10-26 16:38:31.8250
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/52984
TRN_ID                          233468
ATT_ID                          609
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: 8971.62 22725 1076594
customer order: creation 167.76 383 20131
customer order: refuse 14.36 336 1723
order to supplier: creation 43.55 1291 5226
order to supplier: removal 3.51 87803 421
invoice (draft): creation 45.20 3139 5424
invoice (draft): removal 51.08 739 6130
invoice accept: apply 41.04 17420 4925
invoice accept: cancel 45.69 827 5483
customer reserve: creation 3731.22 174 447746
customer reserve: removal 4530.78 54 543694
realization accept: apply 82.83 183 9939
realization accept: cancel 89.61 103 10753
payment from customer: creation 57.25 104 6870
payment from customer: removal 5.49 74 659
payment to supplier: creation 46.73 163 5608
payment to supplier: removal 3.41 67 409
service: total inventory turnovers 4.53 1025 544
service: total monetary turnovers 6.63 19 796
service: aggregate perf. data 0.91 17952 109
service: refresh index statistics 0.03 322651 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-10-2022 16:40:31 to 26-10-2022 16:40:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4961
TEST_TIME 2 4865
TEST_TIME 3 4640
TEST_TIME 4 5033
TEST_TIME 5 5468
TEST_TIME 6 5264
TEST_TIME 7 5262
TEST_TIME 8 5287
TEST_TIME 9 5844
TEST_TIME 10 5917
TEST_TIME 11 6094
TEST_TIME 12 6478
TEST_TIME 13 7122
TEST_TIME 14 7336
TEST_TIME 15 7472
TEST_TIME 16 7772
TEST_TIME 17 7753
TEST_TIME 18 8324
TEST_TIME 19 8642
TEST_TIME 20 8632
TEST_TIME 21 8568
TEST_TIME 22 8586
TEST_TIME 23 9159
TEST_TIME 24 9616
TEST_TIME 25 9902
TEST_TIME 26 10830
TEST_TIME 27 10704
TEST_TIME 28 10586
TEST_TIME 29 10333
TEST_TIME 30 10038
TEST_TIME 31 9976
TEST_TIME 32 9753
TEST_TIME 33 9882
TEST_TIME 34 9739
TEST_TIME 35 9752
TEST_TIME 36 9594
TEST_TIME 37 9555
TEST_TIME 38 9377
TEST_TIME 39 9293
TEST_TIME 40 9201
TEST_TIME 41 9170
TEST_TIME 42 9169
TEST_TIME 43 8983
TEST_TIME 44 8889
TEST_TIME 45 8812
TEST_TIME 46 8748
TEST_TIME 47 8684
TEST_TIME 48 8731
TEST_TIME 49 8763
TEST_TIME 50 8736
TEST_TIME 51 8725
TEST_TIME 52 8900
TEST_TIME 53 8858
TEST_TIME 54 8986
TEST_TIME 55 9107
TEST_TIME 56 9112
TEST_TIME 57 9037
TEST_TIME 58 9191
TEST_TIME 59 9211
TEST_TIME 60 9180
TEST_TIME 61 9311
TEST_TIME 62 9171
TEST_TIME 63 9057
TEST_TIME 64 9058
TEST_TIME 65 8959
TEST_TIME 66 9051
TEST_TIME 67 8982
TEST_TIME 68 8925
TEST_TIME 69 9048
TEST_TIME 70 9008
TEST_TIME 71 9063
TEST_TIME 72 9033
TEST_TIME 73 9114
TEST_TIME 74 9080
TEST_TIME 75 9174
TEST_TIME 76 9152
TEST_TIME 77 9123
TEST_TIME 78 9151
TEST_TIME 79 9213
TEST_TIME 80 9178
TEST_TIME 81 9301
TEST_TIME 82 9273
TEST_TIME 83 9192
TEST_TIME 84 9118
TEST_TIME 85 9062
TEST_TIME 86 9041
TEST_TIME 87 9062
TEST_TIME 88 9016
TEST_TIME 89 8992
TEST_TIME 90 8957
TEST_TIME 91 8886
TEST_TIME 92 8851
TEST_TIME 93 8910
TEST_TIME 94 8938
TEST_TIME 95 8952
TEST_TIME 96 8991
TEST_TIME 97 8976
TEST_TIME 98 9064
TEST_TIME 99 9031
TEST_TIME 100 9024
TEST_TIME 101 9152
TEST_TIME 102 9126
TEST_TIME 103 9091
TEST_TIME 104 9214
TEST_TIME 105 9213
TEST_TIME 106 9184
TEST_TIME 107 9180
TEST_TIME 108 9108
TEST_TIME 109 9076
TEST_TIME 110 9035
TEST_TIME 111 9042
TEST_TIME 112 8990
TEST_TIME 113 8956
TEST_TIME 114 8980
TEST_TIME 115 8981
TEST_TIME 116 9021
TEST_TIME 117 9021
TEST_TIME 118 9007
TEST_TIME 119 9009
TEST_TIME 120 8973

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-10-2022 16:40:35 to 26-10-2022 16:40:38.

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 1491148 1491148 0 0.00 0 17631 6 0 0
doc_list_biud 1491148 1491148 0 0.00 0 20911 25 0 0
sp_add_invoice_to_stock 5049 4925 124 2.45 15 168589 17421 0 124
sp_cancel_adding_invoice 5483 5483 0 0.00 4 67066 827 0 0
sp_cancel_client_order 1723 1723 0 0.00 4 7974 336 0 0
sp_cancel_customer_reserve 543694 543694 0 0.00 0 20913 54 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 5763 74 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 3 2257 68 0 0
sp_cancel_supplier_invoice 6138 6130 8 0.13 1 14986 740 0 8
sp_cancel_supplier_order 421 421 0 0.00 9 1002009 87803 0 0
sp_cancel_write_off 10753 10753 0 0.00 1 9908 103 0 0
sp_client_order 20131 20131 0 0.00 3 19168 384 0 0
sp_customer_reserve 447746 447746 0 0.00 0 84790 174 0 0
sp_fill_shopping_cart 30772 30772 0 0.00 1 14946 38 0 0
sp_fill_shopping_cart_clo_res 447737 447737 0 0.00 0 19103 16 0 0
sp_get_clo_for_invoice 4916 4916 0 0.00 0 33209 356 0 0
sp_kill_cost_storno 17304 17304 0 0.00 0 13153 47 0 0
sp_kill_qstorno_ret_qs2qd 1104692 1104692 0 0.00 0 17951 29 0 0
sp_kill_qty_storno 583059 583059 0 0.00 0 20910 64 0 0
sp_lock_selected_doc 20503 20503 0 0.00 0 7619 5 0 0
sp_make_cost_storno 27333 27333 0 0.00 0 16485 60 0 0
sp_multiply_rows_for_pdistr 27333 27333 0 0.00 0 9281 29 0 0
sp_multiply_rows_for_qdistr 20131 20131 0 0.00 0 18758 180 0 0
sp_pay_from_customer 6870 6870 0 0.00 4 14228 105 0 0
sp_pay_to_supplier 5608 5608 0 0.00 4 24441 163 0 0
sp_payment_common 12478 12478 0 0.00 1 24402 31 0 0
sp_qd_handle_on_cancel_clo 1723 1723 0 0.00 0 7965 310 0 0
sp_qd_handle_on_invoice_upd_sts 10399 10399 0 0.00 0 15336 314 0 0
sp_qd_handle_on_reserve_upd_sts 9939 9939 0 0.00 0 8445 61 0 0
sp_reserve_write_off 9939 9939 0 0.00 6 17620 184 0 0
sp_supplier_invoice 5424 5424 0 0.00 6 75795 3139 0 0
sp_supplier_order 5226 5226 0 0.00 5 40379 1292 0 0
srv_aggregate_perf_data 109 109 0 0.00 2659 302043 17952 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1888 116284 43603 0 0
srv_find_qd_qs_mism 1030336 1030336 0 0.00 0 15110 5 0 0
srv_make_invnt_saldo 544 544 0 0.00 21 45830 1026 0 0
srv_make_money_saldo 796 796 0 0.00 0 676 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 138592 517394 322652 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 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 4925 4925 0 0.00 0 23956 157 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 14640 102 0 0
t$perf-norm:sp_cancel_client_order 1723 1723 0 0.00 0 1536 9 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 3353 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 333 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 8014 34 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 988 13 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 36476 1579 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 4309 16 0 0
t$perf-norm:sp_client_order 20131 20131 0 0.00 0 9960 15 0 0
t$perf-norm:sp_customer_reserve 941 941 0 0.00 0 1216 5 0 0
t$perf-norm:sp_pay_from_customer 6870 6870 0 0.00 0 11859 9 0 0
t$perf-norm:sp_pay_to_supplier 5608 5608 0 0.00 0 3565 9 0 0
t$perf-norm:sp_reserve_write_off 9939 9939 0 0.00 0 4526 11 0 0
t$perf-norm:sp_supplier_invoice 5424 5424 0 0.00 0 7735 18 0 0
t$perf-norm:sp_supplier_order 5226 5226 0 0.00 0 6598 14 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 475 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 259 7 0 0
t$perf-norm:srv_make_invnt_saldo 544 544 0 0.00 0 2932 26 0 0
t$perf-norm:srv_make_money_saldo 796 796 0 0.00 0 7098 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 64 32 0 0
v_add_invoice_to_stock 10076 9828 248 2.46 0 11802 21 0 248
v_all_customers 36540 36540 0 0.00 0 202 1 0 0
v_all_suppliers 29354 29354 0 0.00 0 57 1 0 0
v_all_wares 40262 40262 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 11461 19 0 0
v_cancel_client_order 3446 3446 0 0.00 0 5954 7 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 993 4 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 287 3 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 1973 6 0 0
v_cancel_supplier_invoice 834 818 16 1.91 0 3729 21 0 16
v_cancel_supplier_order 842 842 0 0.00 0 677 3 0 0
v_cancel_write_off 1968 1968 0 0.00 0 8226 23 0 0
v_max_id_clo_ord 5226 5226 0 0.00 0 532 1 0 0
v_max_id_clo_res 941 941 0 0.00 0 5024 27 0 0
v_max_id_ord_sup 5415 5415 0 0.00 0 2312 2 0 0
v_max_non_paid_invoice 1572 1572 0 0.00 0 201 2 0 0
v_max_non_paid_realizn 3671 3671 0 0.00 0 2126 4 0 0
v_min_id_clo_ord 5226 5226 0 0.00 0 2151 7 0 0
v_min_id_clo_res 941 941 0 0.00 0 6500 19 0 0
v_min_id_ord_sup 5415 5415 0 0.00 0 3442 6 0 0
v_min_non_paid_invoice 5608 5608 0 0.00 0 24397 29 0 0
v_min_non_paid_realizn 6870 6870 0 0.00 0 7182 20 0 0
v_our_firm 10120 10120 0 0.00 0 25 1 0 0
v_reserve_write_off 19878 19878 0 0.00 0 11809 8 0 0
x_lock_depdocs_on_canc_invoice 11204 11204 0 0.00 0 22415 126 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 9622 312 0 0
x_make_qstorno_1000_1200 5226 5226 0 0.00 1 40363 1222 0 0
x_make_qstorno_1200_2000 5424 5424 0 0.00 3 75788 3000 0 0
x_make_qstorno_2100_3300 447746 447746 0 0.00 0 84787 156 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 26-10-2022 16:40:39 to 26-10-2022 16:40:44.

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:08:35 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:37 20609 8192000000 9882351696 10190888960 966451872 10.552 100 83 166 0 994775072 5637984 22685216
14:11:03 26915 8192000000 9940040176 10258677760 1019838048 11.070 100 91 182 0 1051333808 7945200 23550560
14:12:36 10794 8192000000 9970383888 10297262080 1053405008 11.393 100 83 166 1 1083462624 8812704 21244912
14:13:53 7128 8192000000 10019443232 10363252736 1109512048 11.928 100 77 152 0 1138195888 9520944 19162896
14:15:06 9244 8192000000 10070308384 10420830208 1153285344 12.340 100 76 152 0 1183129680 12024064 17820272
14:16:24 4793 8192000000 10118293040 10487296000 1206395904 12.836 100 74 145 0 1238053296 15700672 15956720
14:17:30 1999 8192000000 10147016256 10518118400 1236599344 13.115 100 84 168 0 1269131920 14262784 18269792
14:18:35 1695 8192000000 10170782928 10552070144 1262055536 13.349 100 75 151 2 1291053600 11742640 17255424
14:19:38 1004 8192000000 10196304384 10575364096 1284858720 13.557 100 90 178 0 1316353024 11531056 19963248
14:20:41 15263 8192000000 10200259104 10594881536 1293793280 13.639 100 51 102 0 1313846752 8852208 11201264
14:21:57 5235 8192000000 10195870736 10601332736 1302263296 13.716 100 41 81 0 1320319344 9574160 8481888
14:23:04 50375 8192000000 10212024256 10620182528 1313831504 13.821 100 27 55 0 1325791168 5362544 6597120
14:24:55 5486 8192000000 10220900064 10635702272 1327254272 13.942 100 37 73 0 1341538176 6511840 7772064
14:26:02 7149 8192000000 10227144272 10645266432 1336846624 14.029 100 17 34 0 1346053936 5752496 3454816
14:27:11 6518 8192000000 10227751296 10645803008 1336640720 14.027 100 19 38 1 1346347776 5709408 3997648
14:28:22 47324 8192000000 10205196928 10627604480 1294951600 13.649 97 30 61 0 1307883472 5927552 7004320
14:30:13 5145 8192000000 10179250752 10586935296 1287528080 13.582 100 22 44 1 1298537520 6044496 4964944
14:31:19 8666 8192000000 10143964704 10550824960 1245620320 13.198 98 24 48 0 1257466656 6568480 5277856
14:32:28 65056 8192000000 10124761264 10532925440 1195297552 12.733 95 23 45 0 1205640672 5516320 4826800
14:34:38 7131 8192000000 10169180768 10574172160 1267950704 13.403 99 24 45 0 1277843472 4783888 5108880
14:35:45 9221 8192000000 10182230656 10595958784 1293516416 13.636 100 35 70 0 1308359216 5904400 8938400
14:36:55 17438 8192000000 10198936912 10607673344 1300531888 13.700 100 23 46 0 1311258464 5731904 4994672
14:38:13 17440 8192000000 10205405424 10621673472 1316644160 13.846 100 19 36 1 1325426672 5139600 3642912
14:39:35 14209 8192000000 10241832464 10655035392 1340079344 14.058 100 53 106 0 1360884112 9858912 10945856
14:40:52 20245 8192000000 10259353008 10674728960 1354699488 14.190 100 37 72 1 1373809456 10877456 8232512
14:42:18 26600 8192000000 10278132880 10704248832 1370795440 14.334 100 30 60 0 1391985872 14203552 6986880
14:43:47 5919 8192000000 10300460160 10722893824 1383813136 14.451 100 47 93 1 1414783488 19987568 10982784
14:44:56 9076 8192000000 10305362944 10731171840 1395007744 14.551 100 48 95 2 1424491456 18208960 11274752
14:46:09 8501 8192000000 10325814432 10751205376 1407058880 14.658 100 53 102 1 1441023472 21288272 12676320
14:47:21 10170 8192000000 10337396560 10759905280 1413107392 14.712 100 51 101 0 1450493456 25272640 12113424
14:48:43 1887 8192000000 10330433184 10749472768 1393115088 14.534 98 60 118 0 1434331008 26349456 14866464
14:49:53 3028 8192000000 10333697888 10755887104 1388517856 14.493 99 64 126 3 1437353520 33923712 14911952
14:51:00 2556 8192000000 10355106688 10779574272 1413686272 14.717 100 67 133 1 1462348944 31784128 16878544
14:52:07 3782 8192000000 10334574528 10762629120 1398874064 14.585 99 50 100 0 1442198304 31160464 12163776
14:53:14 11882 8192000000 10330565648 10756018176 1395989824 14.559 99 47 91 0 1441136928 33859232 11287872
14:54:37 3129 8192000000 10359830208 10786381824 1420826048 14.780 100 65 130 3 1467709184 29853008 17030128
14:55:46 6240 8192000000 10354688912 10778202112 1400262096 14.597 99 54 107 1 1451936864 38127136 13547632
14:57:02 3098 8192000000 10340582896 10758930432 1393623616 14.538 100 62 123 0 1447080320 37076992 16379712
14:58:09 8890 8192000000 10342747536 10766839808 1393142368 14.534 99 47 93 0 1440272768 35195536 11934864
14:59:31 21916 8192000000 10320294752 10751623168 1372771952 14.352 99 29 58 0 1419471376 38679424 8020000
15:00:59 12401 8192000000 10320600192 10749493248 1370501808 14.332 100 35 70 0 1427119184 46870080 9747296
15:02:31 35869 8192000000 10287299024 10723110912 1338877760 14.047 98 18 36 0 1383281104 39210384 5192960
15:04:11 32969 8192000000 10248683856 10698723328 1320442384 13.881 98 8 16 0 1344859648 22828560 1588704
15:05:47 4056 8192000000 10266592608 10682671104 1336797456 14.029 100 67 130 1 1364820992 11536192 16487344
15:07:00 64334 8192000000 10258341040 10692546560 1332344864 13.988 99 23 46 0 1350939744 13514240 5080640
15:09:40 116284 8192000000 10250206288 10693083136 1326745600 13.938 99 18 37 0 1351027888 20341600 3940688
15:12:49 50314 8192000000 10264657616 10709905408 1362219472 14.257 100 25 47 1 1377542512 10096784 5226256
15:14:40 58512 8192000000 10243244256 10691575808 1320297840 13.879 97 17 35 1 1331556464 7416400 3842224
15:16:49 32229 8192000000 10264661792 10712903680 1367304880 14.303 100 42 78 0 1383173280 6940320 8928080
15:18:31 12973 8192000000 10272225664 10708729856 1358966736 14.228 99 56 112 0 1380304480 8806416 12531328
15:19:49 36694 8192000000 10263974912 10720972800 1358726928 14.226 99 33 66 0 1376736512 10772352 7237232
15:22:08 49313 8192000000 10266941040 10718089216 1363255552 14.267 99 17 34 1 1374148464 6999040 3893872
15:24:01 10450 8192000000 10279886576 10728620032 1380744960 14.423 100 28 54 1 1393263792 6814080 5704752
15:25:13 74596 8192000000 10277881104 10753257472 1380365072 14.420 99 8 16 0 1391797008 9487232 1944704
15:27:32 55258 8192000000 10299029040 10753769472 1372177648 14.347 98 18 36 0 1392558496 15659280 4721568
15:29:30 77254 8192000000 10303358368 10773061632 1393664800 14.539 100 24 48 1 1423761984 24301040 5796144
15:31:47 73167 8192000000 10319668656 10778570752 1407035264 14.658 100 21 42 1 1427977824 16030672 4911888
15:34:18 51412 8192000000 10319783920 10766327808 1395233520 14.553 99 26 52 0 1416607872 15347136 6027216
15:36:10 84406 8192000000 10311692144 10767187968 1377089200 14.391 98 20 40 0 1396621920 14918576 4614144
15:38:35 41966 8192000000 10317314064 10763849728 1396845152 14.567 100 35 70 0 1417020992 12771504 7404336
15:40:25 34014 8192000000 10313394768 10757423104 1403115824 14.623 100 54 101 2 1419723120 5064016 11543280
15:42:03 15532 8192000000 10301529536 10754273280 1395508112 14.555 100 44 87 0 1419526064 14810912 9207040
15:43:19 39295 8192000000 10264908768 10720563200 1332293232 13.988 97 10 20 0 1350100864 15848560 1959072
15:45:15 54059 8192000000 10274613504 10736041984 1345027312 14.103 98 8 16 0 1361737440 14907296 1802832
15:47:09 75290 8192000000 10278360016 10738814976 1335665984 14.018 97 7 15 0 1350925936 13452176 1807776
15:49:26 92648 8192000000 10290966320 10745552896 1334745680 14.010 96 12 24 0 1345788160 8536432 2506048
15:52:34 45029 8192000000 10279769760 10731044864 1341040160 14.067 97 21 42 0 1357605824 11494368 5071296
15:54:22 52799 8192000000 10267776160 10714320896 1335256192 14.015 98 21 42 0 1349838640 10010432 4572016
15:56:18 68819 8192000000 10255268912 10707730432 1283066000 13.541 94 18 36 0 1299487488 12043088 4378400
15:58:30 74811 8192000000 10248543952 10698637312 1303300048 13.725 97 30 58 0 1329079232 19284320 6494864
16:01:06 115626 8192000000 10237959440 10688069632 1343165568 14.086 100 19 38 2 1359249904 12042880 4041456
16:04:10 39754 8192000000 10249365824 10697502720 1325941200 13.930 98 26 52 0 1340731728 8948496 5842032
16:06:03 32575 8192000000 10233207376 10682527744 1320750672 13.884 99 6 13 0 1333016672 10835776 1430224
16:07:37 75870 8192000000 10263569856 10712100864 1350937856 14.156 99 12 24 0 1360105664 6443648 2724160
16:09:54 103473 8192000000 10265685248 10733002752 1354676080 14.190 98 18 37 0 1372016496 12907200 4433216
16:12:39 51906 8192000000 10280441392 10739929088 1364567600 14.278 99 22 43 0 1376943520 7672144 4703776
16:14:31 42580 8192000000 10290712768 10744819712 1383682784 14.449 100 25 52 0 1398636512 9059296 5894432
16:16:16 89482 8192000000 10295657536 10742616064 1368958368 14.318 99 19 38 0 1390439232 17394112 4086752
16:18:48 77496 8192000000 10300437152 10773164032 1389786208 14.504 100 19 36 1 1409596848 16040576 3770064
16:21:06 46762 8192000000 10314141664 10758512640 1393390560 14.536 100 27 53 0 1414574720 15270016 5914144
16:22:59 47149 8192000000 10292298736 10748645376 1360802880 14.245 98 12 24 0 1372645632 9440624 2402128
16:24:52 9044 8192000000 10297921776 10728804352 1356464288 14.206 98 43 86 0 1377372768 11701168 9207312
16:26:02 65359 8192000000 10275867104 10730692608 1373914016 14.362 100 26 52 0 1388644480 9031376 5699088
16:28:10 53493 8192000000 10249869952 10711285760 1331522896 13.981 97 9 18 1 1342963728 9593776 1847056
16:30:12 81059 8192000000 10284186096 10740756480 1381498912 14.430 100 13 26 0 1393862208 9190224 3173072
16:32:41 102692 8192000000 10300203808 10764627968 1383203696 14.445 100 16 32 0 1403092816 16212848 3676272
16:35:24 46170 8192000000 10301791920 10742546432 1346421136 14.115 97 41 77 2 1363367376 8048192 8898048
16:37:12 65713 8192000000 10282650896 10729119744 1328272848 13.952 96 21 42 1 1349653568 16348256 5032464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-10-2022 16:40:46 to 26-10-2022 16:41:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 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 26-10-2022 16:41:02 to 26-10-2022 16:41:04.

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          230660
MON$OLDEST_ACTIVE               233592
MON$OLDEST_SNAPSHOT             233592
MON$NEXT_TRANSACTION            233592
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                       1994688
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.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 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 ---
:234: 
:235: mscs-test-161.budzdorov.ru    Wed Oct 26 16:38:42 2022
:236:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:237: 
:238: 
:239: mscs-test-161.budzdorov.ru    Wed Oct 26 16:38:43 2022
:240:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:241: 
:242: 
:243: mscs-test-161.budzdorov.ru    Wed Oct 26 16:38:45 2022
:244:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:245: 
--- end of diff output ---

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

26.10.22 16:41:05. 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-26 16:41:07 No severe PSQL-related problems occured
26.10.22 16:41:09. End of report.