Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.02.2023 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 15485180 kB
MemAvailable 30442216 kB
Buffers 0 kB
Cached 16250952 kB
SwapCached 3628 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 2023-02-02 01:38:35
DTS_END                         2023-02-02 01:38:35.1340
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43272
TRN_ID                          234259
ATT_ID                          656
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: 9226.19 23930 1107143
customer order: creation 159.07 382 19088
customer order: refuse 13.20 376 1584
order to supplier: creation 43.91 1094 5269
order to supplier: removal 3.38 116978 406
invoice (draft): creation 42.86 3323 5143
invoice (draft): removal 53.92 795 6470
invoice accept: apply 38.92 17797 4671
invoice accept: cancel 49.05 852 5886
customer reserve: creation 3711.53 170 445384
customer reserve: removal 4828.73 65 579448
realization accept: apply 78.24 190 9389
realization accept: cancel 80.12 106 9614
payment from customer: creation 57.00 128 6840
payment from customer: removal 5.31 88 637
payment to supplier: creation 46.44 141 5573
payment to supplier: removal 3.15 84 378
service: total inventory turnovers 4.67 1082 560
service: total monetary turnovers 5.88 25 706
service: aggregate perf. data 0.78 19399 93
service: refresh index statistics 0.03 315515 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-02-2023 01:40:06 to 02-02-2023 01:40:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1828
TEST_TIME 2 3069
TEST_TIME 3 3141
TEST_TIME 4 3780
TEST_TIME 5 4098
TEST_TIME 6 3971
TEST_TIME 7 4169
TEST_TIME 8 4321
TEST_TIME 9 4421
TEST_TIME 10 4428
TEST_TIME 11 4541
TEST_TIME 12 4554
TEST_TIME 13 4848
TEST_TIME 14 4836
TEST_TIME 15 5134
TEST_TIME 16 5469
TEST_TIME 17 5636
TEST_TIME 18 5810
TEST_TIME 19 6172
TEST_TIME 20 7220
TEST_TIME 21 7620
TEST_TIME 22 7542
TEST_TIME 23 7835
TEST_TIME 24 8210
TEST_TIME 25 8567
TEST_TIME 26 9198
TEST_TIME 27 9508
TEST_TIME 28 10753
TEST_TIME 29 10739
TEST_TIME 30 10413
TEST_TIME 31 10269
TEST_TIME 32 10144
TEST_TIME 33 10185
TEST_TIME 34 10144
TEST_TIME 35 10008
TEST_TIME 36 9897
TEST_TIME 37 9804
TEST_TIME 38 9641
TEST_TIME 39 9605
TEST_TIME 40 9523
TEST_TIME 41 9580
TEST_TIME 42 9620
TEST_TIME 43 9657
TEST_TIME 44 9603
TEST_TIME 45 9477
TEST_TIME 46 9460
TEST_TIME 47 9334
TEST_TIME 48 9324
TEST_TIME 49 9195
TEST_TIME 50 9050
TEST_TIME 51 9080
TEST_TIME 52 9160
TEST_TIME 53 9155
TEST_TIME 54 9117
TEST_TIME 55 9240
TEST_TIME 56 9171
TEST_TIME 57 9264
TEST_TIME 58 9294
TEST_TIME 59 9300
TEST_TIME 60 9275
TEST_TIME 61 9401
TEST_TIME 62 9441
TEST_TIME 63 9443
TEST_TIME 64 9471
TEST_TIME 65 9511
TEST_TIME 66 9464
TEST_TIME 67 9427
TEST_TIME 68 9456
TEST_TIME 69 9379
TEST_TIME 70 9350
TEST_TIME 71 9287
TEST_TIME 72 9270
TEST_TIME 73 9237
TEST_TIME 74 9188
TEST_TIME 75 9208
TEST_TIME 76 9294
TEST_TIME 77 9255
TEST_TIME 78 9232
TEST_TIME 79 9287
TEST_TIME 80 9297
TEST_TIME 81 9292
TEST_TIME 82 9333
TEST_TIME 83 9301
TEST_TIME 84 9416
TEST_TIME 85 9401
TEST_TIME 86 9467
TEST_TIME 87 9486
TEST_TIME 88 9480
TEST_TIME 89 9382
TEST_TIME 90 9320
TEST_TIME 91 9344
TEST_TIME 92 9296
TEST_TIME 93 9243
TEST_TIME 94 9291
TEST_TIME 95 9336
TEST_TIME 96 9304
TEST_TIME 97 9267
TEST_TIME 98 9286
TEST_TIME 99 9429
TEST_TIME 100 9410
TEST_TIME 101 9422
TEST_TIME 102 9417
TEST_TIME 103 9407
TEST_TIME 104 9409
TEST_TIME 105 9487
TEST_TIME 106 9520
TEST_TIME 107 9525
TEST_TIME 108 9489
TEST_TIME 109 9420
TEST_TIME 110 9404
TEST_TIME 111 9423
TEST_TIME 112 9404
TEST_TIME 113 9407
TEST_TIME 114 9361
TEST_TIME 115 9335
TEST_TIME 116 9338
TEST_TIME 117 9304
TEST_TIME 118 9297
TEST_TIME 119 9280
TEST_TIME 120 9227

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-02-2023 01:40:10 to 02-02-2023 01:40:13.

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 1520217 1520217 0 0.00 0 28551 6 0 0
doc_list_biud 1520217 1520217 0 0.00 0 46663 31 0 0
sp_add_invoice_to_stock 4727 4671 56 1.18 17 183965 17798 0 56
sp_cancel_adding_invoice 5886 5886 0 0.00 2 62887 853 0 0
sp_cancel_client_order 1584 1584 0 0.00 4 10997 377 0 0
sp_cancel_customer_reserve 579448 579448 0 0.00 0 46666 65 0 0
sp_cancel_pay_from_customer 637 637 0 0.00 3 14160 89 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 3 6252 84 0 0
sp_cancel_supplier_invoice 6473 6470 3 0.04 1 22257 795 0 3
sp_cancel_supplier_order 406 406 0 0.00 5 1202130 116978 0 0
sp_cancel_write_off 9614 9614 0 0.00 1 8928 106 0 0
sp_client_order 19088 19088 0 0.00 3 46101 383 0 0
sp_customer_reserve 445384 445384 0 0.00 0 85559 170 0 0
sp_fill_shopping_cart 29499 29499 0 0.00 1 20170 43 0 0
sp_fill_shopping_cart_clo_res 445382 445382 0 0.00 0 16719 16 0 0
sp_get_clo_for_invoice 4669 4669 0 0.00 0 22855 368 0 0
sp_kill_cost_storno 16515 16515 0 0.00 0 28547 58 0 0
sp_kill_qstorno_ret_qs2qd 1175386 1175386 0 0.00 0 46655 35 0 0
sp_kill_qty_storno 617466 617466 0 0.00 0 46662 75 0 0
sp_lock_selected_doc 19314 19314 0 0.00 0 8105 5 0 0
sp_make_cost_storno 26471 26471 0 0.00 0 14148 53 0 0
sp_multiply_rows_for_pdistr 26471 26471 0 0.00 0 13242 35 0 0
sp_multiply_rows_for_qdistr 19088 19088 0 0.00 0 46017 179 0 0
sp_pay_from_customer 6840 6840 0 0.00 4 14013 129 0 0
sp_pay_to_supplier 5573 5573 0 0.00 4 16918 141 0 0
sp_payment_common 12413 12413 0 0.00 1 16569 47 0 0
sp_qd_handle_on_cancel_clo 1584 1584 0 0.00 0 10989 343 0 0
sp_qd_handle_on_invoice_upd_sts 10555 10555 0 0.00 0 26910 330 0 0
sp_qd_handle_on_reserve_upd_sts 9389 9389 0 0.00 0 14708 63 0 0
sp_reserve_write_off 9389 9389 0 0.00 6 16945 191 0 0
sp_supplier_invoice 5143 5143 0 0.00 6 93348 3324 0 0
sp_supplier_order 5269 5269 0 0.00 5 34316 1094 0 0
srv_aggregate_perf_data 94 93 1 1.06 2673 266165 19399 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1122 153267 48155 0 0
srv_find_qd_qs_mism 1062129 1062129 0 0.00 0 15411 5 0 0
srv_make_invnt_saldo 560 560 0 0.00 25 67397 1082 0 0
srv_make_money_saldo 706 706 0 0.00 1 1387 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 220982 515735 315515 0 0
t$perf-abend:sp_add_invoice_to_stock 56 56 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 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 4671 4671 0 0.00 0 13968 172 0 0
t$perf-norm:sp_cancel_adding_invoice 369 369 0 0.00 0 2640 94 0 0
t$perf-norm:sp_cancel_client_order 1584 1584 0 0.00 0 1544 11 0 0
t$perf-norm:sp_cancel_customer_reserve 586 586 0 0.00 0 2694 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 637 637 0 0.00 0 602 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 253 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 568 9 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 29540 1738 0 0
t$perf-norm:sp_cancel_write_off 913 913 0 0.00 0 2385 11 0 0
t$perf-norm:sp_client_order 19088 19088 0 0.00 0 11981 17 0 0
t$perf-norm:sp_customer_reserve 941 941 0 0.00 0 1512 5 0 0
t$perf-norm:sp_pay_from_customer 6840 6840 0 0.00 0 12280 11 0 0
t$perf-norm:sp_pay_to_supplier 5573 5573 0 0.00 0 8208 10 0 0
t$perf-norm:sp_reserve_write_off 9389 9389 0 0.00 0 7932 10 0 0
t$perf-norm:sp_supplier_invoice 5143 5143 0 0.00 0 5450 18 0 0
t$perf-norm:sp_supplier_order 5269 5269 0 0.00 0 3209 12 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 396 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 3609 60 0 0
t$perf-norm:srv_make_invnt_saldo 560 560 0 0.00 0 959 17 0 0
t$perf-norm:srv_make_money_saldo 706 706 0 0.00 0 5427 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 9450 9338 112 1.18 0 7215 21 0 112
v_all_customers 34900 34900 0 0.00 0 51 1 0 0
v_all_suppliers 29254 29254 0 0.00 0 25 1 0 0
v_all_wares 38176 38176 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 738 738 0 0.00 0 1905 9 0 0
v_cancel_client_order 3168 3168 0 0.00 0 3499 9 0 0
v_cancel_customer_prepayment 1274 1274 0 0.00 0 4773 14 0 0
v_cancel_customer_reserve 1172 1172 0 0.00 0 1667 5 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 2855 12 0 0
v_cancel_supplier_invoice 772 766 6 0.77 0 1065 14 0 6
v_cancel_supplier_order 812 812 0 0.00 0 2848 7 0 0
v_cancel_write_off 1826 1826 0 0.00 0 1720 13 0 0
v_max_id_clo_ord 5268 5268 0 0.00 0 984 1 0 0
v_max_id_clo_res 941 941 0 0.00 0 6304 29 0 0
v_max_id_ord_sup 5143 5143 0 0.00 0 3024 4 0 0
v_max_non_paid_invoice 1357 1357 0 0.00 0 5993 7 0 0
v_max_non_paid_realizn 3614 3614 0 0.00 0 10804 16 0 0
v_min_id_clo_ord 5268 5268 0 0.00 0 15813 16 0 0
v_min_id_clo_res 941 941 0 0.00 0 4101 8 0 0
v_min_id_ord_sup 5143 5143 0 0.00 0 3717 5 0 0
v_min_non_paid_invoice 5573 5573 0 0.00 0 16564 33 0 0
v_min_non_paid_realizn 6840 6840 0 0.00 0 13363 35 0 0
v_our_firm 9728 9728 0 0.00 0 19 1 0 0
v_reserve_write_off 18778 18778 0 0.00 0 6662 7 0 0
x_lock_depdocs_on_canc_invoice 11973 11973 0 0.00 0 30310 151 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 6070 345 0 0
x_make_qstorno_1000_1200 5269 5269 0 0.00 2 26202 1001 0 0
x_make_qstorno_1200_2000 5143 5143 0 0.00 3 92686 3177 0 0
x_make_qstorno_2100_3300 445384 445384 0 0.00 0 85558 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-02-2023 01:40:14 to 02-02-2023 01:40:18.

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:38 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:39 13833 8192000000 9900812016 10209525760 984034736 10.723 100 86 172 0 1011892688 5404336 22453616
23:10:58 23038 8192000000 9948042000 10266730496 1028650096 11.155 100 94 188 1 1061162896 7671840 24840960
23:12:32 16858 8192000000 9985187312 10316488704 1066340544 11.517 100 82 164 0 1096231840 9057936 20833360
23:13:53 8652 8192000000 10020987328 10366107648 1115461792 11.984 100 82 164 1 1144922800 9874304 19586704
23:15:04 7485 8192000000 10075657168 10437271552 1167462384 12.473 100 72 144 0 1194615632 10512752 16640496
23:16:13 4280 8192000000 10128509472 10501877760 1217569248 12.939 100 68 136 0 1243898368 11323920 15005200
23:17:20 3012 8192000000 10168199824 10544340992 1248734160 13.227 100 79 154 1 1279250224 14046304 16469760
23:18:29 1297 8192000000 10201583328 10581807104 1285098480 13.560 100 87 173 0 1319023408 15443904 18481024
23:19:32 1318 8192000000 10212603120 10598965248 1301561744 13.709 100 76 152 0 1330145424 11849632 16734048
23:20:36 24186 8192000000 10203460864 10609221632 1311796800 13.802 100 39 74 0 1325575232 5806768 7971664
23:22:01 6370 8192000000 10205703744 10623156224 1322909216 13.903 100 14 29 0 1332099552 5684416 3505920
23:23:12 5196 8192000000 10223725888 10635423744 1329783680 13.965 100 31 62 0 1344227712 7547392 6896640
23:24:21 6233 8192000000 10216089952 10641022976 1336366256 14.025 100 21 42 1 1351902000 11019248 4516496
23:25:28 46613 8192000000 10250785776 10661593088 1342801184 14.083 100 56 110 2 1361033856 5526448 12706224
23:27:16 8498 8192000000 10233987184 10654486528 1344160800 14.095 100 28 57 1 1356046544 5395680 6490064
23:28:26 11278 8192000000 10196180960 10613493760 1300467424 13.699 98 15 29 0 1309012944 5232272 3313248
23:29:38 45864 8192000000 10176695808 10571866112 1236376720 13.113 95 50 100 0 1254197776 5588704 12232352
23:31:27 5928 8192000000 10146918624 10544779264 1231130784 13.064 98 44 86 1 1249066224 7929360 10006080
23:32:34 8099 8192000000 10129837056 10527649792 1207771856 12.848 97 12 24 0 1215718960 4862624 3084480
23:33:42 8638 8192000000 10152770432 10560069632 1265407344 13.380 100 18 36 0 1275063968 5292512 4364112
23:34:54 18960 8192000000 10167135952 10583113728 1279326624 13.507 100 12 24 0 1291486096 9889024 2270448
23:36:15 5044 8192000000 10186268464 10593595392 1292184064 13.624 100 20 40 0 1302876800 5789440 4903296
23:37:20 12204 8192000000 10184354000 10603663360 1301214096 13.706 100 19 39 0 1311064480 5494432 4355952
23:38:36 33961 8192000000 10227955056 10640609280 1333282064 13.997 100 31 62 0 1344020800 4785504 5953232
23:40:28 47358 8192000000 10252394400 10674528256 1348997888 14.138 100 31 60 0 1368439984 12197408 7244688
23:42:20 14702 8192000000 10279420576 10697138176 1367760768 14.307 100 40 80 1 1393853040 16918800 9173472
23:43:39 6803 8192000000 10291918800 10713620480 1383685584 14.449 100 53 105 1 1413419952 17728624 12005744
23:44:54 16119 8192000000 10304463296 10728169472 1392544336 14.529 100 50 100 1 1423407776 19246336 11617104
23:46:14 11590 8192000000 10329639088 10750935040 1406113488 14.649 100 51 99 1 1439072144 20181504 12777152
23:47:31 3261 8192000000 10348985440 10769047552 1424909088 14.816 100 56 110 0 1463644608 24968240 13767280
23:48:44 6907 8192000000 10334934640 10756063232 1391906368 14.523 98 54 108 0 1432577552 27058704 13612480
23:49:56 5673 8192000000 10349748096 10766606336 1412571312 14.707 100 61 119 1 1462133840 33913168 15649360
23:51:19 6874 8192000000 10356932464 10778435584 1408924304 14.674 99 48 96 0 1461216016 39438816 12852896
23:52:31 1890 8192000000 10367574480 10782445568 1412739456 14.708 99 59 115 1 1470025888 41067040 16219392
23:53:38 2898 8192000000 10392811840 10802892800 1430522928 14.866 100 86 171 0 1497700960 45276800 21901232
23:54:47 1110 8192000000 10394203168 10802401280 1428813648 14.851 100 93 186 0 1500891104 48897184 23180272
23:55:53 2179 8192000000 10376738560 10789478400 1399298144 14.589 100 68 136 0 1482353776 64974432 18081200
23:57:00 2746 8192000000 10367719760 10767937536 1380111408 14.418 100 86 172 1 1468908144 66176560 22620176
23:58:13 1195 8192000000 10395378384 10803093504 1402101584 14.614 100 83 165 1 1496668000 73213520 21352896
23:59:18 18346 8192000000 10353490224 10786627584 1397330672 14.571 100 33 66 0 1463516544 56441376 9744496
00:00:43 9153 8192000000 10362238256 10786033664 1378970480 14.407 98 41 82 0 1449277280 58969152 11337648
00:01:54 16537 8192000000 10341170752 10776649728 1383480400 14.448 100 30 60 0 1455014992 62639440 8895152
00:03:26 30754 8192000000 10315047104 10752155648 1320748720 13.883 96 22 42 1 1386947424 59703680 6495024
00:05:00 29142 8192000000 10294692816 10734174208 1343817424 14.092 100 20 40 1 1400354704 50423680 6113600
00:06:34 57288 8192000000 10277918048 10717671424 1347269840 14.123 99 23 46 1 1382450144 29226992 5953312
00:08:32 82433 8192000000 10253826912 10695307264 1324571616 13.918 97 26 52 0 1338432768 8708176 5152976
00:10:56 62639 8192000000 10270195376 10728407040 1356561024 14.206 99 31 60 1 1376014432 12917792 6535616
00:13:00 91119 8192000000 10260040432 10705956864 1335024240 14.013 97 10 18 0 1346141536 9351056 1766240
00:15:32 91078 8192000000 10298624864 10736001024 1341491040 14.071 97 17 34 0 1356137040 11018512 3627488
00:18:09 153267 8192000000 10282761456 10743586816 1369300880 14.321 99 25 49 0 1393974832 18620336 6053616
00:21:44 46819 8192000000 10278438224 10729558016 1358177936 14.221 98 37 68 1 1373270896 7528208 7564752
00:23:35 59701 8192000000 10269223136 10727632896 1355541568 14.197 98 22 44 0 1368050736 7823648 4685520
00:25:36 62700 8192000000 10274993056 10738204672 1384165280 14.454 100 14 28 0 1397571888 9918992 3487616
00:27:43 106847 8192000000 10281243232 10735644672 1375880544 14.380 99 25 48 0 1392740624 10747728 6112352
00:30:30 66879 8192000000 10287768672 10750517248 1392294176 14.526 100 13 27 0 1411324704 15779904 3250624
00:32:37 15386 8192000000 10326991040 10766319616 1394905648 14.550 99 47 92 1 1417807680 12027104 10874928
00:33:55 57508 8192000000 10305422880 10750132224 1344324192 14.096 96 21 42 0 1364108656 15235968 4548496
00:35:57 76688 8192000000 10278511248 10726146048 1313146592 13.815 94 16 32 0 1331156944 14491408 3518944
00:38:15 77028 8192000000 10306466576 10763673600 1389958848 14.505 99 30 59 0 1411867360 15901248 6007264
00:40:33 80929 8192000000 10303477664 10754129920 1375635776 14.378 98 19 38 0 1387330672 7896224 3798672
00:42:54 51139 8192000000 10311148400 10771247104 1392046224 14.524 99 14 29 0 1414348240 18999936 3302080
00:44:47 80625 8192000000 10286999168 10735083520 1347358096 14.124 96 16 32 0 1360920768 9666944 3895728
00:47:08 31960 8192000000 10287395056 10744225792 1377065472 14.390 99 21 42 1 1391288528 9798016 4425040
00:48:41 41968 8192000000 10268543360 10720329728 1348729408 14.136 97 16 33 0 1365348192 13230864 3387920
00:50:24 35889 8192000000 10273746272 10719293440 1347363520 14.124 98 21 42 0 1363968448 11430912 5174016
00:52:00 70487 8192000000 10277108464 10737246208 1375207072 14.374 99 27 51 1 1394396400 11825040 7364288
00:54:15 48470 8192000000 10245038128 10706046976 1337700400 14.037 98 13 22 0 1348811664 8371760 2739504
00:56:04 40502 8192000000 10259644720 10703364096 1343637760 14.090 99 37 75 0 1361832960 9668448 8526752
00:57:51 58845 8192000000 10275607504 10715697152 1340843984 14.065 98 24 48 0 1355000640 9160192 4996464
01:00:00 76230 8192000000 10258831264 10703048704 1296465520 13.663 95 12 24 0 1317729696 18366752 2897424
01:02:22 38841 8192000000 10255018176 10692771840 1315944736 13.840 97 12 24 0 1326478288 8156672 2376880
01:04:02 95046 8192000000 10281761856 10727297024 1339057664 14.049 98 25 47 0 1354975280 10900960 5016656
01:06:50 94520 8192000000 10285401840 10737897472 1377232704 14.392 100 24 48 0 1394932192 12427344 5272144
01:09:25 28149 8192000000 10279916192 10739646464 1385306816 14.464 100 24 44 0 1399137264 8336240 5494208
01:12:34 81166 8192000000 10287636000 10753585152 1378959840 14.407 99 14 28 0 1396889744 14893904 3036000
01:15:31 83211 8192000000 10320523136 10780819456 1392285776 14.526 99 23 46 1 1417987712 20248720 5453216
01:18:08 54146 8192000000 10310975664 10764435456 1390595552 14.511 99 31 62 0 1408538208 11377008 6565648
01:20:05 48330 8192000000 10300673488 10748268544 1365862320 14.290 99 30 56 1 1391120864 18329248 6929296
01:21:55 12900 8192000000 10310355024 10743693312 1384612384 14.458 100 46 90 0 1403035968 8353872 10069712
01:23:12 108478 8192000000 10299066912 10768941056 1361488336 14.251 98 16 32 0 1385505600 20596352 3420912
01:26:00 56565 8192000000 10300214976 10753994752 1377496880 14.394 99 30 54 2 1393950144 10087744 6365520
01:28:21 81981 8192000000 10298527520 10763251712 1366634880 14.297 98 21 43 0 1381515664 10389056 4491728
01:31:07 68896 8192000000 10284164832 10756972544 1372500848 14.349 99 6 12 1 1387965008 13801264 1662896
01:33:17 82666 8192000000 10270815360 10718494720 1306124768 13.751 95 19 38 0 1318577536 8103728 4349040
01:35:51 56149 8192000000 10258055232 10723991552 1342926304 14.084 98 9 18 0 1356465296 11126096 2412896
01:37:50 48985 8192000000 9463331296 9808531456 594188272 6.762 43 4 8 0 597280672 2624416 467984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-02-2023 01:40:20 to 02-02-2023 01:40:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 56 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-02-2023 01:40:36 to 02-02-2023 01:40:38.

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          234568
MON$OLDEST_ACTIVE               234569
MON$OLDEST_SNAPSHOT             234569
MON$NEXT_TRANSACTION            234569
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                       2016320
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.33658 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33658 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33658 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33658 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'

02.02.23 01:40:40. 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
2023-02-02 01:40:42 No severe PSQL-related problems occured
02.02.23 01:40:43. End of report.