Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.05.2022 19: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 16729892 kB
MemAvailable 31528544 kB
Buffers 0 kB
Cached 15015684 kB
SwapCached 2544 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.10
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-05-06 19:38:21
DTS_END                         2022-05-06 19:38:21.3420
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49256
TRN_ID                          229129
ATT_ID                          692
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: 9050.41 26467 1086049
customer order: creation 162.28 428 19473
customer order: refuse 13.74 389 1649
order to supplier: creation 44.75 1459 5370
order to supplier: removal 3.49 99148 419
invoice (draft): creation 43.08 3508 5170
invoice (draft): removal 53.91 777 6469
invoice accept: apply 39.12 18209 4694
invoice accept: cancel 48.46 708 5815
customer reserve: creation 3633.86 179 436063
customer reserve: removal 4722.73 57 566728
realization accept: apply 78.99 195 9479
realization accept: cancel 82.68 101 9922
payment from customer: creation 55.92 119 6710
payment from customer: removal 5.51 94 661
payment to supplier: creation 46.86 144 5623
payment to supplier: removal 3.46 84 415
service: total inventory turnovers 4.63 1234 555
service: total monetary turnovers 6.17 77 740
service: aggregate perf. data 0.75 23328 90
service: refresh index statistics 0.03 379101 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-05-2022 19:40:20 to 06-05-2022 19:40:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4735
TEST_TIME 2 4839
TEST_TIME 3 4210
TEST_TIME 4 4002
TEST_TIME 5 4845
TEST_TIME 6 4579
TEST_TIME 7 4807
TEST_TIME 8 4765
TEST_TIME 9 4924
TEST_TIME 10 5030
TEST_TIME 11 5284
TEST_TIME 12 5785
TEST_TIME 13 5926
TEST_TIME 14 5919
TEST_TIME 15 6207
TEST_TIME 16 6921
TEST_TIME 17 7446
TEST_TIME 18 8214
TEST_TIME 19 8674
TEST_TIME 20 8806
TEST_TIME 21 8894
TEST_TIME 22 9286
TEST_TIME 23 9168
TEST_TIME 24 9674
TEST_TIME 25 10169
TEST_TIME 26 10470
TEST_TIME 27 10530
TEST_TIME 28 10437
TEST_TIME 29 10374
TEST_TIME 30 10275
TEST_TIME 31 10350
TEST_TIME 32 10248
TEST_TIME 33 10095
TEST_TIME 34 10054
TEST_TIME 35 10091
TEST_TIME 36 10007
TEST_TIME 37 9807
TEST_TIME 38 9694
TEST_TIME 39 9698
TEST_TIME 40 9737
TEST_TIME 41 9626
TEST_TIME 42 9499
TEST_TIME 43 9417
TEST_TIME 44 9323
TEST_TIME 45 9253
TEST_TIME 46 9391
TEST_TIME 47 9259
TEST_TIME 48 9183
TEST_TIME 49 9040
TEST_TIME 50 9015
TEST_TIME 51 9036
TEST_TIME 52 9013
TEST_TIME 53 9036
TEST_TIME 54 8995
TEST_TIME 55 9077
TEST_TIME 56 9036
TEST_TIME 57 9074
TEST_TIME 58 9066
TEST_TIME 59 9081
TEST_TIME 60 9100
TEST_TIME 61 9074
TEST_TIME 62 9269
TEST_TIME 63 9259
TEST_TIME 64 9327
TEST_TIME 65 9287
TEST_TIME 66 9151
TEST_TIME 67 9169
TEST_TIME 68 9081
TEST_TIME 69 9047
TEST_TIME 70 8975
TEST_TIME 71 8939
TEST_TIME 72 8912
TEST_TIME 73 9029
TEST_TIME 74 8978
TEST_TIME 75 8914
TEST_TIME 76 8912
TEST_TIME 77 8843
TEST_TIME 78 8863
TEST_TIME 79 8836
TEST_TIME 80 8881
TEST_TIME 81 8897
TEST_TIME 82 8889
TEST_TIME 83 8898
TEST_TIME 84 8940
TEST_TIME 85 8967
TEST_TIME 86 8954
TEST_TIME 87 9052
TEST_TIME 88 8997
TEST_TIME 89 9041
TEST_TIME 90 9140
TEST_TIME 91 9162
TEST_TIME 92 9108
TEST_TIME 93 9026
TEST_TIME 94 8988
TEST_TIME 95 8958
TEST_TIME 96 8930
TEST_TIME 97 8972
TEST_TIME 98 9003
TEST_TIME 99 8981
TEST_TIME 100 8988
TEST_TIME 101 8989
TEST_TIME 102 8997
TEST_TIME 103 9025
TEST_TIME 104 9021
TEST_TIME 105 8990
TEST_TIME 106 9049
TEST_TIME 107 9090
TEST_TIME 108 9126
TEST_TIME 109 9109
TEST_TIME 110 9178
TEST_TIME 111 9280
TEST_TIME 112 9250
TEST_TIME 113 9209
TEST_TIME 114 9136
TEST_TIME 115 9200
TEST_TIME 116 9175
TEST_TIME 117 9177
TEST_TIME 118 9134
TEST_TIME 119 9100
TEST_TIME 120 9054

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-05-2022 19:40:24 to 06-05-2022 19:40:27.

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 1489812 1489812 0 0.00 0 15588 6 0 0
doc_list_biud 1489812 1489812 0 0.00 0 72290 28 0 0
sp_add_invoice_to_stock 4809 4694 115 2.39 40 170904 18209 0 115
sp_cancel_adding_invoice 5815 5815 0 0.00 5 46471 709 0 0
sp_cancel_client_order 1649 1649 0 0.00 4 10611 390 0 0
sp_cancel_customer_reserve 566728 566728 0 0.00 1 63246 58 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 2 20507 95 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 4378 85 0 0
sp_cancel_supplier_invoice 6478 6469 9 0.13 2 72355 778 0 9
sp_cancel_supplier_order 419 419 0 0.00 6 889920 99149 0 0
sp_cancel_write_off 9922 9922 0 0.00 1 12697 102 0 0
sp_client_order 19473 19473 0 0.00 3 29543 428 0 0
sp_customer_reserve 436063 436063 0 0.00 0 40749 179 0 0
sp_fill_shopping_cart 30007 30007 0 0.00 1 14903 40 0 0
sp_fill_shopping_cart_clo_res 436042 436042 0 0.00 0 16495 15 0 0
sp_get_clo_for_invoice 4673 4673 0 0.00 0 23055 336 0 0
sp_kill_cost_storno 16813 16813 0 0.00 0 7914 56 0 0
sp_kill_qstorno_ret_qs2qd 1150266 1150266 0 0.00 0 72288 31 0 0
sp_kill_qty_storno 605154 605154 0 0.00 0 72289 68 0 0
sp_lock_selected_doc 19676 19676 0 0.00 0 7159 5 0 0
sp_make_cost_storno 26485 26485 0 0.00 0 15583 52 0 0
sp_multiply_rows_for_pdistr 26485 26485 0 0.00 0 6558 30 0 0
sp_multiply_rows_for_qdistr 19473 19473 0 0.00 0 16106 166 0 0
sp_pay_from_customer 6710 6710 0 0.00 4 20494 119 0 0
sp_pay_to_supplier 5623 5623 0 0.00 4 20507 144 0 0
sp_payment_common 12333 12333 0 0.00 1 13112 34 0 0
sp_qd_handle_on_cancel_clo 1649 1649 0 0.00 0 10533 350 0 0
sp_qd_handle_on_invoice_upd_sts 10488 10488 0 0.00 0 41867 340 0 0
sp_qd_handle_on_reserve_upd_sts 9479 9479 0 0.00 0 25554 69 0 0
sp_reserve_write_off 9479 9479 0 0.00 6 25572 195 0 0
sp_supplier_invoice 5170 5170 0 0.00 7 93943 3508 0 0
sp_supplier_order 5370 5370 0 0.00 5 84548 1459 0 0
srv_aggregate_perf_data 90 90 0 0.00 2935 517529 23329 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1565 255111 46886 0 0
srv_find_qd_qs_mism 1040792 1040792 0 0.00 0 11588 5 0 0
srv_make_invnt_saldo 555 555 0 0.00 22 79110 1235 0 0
srv_make_money_saldo 740 740 0 0.00 1 34736 77 0 0
srv_recalc_idx_stat 4 4 0 0.00 207871 549309 379102 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4694 4694 0 0.00 0 12747 171 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 11794 107 0 0
t$perf-norm:sp_cancel_client_order 1649 1649 0 0.00 0 8523 19 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 1204 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 4812 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 88 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 3611 23 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 25065 1660 0 0
t$perf-norm:sp_cancel_write_off 941 941 0 0.00 0 2299 6 0 0
t$perf-norm:sp_client_order 19473 19473 0 0.00 0 11695 19 0 0
t$perf-norm:sp_customer_reserve 908 908 0 0.00 0 4230 10 0 0
t$perf-norm:sp_pay_from_customer 6710 6710 0 0.00 0 10716 15 0 0
t$perf-norm:sp_pay_to_supplier 5623 5623 0 0.00 0 10743 17 0 0
t$perf-norm:sp_reserve_write_off 9479 9479 0 0.00 0 7780 11 0 0
t$perf-norm:sp_supplier_invoice 5170 5170 0 0.00 0 7034 18 0 0
t$perf-norm:sp_supplier_order 5370 5370 0 0.00 0 11066 17 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 58 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 422 17 0 0
t$perf-norm:srv_make_invnt_saldo 555 555 0 0.00 0 2863 23 0 0
t$perf-norm:srv_make_money_saldo 740 740 0 0.00 0 2785 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 90 26 0 0
v_add_invoice_to_stock 9576 9346 230 2.40 0 20502 20 0 230
v_all_customers 35716 35716 0 0.00 0 17 0 0 0
v_all_suppliers 29280 29280 0 0.00 0 21 1 0 0
v_all_wares 38946 38946 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 1369 6 0 0
v_cancel_client_order 3298 3298 0 0.00 0 6143 10 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 20491 21 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 1449 5 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 3174 7 0 0
v_cancel_supplier_invoice 812 794 18 2.21 0 3808 22 0 18
v_cancel_supplier_order 838 838 0 0.00 0 646 5 0 0
v_cancel_write_off 1882 1882 0 0.00 0 1658 14 0 0
v_max_id_clo_ord 5369 5369 0 0.00 0 519 1 0 0
v_max_id_clo_res 908 908 0 0.00 0 3461 18 0 0
v_max_id_ord_sup 5165 5165 0 0.00 0 2399 3 0 0
v_max_non_paid_invoice 1517 1517 0 0.00 0 5143 6 0 0
v_max_non_paid_realizn 3515 3515 0 0.00 0 6558 6 0 0
v_min_id_clo_ord 5369 5369 0 0.00 0 4062 6 0 0
v_min_id_clo_res 908 908 0 0.00 0 5466 12 0 0
v_min_id_ord_sup 5165 5165 0 0.00 0 4099 7 0 0
v_min_non_paid_invoice 5623 5623 0 0.00 0 8378 24 0 0
v_min_non_paid_realizn 6710 6710 0 0.00 0 8344 23 0 0
v_our_firm 9620 9620 0 0.00 0 11 1 0 0
v_reserve_write_off 18958 18958 0 0.00 0 20504 9 0 0
x_lock_depdocs_on_canc_invoice 11887 11887 0 0.00 0 9531 100 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 12774 376 0 0
x_make_qstorno_1000_1200 5370 5370 0 0.00 1 80883 1371 0 0
x_make_qstorno_1200_2000 5170 5170 0 0.00 3 93905 3370 0 0
x_make_qstorno_2100_3300 436063 436063 0 0.00 0 40747 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-05-2022 19:40:29 to 06-05-2022 19:40:33.

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
17:08:24 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:25 20715 8192000000 9908714912 10216439808 990739120 10.789 100 92 183 0 1020845216 6303120 23802976
17:10:54 20007 8192000000 9958644864 10282270720 1036363472 11.230 100 90 180 0 1067971520 8983296 22624752
17:12:21 23757 8192000000 9983807024 10316877824 1066410688 11.518 100 78 156 0 1095466144 9764400 19291056
17:13:49 12987 8192000000 10028369904 10374057984 1109785440 11.930 100 80 160 0 1140175968 10942400 19448128
17:15:04 8460 8192000000 10066937632 10422870016 1160504832 12.408 100 80 160 0 1190987216 12050208 18432176
17:16:15 7370 8192000000 10127399696 10491736064 1215515904 12.920 100 92 183 1 1250247232 14788400 19942928
17:17:23 3215 8192000000 10152846576 10523684864 1237799792 13.126 100 85 170 0 1270075344 13561952 18713600
17:18:28 2201 8192000000 10188262816 10569113600 1269777760 13.420 100 81 162 1 1300738864 12606672 18354432
17:19:31 2809 8192000000 10211415280 10594701312 1295158176 13.651 100 92 185 1 1329090720 13467712 20464832
17:20:37 30500 8192000000 10204636576 10603081728 1305025232 13.741 100 51 102 1 1326193584 9613648 11554704
17:22:09 5785 8192000000 10215986128 10619330560 1317721456 13.856 100 46 93 1 1336079824 8293408 10064960
17:23:20 7482 8192000000 10209111104 10623930368 1323936304 13.912 100 30 60 1 1336459424 5547488 6975632
17:24:28 45968 8192000000 10218236544 10637516800 1327342464 13.943 100 35 71 1 1341441872 5623296 8476112
17:26:17 11864 8192000000 10223351808 10646605824 1337607888 14.036 100 17 33 0 1346243280 5253712 3381680
17:27:29 7229 8192000000 10219109216 10643513344 1332990304 13.994 100 20 40 1 1343144928 5800864 4353760
17:28:41 56829 8192000000 10238114880 10630795264 1302995264 13.722 98 73 148 2 1325635472 5314176 17326032
17:30:38 4164 8192000000 10211445776 10623959040 1318692672 13.865 100 46 91 0 1335849904 6673936 10483296
17:31:44 8862 8192000000 10163760640 10566918144 1255409232 13.288 98 23 46 1 1266246976 4851472 5986272
17:32:54 18592 8192000000 10130748672 10522976256 1201956240 12.794 96 22 44 1 1213597408 5001680 6639488
17:34:13 10607 8192000000 10142823840 10547011584 1255460432 13.288 100 46 94 1 1271730416 5695040 10574944
17:35:24 7846 8192000000 10159171152 10562351104 1254700384 13.281 99 23 45 1 1266281376 5910752 5670240
17:36:34 10213 8192000000 10181956528 10595037184 1292237344 13.625 100 19 38 0 1302146896 5911984 3997568
17:37:46 54546 8192000000 10207963408 10617667584 1308322208 13.771 100 24 48 3 1319412432 5896560 5193664
17:39:47 9258 8192000000 10246083184 10657759232 1340143072 14.059 100 54 106 0 1363337728 10856624 12338032
17:41:01 22862 8192000000 10268353104 10685198336 1361584992 14.252 100 38 76 1 1383594624 13280128 8729504
17:42:27 23564 8192000000 10294249200 10711830528 1375249392 14.374 100 49 98 1 1404976320 18195488 11531440
17:44:14 23021 8192000000 10301106784 10730647552 1389324176 14.500 100 35 69 1 1417869264 19174816 9370272
17:45:44 10920 8192000000 10319513840 10744963072 1401082496 14.605 100 52 104 1 1436848464 22912208 12853760
17:47:09 7698 8192000000 10322481808 10749038592 1398444656 14.581 100 43 85 0 1433167088 24617424 10105008
17:48:22 4563 8192000000 10362088352 10781487104 1423016736 14.799 100 69 138 1 1472397088 32553392 16826960
17:49:41 1561 8192000000 10378328240 10786713600 1422654368 14.796 100 81 161 1 1478401792 36186912 19560512
17:50:46 2668 8192000000 10379702624 10800308224 1430454176 14.865 100 66 133 0 1487192464 39398912 17339376
17:51:52 5602 8192000000 10394636336 10814894080 1440041280 14.950 100 80 160 2 1506405136 46590496 19773360
17:53:07 4806 8192000000 10388976880 10811260928 1411622176 14.698 99 72 144 1 1487404304 56558944 19223184
17:54:16 2889 8192000000 10384581600 10802421760 1413491024 14.715 100 79 158 0 1490515920 55446704 21578192
17:55:24 3883 8192000000 10377897312 10798075904 1406939120 14.657 100 66 130 0 1480565632 56400160 17226352
17:56:32 13088 8192000000 10344434752 10789883904 1408312080 14.669 100 37 74 1 1466739952 48485776 9942096
17:57:49 1828 8192000000 10342452576 10773987328 1392258288 14.526 100 50 100 2 1446422352 40803232 13360832
17:58:58 17368 8192000000 10331735040 10767511552 1381501344 14.430 99 35 70 2 1430266256 39575232 9189680
18:00:34 18681 8192000000 10308361424 10748321792 1364913760 14.281 99 27 54 2 1415036304 43375008 6747536
18:02:22 18981 8192000000 10327832896 10770796544 1377857840 14.397 100 35 68 0 1428480672 41403552 9219280
18:03:44 27513 8192000000 10289152656 10726109184 1344851440 14.101 99 24 48 0 1387554832 35995648 6707744
18:05:27 39612 8192000000 10288892288 10741260288 1355141920 14.194 99 15 31 1 1385507888 26663520 3702448
18:07:22 3448 8192000000 10289755456 10735943680 1362947424 14.264 100 32 64 3 1398646432 27762384 7936624
18:08:27 86321 8192000000 10278863264 10746093568 1355737952 14.199 99 10 20 0 1380438128 22633216 2066960
18:10:59 42992 8192000000 10278305888 10731544576 1368365200 14.312 100 19 38 0 1390615376 17981776 4268400
18:12:50 24274 8192000000 10274100752 10714972160 1358194944 14.221 99 38 77 2 1380600320 13601072 8804304
18:14:19 66037 8192000000 10263563952 10709123072 1360111776 14.238 100 14 28 1 1374113792 11001056 3000960
18:16:47 50171 8192000000 10265271488 10719145984 1343946112 14.093 98 32 65 1 1364057840 12216944 7894784
18:18:38 43470 8192000000 10270570032 10714021888 1342163952 14.077 98 32 64 1 1356742224 7505328 7072944
18:20:27 13793 8192000000 10284033168 10726592512 1373970080 14.363 100 20 40 0 1386216896 7644464 4602352
18:21:47 41019 8192000000 10309013536 10741608448 1385295568 14.464 100 40 79 0 1406287984 11679904 9312512
18:23:29 26414 8192000000 10288972432 10736934912 1372262336 14.347 99 24 48 0 1391537104 13713104 5561664
18:24:57 81225 8192000000 10272318224 10733555712 1361396304 14.250 98 10 20 0 1371255616 7598160 2261152
18:27:19 77977 8192000000 10270975712 10729873408 1352212624 14.167 98 21 42 0 1367301328 10222144 4866560
18:29:51 45577 8192000000 10272689632 10732740608 1378314528 14.401 100 10 20 1 1393006560 12282032 2410000
18:31:40 37829 8192000000 10300271472 10753564672 1392955712 14.532 100 18 36 0 1407433792 10312160 4165920
18:33:26 83704 8192000000 10298934960 10750390272 1370790032 14.334 98 14 28 0 1383069248 9212656 3066560
18:35:55 84221 8192000000 10295638160 10755862528 1377077232 14.390 99 11 22 0 1392663504 13227216 2359056
18:38:27 35830 8192000000 10307273008 10755600384 1375257408 14.374 98 30 60 0 1392728576 10811760 6659408
18:40:11 94014 8192000000 10285392992 10735214592 1337976560 14.039 96 13 26 1 1351806048 11420816 2408672
18:42:45 129919 8192000000 10296364912 10748379136 1368639664 14.315 98 24 46 2 1383582464 9602048 5340752
18:45:57 42306 8192000000 10276083504 10715230208 1349044896 14.139 98 44 88 0 1368003696 8254288 10704512
18:47:41 94350 8192000000 10278881600 10736398336 1353019600 14.175 98 12 24 1 1373616384 17616768 2980016
18:50:15 55588 8192000000 10263761168 10715111424 1330696368 13.973 97 13 26 0 1355725152 21098464 3930320
18:52:11 57822 8192000000 10252295120 10697965568 1320944944 13.885 97 25 48 0 1333229264 6784736 5499584
18:54:16 69008 8192000000 10254799632 10715213824 1359677312 14.234 100 9 18 0 1371884288 10261200 1945776
18:56:25 69143 8192000000 10261014752 10726219776 1341841584 14.074 98 7 14 0 1358550384 15209472 1499328
18:58:35 47147 8192000000 10265584320 10712113152 1336116736 14.022 98 15 30 0 1348198752 8936560 3145456
19:00:31 30273 8192000000 10278056176 10721169408 1363412800 14.268 100 37 74 1 1383850704 11770368 8667536
19:02:06 36225 8192000000 10260659696 10706268160 1315151472 13.833 97 27 54 2 1331751296 10727664 5872160
19:03:47 56521 8192000000 10250670272 10698854400 1293372432 13.635 95 17 34 0 1310030080 12561936 4095712
19:07:10 35165 8192000000 10273196128 10720538624 1353571008 14.180 100 43 86 3 1381490464 18411296 9508160
19:08:59 96110 8192000000 10264978080 10710126592 1322854400 13.903 97 10 20 0 1339315616 14509888 1951328
19:12:40 89032 8192000000 10259823600 10712784896 1312212736 13.806 96 19 38 0 1324547072 7648032 4686304
19:15:09 82475 8192000000 10269522624 10729791488 1348496768 14.134 99 12 24 1 1365106064 13992720 2616576
19:17:46 27470 8192000000 10287943424 10733383680 1347663488 14.126 98 19 38 0 1360185216 8381360 4140368
19:19:22 60617 8192000000 10283390976 10739625984 1365952912 14.291 99 21 42 0 1379933312 9091504 4888896
19:21:22 46885 8192000000 10303390256 10751647744 1378831056 14.406 100 21 41 1 1400596256 16904736 4860464
19:23:14 40242 8192000000 10299947472 10748170240 1375993904 14.381 99 33 65 1 1393478464 10088368 7396192
19:25:07 47957 8192000000 10296947072 10755194880 1374393776 14.366 99 22 44 0 1390905776 11823808 4688192
19:26:57 65433 8192000000 10312562272 10767327232 1376698816 14.387 99 8 16 0 1392686048 14373680 1613552
19:29:07 255111 8192000000 10313504272 10763825152 1345172112 14.104 96 11 22 0 1367378000 19883856 2322032
19:36:18 118732 8192000000 10287385472 10744836096 1344285328 14.096 96 12 24 0 1356740176 9921328 2533520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-05-2022 19:40:34 to 06-05-2022 19:40:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-05-2022 19:40:50 to 06-05-2022 19:40:52.

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          227549
MON$OLDEST_ACTIVE               229351
MON$OLDEST_SNAPSHOT             229351
MON$NEXT_TRANSACTION            229351
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                       2014784
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33586 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33586 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33586 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33586 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 ---
:31: 
:32: mscs-test-161.budzdorov.ru    Fri May  6 19:38:32 2022
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Fri May  6 19:38:35 2022
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:38: 
:39: 
:40: mscs-test-161.budzdorov.ru    Fri May  6 19:38:41 2022
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:42: 
:43: 
:44: mscs-test-161.budzdorov.ru    Fri May  6 19:38:42 2022
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:46: 
:47: 
:48: mscs-test-161.budzdorov.ru    Fri May  6 19:38:43 2022
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:50: 
:51: 
:52: mscs-test-161.budzdorov.ru    Fri May  6 19:38:44 2022
:53:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:54: 
--- end of diff output ---

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

06.05.22 19:40:53. 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-05-06 19:40:55 No severe PSQL-related problems occured
06.05.22 19:40:57. End of report.