Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.07.2022 01: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 16333592 kB
MemAvailable 31160972 kB
Buffers 0 kB
Cached 15414408 kB
SwapCached 2536 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-07-08 01:38:19
DTS_END                         2022-07-08 01:38:19.5320
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41876
TRN_ID                          244454
ATT_ID                          669
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: 8992.46 19612 1079095
customer order: creation 175.51 346 21061
customer order: refuse 15.23 350 1827
order to supplier: creation 46.18 1277 5542
order to supplier: removal 3.41 97451 409
invoice (draft): creation 45.56 3237 5467
invoice (draft): removal 49.95 826 5994
invoice accept: apply 41.98 19939 5037
invoice accept: cancel 45.18 767 5421
customer reserve: creation 3855.13 200 462616
customer reserve: removal 4416.68 58 530002
realization accept: apply 86.04 176 10325
realization accept: cancel 80.11 99 9613
payment from customer: creation 61.48 115 7378
payment from customer: removal 5.34 68 641
payment to supplier: creation 49.19 130 5903
payment to supplier: removal 3.33 79 399
service: total inventory turnovers 4.55 1222 546
service: total monetary turnovers 6.83 25 819
service: aggregate perf. data 0.76 25501 91
service: refresh index statistics 0.03 240377 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-07-2022 01:40:10 to 08-07-2022 01:40:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7354
TEST_TIME 2 5907
TEST_TIME 3 5292
TEST_TIME 4 6033
TEST_TIME 5 6909
TEST_TIME 6 6551
TEST_TIME 7 6279
TEST_TIME 8 6137
TEST_TIME 9 6261
TEST_TIME 10 6465
TEST_TIME 11 6393
TEST_TIME 12 6683
TEST_TIME 13 6670
TEST_TIME 14 6772
TEST_TIME 15 6838
TEST_TIME 16 6993
TEST_TIME 17 7010
TEST_TIME 18 7228
TEST_TIME 19 7685
TEST_TIME 20 7943
TEST_TIME 21 7953
TEST_TIME 22 8056
TEST_TIME 23 8113
TEST_TIME 24 8442
TEST_TIME 25 8599
TEST_TIME 26 8838
TEST_TIME 27 9566
TEST_TIME 28 9661
TEST_TIME 29 10161
TEST_TIME 30 10016
TEST_TIME 31 10285
TEST_TIME 32 10371
TEST_TIME 33 10134
TEST_TIME 34 9923
TEST_TIME 35 9784
TEST_TIME 36 9621
TEST_TIME 37 9492
TEST_TIME 38 9511
TEST_TIME 39 9412
TEST_TIME 40 9300
TEST_TIME 41 9217
TEST_TIME 42 9220
TEST_TIME 43 9210
TEST_TIME 44 9273
TEST_TIME 45 9147
TEST_TIME 46 9154
TEST_TIME 47 9187
TEST_TIME 48 9105
TEST_TIME 49 9072
TEST_TIME 50 9054
TEST_TIME 51 8954
TEST_TIME 52 8843
TEST_TIME 53 8748
TEST_TIME 54 8765
TEST_TIME 55 9043
TEST_TIME 56 8981
TEST_TIME 57 8973
TEST_TIME 58 9085
TEST_TIME 59 9032
TEST_TIME 60 9098
TEST_TIME 61 9135
TEST_TIME 62 9190
TEST_TIME 63 9183
TEST_TIME 64 9173
TEST_TIME 65 9224
TEST_TIME 66 9249
TEST_TIME 67 9311
TEST_TIME 68 9309
TEST_TIME 69 9333
TEST_TIME 70 9248
TEST_TIME 71 9221
TEST_TIME 72 9193
TEST_TIME 73 9148
TEST_TIME 74 9148
TEST_TIME 75 9180
TEST_TIME 76 9124
TEST_TIME 77 9107
TEST_TIME 78 9069
TEST_TIME 79 9026
TEST_TIME 80 9001
TEST_TIME 81 8962
TEST_TIME 82 8975
TEST_TIME 83 8922
TEST_TIME 84 8910
TEST_TIME 85 9082
TEST_TIME 86 9077
TEST_TIME 87 9110
TEST_TIME 88 9082
TEST_TIME 89 9095
TEST_TIME 90 9079
TEST_TIME 91 9195
TEST_TIME 92 9215
TEST_TIME 93 9267
TEST_TIME 94 9254
TEST_TIME 95 9264
TEST_TIME 96 9244
TEST_TIME 97 9192
TEST_TIME 98 9140
TEST_TIME 99 9123
TEST_TIME 100 9078
TEST_TIME 101 9095
TEST_TIME 102 9077
TEST_TIME 103 9029
TEST_TIME 104 9044
TEST_TIME 105 9028
TEST_TIME 106 9024
TEST_TIME 107 9033
TEST_TIME 108 9041
TEST_TIME 109 9076
TEST_TIME 110 9081
TEST_TIME 111 9063
TEST_TIME 112 9098
TEST_TIME 113 9112
TEST_TIME 114 9138
TEST_TIME 115 9164
TEST_TIME 116 9180
TEST_TIME 117 9138
TEST_TIME 118 9105
TEST_TIME 119 9062
TEST_TIME 120 8998

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-07-2022 01:40:13 to 08-07-2022 01:40:17.

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 1507915 1507915 0 0.00 0 17200 7 0 0
doc_list_biud 1507915 1507915 0 0.00 0 24124 26 0 0
sp_add_invoice_to_stock 5196 5037 159 3.06 30 230043 19940 0 159
sp_cancel_adding_invoice 5421 5421 0 0.00 4 83220 767 0 0
sp_cancel_client_order 1827 1827 0 0.00 4 11901 351 0 0
sp_cancel_customer_reserve 530002 530002 0 0.00 0 17624 59 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 2 1534 68 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 2594 79 0 0
sp_cancel_supplier_invoice 6006 5994 12 0.19 1 15788 827 0 12
sp_cancel_supplier_order 409 409 0 0.00 5 1032427 97452 0 0
sp_cancel_write_off 9613 9613 0 0.00 1 7884 100 0 0
sp_client_order 21061 21061 0 0.00 3 69125 347 0 0
sp_customer_reserve 462616 462616 0 0.00 0 32550 201 0 0
sp_fill_shopping_cart 32068 32068 0 0.00 1 18792 42 0 0
sp_fill_shopping_cart_clo_res 462609 462609 0 0.00 0 13925 14 0 0
sp_get_clo_for_invoice 5030 5030 0 0.00 0 25033 307 0 0
sp_kill_cost_storno 16074 16074 0 0.00 0 8067 55 0 0
sp_kill_qstorno_ret_qs2qd 1076020 1076020 0 0.00 0 21485 32 0 0
sp_kill_qty_storno 568621 568621 0 0.00 0 24123 69 0 0
sp_lock_selected_doc 21138 21138 0 0.00 0 10299 4 0 0
sp_make_cost_storno 28636 28636 0 0.00 0 16083 57 0 0
sp_multiply_rows_for_pdistr 28636 28636 0 0.00 0 17193 30 0 0
sp_multiply_rows_for_qdistr 21061 21061 0 0.00 0 69119 151 0 0
sp_pay_from_customer 7378 7378 0 0.00 4 16097 115 0 0
sp_pay_to_supplier 5903 5903 0 0.00 4 17205 131 0 0
sp_payment_common 13281 13281 0 0.00 1 10051 31 0 0
sp_qd_handle_on_cancel_clo 1827 1827 0 0.00 0 11896 325 0 0
sp_qd_handle_on_invoice_upd_sts 10451 10451 0 0.00 0 24119 320 0 0
sp_qd_handle_on_reserve_upd_sts 10325 10325 0 0.00 0 8210 55 0 0
sp_reserve_write_off 10325 10325 0 0.00 6 13509 176 0 0
sp_supplier_invoice 5467 5467 0 0.00 5 76580 3238 0 0
sp_supplier_order 5542 5542 0 0.00 5 36880 1277 0 0
srv_aggregate_perf_data 91 91 0 0.00 2836 431270 25501 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1923 137310 44847 0 0
srv_find_qd_qs_mism 1031112 1031112 0 0.00 0 11504 6 0 0
srv_make_invnt_saldo 546 546 0 0.00 44 26963 1223 0 0
srv_make_money_saldo 819 819 0 0.00 0 1133 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 157777 368186 240377 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5039 5039 0 0.00 0 18878 172 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 3013 98 0 0
t$perf-norm:sp_cancel_client_order 1827 1827 0 0.00 0 3195 20 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 353 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 2468 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 932 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 4240 24 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 29038 1562 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 7731 12 0 0
t$perf-norm:sp_client_order 21061 21061 0 0.00 0 16008 18 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 1152 10 0 0
t$perf-norm:sp_pay_from_customer 7378 7378 0 0.00 0 3193 9 0 0
t$perf-norm:sp_pay_to_supplier 5903 5903 0 0.00 0 9129 12 0 0
t$perf-norm:sp_reserve_write_off 10325 10325 0 0.00 0 6986 13 0 0
t$perf-norm:sp_supplier_invoice 5467 5467 0 0.00 0 4717 15 0 0
t$perf-norm:sp_supplier_order 5542 5542 0 0.00 0 4288 16 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 263 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 647 15 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 841 18 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 2729 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10378 10060 318 3.06 0 5829 19 0 318
v_all_customers 38676 38676 0 0.00 0 15 1 0 0
v_all_suppliers 30614 30614 0 0.00 0 24 1 0 0
v_all_wares 42120 42120 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 2617 12 0 0
v_cancel_client_order 3654 3654 0 0.00 0 2308 8 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 1235 6 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 2046 6 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 1662 10 0 0
v_cancel_supplier_invoice 842 818 24 2.85 0 1849 20 0 24
v_cancel_supplier_order 818 818 0 0.00 0 2233 12 0 0
v_cancel_write_off 2008 2008 0 0.00 0 1597 13 0 0
v_max_id_clo_ord 5542 5542 0 0.00 0 1561 2 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 1772 11 0 0
v_max_id_ord_sup 5466 5466 0 0.00 0 11103 8 0 0
v_max_non_paid_invoice 1604 1604 0 0.00 0 5904 8 0 0
v_max_non_paid_realizn 3884 3884 0 0.00 0 1752 5 0 0
v_min_id_clo_ord 5542 5542 0 0.00 0 6086 9 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 3296 6 0 0
v_min_id_ord_sup 5466 5466 0 0.00 0 6691 10 0 0
v_min_non_paid_invoice 5903 5903 0 0.00 0 8546 20 0 0
v_min_non_paid_realizn 7378 7378 0 0.00 0 8965 25 0 0
v_our_firm 10432 10432 0 0.00 0 16 1 0 0
v_reserve_write_off 20650 20650 0 0.00 0 4029 7 0 0
x_lock_depdocs_on_canc_invoice 11006 11006 0 0.00 0 19650 118 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 3143 328 0 0
x_make_qstorno_1000_1200 5542 5542 0 0.00 1 36868 1190 0 0
x_make_qstorno_1200_2000 5467 5467 0 0.00 2 71000 3084 0 0
x_make_qstorno_2100_3300 462616 462616 0 0.00 0 32545 185 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-07-2022 01:40:18 to 08-07-2022 01:40:22.

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:23 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:24 24166 8192000000 9893406064 10204196864 972270512 10.609 100 89 178 0 1001637232 5753728 23612992
23:10:53 17467 8192000000 9941033296 10261336064 1021351552 11.085 100 80 160 0 1049627744 7708080 20568112
23:12:19 17659 8192000000 9999047840 10335432704 1073894816 11.589 100 75 150 0 1101620528 9127920 18597792
23:13:41 8867 8192000000 10043413152 10392891392 1126730512 12.091 100 80 160 0 1156098560 10083600 19284448
23:14:54 4309 8192000000 10092022480 10448564224 1174650960 12.540 100 84 166 0 1206040000 12555648 18833392
23:16:00 3017 8192000000 10118787136 10489237504 1208345248 12.854 100 72 142 1 1237245312 13024544 15875520
23:17:04 2278 8192000000 10158517840 10532249600 1243606208 13.179 100 84 164 0 1274681648 13273488 17801952
23:18:08 5174 8192000000 10179906272 10559021056 1265071664 13.376 100 90 177 0 1298169712 14047184 19050864
23:19:16 1247 8192000000 10217605472 10602467328 1300447424 13.699 100 92 184 0 1335091264 13937392 20706448
23:20:18 8814 8192000000 10214528192 10607624192 1305729024 13.747 100 68 134 0 1330587376 10562880 14295472
23:21:27 9799 8192000000 10200444928 10607063040 1306344848 13.753 100 23 43 1 1316738048 5403296 4989904
23:22:38 3443 8192000000 10199861808 10615123968 1312411584 13.808 100 34 68 0 1327370256 7714320 7244352
23:23:43 10571 8192000000 10198813824 10618146816 1318638624 13.864 100 16 32 1 1327726656 5852144 3235888
23:24:55 11386 8192000000 10214537424 10635370496 1329487856 13.963 100 33 66 0 1343450496 6308432 7654208
23:26:06 15819 8192000000 10237341648 10647171072 1333027776 13.995 100 46 93 0 1350344624 6412320 10904528
23:27:22 6946 8192000000 10230554400 10648449024 1329320496 13.961 100 26 52 0 1345545840 10312288 5913056
23:28:30 13088 8192000000 10156865104 10563313664 1156027344 12.366 88 15 33 0 1164310208 4762656 3520208
23:29:43 59352 8192000000 10147271520 10553278464 1202667840 12.801 94 31 63 0 1214899872 5019936 7212096
23:31:47 8036 8192000000 10152491904 10555678720 1248573984 13.225 99 24 47 0 1259760352 5746032 5440336
23:32:56 9137 8192000000 10153883680 10553397248 1250384496 13.242 99 27 52 0 1261811056 5035136 6391424
23:34:12 53478 8192000000 10182944784 10590539776 1288216080 13.588 100 31 61 0 1301407072 5436960 7754032
23:36:10 8204 8192000000 10195942032 10610954240 1303872368 13.730 100 22 44 2 1313930688 4502688 5555632
23:37:19 6895 8192000000 10204670016 10617188352 1310061152 13.787 100 24 48 0 1321469472 5467296 5941024
23:38:27 14216 8192000000 10231278560 10639597568 1330444144 13.971 100 49 98 1 1348033536 5472336 12117056
23:39:43 61982 8192000000 10249519808 10666930176 1346880752 14.119 100 37 74 2 1361840016 6815056 8144208
23:41:50 18613 8192000000 10264914224 10678120448 1350692400 14.154 100 50 98 0 1372184896 10894512 10597984
23:43:12 22543 8192000000 10274046368 10700316672 1370314608 14.330 100 33 66 0 1391080576 13265120 7500848
23:44:41 4766 8192000000 10288973472 10712256512 1380930464 14.425 100 48 96 0 1404190944 12395472 10865008
23:46:10 7705 8192000000 10306383536 10728873984 1388645248 14.494 100 61 122 0 1419211776 16209680 14356848
23:47:22 19066 8192000000 10293602528 10728931328 1381623696 14.431 99 37 74 0 1406181792 15755056 8803040
23:48:46 5136 8192000000 10302301648 10731884544 1391508144 14.519 100 40 80 1 1414131104 13281072 9341888
23:49:55 8640 8192000000 10312505344 10736136192 1386950288 14.479 100 50 100 0 1420007088 21317440 11739360
23:51:09 3881 8192000000 10316946448 10738376704 1384964784 14.461 100 62 124 0 1428226144 28369184 14892176
23:52:19 1921 8192000000 10336012368 10749243392 1397901104 14.576 100 77 154 3 1444098960 27830848 18367008
23:53:25 2455 8192000000 10316775840 10731233280 1383314272 14.446 100 70 135 1 1429268880 29533584 16421024
23:54:33 5840 8192000000 10318222560 10725535744 1365503232 14.287 99 68 136 2 1417814128 34511456 17799440
23:55:49 6027 8192000000 10333600656 10744729600 1381262576 14.428 100 71 140 0 1436207872 36721008 18224288
23:56:59 8183 8192000000 10320720016 10727788544 1360304016 14.240 99 65 126 0 1417754672 40834160 16616496
23:58:16 3644 8192000000 10345153856 10749489152 1385379600 14.465 100 85 171 2 1447197088 40047904 21769584
23:59:25 8102 8192000000 10305616816 10723717120 1365176912 14.284 100 47 94 0 1416943920 38952720 12814288
00:00:36 6754 8192000000 10317341664 10740215808 1372934400 14.353 100 43 84 1 1425572432 41403872 11234160
00:01:51 5818 8192000000 10313937024 10726973440 1350441008 14.151 98 60 119 1 1411621040 46475760 14704272
00:02:59 15032 8192000000 10316856064 10743418880 1367764624 14.307 100 39 76 1 1422943088 44430912 10747552
00:04:15 23891 8192000000 10336522512 10765938688 1377190448 14.391 100 41 82 1 1446108512 56827408 12090656
00:05:41 25576 8192000000 10297202736 10750078976 1371851392 14.344 100 19 38 1 1415441520 37405856 6184272
00:07:22 23423 8192000000 10293262320 10735640576 1362025680 14.256 100 18 35 1 1399558192 32255872 5276640
00:08:46 22738 8192000000 10307063888 10739146752 1377733536 14.396 100 36 71 1 1410111904 22472544 9905824
00:10:11 17674 8192000000 10286015584 10727432192 1377999232 14.399 100 17 34 0 1390788800 9053232 3736336
00:11:32 67490 8192000000 10248281568 10704269312 1325182224 13.924 97 10 20 0 1336568800 9205520 2181056
00:13:42 28171 8192000000 10270410416 10719027200 1364816048 14.281 100 11 22 0 1380832064 13982112 2033904
00:15:42 87112 8192000000 10297870128 10757103616 1392146672 14.525 100 35 68 0 1416380400 16780208 7453520
00:18:22 51047 8192000000 10291312048 10745135104 1377967632 14.398 99 30 60 0 1394349600 9224496 7157472
00:20:14 98863 8192000000 10320008800 10775658496 1399639344 14.592 100 19 38 0 1421504816 17300272 4565200
00:22:57 76994 8192000000 10318601856 10777952256 1407180000 14.659 100 32 63 2 1424710528 9871552 7658976
00:25:46 26108 8192000000 10313514976 10772103168 1406249280 14.651 100 12 25 0 1426629360 17874528 2505552
00:27:15 79298 8192000000 10308334032 10775310336 1408705696 14.672 100 11 22 0 1421909376 10660816 2542864
00:29:49 41694 8192000000 10315797376 10779582464 1414593008 14.725 100 17 34 0 1433285776 14675328 4017440
00:31:57 83522 8192000000 10328433200 10803642368 1413394048 14.714 100 11 22 1 1441298784 25018384 2886352
00:34:23 29745 8192000000 10292929664 10744905728 1335613520 14.018 95 19 38 0 1347206080 7721424 3871136
00:35:56 65951 8192000000 10269467552 10734018560 1337139456 14.032 97 11 22 0 1353058176 12723264 3195456
00:38:47 36076 8192000000 10304939248 10759753728 1373712496 14.360 99 31 62 0 1395039392 14659728 6667168
00:40:27 60916 8192000000 10304640144 10767843328 1374297664 14.366 98 12 24 0 1392057632 14948288 2811680
00:42:28 35146 8192000000 10311831824 10762858496 1382616880 14.440 99 45 90 1 1407812016 14568656 10626480
00:44:06 65915 8192000000 10292176800 10743197696 1318274256 13.861 95 17 34 0 1332667408 10974832 3418320
00:46:16 128756 8192000000 10251778272 10706829312 1252791312 13.264 91 14 28 0 1271815088 16198544 2825232
00:50:06 40002 8192000000 10257836720 10688712704 1267847200 13.402 93 29 58 0 1283110352 9144336 6118816
00:51:48 7505 8192000000 10252452192 10684702720 1335734064 14.019 100 42 84 1 1358605440 13238192 9633184
00:52:56 63383 8192000000 10239103296 10691182592 1293288416 13.634 96 14 28 0 1307658256 11213264 3156576
00:55:00 36155 8192000000 10251672512 10700443648 1343187200 14.086 100 11 22 0 1357201792 11319584 2695008
00:56:42 68738 8192000000 10245282976 10693660672 1321931344 13.894 98 15 31 0 1332348656 6730832 3686480
00:59:08 70950 8192000000 10257885216 10715860992 1344034096 14.094 99 13 26 0 1357852480 10747600 3070784
01:02:50 71556 8192000000 10277749136 10730102784 1338152912 14.041 98 20 38 0 1362956768 19603520 5200336
01:05:03 42018 8192000000 10276190704 10729828352 1355371888 14.196 99 17 34 1 1368384272 9361696 3650688
01:06:48 41241 8192000000 10305255280 10740318208 1339463984 14.053 97 42 85 1 1360399824 11859664 9076176
01:08:30 52335 8192000000 10314199440 10760802304 1384452128 14.456 100 34 68 0 1408755376 16753296 7549952
01:10:24 137310 8192000000 10326683664 10788646912 1398246656 14.579 100 16 31 0 1423973536 22763472 2963408
01:13:42 115865 8192000000 10304975104 10758725632 1366134016 14.292 97 29 58 0 1386844736 14390960 6319760
01:17:11 56476 8192000000 10299429168 10762317824 1378559552 14.404 98 14 28 0 1391568656 9981744 3027360
01:19:09 48914 8192000000 10294398160 10766618624 1387606880 14.485 99 14 28 1 1401519664 10857648 3055136
01:21:05 64053 8192000000 10286285632 10748489728 1358061136 14.220 97 24 48 0 1371077520 7258448 5757936
01:23:16 52293 8192000000 10275009312 10732920832 1342270640 14.078 96 14 28 1 1354163840 8597296 3295904
01:25:19 88796 8192000000 10291152048 10740867072 1331618912 13.982 96 16 32 0 1347677184 12492256 3566016
01:27:57 83563 8192000000 10270988000 10716663808 1284696432 13.556 93 29 58 0 1298708656 7797264 6214960
01:30:23 79500 8192000000 10279706304 10730614784 1340379328 14.061 98 24 48 0 1360823056 15468528 4975200
01:33:02 77586 8192000000 10285431552 10719633408 1319365040 13.871 96 28 55 0 1335628448 10237776 6025632
01:35:26 120569 8192000000 10261834352 10706305024 1297715568 13.674 95 21 42 0 1310094384 8068032 4310784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 08-07-2022 01:40:24 to 08-07-2022 01:40:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-07-2022 01:40:39 to 08-07-2022 01:40:41.

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          241885
MON$OLDEST_ACTIVE               244565
MON$OLDEST_SNAPSHOT             244565
MON$NEXT_TRANSACTION            244565
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                       2011840
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:107: 
:108: mscs-test-161.budzdorov.ru    Fri Jul  8 01:38:22 2022
:109:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:110: 
:111: 
:112: mscs-test-161.budzdorov.ru    Fri Jul  8 01:38:26 2022
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:114: 
:115: 
:116: mscs-test-161.budzdorov.ru    Fri Jul  8 01:38:29 2022
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:118: 
--- end of diff output ---

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

08.07.22 01:40:42. 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-07-08 01:40:45 No severe PSQL-related problems occured
08.07.22 01:40:46. End of report.