Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.06.2022 19: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 16359368 kB
MemAvailable 31210028 kB
Buffers 0 kB
Cached 15369828 kB
SwapCached 2500 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.10
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-06-28 19:38:22
DTS_END                         2022-06-28 19:38:22.0130
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/43434
TRN_ID                          242801
ATT_ID                          659
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: 9090.48 24600 1090858
customer order: creation 176.34 341 21161
customer order: refuse 14.72 354 1766
order to supplier: creation 44.74 1127 5369
order to supplier: removal 3.26 94255 391
invoice (draft): creation 45.33 3337 5439
invoice (draft): removal 51.69 723 6203
invoice accept: apply 41.05 17303 4926
invoice accept: cancel 46.46 720 5575
customer reserve: creation 3733.58 173 448029
customer reserve: removal 4640.27 51 556833
realization accept: apply 84.42 199 10130
realization accept: cancel 78.17 102 9380
payment from customer: creation 59.37 126 7124
payment from customer: removal 5.81 114 697
payment to supplier: creation 49.51 151 5941
payment to supplier: removal 3.44 152 413
service: total inventory turnovers 4.69 1089 563
service: total monetary turnovers 6.73 64 807
service: aggregate perf. data 0.89 19375 107
service: refresh index statistics 0.03 352242 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-06-2022 19:39:52 to 28-06-2022 19:39:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4054
TEST_TIME 2 3484
TEST_TIME 3 3149
TEST_TIME 4 2958
TEST_TIME 5 3997
TEST_TIME 6 4446
TEST_TIME 7 5634
TEST_TIME 8 6339
TEST_TIME 9 6601
TEST_TIME 10 7062
TEST_TIME 11 7239
TEST_TIME 12 7360
TEST_TIME 13 7533
TEST_TIME 14 7474
TEST_TIME 15 7704
TEST_TIME 16 7673
TEST_TIME 17 7776
TEST_TIME 18 8134
TEST_TIME 19 8288
TEST_TIME 20 8486
TEST_TIME 21 9264
TEST_TIME 22 9811
TEST_TIME 23 9837
TEST_TIME 24 10467
TEST_TIME 25 10472
TEST_TIME 26 10645
TEST_TIME 27 10640
TEST_TIME 28 10530
TEST_TIME 29 10491
TEST_TIME 30 10314
TEST_TIME 31 10103
TEST_TIME 32 10220
TEST_TIME 33 10026
TEST_TIME 34 9833
TEST_TIME 35 9673
TEST_TIME 36 9512
TEST_TIME 37 9502
TEST_TIME 38 9389
TEST_TIME 39 9417
TEST_TIME 40 9334
TEST_TIME 41 9442
TEST_TIME 42 9375
TEST_TIME 43 9394
TEST_TIME 44 9367
TEST_TIME 45 9251
TEST_TIME 46 9118
TEST_TIME 47 9012
TEST_TIME 48 8942
TEST_TIME 49 9014
TEST_TIME 50 9016
TEST_TIME 51 9107
TEST_TIME 52 9151
TEST_TIME 53 9265
TEST_TIME 54 9198
TEST_TIME 55 9185
TEST_TIME 56 9371
TEST_TIME 57 9309
TEST_TIME 58 9306
TEST_TIME 59 9393
TEST_TIME 60 9435
TEST_TIME 61 9519
TEST_TIME 62 9502
TEST_TIME 63 9394
TEST_TIME 64 9284
TEST_TIME 65 9326
TEST_TIME 66 9259
TEST_TIME 67 9183
TEST_TIME 68 9160
TEST_TIME 69 9252
TEST_TIME 70 9234
TEST_TIME 71 9202
TEST_TIME 72 9288
TEST_TIME 73 9249
TEST_TIME 74 9301
TEST_TIME 75 9276
TEST_TIME 76 9252
TEST_TIME 77 9215
TEST_TIME 78 9390
TEST_TIME 79 9362
TEST_TIME 80 9411
TEST_TIME 81 9370
TEST_TIME 82 9385
TEST_TIME 83 9290
TEST_TIME 84 9262
TEST_TIME 85 9248
TEST_TIME 86 9214
TEST_TIME 87 9271
TEST_TIME 88 9252
TEST_TIME 89 9227
TEST_TIME 90 9188
TEST_TIME 91 9149
TEST_TIME 92 9087
TEST_TIME 93 9072
TEST_TIME 94 9147
TEST_TIME 95 9124
TEST_TIME 96 9157
TEST_TIME 97 9237
TEST_TIME 98 9193
TEST_TIME 99 9156
TEST_TIME 100 9207
TEST_TIME 101 9344
TEST_TIME 102 9329
TEST_TIME 103 9351
TEST_TIME 104 9349
TEST_TIME 105 9334
TEST_TIME 106 9386
TEST_TIME 107 9391
TEST_TIME 108 9333
TEST_TIME 109 9301
TEST_TIME 110 9267
TEST_TIME 111 9231
TEST_TIME 112 9221
TEST_TIME 113 9175
TEST_TIME 114 9158
TEST_TIME 115 9208
TEST_TIME 116 9169
TEST_TIME 117 9150
TEST_TIME 118 9172
TEST_TIME 119 9159
TEST_TIME 120 9093

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-06-2022 19:39:56 to 28-06-2022 19:39:59.

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 1508388 1508388 0 0.00 0 22153 6 0 0
doc_list_biud 1508385 1508385 0 0.00 0 55647 24 0 0
sp_add_invoice_to_stock 5071 4926 145 2.85 41 184518 17303 0 145
sp_cancel_adding_invoice 5575 5575 0 0.00 2 77454 721 0 0
sp_cancel_client_order 1766 1766 0 0.00 4 13218 354 0 0
sp_cancel_customer_reserve 556833 556833 0 0.00 0 24792 52 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 2 12892 114 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 14299 153 0 0
sp_cancel_supplier_invoice 6213 6203 10 0.16 1 55652 724 0 10
sp_cancel_supplier_order 391 391 0 0.00 5 1060971 94256 0 0
sp_cancel_write_off 9380 9380 0 0.00 1 9069 102 0 0
sp_client_order 21161 21161 0 0.00 3 18647 342 0 0
sp_customer_reserve 448029 448029 0 0.00 0 40283 174 0 0
sp_fill_shopping_cart 31966 31966 0 0.00 1 16712 41 0 0
sp_fill_shopping_cart_clo_res 448022 448022 0 0.00 0 37681 15 0 0
sp_get_clo_for_invoice 4919 4919 0 0.00 0 39978 323 0 0
sp_kill_cost_storno 16065 16065 0 0.00 0 14283 52 0 0
sp_kill_qstorno_ret_qs2qd 1129640 1129640 0 0.00 0 55644 28 0 0
sp_kill_qty_storno 595195 595195 0 0.00 0 55646 61 0 0
sp_lock_selected_doc 20820 20820 0 0.00 0 8973 7 0 0
sp_make_cost_storno 28112 28112 0 0.00 0 16987 56 0 0
sp_multiply_rows_for_pdistr 28112 28112 0 0.00 0 14195 32 0 0
sp_multiply_rows_for_qdistr 21161 21161 0 0.00 0 14424 164 0 0
sp_pay_from_customer 7124 7124 0 0.00 4 17283 127 0 0
sp_pay_to_supplier 5941 5941 0 0.00 4 22735 152 0 0
sp_payment_common 13065 13065 0 0.00 1 13994 35 0 0
sp_qd_handle_on_cancel_clo 1766 1766 0 0.00 0 13206 321 0 0
sp_qd_handle_on_invoice_upd_sts 10492 10492 0 0.00 0 40828 309 0 0
sp_qd_handle_on_reserve_upd_sts 10130 10130 0 0.00 0 19513 64 0 0
sp_reserve_write_off 10130 10130 0 0.00 6 22174 199 0 0
sp_supplier_invoice 5439 5439 0 0.00 6 105109 3337 0 0
sp_supplier_order 5369 5369 0 0.00 4 27417 1128 0 0
srv_aggregate_perf_data 107 107 0 0.00 2379 492351 19376 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1611 136079 44878 0 0
srv_find_qd_qs_mism 1044706 1044706 0 0.00 0 13787 5 0 0
srv_make_invnt_saldo 563 563 0 0.00 35 59759 1089 0 0
srv_make_money_saldo 807 807 0 0.00 0 26087 64 0 0
srv_recalc_idx_stat 4 4 0 0.00 193509 431776 352243 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4926 4926 0 0.00 0 21350 178 0 0
t$perf-norm:sp_cancel_adding_invoice 375 375 0 0.00 0 21848 158 0 0
t$perf-norm:sp_cancel_client_order 1766 1766 0 0.00 0 2209 12 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 3267 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 5520 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 1497 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 3915 26 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 26146 1475 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 1086 4 0 0
t$perf-norm:sp_client_order 21161 21161 0 0.00 0 15188 16 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 1741 7 0 0
t$perf-norm:sp_pay_from_customer 7124 7124 0 0.00 0 15196 11 0 0
t$perf-norm:sp_pay_to_supplier 5941 5941 0 0.00 0 11635 11 0 0
t$perf-norm:sp_reserve_write_off 10130 10130 0 0.00 0 15164 12 0 0
t$perf-norm:sp_supplier_invoice 5439 5439 0 0.00 0 4717 15 0 0
t$perf-norm:sp_supplier_order 5369 5369 0 0.00 0 5179 16 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 405 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 988 23 0 0
t$perf-norm:srv_make_invnt_saldo 563 563 0 0.00 0 1133 19 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 819 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10124 9834 290 2.86 0 5537 20 0 290
v_all_customers 37966 37966 0 0.00 0 85 1 0 0
v_all_suppliers 30080 30080 0 0.00 0 84 1 0 0
v_all_wares 42322 42322 0 0.00 0 124 1 0 0
v_cancel_adding_invoice 750 750 0 0.00 0 584 6 0 0
v_cancel_client_order 3532 3532 0 0.00 0 7618 8 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 6945 11 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 4288 17 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 13234 25 0 0
v_cancel_supplier_invoice 830 810 20 2.40 0 1319 15 0 20
v_cancel_supplier_order 782 782 0 0.00 0 2025 5 0 0
v_cancel_write_off 2062 2062 0 0.00 0 3070 16 0 0
v_max_id_clo_ord 5368 5368 0 0.00 0 1050 2 0 0
v_max_id_clo_res 997 997 0 0.00 0 2379 14 0 0
v_max_id_ord_sup 5435 5435 0 0.00 0 6570 9 0 0
v_max_non_paid_invoice 1704 1704 0 0.00 0 2479 4 0 0
v_max_non_paid_realizn 4019 4019 0 0.00 0 6892 7 0 0
v_min_id_clo_ord 5368 5368 0 0.00 0 10469 12 0 0
v_min_id_clo_res 997 997 0 0.00 0 6970 17 0 0
v_min_id_ord_sup 5435 5435 0 0.00 0 5024 7 0 0
v_min_non_paid_invoice 5941 5941 0 0.00 0 13984 28 0 0
v_min_non_paid_realizn 7124 7124 0 0.00 0 12460 25 0 0
v_our_firm 10566 10566 0 0.00 0 98 1 0 0
v_reserve_write_off 20260 20260 0 0.00 0 13232 9 0 0
x_lock_depdocs_on_canc_invoice 11373 11373 0 0.00 0 13323 128 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 7944 328 0 0
x_make_qstorno_1000_1200 5369 5369 0 0.00 1 27395 1040 0 0
x_make_qstorno_1200_2000 5439 5439 0 0.00 2 104897 3189 0 0
x_make_qstorno_2100_3300 448029 448029 0 0.00 0 39798 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-06-2022 19:40:00 to 28-06-2022 19:40:04.

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:25 2 8192000000 8799208752 8810692608 5605280 0.068 2 1 2 0 5704912 56848 42784
17:09:27 24807 8192000000 9883220128 10185125888 963850992 10.527 100 88 176 0 994028880 5966800 24211088
17:10:55 17520 8192000000 9938924752 10259087360 1020020864 11.072 100 85 170 0 1050602064 7919344 22661856
17:12:17 14910 8192000000 9979696352 10309480448 1061008176 11.466 100 85 170 1 1092272880 9906992 21357712
17:13:42 10483 8192000000 10029718256 10375553024 1109870832 11.931 100 85 170 0 1141251296 10947552 20432912
17:15:01 4235 8192000000 10077123808 10434633728 1164961024 12.450 100 83 166 2 1197148304 12911376 19275904
17:16:09 4035 8192000000 10121326912 10486657024 1204035696 12.814 100 75 148 0 1234069312 13109872 16923744
17:17:15 3293 8192000000 10146189856 10527268864 1239283584 13.140 100 73 145 0 1268063152 12961104 15818464
17:18:21 1685 8192000000 10174402608 10557571072 1269766000 13.419 100 73 144 0 1297722544 12094128 15862416
17:19:25 1791 8192000000 10204323472 10587164672 1289726992 13.602 100 90 177 0 1321685632 12967344 18991296
17:20:29 1747 8192000000 10219510272 10607190016 1302151232 13.715 100 85 170 1 1334597280 12962656 19483392
17:21:32 3080 8192000000 10198728768 10603913216 1304869280 13.739 100 48 98 1 1323880896 8045872 10965744
17:22:37 8322 8192000000 10199314656 10616541184 1314487712 13.827 100 24 48 1 1325827792 6385488 4954592
17:23:50 7724 8192000000 10214258624 10631729152 1326864816 13.939 100 23 45 0 1337909648 6017536 5027296
17:24:58 59812 8192000000 10234537424 10645393408 1332702032 13.992 100 37 74 2 1346650800 4755344 9193424
17:26:59 6819 8192000000 10225421072 10650435584 1339913328 14.057 100 19 38 0 1349671072 5450128 4307616
17:28:06 8411 8192000000 10220219280 10638442496 1325321216 13.925 100 10 16 1 1332125504 5119856 1684432
17:29:16 9432 8192000000 10160631360 10572046336 1233725920 13.088 96 18 37 0 1243081952 5269552 4086480
17:30:29 31597 8192000000 10178669104 10574311424 1272670128 13.446 100 44 86 1 1287289840 4268496 10351216
17:32:08 6196 8192000000 10153016416 10550931456 1234206160 13.093 98 28 56 0 1247453520 6818032 6429328
17:33:17 9647 8192000000 10161002448 10561798144 1254455648 13.279 99 26 52 0 1265891264 5534656 5900960
17:34:28 73390 8192000000 10177302256 10593980416 1279642048 13.510 99 26 52 1 1291061456 5501152 5918256
17:36:42 5396 8192000000 10206250112 10618490880 1309639584 13.783 100 31 62 0 1323422880 6976576 6806720
17:37:49 9954 8192000000 10208181072 10627190784 1318689728 13.865 100 17 32 0 1327853232 5545488 3618016
17:39:02 23303 8192000000 10255986080 10671026176 1355920000 14.201 100 46 89 1 1373234608 7307760 10006848
17:41:06 99579 8192000000 10285645680 10708312064 1380650816 14.422 100 37 74 2 1400493136 11149968 8692352
17:43:53 13293 8192000000 10296087824 10718908416 1389889872 14.505 100 32 64 0 1410591392 12954512 7747008
17:45:11 16069 8192000000 10304914240 10733080576 1392584160 14.529 100 43 80 1 1419983824 17947296 9452368
17:46:30 7669 8192000000 10328261728 10748788736 1402740384 14.619 100 74 144 3 1438703072 19441424 16521264
17:47:42 6749 8192000000 10323467424 10751729664 1396118208 14.560 99 43 86 0 1429954016 23492960 10342848
17:48:54 3018 8192000000 10342093120 10766729216 1418248000 14.757 100 64 128 0 1452335008 18263472 15823536
17:50:01 1866 8192000000 10344675392 10762395648 1410711584 14.690 100 77 153 2 1450122976 20821360 18590032
17:51:06 4620 8192000000 10341247680 10756161536 1388910432 14.496 99 81 162 1 1433227984 25006480 19311072
17:52:19 3719 8192000000 10299218448 10706804736 1360048096 14.238 99 70 136 2 1405771072 28987648 16735328
17:53:29 4827 8192000000 10323806496 10740334592 1386241056 14.472 100 63 122 2 1429879840 27896224 15742560
17:54:41 1611 8192000000 10330373216 10729865216 1373439280 14.358 100 94 185 1 1431806432 35168608 23198544
17:55:49 3490 8192000000 10322517840 10727284736 1367513504 14.305 100 74 146 0 1426726848 40286432 18926912
17:56:57 2603 8192000000 10313674768 10710306816 1342181984 14.077 99 84 165 0 1401330848 38582688 20566176
17:58:02 8844 8192000000 10315885584 10731315200 1361332384 14.249 100 48 94 0 1420958288 46403712 13222192
17:59:18 21758 8192000000 10284142096 10717171712 1335459104 14.016 99 33 66 2 1388383280 43777248 9146928
18:00:47 18463 8192000000 10283235008 10713260032 1345488688 14.107 99 21 42 0 1378617376 28026000 5102688
18:02:07 35201 8192000000 10287211152 10723233792 1347700464 14.127 99 28 56 0 1397416384 42008800 7707120
18:03:49 4119 8192000000 10298355856 10728112128 1371046080 14.336 100 41 81 1 1401660688 21204400 9410208
18:04:56 20115 8192000000 10261648336 10706919424 1349844368 14.146 99 20 39 0 1372669968 18101824 4723776
18:06:20 59710 8192000000 10275653328 10721566720 1366691808 14.297 100 16 32 0 1391038464 20330064 4016592
18:08:24 72609 8192000000 10261286912 10697551872 1335395136 14.016 97 23 47 0 1353474848 13500544 4579168
18:10:36 96592 8192000000 10266370160 10711367680 1358339840 14.222 99 19 37 2 1378614512 15919808 4354864
18:13:22 57364 8192000000 10295439328 10730115072 1369061904 14.319 99 33 66 0 1386654816 10841808 6751104
18:15:21 102243 8192000000 10286058528 10743468032 1380161072 14.418 100 13 26 0 1404763840 21299264 3303504
18:18:04 27110 8192000000 10286011664 10733117440 1382441808 14.438 100 15 30 0 1400148784 13881104 3825872
18:19:41 18902 8192000000 10296756064 10742521856 1389229904 14.499 100 47 91 2 1410540208 10641456 10668848
18:21:05 42032 8192000000 10278619456 10733621248 1364563232 14.278 98 20 40 1 1378458704 9364336 4531136
18:22:51 42241 8192000000 10274981312 10734219264 1372163584 14.346 99 8 14 0 1382447600 9032640 1251376
18:24:33 54388 8192000000 10279245648 10738024448 1389642880 14.503 100 9 17 0 1397396208 5936224 1817104
18:26:28 59813 8192000000 10306500784 10763268096 1379882336 14.415 98 20 40 1 1398755088 14076448 4796304
18:28:46 65611 8192000000 10302529520 10764836864 1362259264 14.258 97 12 24 0 1381429440 16453968 2716208
18:30:56 59375 8192000000 10302233024 10754879488 1389295040 14.500 99 14 28 0 1408221808 15742672 3184096
18:32:59 50300 8192000000 10310390800 10761080832 1387722224 14.486 99 36 72 0 1411132752 15258288 8152240
18:34:55 67931 8192000000 10289897184 10732339200 1287246464 13.579 92 20 40 0 1302041248 10152000 4642784
18:37:09 84202 8192000000 10266529280 10719547392 1335571808 14.017 97 18 36 0 1352673392 13065440 4036144
18:39:34 45703 8192000000 10271531600 10731331584 1319786000 13.875 95 16 33 0 1335007296 11804944 3416352
18:41:20 58822 8192000000 10279037904 10733658112 1373496176 14.358 100 12 24 0 1389840512 13692352 2651984
18:43:40 46385 8192000000 10253150656 10700029952 1337751840 14.037 98 15 30 0 1349833088 9013280 3067968
18:45:26 53601 8192000000 10283796960 10724233216 1358452336 14.223 99 15 30 0 1372180592 9383968 4344288
18:47:22 64138 8192000000 10251906672 10701139968 1286861056 13.576 94 11 22 0 1297682672 8276336 2545280
18:49:28 51879 8192000000 10261990944 10706550784 1317982928 13.858 97 25 50 0 1340444064 16602704 5858432
18:52:17 57996 8192000000 10251394000 10695790592 1301449824 13.708 96 20 39 2 1315429440 8444352 5535264
18:54:18 46292 8192000000 10289083744 10740776960 1369063440 14.319 100 31 62 0 1399736448 23884880 6788128
18:56:49 65213 8192000000 10260596064 10702999552 1301711360 13.711 95 14 28 0 1313690608 8781872 3197376
18:59:02 136079 8192000000 10259272384 10709876736 1343938576 14.093 98 13 26 0 1359573136 12829600 2804960
19:02:38 65713 8192000000 10267949616 10734592000 1353352736 14.178 99 6 12 0 1376317360 21758816 1205808
19:04:44 85130 8192000000 10274518208 10751074304 1365266896 14.285 99 15 30 1 1383289696 14672576 3350224
19:07:14 43037 8192000000 10279420224 10729734144 1378817792 14.406 100 23 46 0 1390544112 6868432 4857888
19:08:57 37727 8192000000 10282298480 10734301184 1372758112 14.352 99 12 24 0 1383002304 7523808 2720384
19:10:40 56718 8192000000 10281466464 10746880000 1387292576 14.482 100 22 44 0 1407522880 15674768 4555536
19:12:50 37319 8192000000 10275653280 10730942464 1369622352 14.324 99 16 32 0 1383254000 10353328 3278320
19:14:28 81274 8192000000 10285685872 10751176704 1356196736 14.203 97 17 32 1 1374962080 15046640 3718704
19:16:51 29277 8192000000 10310730032 10755645440 1382561888 14.439 99 28 56 0 1402242912 12775760 6905264
19:18:24 54907 8192000000 10292719680 10754035712 1372035376 14.345 99 11 22 0 1396696832 21807376 2854080
19:20:25 33071 8192000000 10286871152 10718240768 1316491280 13.845 95 18 36 0 1329411584 8170880 4749424
19:21:58 27524 8192000000 10292266000 10737725440 1361335504 14.249 99 31 62 0 1382812592 14623392 6853696
19:23:26 9130 8192000000 10286636080 10717757440 1353335568 14.177 99 37 74 0 1370684768 9529376 7819824
19:24:38 109236 8192000000 10277428768 10734141440 1318369472 13.862 95 21 40 1 1337535312 14779552 4386288
19:27:56 74778 8192000000 10275654064 10729746432 1357599296 14.216 98 24 48 0 1371002912 7883424 5520192
19:30:21 57740 8192000000 10288888000 10756059136 1391904544 14.523 100 7 14 0 1406833536 13234848 1694144
19:33:21 55593 8192000000 10282818144 10746736640 1357162912 14.212 97 12 25 0 1369743264 9342704 3237648
19:35:18 98405 8192000000 10283379744 10743570432 1326620624 13.937 95 13 26 0 1336492256 7117440 2754192
19:38:09 28086 8192000000 9073534432 9300557824 239506288 2.840 18 1 2 0 244426880 4878240 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-06-2022 19:40:06 to 28-06-2022 19:40:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-06-2022 19:40:22 to 28-06-2022 19:40:24.

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          243419
MON$OLDEST_ACTIVE               243420
MON$OLDEST_SNAPSHOT             243420
MON$NEXT_TRANSACTION            243420
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                       2015808
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33605 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33605 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33605 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33605 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 ---
:19: 
:20: mscs-test-161.budzdorov.ru    Tue Jun 28 19:38:44 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
--- end of diff output ---

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

28.06.22 19:40:25. 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-06-28 19:40:27 No severe PSQL-related problems occured
28.06.22 19:40:28. End of report.