Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.12.2022 16:40
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 15087752 kB
MemAvailable 30135748 kB
Buffers 0 kB
Cached 16646204 kB
SwapCached 3328 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-12-09 16:38:31
DTS_END                         2022-12-09 16:38:31.0060
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59472
TRN_ID                          238652
ATT_ID                          649
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: 9071.60 17710 1088592
customer order: creation 174.15 376 20898
customer order: refuse 15.38 365 1846
order to supplier: creation 44.59 1324 5351
order to supplier: removal 3.52 94948 422
invoice (draft): creation 46.97 3278 5636
invoice (draft): removal 51.83 712 6220
invoice accept: apply 41.52 18783 4982
invoice accept: cancel 46.07 690 5528
customer reserve: creation 3864.77 185 463772
customer reserve: removal 4488.02 59 538563
realization accept: apply 82.58 188 9909
realization accept: cancel 82.05 113 9846
payment from customer: creation 58.41 118 7009
payment from customer: removal 5.59 76 671
payment to supplier: creation 49.95 150 5994
payment to supplier: removal 3.51 102 421
service: total inventory turnovers 4.71 1177 565
service: total monetary turnovers 7.08 25 849
service: aggregate perf. data 0.88 21154 106
service: refresh index statistics 0.03 210369 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-12-2022 16:40:38 to 09-12-2022 16:40:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3063
TEST_TIME 2 3618
TEST_TIME 3 4605
TEST_TIME 4 3746
TEST_TIME 5 3614
TEST_TIME 6 3609
TEST_TIME 7 3644
TEST_TIME 8 3964
TEST_TIME 9 4399
TEST_TIME 10 4759
TEST_TIME 11 5376
TEST_TIME 12 6326
TEST_TIME 13 6156
TEST_TIME 14 6197
TEST_TIME 15 6624
TEST_TIME 16 6689
TEST_TIME 17 6903
TEST_TIME 18 7271
TEST_TIME 19 7525
TEST_TIME 20 8160
TEST_TIME 21 8254
TEST_TIME 22 8453
TEST_TIME 23 8638
TEST_TIME 24 8663
TEST_TIME 25 9029
TEST_TIME 26 9092
TEST_TIME 27 9478
TEST_TIME 28 9685
TEST_TIME 29 9729
TEST_TIME 30 10557
TEST_TIME 31 10657
TEST_TIME 32 10588
TEST_TIME 33 10409
TEST_TIME 34 10288
TEST_TIME 35 10162
TEST_TIME 36 10038
TEST_TIME 37 10070
TEST_TIME 38 10015
TEST_TIME 39 9860
TEST_TIME 40 9720
TEST_TIME 41 9588
TEST_TIME 42 9513
TEST_TIME 43 9696
TEST_TIME 44 9694
TEST_TIME 45 9560
TEST_TIME 46 9365
TEST_TIME 47 9422
TEST_TIME 48 9271
TEST_TIME 49 9216
TEST_TIME 50 9120
TEST_TIME 51 9206
TEST_TIME 52 9232
TEST_TIME 53 9233
TEST_TIME 54 9259
TEST_TIME 55 9126
TEST_TIME 56 9094
TEST_TIME 57 9120
TEST_TIME 58 9071
TEST_TIME 59 9099
TEST_TIME 60 9153
TEST_TIME 61 9177
TEST_TIME 62 9166
TEST_TIME 63 9252
TEST_TIME 64 9304
TEST_TIME 65 9392
TEST_TIME 66 9389
TEST_TIME 67 9399
TEST_TIME 68 9343
TEST_TIME 69 9348
TEST_TIME 70 9363
TEST_TIME 71 9376
TEST_TIME 72 9281
TEST_TIME 73 9232
TEST_TIME 74 9185
TEST_TIME 75 9134
TEST_TIME 76 9155
TEST_TIME 77 9121
TEST_TIME 78 9151
TEST_TIME 79 9120
TEST_TIME 80 9062
TEST_TIME 81 9017
TEST_TIME 82 9006
TEST_TIME 83 8977
TEST_TIME 84 9005
TEST_TIME 85 9099
TEST_TIME 86 9141
TEST_TIME 87 9190
TEST_TIME 88 9207
TEST_TIME 89 9194
TEST_TIME 90 9199
TEST_TIME 91 9178
TEST_TIME 92 9182
TEST_TIME 93 9233
TEST_TIME 94 9211
TEST_TIME 95 9263
TEST_TIME 96 9231
TEST_TIME 97 9179
TEST_TIME 98 9162
TEST_TIME 99 9138
TEST_TIME 100 9146
TEST_TIME 101 9140
TEST_TIME 102 9097
TEST_TIME 103 9064
TEST_TIME 104 9053
TEST_TIME 105 9030
TEST_TIME 106 9081
TEST_TIME 107 9083
TEST_TIME 108 9071
TEST_TIME 109 9054
TEST_TIME 110 9105
TEST_TIME 111 9142
TEST_TIME 112 9115
TEST_TIME 113 9130
TEST_TIME 114 9121
TEST_TIME 115 9168
TEST_TIME 116 9173
TEST_TIME 117 9164
TEST_TIME 118 9169
TEST_TIME 119 9113
TEST_TIME 120 9074

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-12-2022 16:40:42 to 09-12-2022 16:40:45.

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 1517936 1517936 0 0.00 0 22043 6 0 0
doc_list_biud 1517936 1517936 0 0.00 0 29069 26 0 0
sp_add_invoice_to_stock 5095 4982 113 2.21 21 177199 18783 0 113
sp_cancel_adding_invoice 5528 5528 0 0.00 5 57889 691 0 0
sp_cancel_client_order 1846 1846 0 0.00 4 21830 365 0 0
sp_cancel_customer_reserve 538563 538563 0 0.00 0 28934 59 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 2 3404 77 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 7475 103 0 0
sp_cancel_supplier_invoice 6228 6220 8 0.12 2 23925 712 0 8
sp_cancel_supplier_order 422 422 0 0.00 7 886080 94949 0 0
sp_cancel_write_off 9846 9846 0 0.00 1 16303 114 0 0
sp_client_order 20898 20898 0 0.00 3 23912 376 0 0
sp_customer_reserve 463772 463772 0 0.00 0 48740 186 0 0
sp_fill_shopping_cart 31874 31874 0 0.00 1 14214 42 0 0
sp_fill_shopping_cart_clo_res 463762 463762 0 0.00 0 29667 14 0 0
sp_get_clo_for_invoice 4972 4972 0 0.00 0 18401 294 0 0
sp_kill_cost_storno 16466 16466 0 0.00 0 22039 59 0 0
sp_kill_qstorno_ret_qs2qd 1093614 1093614 0 0.00 0 28931 32 0 0
sp_kill_qty_storno 577306 577306 0 0.00 0 29068 68 0 0
sp_lock_selected_doc 20690 20690 0 0.00 0 7454 5 0 0
sp_make_cost_storno 27884 27884 0 0.00 0 11997 51 0 0
sp_multiply_rows_for_pdistr 27884 27884 0 0.00 0 10148 30 0 0
sp_multiply_rows_for_qdistr 20898 20898 0 0.00 0 20122 159 0 0
sp_pay_from_customer 7009 7009 0 0.00 4 23952 118 0 0
sp_pay_to_supplier 5994 5994 0 0.00 4 33147 150 0 0
sp_payment_common 13003 13003 0 0.00 1 33080 32 0 0
sp_qd_handle_on_cancel_clo 1846 1846 0 0.00 0 12354 321 0 0
sp_qd_handle_on_invoice_upd_sts 10500 10500 0 0.00 0 29066 300 0 0
sp_qd_handle_on_reserve_upd_sts 9909 9909 0 0.00 0 16209 64 0 0
sp_reserve_write_off 9909 9909 0 0.00 6 24289 188 0 0
sp_supplier_invoice 5636 5636 0 0.00 6 75617 3279 0 0
sp_supplier_order 5351 5351 0 0.00 5 40267 1325 0 0
srv_aggregate_perf_data 107 106 1 0.93 2724 434165 21154 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1528 105806 42964 0 0
srv_find_qd_qs_mism 1040583 1040583 0 0.00 0 21546 6 0 0
srv_make_invnt_saldo 565 565 0 0.00 37 65850 1177 0 0
srv_make_money_saldo 849 849 0 0.00 1 947 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 141516 368814 210370 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4982 4982 0 0.00 0 11337 180 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 0 10168 127 0 0
t$perf-norm:sp_cancel_client_order 1846 1846 0 0.00 0 4896 18 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 463 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 13373 52 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 3201 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 7519 40 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 25907 1515 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 3174 12 0 0
t$perf-norm:sp_client_order 20898 20898 0 0.00 0 17294 21 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 2225 7 0 0
t$perf-norm:sp_pay_from_customer 7009 7009 0 0.00 0 11472 9 0 0
t$perf-norm:sp_pay_to_supplier 5994 5994 0 0.00 0 13421 11 0 0
t$perf-norm:sp_reserve_write_off 9909 9909 0 0.00 0 13828 16 0 0
t$perf-norm:sp_supplier_invoice 5636 5636 0 0.00 0 12554 17 0 0
t$perf-norm:sp_supplier_order 5351 5351 0 0.00 0 11382 17 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 345 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 862 12 0 0
t$perf-norm:srv_make_money_saldo 849 849 0 0.00 0 1240 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10170 9944 226 2.22 0 21519 22 0 226
v_all_customers 38442 38442 0 0.00 0 53 1 0 0
v_all_suppliers 30684 30684 0 0.00 0 19 1 0 0
v_all_wares 41796 41796 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 3009 11 0 0
v_cancel_client_order 3692 3692 0 0.00 0 21767 13 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 3396 10 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 4456 8 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 3086 10 0 0
v_cancel_supplier_invoice 834 818 16 1.91 0 10934 51 0 16
v_cancel_supplier_order 844 844 0 0.00 0 3309 8 0 0
v_cancel_write_off 1946 1946 0 0.00 0 4478 17 0 0
v_max_id_clo_ord 5346 5346 0 0.00 0 1051 2 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 6695 23 0 0
v_max_id_ord_sup 5630 5630 0 0.00 0 10919 5 0 0
v_max_non_paid_invoice 1628 1628 0 0.00 0 419 3 0 0
v_max_non_paid_realizn 3500 3500 0 0.00 0 1722 4 0 0
v_min_id_clo_ord 5346 5346 0 0.00 0 9172 11 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 4031 8 0 0
v_min_id_ord_sup 5630 5630 0 0.00 0 8771 11 0 0
v_min_non_paid_invoice 5994 5994 0 0.00 0 33076 32 0 0
v_min_non_paid_realizn 7009 7009 0 0.00 0 9701 20 0 0
v_our_firm 10372 10372 0 0.00 0 46 1 0 0
v_reserve_write_off 19818 19818 0 0.00 0 24189 9 0 0
x_lock_depdocs_on_canc_invoice 11339 11339 0 0.00 0 29282 127 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 12024 364 0 0
x_make_qstorno_1000_1200 5351 5351 0 0.00 1 40252 1235 0 0
x_make_qstorno_1200_2000 5636 5636 0 0.00 3 66590 3129 0 0
x_make_qstorno_2100_3300 463772 463772 0 0.00 0 43336 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-12-2022 16:40:46 to 09-12-2022 16:40:50.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:36 24237 8192000000 9895351696 10202415104 973300320 10.619 100 86 172 0 1002296672 6173376 22822976
14:11:06 20461 8192000000 9943255456 10265055232 1025383632 11.124 100 87 174 0 1056880048 8526944 22969472
14:12:34 16845 8192000000 9977890384 10308313088 1061637104 11.472 100 81 162 0 1091871648 10151888 20082656
14:13:54 10991 8192000000 10012634688 10357043200 1099045824 11.829 100 79 158 0 1128822624 10760000 19016800
14:15:10 10220 8192000000 10075104704 10430742528 1159235952 12.396 100 75 150 1 1187768608 11468160 17064496
14:16:24 2274 8192000000 10125929360 10493247488 1212667296 12.894 100 75 150 0 1242463760 12274464 17522000
14:17:29 2286 8192000000 10166657808 10539237376 1253566672 13.271 100 91 176 0 1285047808 11805056 19676080
14:18:34 3791 8192000000 10190049872 10575167488 1286022096 13.568 100 74 142 0 1313478640 12329840 15126704
14:19:39 989 8192000000 10217838240 10601689088 1302172224 13.715 100 92 179 2 1334948208 13257536 19518448
14:20:41 9259 8192000000 10214158928 10611134464 1309928000 13.785 100 66 130 0 1333949408 9788064 14233344
14:21:51 6146 8192000000 10210694656 10623021056 1318671088 13.865 100 30 60 0 1337485232 12058160 6755984
14:22:59 13093 8192000000 10221043040 10637926400 1328231472 13.951 100 18 36 0 1342565600 10547264 3786864
14:24:16 11442 8192000000 10221155216 10641711104 1335795664 14.019 100 14 29 0 1343919600 5379552 2744384
14:25:30 54597 8192000000 10250943040 10659184640 1340226992 14.059 100 51 104 0 1358895712 5599200 13069520
14:27:25 5298 8192000000 10239390992 10661302272 1344753744 14.100 100 24 48 1 1355963232 5488704 5720784
14:28:30 11905 8192000000 10213527120 10633027584 1293294768 13.634 97 21 42 0 1304372736 6201104 4876864
14:29:44 15869 8192000000 10160026320 10557530112 1200900192 12.785 93 17 34 0 1209253856 5029760 3323904
14:31:00 47957 8192000000 10120524224 10518917120 1087708496 11.721 85 25 49 0 1101986688 8918304 5359888
14:32:48 8355 8192000000 10166035824 10569613312 1266581520 13.390 100 26 52 0 1278000544 5302320 6116704
14:33:57 6884 8192000000 10165361104 10573201408 1254483792 13.279 98 15 30 1 1263081856 5150304 3447760
14:35:04 62026 8192000000 10183597312 10589868032 1286042416 13.568 100 34 68 0 1300081056 5778592 8260048
14:37:11 4043 8192000000 10198239312 10609651712 1303310080 13.725 100 30 60 0 1316587744 6481760 6795904
14:38:17 6097 8192000000 10212659872 10629406720 1321869024 13.894 100 25 49 1 1332192384 4895856 5427504
14:39:34 5763 8192000000 10243349968 10653134848 1335687776 14.019 100 50 98 0 1354568448 7817728 11062944
14:40:54 13223 8192000000 10257532272 10675580928 1352224144 14.167 100 29 57 0 1367247792 8177136 6846512
14:42:10 74825 8192000000 10276846688 10701398016 1375799696 14.379 100 39 76 0 1393861008 8887584 9173728
14:44:40 21115 8192000000 10293819776 10716393472 1383766224 14.450 100 45 86 0 1405755280 11566000 10423056
14:46:06 19057 8192000000 10309726224 10741616640 1402213328 14.615 100 45 91 0 1429388416 16105216 11069872
14:47:29 10256 8192000000 10331342928 10757062656 1400883088 14.603 99 47 94 0 1433097520 20651792 11562640
14:48:43 5420 8192000000 10333188192 10759761920 1407142640 14.659 100 51 102 0 1446196336 26159184 12894512
14:49:58 5120 8192000000 10353011040 10779377664 1419097104 14.765 100 57 114 3 1460328304 27066864 14164336
14:51:06 8334 8192000000 10332593424 10760949760 1403735648 14.628 100 41 80 1 1437573232 24416224 9421360
14:52:18 3641 8192000000 10357565952 10769457152 1408411760 14.670 100 81 155 3 1457670736 30334480 18924496
14:53:28 1527 8192000000 10360995600 10773762048 1407673168 14.663 100 88 175 1 1465330240 35582672 22074400
14:54:33 4684 8192000000 10342290672 10758955008 1396970912 14.568 100 69 137 2 1448376560 34451472 16954176
14:55:45 3475 8192000000 10358336096 10770575360 1400165840 14.596 100 80 160 2 1459081088 38487392 20427856
14:56:53 2099 8192000000 10344162528 10751008768 1382950688 14.443 100 86 170 4 1447602128 43152928 21498512
14:57:59 2373 8192000000 10321037984 10727350272 1358240656 14.222 99 78 152 0 1419500800 42160672 19099472
14:59:07 17379 8192000000 10326273808 10752262144 1374271648 14.365 99 39 79 0 1417774128 32739008 10763472
15:00:33 5278 8192000000 10344101520 10765717504 1388820848 14.495 100 60 117 0 1443816032 39637856 15357328
15:01:41 22449 8192000000 10336792448 10771873792 1397550192 14.573 100 33 66 0 1449913968 43073360 9290416
15:03:11 14937 8192000000 10316586096 10755624960 1354211488 14.185 98 36 71 1 1400913376 37259120 9442768
15:04:37 12161 8192000000 10298433760 10721742848 1338276384 14.042 98 36 70 2 1382429952 35430064 8723504
15:05:56 27437 8192000000 10303996512 10742243328 1361882080 14.254 100 19 38 0 1405280432 37987552 5410800
15:07:25 42893 8192000000 10292217408 10751148032 1338095168 14.040 98 20 40 0 1388537840 44928416 5514256
15:09:11 100582 8192000000 10272420432 10711293952 1335216640 14.014 97 22 42 0 1355189824 15092464 4880720
15:12:28 29771 8192000000 10262066528 10709753856 1356504800 14.206 100 25 51 0 1370247456 8370304 5372352
15:14:02 35933 8192000000 10285355872 10736492544 1377686256 14.396 100 27 54 0 1394924976 11187168 6051552
15:15:41 54041 8192000000 10293363968 10748895232 1384785392 14.459 100 30 58 0 1406717472 14894512 7037568
15:17:44 50226 8192000000 10299895648 10738798592 1359441312 14.232 98 33 65 1 1378639024 12015488 7182224
15:19:37 81452 8192000000 10306976256 10763374592 1382246576 14.437 99 21 42 0 1408668976 20893136 5529264
15:22:11 51863 8192000000 10289698032 10756837376 1383424352 14.447 100 7 14 1 1403908320 19083248 1400720
15:24:03 86921 8192000000 10289367296 10751774720 1394627376 14.547 100 26 52 1 1408517056 7618768 6270912
15:26:33 30166 8192000000 10313589664 10764582912 1405377296 14.643 100 35 71 0 1426882352 13444976 8060080
15:28:05 38478 8192000000 10308193120 10765475840 1393064464 14.533 99 19 38 0 1414517856 16729104 4724288
15:30:04 67709 8192000000 10297372832 10773602304 1386309680 14.473 99 12 24 1 1413570560 24287712 2973168
15:32:12 56766 8192000000 10284562736 10754469888 1381559200 14.430 99 13 26 0 1398133760 13317872 3256688
15:34:29 45999 8192000000 10282789616 10751614976 1378484736 14.403 99 25 50 0 1393089712 8506848 6098128
15:36:21 35845 8192000000 10302780400 10751324160 1369888144 14.326 98 43 85 1 1388025568 8406224 9731200
15:38:53 105806 8192000000 10300709488 10755317760 1327636224 13.946 95 16 32 1 1339187824 8392384 3159216
15:41:52 55205 8192000000 10291029584 10745278464 1353315312 14.177 97 7 14 0 1368299216 13178992 1804912
15:43:48 64698 8192000000 10288826160 10742779904 1346064656 14.112 97 12 25 0 1360786800 11435568 3286576
15:45:56 33786 8192000000 10292193824 10725404672 1340273104 14.060 97 29 57 0 1354637296 7958976 6405216
15:47:30 32200 8192000000 10280297136 10717822976 1324447696 13.917 97 47 94 0 1352611024 18317456 9845872
15:49:04 28393 8192000000 10272328240 10708742144 1337497488 14.035 99 30 60 0 1354745488 10713824 6534176
15:50:36 89860 8192000000 10249522816 10694225920 1330621456 13.973 98 26 52 0 1349292736 12912896 5758384
15:53:06 13220 8192000000 10257255824 10691010560 1337525856 14.035 98 39 78 0 1354764272 8421856 8816560
15:54:20 74662 8192000000 10236084992 10688299008 1313176976 13.815 96 14 29 0 1327996512 11599968 3219568
15:56:36 64127 8192000000 10267295680 10707283968 1348274512 14.132 99 13 26 0 1361084560 9719664 3090384
15:58:40 42248 8192000000 10254886640 10712649728 1349192224 14.140 99 9 18 0 1358090240 6857952 2040064
16:00:23 49046 8192000000 10256532880 10707066880 1319390784 13.871 97 16 32 0 1337455488 14236176 3828528
16:02:12 77887 8192000000 10282373152 10728427520 1355086320 14.193 99 9 18 0 1373964320 16815008 2062992
16:04:30 71412 8192000000 10282446768 10741755904 1350059040 14.148 98 21 40 0 1365392320 10134720 5198560
16:06:42 71985 8192000000 10290739312 10733936640 1347708848 14.127 98 18 36 0 1360016640 8329088 3978704
16:08:59 37929 8192000000 10295481904 10740416512 1368851408 14.317 99 30 61 0 1383863744 8134192 6878144
16:10:41 50605 8192000000 10298574000 10746179584 1359211536 14.230 98 34 69 0 1381407120 14999840 7195744
16:12:36 68552 8192000000 10309367280 10747097088 1361094688 14.247 98 22 44 0 1382669472 16588400 4986384
16:14:47 77695 8192000000 10289827296 10748416000 1389694144 14.503 100 43 86 0 1408890720 8795568 10401008
16:17:08 53383 8192000000 10289987776 10753761280 1392418928 14.527 100 13 26 0 1407352464 11463680 3469856
16:19:44 79959 8192000000 10301033744 10761072640 1396269472 14.562 100 16 30 0 1407184000 7313264 3601264
16:22:44 86146 8192000000 10301411840 10787266560 1404958160 14.639 100 7 14 0 1421847136 14964336 1924640
16:25:13 65727 8192000000 10299276624 10763112448 1390521600 14.511 99 14 29 0 1405098176 11426880 3149696
16:27:20 31977 8192000000 10297013632 10751971328 1379865312 14.415 99 27 54 0 1396297312 9817360 6614640
16:29:01 50910 8192000000 10277796272 10743099392 1351690992 14.163 97 12 24 0 1363947232 9275712 2980528
16:31:07 61960 8192000000 10304071120 10756792320 1374234928 14.365 99 24 48 0 1392036480 12452368 5349184
16:33:09 18900 8192000000 10302233232 10746056704 1381871312 14.433 100 48 94 1 1404072672 12029664 10171696
16:34:30 41463 8192000000 10281304912 10728386560 1345431328 14.106 98 31 61 0 1362468272 10210512 6826432
16:36:12 103586 8192000000 10259887600 10721095680 1329341952 13.961 97 13 26 0 1344600928 12536176 2722800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-12-2022 16:40:52 to 09-12-2022 16:41:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 113 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-12-2022 16:41:09 to 09-12-2022 16:41:11.

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          238957
MON$OLDEST_ACTIVE               238958
MON$OLDEST_SNAPSHOT             238958
MON$NEXT_TRANSACTION            238958
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                       2028832
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.33646 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33646 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33646 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33646 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 ---
--- end of diff output ---

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

09.12.22 16:41:12. 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-12-09 16:41:14 No severe PSQL-related problems occured
09.12.22 16:41:16. End of report.