Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.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 15134792 kB
MemAvailable 30392036 kB
Buffers 0 kB
Cached 16555752 kB
SwapCached 3140 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-25 01:38:44
DTS_END                         2023-02-25 01:38:44.2070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58198
TRN_ID                          244103
ATT_ID                          710
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: 9139.25 21647 1096710
customer order: creation 175.75 353 21090
customer order: refuse 14.80 381 1776
order to supplier: creation 47.17 1298 5660
order to supplier: removal 3.13 112978 375
invoice (draft): creation 44.90 3259 5388
invoice (draft): removal 50.69 852 6083
invoice accept: apply 42.63 16324 5115
invoice accept: cancel 46.58 929 5589
customer reserve: creation 3825.48 164 459057
customer reserve: removal 4577.64 61 549317
realization accept: apply 83.30 214 9996
realization accept: cancel 94.38 100 11326
payment from customer: creation 60.89 150 7307
payment from customer: removal 6.00 107 720
payment to supplier: creation 50.06 152 6007
payment to supplier: removal 3.63 79 435
service: total inventory turnovers 4.68 1422 562
service: total monetary turnovers 6.67 56 800
service: aggregate perf. data 0.86 25486 103
service: refresh index statistics 0.03 268565 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-02-2023 01:39:48 to 25-02-2023 01:39:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3077
TEST_TIME 2 3561
TEST_TIME 3 3842
TEST_TIME 4 4452
TEST_TIME 5 4727
TEST_TIME 6 4598
TEST_TIME 7 4666
TEST_TIME 8 4834
TEST_TIME 9 5057
TEST_TIME 10 5410
TEST_TIME 11 5211
TEST_TIME 12 5411
TEST_TIME 13 5806
TEST_TIME 14 6087
TEST_TIME 15 6506
TEST_TIME 16 6852
TEST_TIME 17 6913
TEST_TIME 18 7353
TEST_TIME 19 7733
TEST_TIME 20 8313
TEST_TIME 21 8062
TEST_TIME 22 8061
TEST_TIME 23 8184
TEST_TIME 24 8131
TEST_TIME 25 8402
TEST_TIME 26 8281
TEST_TIME 27 8402
TEST_TIME 28 8650
TEST_TIME 29 8922
TEST_TIME 30 8749
TEST_TIME 31 9022
TEST_TIME 32 9211
TEST_TIME 33 10064
TEST_TIME 34 9932
TEST_TIME 35 10097
TEST_TIME 36 10104
TEST_TIME 37 10428
TEST_TIME 38 10239
TEST_TIME 39 10061
TEST_TIME 40 10012
TEST_TIME 41 9901
TEST_TIME 42 9750
TEST_TIME 43 9725
TEST_TIME 44 9649
TEST_TIME 45 9490
TEST_TIME 46 9527
TEST_TIME 47 9634
TEST_TIME 48 9501
TEST_TIME 49 9444
TEST_TIME 50 9539
TEST_TIME 51 9398
TEST_TIME 52 9365
TEST_TIME 53 9331
TEST_TIME 54 9311
TEST_TIME 55 9296
TEST_TIME 56 9198
TEST_TIME 57 9223
TEST_TIME 58 9165
TEST_TIME 59 9064
TEST_TIME 60 9006
TEST_TIME 61 8973
TEST_TIME 62 8902
TEST_TIME 63 9009
TEST_TIME 64 9219
TEST_TIME 65 9210
TEST_TIME 66 9256
TEST_TIME 67 9201
TEST_TIME 68 9230
TEST_TIME 69 9207
TEST_TIME 70 9170
TEST_TIME 71 9213
TEST_TIME 72 9275
TEST_TIME 73 9298
TEST_TIME 74 9455
TEST_TIME 75 9430
TEST_TIME 76 9376
TEST_TIME 77 9423
TEST_TIME 78 9411
TEST_TIME 79 9362
TEST_TIME 80 9314
TEST_TIME 81 9239
TEST_TIME 82 9232
TEST_TIME 83 9219
TEST_TIME 84 9189
TEST_TIME 85 9132
TEST_TIME 86 9092
TEST_TIME 87 9179
TEST_TIME 88 9161
TEST_TIME 89 9107
TEST_TIME 90 9120
TEST_TIME 91 9172
TEST_TIME 92 9149
TEST_TIME 93 9173
TEST_TIME 94 9156
TEST_TIME 95 9249
TEST_TIME 96 9273
TEST_TIME 97 9280
TEST_TIME 98 9302
TEST_TIME 99 9304
TEST_TIME 100 9342
TEST_TIME 101 9326
TEST_TIME 102 9290
TEST_TIME 103 9324
TEST_TIME 104 9341
TEST_TIME 105 9256
TEST_TIME 106 9217
TEST_TIME 107 9195
TEST_TIME 108 9167
TEST_TIME 109 9210
TEST_TIME 110 9179
TEST_TIME 111 9154
TEST_TIME 112 9124
TEST_TIME 113 9090
TEST_TIME 114 9114
TEST_TIME 115 9111
TEST_TIME 116 9113
TEST_TIME 117 9123
TEST_TIME 118 9118
TEST_TIME 119 9163
TEST_TIME 120 9141

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-02-2023 01:39:52 to 25-02-2023 01:39:55.

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 1521946 1521946 0 0.00 0 34178 6 0 0
doc_list_biud 1521946 1521946 0 0.00 0 35377 28 0 0
sp_add_invoice_to_stock 5275 5115 160 3.03 25 176825 16324 0 160
sp_cancel_adding_invoice 5589 5589 0 0.00 5 82650 930 0 0
sp_cancel_client_order 1776 1776 0 0.00 4 10873 382 0 0
sp_cancel_customer_reserve 549317 549317 0 0.00 0 34805 61 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 2 12058 107 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 3228 79 0 0
sp_cancel_supplier_invoice 6097 6083 14 0.22 1 15278 853 0 14
sp_cancel_supplier_order 375 375 0 0.00 7 947711 112978 0 0
sp_cancel_write_off 11326 11326 0 0.00 1 14324 101 0 0
sp_client_order 21090 21090 0 0.00 3 21758 353 0 0
sp_customer_reserve 459057 459057 0 0.00 0 39461 164 0 0
sp_fill_shopping_cart 32127 32127 0 0.00 1 15098 45 0 0
sp_fill_shopping_cart_clo_res 459053 459053 0 0.00 0 20065 13 0 0
sp_get_clo_for_invoice 5111 5111 0 0.00 0 22228 317 0 0
sp_kill_cost_storno 18070 18070 0 0.00 0 16567 58 0 0
sp_kill_qstorno_ret_qs2qd 1116418 1116418 0 0.00 0 34801 33 0 0
sp_kill_qty_storno 589573 589573 0 0.00 0 35376 72 0 0
sp_lock_selected_doc 20954 20954 0 0.00 0 10748 7 0 0
sp_make_cost_storno 28421 28421 0 0.00 0 34169 68 0 0
sp_multiply_rows_for_pdistr 28421 28421 0 0.00 0 15904 31 0 0
sp_multiply_rows_for_qdistr 21090 21090 0 0.00 0 20342 160 0 0
sp_pay_from_customer 7307 7307 0 0.00 4 25789 150 0 0
sp_pay_to_supplier 6007 6007 0 0.00 4 31170 153 0 0
sp_payment_common 13314 13314 0 0.00 1 31137 40 0 0
sp_qd_handle_on_cancel_clo 1776 1776 0 0.00 0 8431 347 0 0
sp_qd_handle_on_invoice_upd_sts 10700 10700 0 0.00 0 35376 338 0 0
sp_qd_handle_on_reserve_upd_sts 9996 9996 0 0.00 0 12893 62 0 0
sp_reserve_write_off 9996 9996 0 0.00 6 25903 215 0 0
sp_supplier_invoice 5388 5388 0 0.00 8 77078 3259 0 0
sp_supplier_order 5660 5660 0 0.00 4 22899 1298 0 0
srv_aggregate_perf_data 103 103 0 0.00 2670 497639 25487 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1673 167134 46126 0 0
srv_find_qd_qs_mism 1048531 1048531 0 0.00 0 15547 5 0 0
srv_make_invnt_saldo 562 562 0 0.00 39 186767 1422 0 0
srv_make_money_saldo 800 800 0 0.00 1 16941 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 118190 528035 268565 0 0
t$perf-abend:sp_add_invoice_to_stock 160 160 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5116 5116 0 0.00 0 10264 140 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 10937 175 0 0
t$perf-norm:sp_cancel_client_order 1776 1776 0 0.00 0 2886 14 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 1310 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 988 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 1377 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 1388 24 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 19981 1607 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 2329 7 0 0
t$perf-norm:sp_client_order 21090 21090 0 0.00 0 11644 16 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 6163 10 0 0
t$perf-norm:sp_pay_from_customer 7307 7307 0 0.00 0 5393 7 0 0
t$perf-norm:sp_pay_to_supplier 6007 6007 0 0.00 0 8113 11 0 0
t$perf-norm:sp_reserve_write_off 9996 9996 0 0.00 0 6089 12 0 0
t$perf-norm:sp_supplier_invoice 5388 5388 0 0.00 0 11548 21 0 0
t$perf-norm:sp_supplier_order 5660 5660 0 0.00 0 4470 16 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 628 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 884 20 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 1891 30 0 0
t$perf-norm:srv_make_money_saldo 800 800 0 0.00 0 1000 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 5 0 0
v_add_invoice_to_stock 10540 10220 320 3.03 0 12541 24 0 320
v_all_customers 38388 38388 0 0.00 0 128 1 0 0
v_all_suppliers 30402 30402 0 0.00 0 60 1 0 0
v_all_wares 42180 42180 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 1781 10 0 0
v_cancel_client_order 3552 3552 0 0.00 0 4628 6 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 4126 13 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 1368 7 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 3207 8 0 0
v_cancel_supplier_invoice 798 770 28 3.50 0 5409 27 0 28
v_cancel_supplier_order 750 750 0 0.00 0 3324 13 0 0
v_cancel_write_off 2032 2032 0 0.00 0 5298 24 0 0
v_max_id_clo_ord 5655 5655 0 0.00 0 2718 2 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 4202 20 0 0
v_max_id_ord_sup 5381 5381 0 0.00 0 4911 6 0 0
v_max_non_paid_invoice 1842 1842 0 0.00 0 2822 5 0 0
v_max_non_paid_realizn 3975 3975 0 0.00 0 6616 6 0 0
v_min_id_clo_ord 5655 5655 0 0.00 0 10274 22 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 3755 16 0 0
v_min_id_ord_sup 5381 5381 0 0.00 0 4060 6 0 0
v_min_non_paid_invoice 6007 6007 0 0.00 0 17629 19 0 0
v_min_non_paid_realizn 7307 7307 0 0.00 0 19092 34 0 0
v_our_firm 10456 10456 0 0.00 0 30 1 0 0
v_reserve_write_off 19992 19992 0 0.00 0 5377 9 0 0
x_lock_depdocs_on_canc_invoice 11287 11287 0 0.00 0 27641 127 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 14919 471 0 0
x_make_qstorno_1000_1200 5660 5660 0 0.00 1 22876 1194 0 0
x_make_qstorno_1200_2000 5388 5388 0 0.00 2 69718 3107 0 0
x_make_qstorno_2100_3300 459057 459057 0 0.00 0 35927 148 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 25-02-2023 01:39:56 to 25-02-2023 01:40:01.

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:48 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:48 42892 8192000000 9926021760 10241523712 1003763968 10.915 100 90 181 0 1033908464 7175632 22968864
23:11:34 20729 8192000000 9955923392 10279944192 1036435648 11.230 100 91 182 0 1069403072 9535568 23431856
23:13:06 13434 8192000000 9994087824 10327646208 1073847408 11.589 100 87 174 0 1105827984 10734416 21246160
23:14:22 8436 8192000000 10033080224 10379812864 1115938976 11.989 100 84 167 0 1147033984 11531008 19564000
23:15:35 6903 8192000000 10084340864 10448908288 1173342320 12.528 100 75 149 0 1202469968 12447920 16679728
23:16:44 3345 8192000000 10129451776 10499624960 1220410224 12.965 100 83 166 0 1253029648 13798640 18820784
23:17:50 1403 8192000000 10167169200 10546044928 1255598096 13.290 100 76 150 0 1284988832 12435248 16955488
23:18:53 895 8192000000 10203013696 10585612288 1286673648 13.574 100 90 176 1 1318826976 12661760 19491568
23:19:55 45884 8192000000 10200273552 10601107456 1299599728 13.692 100 39 75 1 1314126176 5277328 9249120
23:21:42 4242 8192000000 10205542528 10614112256 1309876544 13.785 100 33 66 0 1324841600 7968480 6996576
23:22:48 2928 8192000000 10210529456 10625228800 1322451856 13.899 100 40 80 0 1339379232 7984800 8942576
23:23:58 12858 8192000000 10227882848 10645630976 1332807552 13.993 100 18 36 1 1346988736 10294640 3886544
23:25:12 38876 8192000000 10223973744 10645622784 1334630400 14.009 100 33 66 0 1349234192 6535952 8067840
23:26:51 5032 8192000000 10229937936 10652237824 1327369472 13.943 99 32 64 0 1345742608 10518256 7854880
23:27:57 7423 8192000000 10224753488 10642935808 1322411408 13.899 99 26 52 0 1334570768 6666368 5492992
23:29:08 56118 8192000000 10189626544 10596958208 1204708400 12.820 91 26 52 1 1215772000 4394048 6669552
23:31:06 5158 8192000000 10176356528 10580766720 1245592272 13.198 97 26 51 0 1257353280 6090144 5670864
23:32:13 6787 8192000000 10136784704 10535628800 1187213712 12.657 94 25 50 2 1199284864 5700464 6370688
23:33:20 9547 8192000000 10158639936 10564050944 1262930160 13.357 99 19 36 0 1272145008 5085392 4129456
23:34:33 51685 8192000000 10177562688 10589802496 1286926000 13.576 100 20 41 1 1297276144 5570912 4779232
23:36:28 4374 8192000000 10200119008 10613432320 1303531232 13.727 100 23 46 0 1318845056 9989744 5324080
23:37:35 5096 8192000000 10206890704 10618998784 1309989472 13.786 100 19 39 0 1319718464 5609408 4119584
23:38:44 29019 8192000000 10237843664 10650902528 1337294352 14.033 100 26 52 0 1352426144 9491424 5640368
23:40:16 57201 8192000000 10249351056 10671980544 1348799200 14.137 100 34 68 1 1363453456 6505536 8148720
23:42:18 5974 8192000000 10267371584 10689380352 1364877888 14.281 100 51 96 0 1385391360 8899376 11614096
23:43:27 8940 8192000000 10281420144 10704023552 1378009552 14.399 100 48 95 0 1398409984 9611088 10789344
23:44:40 11804 8192000000 10284804464 10712109056 1372324208 14.348 99 40 78 1 1393314448 11893456 9096784
23:45:56 18069 8192000000 10309487488 10737242112 1397407648 14.572 100 42 84 1 1421984032 14152480 10423904
23:47:18 9595 8192000000 10305940528 10732752896 1385262416 14.464 99 37 74 0 1410143664 15550224 9331024
23:48:30 20380 8192000000 10311355552 10745085952 1400065920 14.596 100 25 49 0 1422986912 17206480 5714512
23:49:55 7613 8192000000 10311802752 10740633600 1387238576 14.481 99 37 72 0 1415577424 19056528 9282320
23:51:08 7175 8192000000 10318223728 10744524800 1384990960 14.461 99 54 105 0 1418706752 21622944 12092848
23:52:28 1673 8192000000 10325203200 10746003456 1392562416 14.529 100 69 138 1 1431232032 22207952 16461664
23:53:36 3041 8192000000 10310022768 10715398144 1367726704 14.307 100 79 158 0 1414482608 27918128 18837776
23:54:44 2161 8192000000 10279142144 10698395648 1352884736 14.173 100 62 121 0 1392727184 23575552 16266896
23:55:52 5368 8192000000 10289623856 10700263424 1328303936 13.952 98 55 108 0 1373408416 32154896 12949584
23:57:02 2743 8192000000 10316141280 10719813632 1352769936 14.172 99 84 166 1 1407271680 34697376 19804368
23:58:11 2210 8192000000 10319071936 10721095680 1361681488 14.252 99 82 164 0 1420743376 39025040 20036848
23:59:15 27373 8192000000 10294242208 10708361216 1358621952 14.225 99 37 74 2 1396380896 28470112 9288832
00:00:49 15467 8192000000 10339833728 10760843264 1394373344 14.545 100 50 99 0 1446301632 39906080 12022208
00:02:09 6896 8192000000 10332833024 10752417792 1389936784 14.505 100 51 102 1 1444273760 41642384 12694592
00:03:25 15294 8192000000 10330749984 10761596928 1392850256 14.531 100 28 56 0 1443253712 42620672 7782784
00:04:46 8415 8192000000 10349025328 10770718720 1394841280 14.549 100 43 84 3 1456606528 50624256 11140992
00:06:04 16304 8192000000 10346894640 10777477120 1387611024 14.485 99 36 73 0 1446431456 48424592 10395840
00:07:28 50578 8192000000 10344630592 10776702976 1402439168 14.617 100 24 48 0 1451286736 42355184 6492384
00:09:20 30835 8192000000 10346860416 10792120320 1387285200 14.482 99 33 66 0 1461597040 64956800 9355040
00:10:52 18405 8192000000 10324861136 10761531392 1361800432 14.254 98 30 60 0 1424939776 54342400 8796944
00:12:11 75766 8192000000 10301231440 10734649344 1352252688 14.168 98 17 36 0 1389986608 32984224 4749696
00:14:29 61299 8192000000 10315844544 10760675328 1378426944 14.402 100 34 69 1 1413540176 27383632 7729600
00:16:40 49668 8192000000 10281242880 10736295936 1376573824 14.386 100 15 28 0 1392094560 12309200 3211536
00:18:30 167134 8192000000 10315172064 10760712192 1370074224 14.328 98 31 60 0 1392159008 15380096 6704688
00:22:24 67460 8192000000 10302567072 10759188480 1393904992 14.541 100 10 20 0 1411664624 15753408 2006224
00:24:35 65690 8192000000 10299725680 10764468224 1392831712 14.531 100 10 20 0 1411548784 16217648 2499424
00:27:26 78878 8192000000 10291307344 10766020608 1378965232 14.407 98 14 28 0 1396278960 13333152 3980576
00:29:45 42592 8192000000 10304765920 10752016384 1392154464 14.525 100 11 22 0 1409549088 14649648 2744976
00:31:30 63252 8192000000 10298454512 10753548288 1367001872 14.300 98 16 32 2 1382760848 12104736 3654240
00:33:34 75264 8192000000 10295062640 10757971968 1397038624 14.569 100 18 37 0 1416102960 14624816 4439520
00:36:09 87619 8192000000 10293345504 10745757696 1333661232 14.000 95 20 40 0 1356136528 17502480 4972816
00:38:47 70465 8192000000 10267164208 10721906688 1320528000 13.881 95 19 36 0 1351044352 25647280 4869072
00:41:06 63780 8192000000 10266766096 10721222656 1340510016 14.062 98 16 32 0 1371800320 27244272 4046032
00:43:23 71840 8192000000 10266329552 10731827200 1345607456 14.108 98 30 57 1 1367692240 15601072 6483712
00:45:36 93691 8192000000 10278261088 10728095744 1327580928 13.945 97 27 55 0 1342110304 9199776 5329600
00:48:10 21186 8192000000 10275483584 10713391104 1339937472 14.057 99 31 62 0 1366626224 19923632 6765120
00:49:32 44318 8192000000 10269358160 10714664960 1318297488 13.861 97 26 52 0 1340679920 16288672 6093760
00:51:24 86740 8192000000 10285397088 10758627328 1347646272 14.126 98 16 32 0 1375890736 24574192 3670272
00:53:51 44870 8192000000 10293168576 10737201152 1377360256 14.393 100 11 22 0 1389124400 9371728 2392416
00:55:46 54048 8192000000 10275402944 10718339072 1315400064 13.835 96 19 38 0 1328843424 9654048 3789312
00:57:40 112875 8192000000 10282584416 10723196928 1366249920 14.293 100 27 54 0 1385691872 12965648 6476304
01:00:34 62426 8192000000 10265957216 10710335488 1341535808 14.071 98 29 58 0 1358742048 10513984 6692256
01:02:39 64260 8192000000 10272111648 10720190464 1344497552 14.098 98 19 38 0 1361965936 13059984 4408400
01:04:54 55799 8192000000 10286576096 10742018048 1377940768 14.398 100 21 42 0 1399843056 16463856 5438432
01:06:54 40416 8192000000 10281696368 10732941312 1381718960 14.432 100 6 12 0 1392496208 9200224 1577024
01:09:00 55193 8192000000 10281784864 10747084800 1365153408 14.284 98 10 20 0 1374627520 6979568 2494544
01:10:56 15509 8192000000 10301384464 10746408960 1393981872 14.541 100 50 100 0 1414780896 9091024 11708000
01:12:13 35192 8192000000 10321470944 10766622720 1402616960 14.618 100 28 56 0 1419206336 9998544 6590832
01:13:48 148093 8192000000 10319708576 10789433344 1382208672 14.436 98 11 22 1 1405715696 21111824 2395200
01:17:17 27167 8192000000 10330548336 10784149504 1413250192 14.713 100 15 29 0 1433054032 16471488 3332352
01:18:56 81487 8192000000 10316962912 10774802432 1386443472 14.474 98 20 40 0 1408444960 17330944 4670544
01:21:22 90045 8192000000 10291139696 10746232832 1352582192 14.171 96 8 16 0 1368821312 14102096 2137024
01:24:49 75365 8192000000 10275589216 10723450880 1314364656 13.826 95 31 60 1 1335757504 14643552 6749296
01:27:20 50562 8192000000 10243282784 10705100800 1326956880 13.940 98 6 12 0 1340182032 11910176 1314976
01:29:14 49898 8192000000 10271259328 10720796672 1352840368 14.173 99 16 33 0 1365135888 8793776 3501744
01:31:09 55139 8192000000 10254181808 10703396864 1341218944 14.068 99 18 36 0 1358859584 13770096 3870544
01:33:31 65505 8192000000 10270067952 10723463168 1328946608 13.958 97 26 44 0 1342733488 8934528 4852352
01:35:36 42252 8192000000 10258352432 10699378688 1284020384 13.550 94 16 32 0 1303249216 15779184 3449648
01:37:21 56716 8192000000 10252014080 10697056256 1289303264 13.598 95 14 28 0 1305711728 12710624 3697840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-02-2023 01:40:02 to 25-02-2023 01:40:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 160 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

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

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          242665
MON$OLDEST_ACTIVE               244259
MON$OLDEST_SNAPSHOT             244259
MON$NEXT_TRANSACTION            244259
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                       2047264
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:569: 
:570: mscs-test-161.budzdorov.ru    Sat Feb 25 01:38:47 2023
:571:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:572: 
:573: 
:574: mscs-test-161.budzdorov.ru    Sat Feb 25 01:38:50 2023
:575:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:576: 
:577: 
:578: mscs-test-161.budzdorov.ru    Sat Feb 25 01:38:51 2023
:579:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:580: 
--- end of diff output ---

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

25.02.23 01:40:22. 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-25 01:40:24 No severe PSQL-related problems occured
25.02.23 01:40:26. End of report.