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

Server and database settinfs

SERVER_MODE                     SuperServer 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-29 01:38:14
DTS_END                         2022-05-29 01:38:14.4250
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/50738
TRN_ID                          242243
ATT_ID                          631
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: 9355.51 25372 1122661
customer order: creation 170.91 394 20509
customer order: refuse 15.05 350 1806
order to supplier: creation 47.31 1208 5677
order to supplier: removal 3.58 96516 429
invoice (draft): creation 46.64 3393 5597
invoice (draft): removal 55.09 735 6611
invoice accept: apply 42.06 18397 5047
invoice accept: cancel 48.85 751 5862
customer reserve: creation 3846.37 183 461564
customer reserve: removal 4783.88 57 574065
realization accept: apply 82.55 178 9906
realization accept: cancel 84.93 106 10192
payment from customer: creation 58.29 111 6995
payment from customer: removal 5.53 98 663
payment to supplier: creation 48.40 142 5808
payment to supplier: removal 3.71 97 445
service: total inventory turnovers 4.83 1222 580
service: total monetary turnovers 6.63 30 795
service: aggregate perf. data 0.88 18080 106
service: refresh index statistics 0.03 365386 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-05-2022 01:38:56 to 29-05-2022 01:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5132
TEST_TIME 2 5790
TEST_TIME 3 5818
TEST_TIME 4 5905
TEST_TIME 5 5239
TEST_TIME 6 4987
TEST_TIME 7 4975
TEST_TIME 8 5528
TEST_TIME 9 5518
TEST_TIME 10 5554
TEST_TIME 11 5871
TEST_TIME 12 5904
TEST_TIME 13 6123
TEST_TIME 14 6635
TEST_TIME 15 6807
TEST_TIME 16 7041
TEST_TIME 17 7849
TEST_TIME 18 8271
TEST_TIME 19 8507
TEST_TIME 20 8804
TEST_TIME 21 8957
TEST_TIME 22 9393
TEST_TIME 23 9174
TEST_TIME 24 9597
TEST_TIME 25 9796
TEST_TIME 26 9902
TEST_TIME 27 10078
TEST_TIME 28 10394
TEST_TIME 29 11110
TEST_TIME 30 11097
TEST_TIME 31 10950
TEST_TIME 32 10819
TEST_TIME 33 10712
TEST_TIME 34 10571
TEST_TIME 35 10313
TEST_TIME 36 10267
TEST_TIME 37 10387
TEST_TIME 38 10212
TEST_TIME 39 10121
TEST_TIME 40 10038
TEST_TIME 41 9896
TEST_TIME 42 9920
TEST_TIME 43 10088
TEST_TIME 44 10324
TEST_TIME 45 10217
TEST_TIME 46 10103
TEST_TIME 47 10123
TEST_TIME 48 10072
TEST_TIME 49 10095
TEST_TIME 50 10037
TEST_TIME 51 9917
TEST_TIME 52 9883
TEST_TIME 53 9843
TEST_TIME 54 9805
TEST_TIME 55 9739
TEST_TIME 56 9718
TEST_TIME 57 9696
TEST_TIME 58 9534
TEST_TIME 59 9663
TEST_TIME 60 9601
TEST_TIME 61 9582
TEST_TIME 62 9640
TEST_TIME 63 9561
TEST_TIME 64 9751
TEST_TIME 65 9732
TEST_TIME 66 9698
TEST_TIME 67 9781
TEST_TIME 68 9811
TEST_TIME 69 9765
TEST_TIME 70 9821
TEST_TIME 71 9759
TEST_TIME 72 9765
TEST_TIME 73 9876
TEST_TIME 74 9868
TEST_TIME 75 9765
TEST_TIME 76 9753
TEST_TIME 77 9657
TEST_TIME 78 9644
TEST_TIME 79 9634
TEST_TIME 80 9597
TEST_TIME 81 9562
TEST_TIME 82 9504
TEST_TIME 83 9469
TEST_TIME 84 9402
TEST_TIME 85 9432
TEST_TIME 86 9426
TEST_TIME 87 9378
TEST_TIME 88 9436
TEST_TIME 89 9404
TEST_TIME 90 9418
TEST_TIME 91 9409
TEST_TIME 92 9544
TEST_TIME 93 9552
TEST_TIME 94 9576
TEST_TIME 95 9612
TEST_TIME 96 9600
TEST_TIME 97 9570
TEST_TIME 98 9705
TEST_TIME 99 9702
TEST_TIME 100 9644
TEST_TIME 101 9556
TEST_TIME 102 9574
TEST_TIME 103 9512
TEST_TIME 104 9501
TEST_TIME 105 9480
TEST_TIME 106 9445
TEST_TIME 107 9432
TEST_TIME 108 9403
TEST_TIME 109 9364
TEST_TIME 110 9316
TEST_TIME 111 9316
TEST_TIME 112 9361
TEST_TIME 113 9328
TEST_TIME 114 9352
TEST_TIME 115 9386
TEST_TIME 116 9417
TEST_TIME 117 9430
TEST_TIME 118 9450
TEST_TIME 119 9414
TEST_TIME 120 9366

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-05-2022 01:39:00 to 29-05-2022 01:39:03.

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 1549960 1549960 0 0.00 0 17622 6 0 0
doc_list_biud 1549954 1549954 0 0.00 0 33678 27 0 0
sp_add_invoice_to_stock 5167 5047 120 2.32 36 177695 18397 0 120
sp_cancel_adding_invoice 5862 5862 0 0.00 5 89160 751 0 0
sp_cancel_client_order 1806 1806 0 0.00 4 11805 350 0 0
sp_cancel_customer_reserve 574065 574065 0 0.00 1 32637 57 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 5889 98 0 0
sp_cancel_pay_to_supplier 445 445 0 0.00 3 6260 97 0 0
sp_cancel_supplier_invoice 6620 6611 9 0.13 2 18502 735 0 9
sp_cancel_supplier_order 429 429 0 0.00 9 964809 96516 0 0
sp_cancel_write_off 10192 10192 0 0.00 1 11859 106 0 0
sp_client_order 20509 20509 0 0.00 3 22530 395 0 0
sp_customer_reserve 461564 461564 0 0.00 0 50352 184 0 0
sp_fill_shopping_cart 31781 31781 0 0.00 1 17661 42 0 0
sp_fill_shopping_cart_clo_res 461547 461547 0 0.00 0 17873 14 0 0
sp_get_clo_for_invoice 5028 5028 0 0.00 0 22112 326 0 0
sp_kill_cost_storno 17162 17162 0 0.00 0 11854 55 0 0
sp_kill_qstorno_ret_qs2qd 1165362 1165362 0 0.00 0 32000 31 0 0
sp_kill_qty_storno 613899 613899 0 0.00 0 33674 67 0 0
sp_lock_selected_doc 20883 20883 0 0.00 0 9097 5 0 0
sp_make_cost_storno 27737 27737 0 0.00 0 17618 54 0 0
sp_multiply_rows_for_pdistr 27737 27737 0 0.00 0 8342 29 0 0
sp_multiply_rows_for_qdistr 20509 20509 0 0.00 0 21246 202 0 0
sp_pay_from_customer 6995 6995 0 0.00 4 14355 111 0 0
sp_pay_to_supplier 5808 5808 0 0.00 4 15823 142 0 0
sp_payment_common 12803 12803 0 0.00 2 9082 27 0 0
sp_qd_handle_on_cancel_clo 1806 1806 0 0.00 0 8933 315 0 0
sp_qd_handle_on_invoice_upd_sts 10890 10890 0 0.00 0 33665 331 0 0
sp_qd_handle_on_reserve_upd_sts 9906 9906 0 0.00 0 13465 55 0 0
sp_reserve_write_off 9906 9906 0 0.00 6 18848 178 0 0
sp_supplier_invoice 5597 5597 0 0.00 6 115031 3393 0 0
sp_supplier_order 5677 5677 0 0.00 5 29242 1208 0 0
srv_aggregate_perf_data 106 106 0 0.00 2676 163903 18081 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1417 138670 46469 0 0
srv_find_qd_qs_mism 1074980 1074980 0 0.00 0 12309 5 0 0
srv_make_invnt_saldo 580 580 0 0.00 28 80956 1223 0 0
srv_make_money_saldo 795 795 0 0.00 0 4220 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 141679 572326 365387 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 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 5047 5047 0 0.00 0 15739 175 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 5264 97 0 0
t$perf-norm:sp_cancel_client_order 1806 1806 0 0.00 0 5844 16 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 2136 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 4123 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 445 445 0 0.00 0 1129 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 2976 22 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 27194 1598 0 0
t$perf-norm:sp_cancel_write_off 1070 1070 0 0.00 0 1131 9 0 0
t$perf-norm:sp_client_order 20509 20509 0 0.00 0 6439 21 0 0
t$perf-norm:sp_customer_reserve 969 969 0 0.00 0 4453 12 0 0
t$perf-norm:sp_pay_from_customer 6995 6995 0 0.00 0 5834 12 0 0
t$perf-norm:sp_pay_to_supplier 5808 5808 0 0.00 0 4722 10 0 0
t$perf-norm:sp_reserve_write_off 9906 9906 0 0.00 0 15885 15 0 0
t$perf-norm:sp_supplier_invoice 5597 5597 0 0.00 0 4259 18 0 0
t$perf-norm:sp_supplier_order 5677 5677 0 0.00 0 6832 16 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 4427 79 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 580 580 0 0.00 0 662 20 0 0
t$perf-norm:srv_make_money_saldo 795 795 0 0.00 0 2637 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10296 10056 240 2.33 0 3957 19 0 240
v_all_customers 37440 37440 0 0.00 0 21 1 0 0
v_all_suppliers 30940 30940 0 0.00 0 19 1 0 0
v_all_wares 41018 41018 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 3450 10 0 0
v_cancel_client_order 3612 3612 0 0.00 0 4106 8 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 3119 8 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 1976 6 0 0
v_cancel_payment_to_supplier 890 890 0 0.00 0 3813 8 0 0
v_cancel_supplier_invoice 872 854 18 2.06 0 312 14 0 18
v_cancel_supplier_order 858 858 0 0.00 0 2587 18 0 0
v_cancel_write_off 2140 2140 0 0.00 0 2573 15 0 0
v_max_id_clo_ord 5677 5677 0 0.00 0 2567 2 0 0
v_max_id_clo_res 969 969 0 0.00 0 1201 7 0 0
v_max_id_ord_sup 5595 5595 0 0.00 0 5125 4 0 0
v_max_non_paid_invoice 1610 1610 0 0.00 0 1912 4 0 0
v_max_non_paid_realizn 3692 3692 0 0.00 0 459 3 0 0
v_min_id_clo_ord 5677 5677 0 0.00 0 6257 8 0 0
v_min_id_clo_res 969 969 0 0.00 0 1704 6 0 0
v_min_id_ord_sup 5595 5595 0 0.00 0 6841 9 0 0
v_min_non_paid_invoice 5808 5808 0 0.00 0 9077 21 0 0
v_min_non_paid_realizn 6995 6995 0 0.00 0 7608 20 0 0
v_our_firm 10184 10184 0 0.00 0 20 1 0 0
v_reserve_write_off 19808 19808 0 0.00 0 4399 8 0 0
x_lock_depdocs_on_canc_invoice 12046 12046 0 0.00 0 19696 126 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 15763 434 0 0
x_make_qstorno_1000_1200 5677 5677 0 0.00 2 29230 1119 0 0
x_make_qstorno_1200_2000 5597 5597 0 0.00 2 103529 3248 0 0
x_make_qstorno_2100_3300 461564 461564 0 0.00 0 47474 167 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 29-05-2022 01:39:04 to 29-05-2022 01:39:09.

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
23:08:18 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:18 31899 8192000000 9913252320 10229624832 998977584 10.869 100 86 172 0 1027614912 6237264 22400064
23:10:59 20177 8192000000 9957571984 10280882176 1038773808 11.253 100 92 183 1 1071295696 9305232 23216656
23:12:28 17212 8192000000 9983061104 10321473536 1068558064 11.538 100 70 140 0 1095275200 9569216 17147920
23:13:50 11580 8192000000 10037368352 10388701184 1124912304 12.073 100 75 150 0 1153034096 10745728 17376064
23:15:05 8435 8192000000 10092851488 10454712320 1181642064 12.606 100 80 160 1 1211575808 11818480 18115264
23:16:16 5875 8192000000 10137509968 10512039936 1226599056 13.023 100 79 158 0 1258382960 13807856 17976048
23:17:25 2400 8192000000 10175709328 10555248640 1265901072 13.384 100 87 175 2 1297315184 11936320 19477792
23:18:29 1665 8192000000 10206135632 10588520448 1288714208 13.593 100 90 179 1 1321689440 12754064 20221168
23:19:32 30581 8192000000 10219177520 10611507200 1306296992 13.752 100 60 121 1 1334096944 14260592 13539360
23:21:03 2473 8192000000 10216410800 10624053248 1318335552 13.862 100 48 97 2 1338103168 8556352 11211264
23:22:10 10032 8192000000 10226828304 10643566592 1328570448 13.954 100 22 42 0 1347811808 13995904 5245456
23:23:22 6524 8192000000 10246965200 10646777856 1334285248 14.006 100 67 137 3 1356168672 5979008 15904416
23:24:29 7215 8192000000 10225005344 10646933504 1335371296 14.016 100 22 44 0 1346699776 6645808 4682672
23:25:37 9025 8192000000 10231409184 10651840512 1338058272 14.040 100 8 16 0 1344888112 5320112 1509728
23:26:46 66716 8192000000 10210953824 10631270400 1322473792 13.899 99 29 58 0 1334979440 4905744 7599904
23:28:54 6695 8192000000 10174870976 10600648704 1269470112 13.417 96 27 54 1 1282365216 7112144 5782960
23:30:05 5914 8192000000 10176329776 10581512192 1275023984 13.468 99 29 56 1 1292350896 10076416 7250496
23:31:12 62464 8192000000 10112689728 10513235968 1130015104 12.122 91 26 51 1 1141557760 4487776 7054880
23:33:23 7519 8192000000 10152442752 10557145088 1251293744 13.250 99 34 68 1 1265345024 5314272 8737008
23:34:32 9491 8192000000 10190736656 10597183488 1295058144 13.650 100 25 50 0 1307148848 6385936 5704768
23:35:41 18300 8192000000 10206766288 10618404864 1309510528 13.782 100 18 36 1 1318782688 4718352 4553808
23:37:03 10855 8192000000 10199701280 10620596224 1309575824 13.782 100 27 54 1 1325885056 10212352 6096880
23:38:21 6232 8192000000 10246877616 10653667328 1334885088 14.011 100 57 114 2 1354898288 7120128 12893072
23:39:36 13272 8192000000 10264024576 10670153728 1348836320 14.137 100 60 119 0 1373970160 11417680 13716160
23:40:52 11761 8192000000 10280047152 10689609728 1362746512 14.262 100 57 114 0 1389030112 13789088 12494512
23:42:10 41384 8192000000 10289369376 10712375296 1375975488 14.381 100 51 102 0 1401504192 13284816 12243888
23:43:57 8051 8192000000 10304948080 10732847104 1393247168 14.535 100 37 74 0 1422743360 20473536 9022656
23:45:14 13994 8192000000 10316199872 10742153216 1395062096 14.551 100 44 88 2 1432806832 26469104 11275632
23:46:34 15929 8192000000 10323016400 10742132736 1375151152 14.373 98 62 122 1 1416596720 26575392 14870176
23:47:54 7581 8192000000 10329108192 10755371008 1388165712 14.489 99 48 96 1 1432389248 32385344 11838192
23:49:09 3075 8192000000 10352157200 10769317888 1409432864 14.679 100 63 126 0 1460189056 34727232 16028960
23:50:15 1416 8192000000 10350462640 10763485184 1401446048 14.608 100 81 161 0 1452199808 31532208 19221552
23:51:20 4776 8192000000 10360055648 10776801280 1401807072 14.611 99 82 162 0 1457666432 36072272 19787088
23:52:30 2032 8192000000 10374983184 10787987456 1416190288 14.739 100 84 166 3 1478575664 42004160 20381216
23:53:37 2006 8192000000 10371965440 10786271232 1411446400 14.697 100 86 170 1 1476117808 44001136 20670272
23:54:45 3480 8192000000 10346837744 10754576384 1362531920 14.260 98 83 166 0 1435231792 51332096 21367776
23:55:56 14830 8192000000 10306341968 10741194752 1371191136 14.338 99 37 72 1 1430622800 48958816 10472848
23:57:32 4364 8192000000 10312876928 10727636992 1364011616 14.273 99 69 136 0 1416324464 35879264 16433584
23:58:40 3809 8192000000 10343740256 10757906432 1389081824 14.498 100 63 124 1 1440274304 34207104 16985376
23:59:48 1756 8192000000 10352426208 10768531456 1392235504 14.526 100 78 157 1 1455657072 42897936 20523632
00:00:55 6111 8192000000 10331833536 10756771840 1368357296 14.312 98 50 100 0 1422840288 41622320 12860672
00:02:06 4190 8192000000 10332046144 10752118784 1374138368 14.364 100 52 104 1 1434427472 46703536 13585568
00:03:18 29456 8192000000 10293910304 10731163648 1341704496 14.073 98 20 40 1 1381845136 34444992 5695648
00:04:53 29837 8192000000 10304339056 10744832000 1361939168 14.255 100 20 36 2 1408016768 40626256 5451344
00:06:51 63414 8192000000 10269799008 10720677888 1341557536 14.071 98 9 18 2 1367474624 23549376 2367712
00:08:57 98060 8192000000 10279632160 10717802496 1309572720 13.782 95 24 48 0 1322883760 8301840 5009200
00:11:46 47477 8192000000 10270823360 10707955712 1363987872 14.273 100 20 38 1 1374242384 6257552 3996960
00:13:33 69755 8192000000 10283841456 10726645760 1372432576 14.349 100 26 53 3 1390610928 11984688 6193664
00:15:54 92755 8192000000 10304698320 10754224128 1377774224 14.397 100 15 30 1 1391839360 11074400 2990736
00:18:28 13707 8192000000 10323979056 10764021760 1399273504 14.589 100 49 98 0 1426737056 15014928 12448624
00:19:42 53427 8192000000 10341167888 10801758208 1409956816 14.684 100 31 62 0 1453512224 35365680 8189728
00:21:56 39943 8192000000 10313625136 10807287808 1397747024 14.575 100 13 26 1 1438179600 37023120 3409456
00:23:38 33296 8192000000 10308008960 10768797696 1407861728 14.665 100 10 20 1 1422269520 11927504 2480288
00:25:15 64246 8192000000 10322750352 10777309184 1410324032 14.687 100 21 42 0 1433653872 18354192 4975648
00:27:29 96102 8192000000 10294738768 10756399104 1384580768 14.457 98 10 21 0 1394636336 7983136 2072432
00:30:07 43038 8192000000 10284507472 10742181888 1347455424 14.125 96 27 54 0 1362824976 8902320 6467232
00:31:52 35039 8192000000 10321405664 10777407488 1407426752 14.661 100 25 48 1 1428789472 16033984 5328736
00:33:52 45476 8192000000 10303041488 10773684224 1394790896 14.549 99 12 24 1 1414852432 17353856 2707680
00:35:41 80781 8192000000 10312363744 10768166912 1404745744 14.637 100 14 28 1 1419354864 10883392 3725728
00:38:02 79729 8192000000 10294807792 10753982464 1361754320 14.253 97 11 22 0 1373341664 9295504 2291840
00:40:30 85358 8192000000 10282354400 10733707264 1293654352 13.638 93 11 22 0 1306346576 10268320 2423904
00:43:08 73904 8192000000 10305920448 10752745472 1359905488 14.237 98 33 66 1 1379489312 12905536 6678288
00:45:51 103445 8192000000 10290892256 10741739520 1338092064 14.040 96 31 62 2 1358071760 12227824 7751872
00:48:38 70626 8192000000 10306625328 10742829056 1354357328 14.187 98 31 62 3 1374248880 11940544 7951008
00:50:50 57383 8192000000 10284346256 10742222848 1361455312 14.250 99 33 66 0 1387306992 18470016 7381664
00:52:48 138106 8192000000 10246478080 10705969152 1305081776 13.741 95 17 34 0 1326315056 16957808 4275472
00:56:26 58296 8192000000 10221824032 10672005120 1317648528 13.855 97 32 64 0 1334672592 9470288 7553776
00:58:44 33660 8192000000 10254053296 10710245376 1355564976 14.198 100 8 16 0 1365320752 8251280 1504496
01:00:18 66359 8192000000 10263472672 10721873920 1356937024 14.210 99 13 26 0 1370763232 10829408 2996800
01:02:25 73177 8192000000 10244974800 10704084992 1303727408 13.729 95 16 32 2 1324383424 16980112 3675904
01:06:08 31885 8192000000 10270537520 10728652800 1359060880 14.229 99 24 49 0 1378419200 13840320 5518000
01:08:05 58044 8192000000 10297746384 10750304256 1383871616 14.451 100 22 44 0 1403149024 14475744 4801664
01:10:03 79717 8192000000 10292386560 10758639616 1374309936 14.366 99 13 26 0 1399867056 21956752 3600368
01:12:24 80798 8192000000 10299164640 10744135680 1356489216 14.206 97 13 26 0 1373323184 14155792 2678176
01:14:49 24332 8192000000 10326910832 10769010688 1399886240 14.594 100 29 58 0 1414532320 8557648 6088432
01:16:14 102060 8192000000 10295819296 10756947968 1352640896 14.171 97 11 21 0 1373461280 18822112 1998272
01:18:58 138670 8192000000 10306531344 10758520832 1388092256 14.489 99 47 94 0 1408821680 10133840 10595584
01:22:22 61632 8192000000 10288399824 10760601600 1396511776 14.564 100 9 18 0 1406711296 8263792 1935728
01:24:30 72708 8192000000 10293125744 10749550592 1364617280 14.279 97 18 35 0 1376067968 7058048 4392640
01:26:43 74208 8192000000 10273037648 10733252608 1328228016 13.951 95 14 28 1 1342040496 10415984 3396496
01:29:20 50896 8192000000 10280696144 10738388992 1350561664 14.153 98 16 32 0 1366531760 12189648 3780448
01:31:13 45285 8192000000 10298353600 10748612608 1365357792 14.285 98 19 38 1 1379860416 9607440 4895184
01:33:09 66323 8192000000 10284737440 10753179648 1370626816 14.333 98 17 34 2 1391748368 16505968 4615584
01:35:19 86116 8192000000 10282452480 10739871744 1337707520 14.037 96 26 52 1 1355165280 10880832 6576928
01:37:52 8831 8192000000 10303451152 10736730112 1365050032 14.283 99 58 116 0 1392868192 15351424 12466736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-05-2022 01:39:10 to 29-05-2022 01:39:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 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 29-05-2022 01:39:26 to 29-05-2022 01:39:28.

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          240202
MON$OLDEST_ACTIVE               242400
MON$OLDEST_SNAPSHOT             242400
MON$NEXT_TRANSACTION            242400
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                       2042272
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.33597 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33597 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33597 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33597 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 ---
:89: 
:90: mscs-test-161.budzdorov.ru    Sun May 29 01:38:15 2022
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:92: 
:93: 
:94: mscs-test-161.budzdorov.ru    Sun May 29 01:38:18 2022
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:96: 
:97: 
:98: mscs-test-161.budzdorov.ru    Sun May 29 01:38:18 2022
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:100: 
--- end of diff output ---

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

29.05.22 01:39:29. 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-29 01:39:31 No severe PSQL-related problems occured
29.05.22 01:39:33. End of report.