Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.01.2022 19:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16596996 kB
MemAvailable 31296432 kB
Buffers 0 kB
Cached 15056444 kB
SwapCached 1200 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.9
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-01-04 19:38:02
DTS_END                         2022-01-04 19:38:02.4220
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40446
TRN_ID                          243218
ATT_ID                          706
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: 9159.85 24368 1099182
customer order: creation 168.89 366 20267
customer order: refuse 14.38 335 1725
order to supplier: creation 44.28 1326 5313
order to supplier: removal 3.28 115612 393
invoice (draft): creation 46.08 3807 5530
invoice (draft): removal 51.13 855 6135
invoice accept: apply 42.33 18081 5079
invoice accept: cancel 46.41 901 5569
customer reserve: creation 3871.23 181 464547
customer reserve: removal 4575.56 66 549067
realization accept: apply 83.08 191 9970
realization accept: cancel 83.38 114 10005
payment from customer: creation 59.07 113 7088
payment from customer: removal 5.58 78 670
payment to supplier: creation 49.20 136 5904
payment to supplier: removal 3.35 146 402
service: total inventory turnovers 4.84 1170 581
service: total monetary turnovers 6.90 37 828
service: aggregate perf. data 0.88 16642 105
service: refresh index statistics 0.03 327196 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-01-2022 19:39:21 to 04-01-2022 19:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5304
TEST_TIME 2 5951
TEST_TIME 3 5123
TEST_TIME 4 5941
TEST_TIME 5 5403
TEST_TIME 6 5159
TEST_TIME 7 5180
TEST_TIME 8 5255
TEST_TIME 9 5365
TEST_TIME 10 5570
TEST_TIME 11 6160
TEST_TIME 12 6337
TEST_TIME 13 6167
TEST_TIME 14 6368
TEST_TIME 15 6649
TEST_TIME 16 7056
TEST_TIME 17 7187
TEST_TIME 18 7355
TEST_TIME 19 7463
TEST_TIME 20 7685
TEST_TIME 21 7792
TEST_TIME 22 8380
TEST_TIME 23 8942
TEST_TIME 24 9029
TEST_TIME 25 9243
TEST_TIME 26 9607
TEST_TIME 27 10151
TEST_TIME 28 10840
TEST_TIME 29 10788
TEST_TIME 30 10613
TEST_TIME 31 10371
TEST_TIME 32 10298
TEST_TIME 33 10308
TEST_TIME 34 10204
TEST_TIME 35 10045
TEST_TIME 36 9923
TEST_TIME 37 9814
TEST_TIME 38 9770
TEST_TIME 39 10054
TEST_TIME 40 9915
TEST_TIME 41 9798
TEST_TIME 42 9691
TEST_TIME 43 9543
TEST_TIME 44 9641
TEST_TIME 45 9607
TEST_TIME 46 9552
TEST_TIME 47 9472
TEST_TIME 48 9393
TEST_TIME 49 9325
TEST_TIME 50 9352
TEST_TIME 51 9263
TEST_TIME 52 9312
TEST_TIME 53 9374
TEST_TIME 54 9316
TEST_TIME 55 9341
TEST_TIME 56 9396
TEST_TIME 57 9416
TEST_TIME 58 9443
TEST_TIME 59 9398
TEST_TIME 60 9478
TEST_TIME 61 9467
TEST_TIME 62 9517
TEST_TIME 63 9653
TEST_TIME 64 9643
TEST_TIME 65 9640
TEST_TIME 66 9594
TEST_TIME 67 9551
TEST_TIME 68 9492
TEST_TIME 69 9438
TEST_TIME 70 9414
TEST_TIME 71 9364
TEST_TIME 72 9299
TEST_TIME 73 9284
TEST_TIME 74 9267
TEST_TIME 75 9247
TEST_TIME 76 9314
TEST_TIME 77 9296
TEST_TIME 78 9295
TEST_TIME 79 9320
TEST_TIME 80 9328
TEST_TIME 81 9280
TEST_TIME 82 9348
TEST_TIME 83 9338
TEST_TIME 84 9365
TEST_TIME 85 9428
TEST_TIME 86 9442
TEST_TIME 87 9492
TEST_TIME 88 9447
TEST_TIME 89 9356
TEST_TIME 90 9309
TEST_TIME 91 9275
TEST_TIME 92 9273
TEST_TIME 93 9316
TEST_TIME 94 9239
TEST_TIME 95 9239
TEST_TIME 96 9212
TEST_TIME 97 9278
TEST_TIME 98 9277
TEST_TIME 99 9241
TEST_TIME 100 9288
TEST_TIME 101 9282
TEST_TIME 102 9306
TEST_TIME 103 9292
TEST_TIME 104 9280
TEST_TIME 105 9409
TEST_TIME 106 9407
TEST_TIME 107 9421
TEST_TIME 108 9403
TEST_TIME 109 9334
TEST_TIME 110 9303
TEST_TIME 111 9270
TEST_TIME 112 9256
TEST_TIME 113 9228
TEST_TIME 114 9240
TEST_TIME 115 9207
TEST_TIME 116 9158
TEST_TIME 117 9150
TEST_TIME 118 9130
TEST_TIME 119 9237
TEST_TIME 120 9162

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-01-2022 19:39:24 to 04-01-2022 19:39:28.

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 1526733 1526733 0 0.00 0 25301 6 0 0
doc_list_biud 1526733 1526733 0 0.00 0 64787 30 0 0
sp_add_invoice_to_stock 5194 5079 115 2.21 14 196711 18082 0 115
sp_cancel_adding_invoice 5569 5569 0 0.00 6 56225 902 0 0
sp_cancel_client_order 1725 1725 0 0.00 4 10514 336 0 0
sp_cancel_customer_reserve 549067 549067 0 0.00 1 64790 66 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 3388 78 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 12345 146 0 0
sp_cancel_supplier_invoice 6148 6135 13 0.21 2 12572 855 0 13
sp_cancel_supplier_order 393 393 0 0.00 7 1039892 115612 0 0
sp_cancel_write_off 10005 10005 0 0.00 1 24050 114 0 0
sp_client_order 20267 20267 0 0.00 3 57063 366 0 0
sp_customer_reserve 464547 464547 0 0.00 0 145102 181 0 0
sp_fill_shopping_cart 31100 31100 0 0.00 1 15904 45 0 0
sp_fill_shopping_cart_clo_res 464542 464542 0 0.00 0 11530 13 0 0
sp_get_clo_for_invoice 5073 5073 0 0.00 0 22511 317 0 0
sp_kill_cost_storno 16646 16646 0 0.00 0 8456 60 0 0
sp_kill_qstorno_ret_qs2qd 1114667 1114667 0 0.00 0 64741 36 0 0
sp_kill_qty_storno 587937 587937 0 0.00 0 64787 76 0 0
sp_lock_selected_doc 20780 20780 0 0.00 0 8792 6 0 0
sp_make_cost_storno 28035 28035 0 0.00 0 25269 55 0 0
sp_multiply_rows_for_pdistr 28035 28035 0 0.00 0 7644 33 0 0
sp_multiply_rows_for_qdistr 20267 20267 0 0.00 0 57042 158 0 0
sp_pay_from_customer 7088 7088 0 0.00 4 9848 114 0 0
sp_pay_to_supplier 5904 5904 0 0.00 5 14264 136 0 0
sp_payment_common 12992 12992 0 0.00 2 9190 31 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 9144 302 0 0
sp_qd_handle_on_invoice_upd_sts 10642 10642 0 0.00 0 21507 332 0 0
sp_qd_handle_on_reserve_upd_sts 9970 9970 0 0.00 0 15259 62 0 0
sp_reserve_write_off 9970 9970 0 0.00 6 15296 191 0 0
sp_supplier_invoice 5530 5530 0 0.00 6 78992 3807 0 0
sp_supplier_order 5313 5313 0 0.00 5 38289 1326 0 0
srv_aggregate_perf_data 105 105 0 0.00 2896 242454 16642 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2108 170183 47768 0 0
srv_find_qd_qs_mism 1050308 1050308 0 0.00 0 15337 6 0 0
srv_make_invnt_saldo 581 581 0 0.00 50 53011 1170 0 0
srv_make_money_saldo 828 828 0 0.00 1 5848 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 151421 467858 327197 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5079 5079 0 0.00 0 10913 154 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 1 11687 132 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 5891 18 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 1087 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 4919 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 453 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 1335 12 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 21519 1708 0 0
t$perf-norm:sp_cancel_write_off 1028 1028 0 0.00 0 2459 10 0 0
t$perf-norm:sp_client_order 20267 20267 0 0.00 0 11416 17 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 2761 9 0 0
t$perf-norm:sp_pay_from_customer 7088 7088 0 0.00 0 5525 10 0 0
t$perf-norm:sp_pay_to_supplier 5904 5904 0 0.00 0 5883 12 0 0
t$perf-norm:sp_reserve_write_off 9970 9970 0 0.00 0 8810 11 0 0
t$perf-norm:sp_supplier_invoice 5530 5530 0 0.00 0 4156 16 0 0
t$perf-norm:sp_supplier_order 5313 5313 0 0.00 0 10246 17 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 54 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1210 20 0 0
t$perf-norm:srv_make_invnt_saldo 581 581 0 0.00 0 4807 29 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 864 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 20 7 0 0
v_add_invoice_to_stock 10376 10146 230 2.21 0 8872 21 0 230
v_all_customers 36824 36824 0 0.00 0 24 1 0 0
v_all_suppliers 30062 30062 0 0.00 0 60 1 0 0
v_all_wares 40534 40534 0 0.00 0 38 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 3911 15 0 0
v_cancel_client_order 3450 3450 0 0.00 0 7909 10 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 1819 6 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 2203 6 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 3545 12 0 0
v_cancel_supplier_invoice 792 766 26 3.28 0 4185 21 0 26
v_cancel_supplier_order 786 786 0 0.00 0 2594 15 0 0
v_cancel_write_off 2056 2056 0 0.00 0 4538 19 0 0
v_max_id_clo_ord 5309 5309 0 0.00 0 2072 2 0 0
v_max_id_clo_res 962 962 0 0.00 0 2146 14 0 0
v_max_id_ord_sup 5524 5524 0 0.00 0 5655 8 0 0
v_max_non_paid_invoice 1706 1706 0 0.00 0 612 3 0 0
v_max_non_paid_realizn 3867 3867 0 0.00 0 2775 4 0 0
v_min_id_clo_ord 5309 5309 0 0.00 0 9830 11 0 0
v_min_id_clo_res 962 962 0 0.00 0 8652 22 0 0
v_min_id_ord_sup 5524 5524 0 0.00 0 7843 12 0 0
v_min_non_paid_invoice 5904 5904 0 0.00 0 8215 25 0 0
v_min_non_paid_realizn 7088 7088 0 0.00 0 9184 22 0 0
v_our_firm 10152 10152 0 0.00 0 19 1 0 0
v_reserve_write_off 19940 19940 0 0.00 0 7091 8 0 0
x_lock_depdocs_on_canc_invoice 11321 11321 0 0.00 0 18512 136 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 16899 407 0 0
x_make_qstorno_1000_1200 5313 5313 0 0.00 1 38283 1231 0 0
x_make_qstorno_1200_2000 5530 5530 0 0.00 2 78975 3647 0 0
x_make_qstorno_2100_3300 464547 464547 0 0.00 0 145100 165 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:06 2 8192000000 8801705552 8813842432 7731408 0.094 2 2 3 0 7872512 66144 74960
17:09:07 30241 8192000000 9914668768 10226548736 986726688 10.750 100 85 170 0 1015164816 6162896 22275232
17:10:47 21997 8192000000 9972871984 10299092992 1048786080 11.349 100 86 172 0 1079063232 8675328 21601824
17:12:14 19897 8192000000 9989216592 10326536192 1075446016 11.604 100 82 164 0 1106266848 10320144 20500688
17:13:36 9765 8192000000 10051789456 10405404672 1134298784 12.162 100 68 136 0 1159978912 9825552 15854576
17:14:48 7512 8192000000 10103867568 10467467264 1192881904 12.710 100 80 159 0 1222587360 11559472 18145984
17:15:59 4507 8192000000 10148131120 10517712896 1229070272 13.045 100 86 170 1 1262316656 14981856 18264528
17:17:04 4350 8192000000 10178040288 10557489152 1262844320 13.356 100 92 181 1 1296861776 14237072 19780384
17:18:09 1191 8192000000 10218995056 10602627072 1298850176 13.685 100 94 189 2 1334462176 13809616 21802384
17:19:11 2073 8192000000 10209584608 10611961856 1308473152 13.772 100 63 126 0 1333831520 11375056 13983312
17:20:14 9095 8192000000 10208882512 10622296064 1319330688 13.871 100 31 62 0 1333674720 7722000 6622032
17:21:25 35472 8192000000 10215971072 10632802304 1325441344 13.926 100 35 70 1 1338641360 4545184 8654832
17:23:01 3744 8192000000 10230539376 10643800064 1329785344 13.965 100 40 80 1 1346553808 7726128 9042336
17:24:06 11390 8192000000 10222625152 10645069824 1333821360 14.002 100 22 42 2 1345325120 6193584 5310176
17:25:20 10216 8192000000 10226462384 10652028928 1335924240 14.021 100 21 40 1 1350685712 10245296 4516176
17:26:32 23962 8192000000 10212350304 10636357632 1311553920 13.800 98 25 50 0 1323691424 5474192 6663312
17:28:00 7504 8192000000 10183706816 10594250752 1263481120 13.362 96 17 33 1 1272527408 5204160 3842128
17:29:07 7073 8192000000 10159507568 10562392064 1237190064 13.120 97 28 56 5 1250624192 6464704 6969424
17:30:15 33471 8192000000 10103573424 10503917568 1164551808 12.446 92 26 51 3 1176142496 4398128 7192560
17:32:06 7976 8192000000 10145817088 10541137920 1238557888 13.133 99 37 74 3 1253217664 6398640 8261136
17:33:16 6080 8192000000 10162974848 10570780672 1273497104 13.454 100 31 62 3 1290301920 10128384 6676432
17:34:22 9095 8192000000 10174155856 10587828224 1285034672 13.559 100 17 34 1 1294297536 5083296 4179568
17:35:31 45769 8192000000 10208839568 10606219264 1296341232 13.662 100 64 131 1 1316902512 5618864 14942416
17:37:17 10206 8192000000 10198147072 10615382016 1307073568 13.760 100 24 48 1 1318017952 5411696 5532688
17:38:33 20802 8192000000 10236223040 10648473600 1330891984 13.975 100 39 77 1 1349381184 9820656 8668544
17:39:59 11284 8192000000 10264570544 10677395456 1354421728 14.187 100 53 106 0 1378146304 11304848 12419728
17:41:18 46602 8192000000 10283375600 10706259968 1368272800 14.312 100 50 100 2 1395234192 15112816 11848576
17:43:22 26629 8192000000 10283583888 10702684160 1364192240 14.275 99 47 94 2 1390200048 15048224 10959584
17:44:58 9359 8192000000 10312367616 10726899712 1374095856 14.364 99 67 133 0 1412198880 23242752 14860272
17:46:27 10030 8192000000 10312962960 10732011520 1388836208 14.495 100 49 98 0 1424523872 24237376 11450288
17:47:50 5821 8192000000 10340007040 10750914560 1402049856 14.613 100 74 144 0 1447317408 28207856 17059696
17:48:59 3588 8192000000 10338420800 10750316544 1400320928 14.598 100 63 126 0 1445756144 30400336 15034880
17:50:07 4726 8192000000 10324802752 10733813760 1361612816 14.252 98 70 140 2 1410699056 32279264 16806976
17:51:18 5855 8192000000 10326492064 10738589696 1378678208 14.405 99 69 136 0 1429797424 34779776 16339440
17:52:28 14566 8192000000 10356100880 10780663808 1413601824 14.716 100 46 92 1 1469308864 43227152 12479888
17:53:48 6876 8192000000 10377145712 10787143680 1418869360 14.763 100 75 151 3 1479934960 42185424 18880176
17:54:59 4405 8192000000 10362255264 10769440768 1402564000 14.618 100 67 133 2 1463556400 43589872 17402528
17:56:12 2108 8192000000 10354445968 10766925824 1385953984 14.470 99 57 114 1 1452360064 50978368 15427712
17:57:17 9888 8192000000 10344388496 10760921088 1373151072 14.355 98 47 90 2 1438331056 51823728 13356256
17:58:30 9778 8192000000 10337092016 10758107136 1374126288 14.364 99 48 96 1 1441692992 53558304 14008400
17:59:54 2444 8192000000 10363121296 10771267584 1386648400 14.476 100 69 138 0 1462158912 57653872 17856640
18:01:06 16616 8192000000 10344229376 10783100928 1396643104 14.565 100 41 82 1 1457490144 49019280 11827760
18:02:31 18094 8192000000 10322444144 10759639040 1372035872 14.345 99 29 58 2 1425199536 44533040 8630624
18:04:12 21785 8192000000 10313613840 10758332416 1351094752 14.157 99 24 48 1 1415801120 57398464 7307904
18:05:37 32344 8192000000 10286131040 10725289984 1331088320 13.977 98 16 32 0 1380392640 44862112 4442208
18:07:11 97105 8192000000 10266825360 10708889600 1345357904 14.106 99 37 74 1 1363113504 9031664 8723936
18:10:19 45923 8192000000 10290833808 10732793856 1379421952 14.411 100 33 66 0 1395054640 8107216 7525472
18:12:23 28851 8192000000 10294372048 10743480320 1387551280 14.484 100 12 24 0 1396769840 6804432 2414128
18:13:52 58656 8192000000 10302940240 10751467520 1386286032 14.473 99 30 61 1 1402890560 9118576 7485952
18:15:56 54484 8192000000 10300929168 10750984192 1390799600 14.513 100 18 36 1 1413035776 17627200 4608976
18:17:55 79657 8192000000 10290038848 10743201792 1378029632 14.399 99 15 30 0 1389322160 7929312 3363216
18:20:16 170182 8192000000 10282660864 10744020992 1389704160 14.503 99 28 57 0 1410324608 13497520 7122928
18:24:09 2569 8192000000 10305502336 10750140416 1396345072 14.562 100 55 109 0 1416565488 6574544 13645872
18:25:12 33041 8192000000 10255031392 10712948736 1359827472 14.236 98 27 54 0 1375842352 9750160 6264720
18:26:46 83354 8192000000 10291179072 10752073728 1384229712 14.454 99 17 34 1 1400850496 12444320 4176464
18:29:38 46073 8192000000 10290262160 10746675200 1377654720 14.396 99 25 50 1 1397126736 13711440 5760576
18:31:28 67362 8192000000 10296984864 10748829696 1370485216 14.331 98 24 49 0 1385012160 9417840 5109104
18:33:42 20930 8192000000 10301493760 10753097728 1385636848 14.467 99 36 70 0 1401089600 7364320 8088432
18:35:05 52792 8192000000 10258060560 10715820032 1311987472 13.804 95 24 48 0 1329634304 12411344 5235488
18:37:00 38177 8192000000 10273023344 10717286400 1352033872 14.166 99 25 50 0 1372757056 15568224 5154960
18:38:39 64362 8192000000 10256095920 10705108992 1286705216 13.574 94 15 30 0 1307332208 17281824 3345168
18:40:49 102964 8192000000 10273575504 10724093952 1312758480 13.811 95 13 26 1 1331974800 16782736 2433584
18:43:33 120742 8192000000 10286580368 10730872832 1353589008 14.180 98 21 42 0 1371801920 13327104 4885808
18:46:36 44638 8192000000 10275016400 10714943488 1344556496 14.098 98 49 97 3 1364478928 9669680 10252752
18:48:26 70082 8192000000 10259444992 10718261248 1357010512 14.211 99 11 22 0 1369856112 10284576 2561024
18:50:36 55973 8192000000 10250447616 10695344128 1305129856 13.742 95 20 40 1 1324660592 14953632 4577104
18:53:41 56655 8192000000 10268700016 10719125504 1330537136 13.972 97 14 28 0 1347946704 14058512 3351056
18:55:42 49569 8192000000 10262757440 10709762048 1321204640 13.888 97 21 42 2 1338212944 12000080 5008224
18:57:36 79713 8192000000 10268947952 10702524416 1311091488 13.796 96 21 42 0 1325758096 9836432 4830176
18:59:56 49937 8192000000 10275508624 10718609408 1347750784 14.127 99 34 68 1 1369112208 14085600 7275824
19:01:46 24563 8192000000 10284205424 10726285312 1368437296 14.313 100 39 78 0 1386684976 9241904 9005776
19:03:15 82927 8192000000 10291489200 10748248064 1363415168 14.268 99 11 22 0 1380813520 14913952 2484400
19:05:38 62593 8192000000 10278541184 10738286592 1366637600 14.297 99 9 18 0 1383166544 14724800 1804144
19:07:53 100761 8192000000 10313663504 10757103616 1377837536 14.397 99 31 62 0 1392058608 7313088 6907984
19:10:38 70070 8192000000 10280215568 10752073728 1382850656 14.442 99 11 22 0 1400835472 15397568 2587248
19:13:46 94361 8192000000 10295215776 10761195520 1370764416 14.334 98 13 28 0 1382001824 8258784 2978624
19:16:59 44111 8192000000 10306406368 10754166784 1349745760 14.145 96 21 43 0 1361685728 7362976 4576992
19:18:50 56665 8192000000 10294777376 10746601472 1364535200 14.278 98 13 26 0 1376139008 8655840 2947968
19:21:00 34059 8192000000 10266943136 10707861504 1321208320 13.888 96 25 50 0 1335626000 8933696 5483984
19:22:38 114398 8192000000 10296151616 10763468800 1359429728 14.232 98 7 14 0 1382779136 21811936 1537472
19:25:33 86954 8192000000 10294545536 10737164288 1380424336 14.420 100 15 30 0 1393015232 9412528 3178368
19:28:07 65996 8192000000 10282621632 10728546304 1302449440 13.718 94 21 42 0 1314101584 6923808 4728336
19:30:14 60688 8192000000 10274193664 10731397120 1366336992 14.294 99 16 32 1 1381669840 11197888 4134960
19:32:25 50326 8192000000 10251935040 10713939968 1314946208 13.831 96 10 20 1 1329294176 12045328 2302640
19:34:18 72511 8192000000 10263832832 10714607616 1327813008 13.947 97 20 40 0 1350202352 17409392 4979952
19:36:42 83126 8192000000 9605680112 9958957056 745338224 8.339 55 13 26 0 760005232 11546432 3120576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 04-01-2022 19:39:35 to 04-01-2022 19:39:49.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-01-2022 19:39:50 to 04-01-2022 19:39:52.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243549
MON$OLDEST_ACTIVE               243550
MON$OLDEST_SNAPSHOT             243550
MON$NEXT_TRANSACTION            243550
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                       2018368
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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'

04.01.22 19:39:53. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-01-04 19:39:56 No severe PSQL-related problems occured
04.01.22 19:39:57. End of report.