Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.10.2022 16:42
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 15681588 kB
MemAvailable 30512724 kB
Buffers 0 kB
Cached 16070376 kB
SwapCached 1896 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-20 16:41:17
DTS_END                         2022-10-20 16:41:17.0560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47992
TRN_ID                          231037
ATT_ID                          649
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9095.44 27990 1091453
customer order: creation 162.82 345 19538
customer order: refuse 14.08 373 1690
order to supplier: creation 44.49 1194 5339
order to supplier: removal 3.60 90238 432
invoice (draft): creation 44.12 3376 5294
invoice (draft): removal 52.57 761 6308
invoice accept: apply 40.94 18082 4913
invoice accept: cancel 47.23 709 5668
customer reserve: creation 3785.65 180 454278
customer reserve: removal 4618.60 54 554232
realization accept: apply 79.61 198 9553
realization accept: cancel 78.50 134 9420
payment from customer: creation 56.24 127 6749
payment from customer: removal 5.58 107 669
payment to supplier: creation 46.84 158 5621
payment to supplier: removal 2.93 114 351
service: total inventory turnovers 4.32 1483 518
service: total monetary turnovers 6.49 29 779
service: aggregate perf. data 0.81 18316 97
service: refresh index statistics 0.03 423821 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-10-2022 16:43:03 to 20-10-2022 16:43:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3432
TEST_TIME 2 2909
TEST_TIME 3 2203
TEST_TIME 4 2318
TEST_TIME 5 2653
TEST_TIME 6 3278
TEST_TIME 7 3764
TEST_TIME 8 4709
TEST_TIME 9 5539
TEST_TIME 10 5954
TEST_TIME 11 6379
TEST_TIME 12 6971
TEST_TIME 13 7460
TEST_TIME 14 7663
TEST_TIME 15 7657
TEST_TIME 16 8086
TEST_TIME 17 8137
TEST_TIME 18 8692
TEST_TIME 19 8669
TEST_TIME 20 9035
TEST_TIME 21 8973
TEST_TIME 22 9197
TEST_TIME 23 9428
TEST_TIME 24 9905
TEST_TIME 25 10131
TEST_TIME 26 9986
TEST_TIME 27 10187
TEST_TIME 28 10872
TEST_TIME 29 10644
TEST_TIME 30 10875
TEST_TIME 31 10577
TEST_TIME 32 10392
TEST_TIME 33 10214
TEST_TIME 34 10086
TEST_TIME 35 10064
TEST_TIME 36 10109
TEST_TIME 37 9917
TEST_TIME 38 10119
TEST_TIME 39 10027
TEST_TIME 40 9875
TEST_TIME 41 9790
TEST_TIME 42 9615
TEST_TIME 43 9464
TEST_TIME 44 9641
TEST_TIME 45 9647
TEST_TIME 46 9619
TEST_TIME 47 9482
TEST_TIME 48 9511
TEST_TIME 49 9366
TEST_TIME 50 9430
TEST_TIME 51 9321
TEST_TIME 52 9226
TEST_TIME 53 9300
TEST_TIME 54 9273
TEST_TIME 55 9335
TEST_TIME 56 9269
TEST_TIME 57 9323
TEST_TIME 58 9301
TEST_TIME 59 9348
TEST_TIME 60 9332
TEST_TIME 61 9379
TEST_TIME 62 9451
TEST_TIME 63 9448
TEST_TIME 64 9424
TEST_TIME 65 9479
TEST_TIME 66 9459
TEST_TIME 67 9545
TEST_TIME 68 9420
TEST_TIME 69 9308
TEST_TIME 70 9286
TEST_TIME 71 9302
TEST_TIME 72 9208
TEST_TIME 73 9270
TEST_TIME 74 9275
TEST_TIME 75 9248
TEST_TIME 76 9193
TEST_TIME 77 9224
TEST_TIME 78 9173
TEST_TIME 79 9211
TEST_TIME 80 9252
TEST_TIME 81 9262
TEST_TIME 82 9244
TEST_TIME 83 9255
TEST_TIME 84 9331
TEST_TIME 85 9310
TEST_TIME 86 9313
TEST_TIME 87 9335
TEST_TIME 88 9351
TEST_TIME 89 9298
TEST_TIME 90 9226
TEST_TIME 91 9260
TEST_TIME 92 9207
TEST_TIME 93 9199
TEST_TIME 94 9147
TEST_TIME 95 9116
TEST_TIME 96 9093
TEST_TIME 97 9129
TEST_TIME 98 9190
TEST_TIME 99 9157
TEST_TIME 100 9231
TEST_TIME 101 9215
TEST_TIME 102 9257
TEST_TIME 103 9260
TEST_TIME 104 9284
TEST_TIME 105 9269
TEST_TIME 106 9303
TEST_TIME 107 9277
TEST_TIME 108 9316
TEST_TIME 109 9371
TEST_TIME 110 9350
TEST_TIME 111 9282
TEST_TIME 112 9289
TEST_TIME 113 9222
TEST_TIME 114 9244
TEST_TIME 115 9213
TEST_TIME 116 9179
TEST_TIME 117 9156
TEST_TIME 118 9157
TEST_TIME 119 9169
TEST_TIME 120 9098

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-10-2022 16:43:07 to 20-10-2022 16:43:10.

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 1510576 1510576 0 0.00 0 34503 6 0 0
doc_list_biud 1510576 1510576 0 0.00 0 41857 26 0 0
sp_add_invoice_to_stock 5009 4913 96 1.91 48 189039 18083 0 96
sp_cancel_adding_invoice 5668 5668 0 0.00 5 73222 709 0 0
sp_cancel_client_order 1690 1690 0 0.00 4 16863 373 0 0
sp_cancel_customer_reserve 554232 554232 0 0.00 0 41858 55 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 2 13156 108 0 0
sp_cancel_pay_to_supplier 351 351 0 0.00 3 6771 114 0 0
sp_cancel_supplier_invoice 6318 6308 10 0.15 2 21570 761 0 10
sp_cancel_supplier_order 432 432 0 0.00 9 851513 90239 0 0
sp_cancel_write_off 9420 9420 0 0.00 1 25047 134 0 0
sp_client_order 19538 19538 0 0.00 3 22762 345 0 0
sp_customer_reserve 454278 454278 0 0.00 0 46166 180 0 0
sp_fill_shopping_cart 30170 30170 0 0.00 1 22322 44 0 0
sp_fill_shopping_cart_clo_res 454276 454276 0 0.00 0 26133 17 0 0
sp_get_clo_for_invoice 4911 4911 0 0.00 0 22409 331 0 0
sp_kill_cost_storno 16108 16108 0 0.00 0 13562 59 0 0
sp_kill_qstorno_ret_qs2qd 1124624 1124624 0 0.00 0 41848 30 0 0
sp_kill_qty_storno 592214 592214 0 0.00 0 41857 65 0 0
sp_lock_selected_doc 19924 19924 0 0.00 0 8274 5 0 0
sp_make_cost_storno 26834 26834 0 0.00 0 34467 63 0 0
sp_multiply_rows_for_pdistr 26834 26834 0 0.00 0 13422 32 0 0
sp_multiply_rows_for_qdistr 19538 19538 0 0.00 0 12549 156 0 0
sp_pay_from_customer 6749 6749 0 0.00 4 16948 128 0 0
sp_pay_to_supplier 5621 5621 0 0.00 4 34519 158 0 0
sp_payment_common 12370 12370 0 0.00 2 16392 40 0 0
sp_qd_handle_on_cancel_clo 1690 1690 0 0.00 0 16849 334 0 0
sp_qd_handle_on_invoice_upd_sts 10579 10579 0 0.00 0 19058 311 0 0
sp_qd_handle_on_reserve_upd_sts 9553 9553 0 0.00 0 18727 62 0 0
sp_reserve_write_off 9553 9553 0 0.00 6 30206 198 0 0
sp_supplier_invoice 5294 5294 0 0.00 5 98046 3377 0 0
sp_supplier_order 5339 5339 0 0.00 5 55569 1194 0 0
srv_aggregate_perf_data 97 97 0 0.00 2631 268992 18316 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1276 124554 47982 0 0
srv_find_qd_qs_mism 1044701 1044701 0 0.00 0 22586 5 0 0
srv_make_invnt_saldo 518 518 0 0.00 50 67698 1484 0 0
srv_make_money_saldo 779 779 0 0.00 1 4333 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 235403 510006 423821 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 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 4915 4915 0 0.00 0 20858 195 0 0
t$perf-norm:sp_cancel_adding_invoice 378 378 0 0.00 0 9414 132 0 0
t$perf-norm:sp_cancel_client_order 1690 1690 0 0.00 0 5883 18 0 0
t$perf-norm:sp_cancel_customer_reserve 639 639 0 0.00 0 6048 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 2120 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 351 351 0 0.00 0 1821 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 1236 15 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 18215 1420 0 0
t$perf-norm:sp_cancel_write_off 908 908 0 0.00 0 3164 13 0 0
t$perf-norm:sp_client_order 19538 19538 0 0.00 0 6155 14 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 10449 26 0 0
t$perf-norm:sp_pay_from_customer 6749 6749 0 0.00 0 6509 11 0 0
t$perf-norm:sp_pay_to_supplier 5621 5621 0 0.00 0 8512 11 0 0
t$perf-norm:sp_reserve_write_off 9553 9553 0 0.00 0 7754 12 0 0
t$perf-norm:sp_supplier_invoice 5294 5294 0 0.00 0 14618 29 0 0
t$perf-norm:sp_supplier_order 5339 5339 0 0.00 0 4118 15 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 730 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 39 1 0 0
t$perf-norm:srv_make_invnt_saldo 518 518 0 0.00 0 1008 22 0 0
t$perf-norm:srv_make_money_saldo 779 779 0 0.00 0 9737 34 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10014 9822 192 1.91 0 6719 20 0 192
v_all_customers 35598 35598 0 0.00 0 262 1 0 0
v_all_suppliers 29418 29418 0 0.00 0 19 1 0 0
v_all_wares 39076 39076 0 0.00 0 100 1 0 0
v_cancel_adding_invoice 756 756 0 0.00 0 500 6 0 0
v_cancel_client_order 3380 3380 0 0.00 0 4891 8 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 597 4 0 0
v_cancel_customer_reserve 1278 1278 0 0.00 0 5202 14 0 0
v_cancel_payment_to_supplier 702 702 0 0.00 0 3424 11 0 0
v_cancel_supplier_invoice 806 786 20 2.48 0 6341 22 0 20
v_cancel_supplier_order 864 864 0 0.00 0 2423 10 0 0
v_cancel_write_off 1816 1816 0 0.00 0 5219 21 0 0
v_max_id_clo_ord 5339 5339 0 0.00 0 5230 4 0 0
v_max_id_clo_res 978 978 0 0.00 0 925 9 0 0
v_max_id_ord_sup 5293 5293 0 0.00 0 6233 8 0 0
v_max_non_paid_invoice 1544 1544 0 0.00 0 3550 6 0 0
v_max_non_paid_realizn 3726 3726 0 0.00 0 3718 6 0 0
v_min_id_clo_ord 5339 5339 0 0.00 0 6375 8 0 0
v_min_id_clo_res 978 978 0 0.00 0 13518 22 0 0
v_min_id_ord_sup 5293 5293 0 0.00 0 6948 9 0 0
v_min_non_paid_invoice 5621 5621 0 0.00 0 15480 32 0 0
v_min_non_paid_realizn 6749 6749 0 0.00 0 13866 29 0 0
v_our_firm 9524 9524 0 0.00 0 22 1 0 0
v_reserve_write_off 19106 19106 0 0.00 0 5391 7 0 0
x_lock_depdocs_on_canc_invoice 11583 11583 0 0.00 0 15132 112 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 6084 321 0 0
x_make_qstorno_1000_1200 5339 5339 0 0.00 1 55560 1114 0 0
x_make_qstorno_1200_2000 5294 5294 0 0.00 2 98038 3210 0 0
x_make_qstorno_2100_3300 454278 454278 0 0.00 0 46166 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-10-2022 16:43:11 to 20-10-2022 16:43:15.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:11:20 1 8192000000 8798053728 8809119744 4656880 0.056 2 1 2 0 4756480 56816 42784
14:12:21 22687 8192000000 9892767168 10202583040 975169280 10.637 100 85 170 0 1003626992 5881312 22576400
14:13:49 22348 8192000000 9948023648 10269818880 1028605520 11.155 100 88 176 1 1059647600 8347536 22694544
14:15:17 11759 8192000000 9986628848 10321293312 1062762064 11.483 100 77 154 0 1091482976 9285072 19435840
14:16:39 8122 8192000000 10032124384 10381090816 1127206928 12.095 100 87 174 0 1159539600 11413344 20919328
14:17:50 6301 8192000000 10079761344 10437550080 1166828864 12.467 100 79 158 0 1197339344 12010192 18500288
14:19:00 4065 8192000000 10113629504 10479263744 1200502080 12.781 100 81 159 0 1231880656 13531472 17847104
14:20:07 2842 8192000000 10151850192 10523951104 1237723488 13.125 100 85 167 1 1269739232 13735184 18280560
14:21:11 1688 8192000000 10174867792 10554273792 1266183152 13.387 100 84 163 1 1295086480 10994816 17908512
14:22:15 1277 8192000000 10205608800 10592374784 1292141600 13.624 100 87 174 0 1324149280 12462928 19544752
14:23:19 3169 8192000000 10200263600 10599665664 1304219776 13.734 100 46 92 0 1321465696 6509088 10736832
14:24:23 7865 8192000000 10199571616 10610200576 1310350096 13.789 100 18 37 0 1320636512 6401888 3884528
14:25:32 5808 8192000000 10206479216 10619875328 1319817104 13.875 100 24 47 0 1330463728 5305056 5341568
14:26:42 11952 8192000000 10209459824 10628669440 1326603808 13.936 100 25 50 0 1339194368 6529840 6060720
14:27:58 67675 8192000000 10245932320 10659770368 1338339408 14.042 100 35 68 2 1351679216 4882896 8456912
14:30:08 8568 8192000000 10240004960 10663436288 1350759680 14.154 100 35 68 0 1365818880 7716784 7342416
14:31:17 7448 8192000000 10229050400 10648477696 1331253392 13.978 99 23 46 0 1341963568 5252256 5457920
14:32:25 60827 8192000000 10132933568 10534789120 1206448368 12.836 91 32 61 1 1218097472 4800016 6849088
14:34:29 6184 8192000000 10139950432 10537140224 1219795440 12.960 96 25 51 0 1231123584 5297824 6030320
14:35:36 9495 8192000000 10135523936 10537439232 1229429808 13.049 98 25 50 0 1240745056 5004928 6310320
14:36:47 8650 8192000000 10159265904 10563940352 1271224560 13.433 100 49 99 1 1289204272 5589424 12390288
14:37:58 25426 8192000000 10167064048 10568970240 1255511168 13.289 98 17 34 0 1264796000 5647440 3637392
14:39:24 10159 8192000000 10174951792 10590494720 1289981296 13.604 100 21 42 0 1300157808 5263472 4913040
14:40:34 11431 8192000000 10191517008 10606755840 1302518208 13.718 100 19 37 2 1313036752 5463712 5054832
14:41:52 43227 8192000000 10237952640 10644045824 1331163920 13.978 100 47 94 0 1349100848 6582112 11354816
14:43:38 103503 8192000000 10260125712 10681401344 1357680992 14.217 100 44 90 0 1382163056 12939024 11543040
14:46:36 27123 8192000000 10276061504 10700320768 1367130544 14.301 100 31 62 0 1392897248 18862784 6903920
14:48:06 22553 8192000000 10290219936 10715455488 1374128000 14.364 100 41 82 0 1405606864 21376304 10102560
14:49:32 8639 8192000000 10295205648 10720673792 1382502112 14.439 100 53 105 1 1414264320 19361024 12401184
14:50:43 3491 8192000000 10331712208 10752802816 1398925776 14.585 100 70 139 0 1441107840 25822912 16359152
14:51:52 2666 8192000000 10339885744 10762842112 1407298352 14.660 100 67 132 1 1449885152 25566352 17020448
14:53:06 1274 8192000000 10331182560 10741956608 1385616832 14.467 98 91 178 0 1433935904 27425248 20893824
14:54:11 5177 8192000000 10337094720 10759401472 1396799376 14.566 99 67 133 0 1434520928 22398368 15323184
14:55:21 4248 8192000000 10346990160 10762579968 1385513296 14.466 98 79 157 0 1428933216 24822640 18597280
14:56:31 2033 8192000000 10342745152 10755936256 1392092080 14.525 100 80 157 1 1448947264 37060320 19794864
14:57:39 3044 8192000000 10366006256 10774401024 1396224736 14.561 99 85 168 0 1453395072 36903984 20266352
14:58:46 3360 8192000000 10353080304 10764730368 1402621408 14.618 100 81 160 2 1461862416 39094304 20146704
14:59:52 13016 8192000000 10330801696 10760130560 1394968624 14.550 100 34 68 0 1450601248 45977408 9655216
15:01:07 6444 8192000000 10330912144 10758344704 1391511856 14.519 100 39 78 0 1440188224 38063664 10612704
15:02:16 16786 8192000000 10333534560 10769760256 1401098848 14.605 100 30 60 1 1441083216 32514560 7469808
15:03:39 3639 8192000000 10339544000 10769055744 1387350496 14.482 100 46 88 0 1444566752 45174672 12041584
15:04:45 25608 8192000000 10321907824 10760216576 1376559792 14.386 100 30 61 0 1428315504 43184592 8571120
15:06:24 45850 8192000000 10304770832 10746916864 1362784048 14.262 99 16 32 0 1406932416 39737632 4410736
15:08:14 12731 8192000000 10329349968 10754797568 1365528896 14.287 100 42 84 1 1423646592 47610720 10506976
15:09:31 22286 8192000000 10261317360 10702344192 1345027376 14.103 99 18 36 0 1364013920 14779776 4206768
15:10:54 46888 8192000000 10255855712 10707611648 1342995936 14.084 99 20 41 0 1360986720 13573808 4416976
15:12:43 113083 8192000000 10267030560 10713317376 1357943792 14.219 99 12 24 0 1371824160 11068928 2811440
15:15:38 90883 8192000000 10289647072 10740293632 1352956272 14.174 98 13 26 0 1367223952 11397152 2870528
15:18:11 81759 8192000000 10273003040 10743525376 1376114656 14.382 99 28 56 0 1396266704 13624848 6527200
15:20:38 76405 8192000000 10288941968 10741157888 1359065712 14.229 98 16 33 0 1377281712 14881296 3334704
15:23:02 67165 8192000000 10275708752 10724589568 1360024560 14.238 98 15 30 0 1379580000 15938096 3617344
15:26:06 35106 8192000000 10281457632 10736586752 1384061760 14.453 100 18 36 0 1397092064 8979856 4050448
15:27:46 47456 8192000000 10288041040 10742116352 1383183760 14.445 99 15 31 1 1394930464 8255296 3491408
15:29:34 68837 8192000000 10293013344 10744180736 1378151248 14.400 99 15 30 0 1392525360 10883232 3490880
15:34:16 29591 8192000000 10296096720 10754797568 1394335008 14.545 100 14 25 0 1410461088 13005248 3120832
15:35:46 90653 8192000000 10314864768 10777710592 1394769280 14.548 99 7 14 1 1407721568 11596912 1355376
15:38:19 60475 8192000000 10320616688 10771529728 1415377600 14.732 100 18 36 0 1431338880 12131376 3829904
15:40:22 29582 8192000000 10319334720 10773336064 1408575824 14.671 100 36 72 0 1430409296 14102944 7730528
15:41:55 40354 8192000000 10313327472 10769985536 1397709120 14.575 99 31 62 1 1414454384 10421408 6323856
15:44:23 42279 8192000000 10292284112 10732548096 1330420496 13.971 95 18 36 0 1342747424 8699168 3627760
15:46:07 38720 8192000000 10304223968 10748674048 1368817008 14.316 98 26 52 0 1384607344 10137600 5652736
15:47:47 10640 8192000000 10290472368 10725527552 1365178496 14.284 99 54 108 0 1387456160 10368656 11909008
15:48:59 124553 8192000000 10304394944 10748579840 1386317424 14.473 100 27 56 0 1413862544 21275088 6270032
15:52:08 94825 8192000000 10290355568 10754342912 1376213328 14.383 99 18 36 0 1398491568 18005840 4272400
15:54:44 44052 8192000000 10278880592 10735116288 1370395920 14.331 99 17 34 0 1381846176 7380064 4070192
15:56:28 48177 8192000000 10275643136 10731192320 1348487008 14.134 97 17 34 0 1359540608 6965200 4088400
15:58:17 55376 8192000000 10242523600 10695024640 1311850080 13.803 96 16 32 0 1327150192 11431312 3868800
16:00:12 82108 8192000000 10264732576 10713210880 1312165776 13.806 96 15 30 0 1332010832 16262768 3582288
16:02:39 33419 8192000000 10244787456 10680508416 1277194992 13.487 94 22 45 0 1289401648 7004080 5202576
16:04:13 52984 8192000000 10253296848 10691551232 1318824976 13.866 98 36 72 0 1338791488 12013344 7953168
16:06:14 99318 8192000000 10262030496 10698088448 1304613312 13.737 96 7 14 0 1320101488 14126336 1361840
16:08:54 46750 8192000000 10252686144 10692972544 1306427168 13.754 97 26 52 0 1323033616 11165488 5440960
16:10:41 117111 8192000000 10261552512 10712907776 1358991136 14.228 100 27 54 0 1378160096 13398912 5770048
16:13:40 69098 8192000000 10257545168 10710069248 1329837360 13.966 97 16 30 1 1345035280 11810384 3387536
16:15:49 33585 8192000000 10258651632 10717085696 1368974864 14.318 100 11 22 0 1377501184 6263392 2262928
16:17:25 57302 8192000000 10290717040 10739257344 1369299632 14.321 99 12 25 0 1382359504 10340352 2719520
16:19:42 74915 8192000000 10285707728 10730696704 1347136960 14.122 97 20 40 1 1367032032 14754528 5140544
16:21:57 59371 8192000000 10313546992 10749353984 1378991568 14.408 99 21 41 0 1392251488 8896752 4363168
16:24:21 71730 8192000000 10291460240 10741776384 1366020432 14.291 98 10 20 0 1382163472 13933232 2209808
16:26:45 71444 8192000000 10283964272 10740969472 1368540384 14.314 99 13 26 0 1384955488 13458528 2956576
16:28:57 47395 8192000000 10297692800 10751549440 1370917984 14.335 99 29 58 1 1403676288 26613936 6144368
16:30:50 92959 8192000000 10281701856 10725658624 1360238672 14.239 98 18 36 0 1377242816 13135904 3868240
16:33:49 59636 8192000000 10267584816 10717102080 1340906496 14.066 97 18 36 0 1352259136 7630192 3722448
16:35:49 46379 8192000000 10248954640 10700713984 1324595040 13.918 97 17 34 0 1335977584 7435456 3947088
16:37:36 84025 8192000000 10279361232 10732949504 1354041536 14.184 98 16 32 0 1370561968 12719520 3800912
16:40:05 61719 8192000000 10276069872 10733617152 1372028592 14.345 100 12 24 0 1389965408 15264272 2672544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-10-2022 16:43:17 to 20-10-2022 16:43:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 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 20-10-2022 16:43:33 to 20-10-2022 16:43:35.

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          230591
MON$OLDEST_ACTIVE               231187
MON$OLDEST_SNAPSHOT             231187
MON$NEXT_TRANSACTION            231187
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                       2006144
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.33636 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33636 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33636 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33636 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Oct 20 16:41:17 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Oct 20 16:41:33 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
--- end of diff output ---

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

20.10.22 16:43:36. 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-10-20 16:43:39 No severe PSQL-related problems occured
20.10.22 16:43:40. End of report.