Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.10.2022 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 15440276 kB
MemAvailable 30489100 kB
Buffers 0 kB
Cached 16259168 kB
SwapCached 2184 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-15 01:38:33
DTS_END                         2022-10-15 01:38:33.8050
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/46952
TRN_ID                          235847
ATT_ID                          672
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: 9180.81 23124 1101697
customer order: creation 169.70 373 20364
customer order: refuse 14.44 380 1733
order to supplier: creation 43.90 1287 5268
order to supplier: removal 3.73 98591 448
invoice (draft): creation 45.41 3584 5449
invoice (draft): removal 55.48 804 6657
invoice accept: apply 39.19 17472 4703
invoice accept: cancel 50.05 798 6006
customer reserve: creation 3623.62 173 434834
customer reserve: removal 4839.59 59 580751
realization accept: apply 80.21 195 9625
realization accept: cancel 89.72 110 10766
payment from customer: creation 58.01 117 6961
payment from customer: removal 5.93 76 712
payment to supplier: creation 46.74 133 5609
payment to supplier: removal 3.28 122 394
service: total inventory turnovers 4.63 1238 556
service: total monetary turnovers 6.32 36 758
service: aggregate perf. data 0.83 16804 99
service: refresh index statistics 0.03 320126 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-10-2022 01:39:46 to 15-10-2022 01:39:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7155
TEST_TIME 2 6233
TEST_TIME 3 6886
TEST_TIME 4 6349
TEST_TIME 5 6116
TEST_TIME 6 6061
TEST_TIME 7 6142
TEST_TIME 8 5581
TEST_TIME 9 5444
TEST_TIME 10 5272
TEST_TIME 11 5243
TEST_TIME 12 5204
TEST_TIME 13 5442
TEST_TIME 14 5584
TEST_TIME 15 5770
TEST_TIME 16 5823
TEST_TIME 17 6015
TEST_TIME 18 6019
TEST_TIME 19 6296
TEST_TIME 20 6426
TEST_TIME 21 7211
TEST_TIME 22 7189
TEST_TIME 23 7392
TEST_TIME 24 7761
TEST_TIME 25 8432
TEST_TIME 26 8826
TEST_TIME 27 9271
TEST_TIME 28 9155
TEST_TIME 29 9760
TEST_TIME 30 10371
TEST_TIME 31 10263
TEST_TIME 32 10008
TEST_TIME 33 9753
TEST_TIME 34 9820
TEST_TIME 35 9732
TEST_TIME 36 9656
TEST_TIME 37 9699
TEST_TIME 38 9861
TEST_TIME 39 9650
TEST_TIME 40 9655
TEST_TIME 41 9725
TEST_TIME 42 9716
TEST_TIME 43 9582
TEST_TIME 44 9542
TEST_TIME 45 9660
TEST_TIME 46 9734
TEST_TIME 47 9754
TEST_TIME 48 9828
TEST_TIME 49 9780
TEST_TIME 50 9937
TEST_TIME 51 9772
TEST_TIME 52 9700
TEST_TIME 53 9541
TEST_TIME 54 9398
TEST_TIME 55 9451
TEST_TIME 56 9445
TEST_TIME 57 9360
TEST_TIME 58 9303
TEST_TIME 59 9348
TEST_TIME 60 9269
TEST_TIME 61 9202
TEST_TIME 62 9137
TEST_TIME 63 9073
TEST_TIME 64 9185
TEST_TIME 65 9149
TEST_TIME 66 9303
TEST_TIME 67 9307
TEST_TIME 68 9333
TEST_TIME 69 9326
TEST_TIME 70 9291
TEST_TIME 71 9348
TEST_TIME 72 9416
TEST_TIME 73 9393
TEST_TIME 74 9405
TEST_TIME 75 9395
TEST_TIME 76 9507
TEST_TIME 77 9488
TEST_TIME 78 9517
TEST_TIME 79 9459
TEST_TIME 80 9429
TEST_TIME 81 9418
TEST_TIME 82 9369
TEST_TIME 83 9354
TEST_TIME 84 9323
TEST_TIME 85 9345
TEST_TIME 86 9296
TEST_TIME 87 9248
TEST_TIME 88 9229
TEST_TIME 89 9252
TEST_TIME 90 9248
TEST_TIME 91 9238
TEST_TIME 92 9268
TEST_TIME 93 9264
TEST_TIME 94 9250
TEST_TIME 95 9246
TEST_TIME 96 9307
TEST_TIME 97 9337
TEST_TIME 98 9286
TEST_TIME 99 9321
TEST_TIME 100 9396
TEST_TIME 101 9423
TEST_TIME 102 9431
TEST_TIME 103 9483
TEST_TIME 104 9487
TEST_TIME 105 9455
TEST_TIME 106 9392
TEST_TIME 107 9394
TEST_TIME 108 9370
TEST_TIME 109 9359
TEST_TIME 110 9353
TEST_TIME 111 9340
TEST_TIME 112 9324
TEST_TIME 113 9294
TEST_TIME 114 9285
TEST_TIME 115 9233
TEST_TIME 116 9224
TEST_TIME 117 9212
TEST_TIME 118 9224
TEST_TIME 119 9205
TEST_TIME 120 9183

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-10-2022 01:39:50 to 15-10-2022 01:39:53.

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 1507765 1507765 0 0.00 0 52394 6 0 0
doc_list_biud 1507765 1507765 0 0.00 0 42221 29 0 0
sp_add_invoice_to_stock 4821 4703 118 2.44 32 209617 17473 0 118
sp_cancel_adding_invoice 6006 6006 0 0.00 4 100934 799 0 0
sp_cancel_client_order 1733 1733 0 0.00 5 21007 381 0 0
sp_cancel_customer_reserve 580751 580751 0 0.00 0 30118 60 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 3 7659 76 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 11681 123 0 0
sp_cancel_supplier_invoice 6667 6657 10 0.14 1 42244 805 0 10
sp_cancel_supplier_order 448 448 0 0.00 9 1248538 98592 0 0
sp_cancel_write_off 10766 10766 0 0.00 1 24098 111 0 0
sp_client_order 20364 20364 0 0.00 3 25014 374 0 0
sp_customer_reserve 434834 434834 0 0.00 0 97795 173 0 0
sp_fill_shopping_cart 31073 31073 0 0.00 1 17919 36 0 0
sp_fill_shopping_cart_clo_res 434830 434830 0 0.00 0 18086 13 0 0
sp_get_clo_for_invoice 4699 4699 0 0.00 0 31784 308 0 0
sp_kill_cost_storno 17878 17878 0 0.00 0 9200 53 0 0
sp_kill_qstorno_ret_qs2qd 1179373 1179373 0 0.00 0 42219 32 0 0
sp_kill_qty_storno 620685 620685 0 0.00 0 42221 70 0 0
sp_lock_selected_doc 20056 20056 0 0.00 0 7674 6 0 0
sp_make_cost_storno 26894 26894 0 0.00 0 52317 58 0 0
sp_multiply_rows_for_pdistr 26894 26894 0 0.00 0 10033 30 0 0
sp_multiply_rows_for_qdistr 20364 20364 0 0.00 0 25007 154 0 0
sp_pay_from_customer 6961 6961 0 0.00 4 20739 117 0 0
sp_pay_to_supplier 5609 5609 0 0.00 4 12473 133 0 0
sp_payment_common 12570 12570 0 0.00 1 8407 35 0 0
sp_qd_handle_on_cancel_clo 1733 1733 0 0.00 0 20985 361 0 0
sp_qd_handle_on_invoice_upd_sts 10705 10705 0 0.00 0 38714 339 0 0
sp_qd_handle_on_reserve_upd_sts 9625 9625 0 0.00 0 23804 73 0 0
sp_reserve_write_off 9625 9625 0 0.00 6 23867 196 0 0
sp_supplier_invoice 5449 5449 0 0.00 5 105230 3585 0 0
sp_supplier_order 5268 5268 0 0.00 4 57860 1288 0 0
srv_aggregate_perf_data 99 99 0 0.00 2864 180653 16805 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1322 136758 47147 0 0
srv_find_qd_qs_mism 1057392 1057392 0 0.00 0 23732 5 0 0
srv_make_invnt_saldo 556 556 0 0.00 21 111726 1239 0 0
srv_make_money_saldo 758 758 0 0.00 1 9196 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 104335 494140 320127 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 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 4703 4703 0 0.00 0 14601 195 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 16367 168 0 0
t$perf-norm:sp_cancel_client_order 1733 1733 0 0.00 0 2298 12 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 1255 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 5614 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 4903 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 4909 27 0 0
t$perf-norm:sp_cancel_supplier_order 448 448 0 0.00 0 18790 1512 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 2713 10 0 0
t$perf-norm:sp_client_order 20364 20364 0 0.00 0 10309 21 0 0
t$perf-norm:sp_customer_reserve 928 928 0 0.00 0 8560 16 0 0
t$perf-norm:sp_pay_from_customer 6961 6961 0 0.00 0 10349 18 0 0
t$perf-norm:sp_pay_to_supplier 5609 5609 0 0.00 0 7017 16 0 0
t$perf-norm:sp_reserve_write_off 9625 9625 0 0.00 0 20183 16 0 0
t$perf-norm:sp_supplier_invoice 5449 5449 0 0.00 0 6679 24 0 0
t$perf-norm:sp_supplier_order 5268 5268 0 0.00 0 4573 15 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 738 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2121 66 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 1291 19 0 0
t$perf-norm:srv_make_money_saldo 758 758 0 0.00 0 1491 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9632 9396 236 2.45 0 9268 18 0 236
v_all_customers 37292 37292 0 0.00 0 35 1 0 0
v_all_suppliers 29812 29812 0 0.00 0 231 1 0 0
v_all_wares 40726 40726 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 5106 13 0 0
v_cancel_client_order 3466 3466 0 0.00 0 1921 4 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 1472 4 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 1927 5 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 6228 18 0 0
v_cancel_supplier_invoice 744 724 20 2.68 0 5002 24 0 20
v_cancel_supplier_order 896 896 0 0.00 0 697 4 0 0
v_cancel_write_off 1974 1974 0 0.00 0 3990 16 0 0
v_max_id_clo_ord 5268 5268 0 0.00 0 4900 4 0 0
v_max_id_clo_res 928 928 0 0.00 0 2113 21 0 0
v_max_id_ord_sup 5442 5442 0 0.00 0 3262 5 0 0
v_max_non_paid_invoice 1413 1413 0 0.00 0 4647 11 0 0
v_max_non_paid_realizn 3573 3573 0 0.00 0 3815 7 0 0
v_min_id_clo_ord 5268 5268 0 0.00 0 2234 5 0 0
v_min_id_clo_res 928 928 0 0.00 0 1720 7 0 0
v_min_id_ord_sup 5442 5442 0 0.00 0 3631 6 0 0
v_min_non_paid_invoice 5609 5609 0 0.00 0 7353 21 0 0
v_min_non_paid_realizn 6961 6961 0 0.00 0 8402 31 0 0
v_our_firm 10210 10210 0 0.00 0 17 1 0 0
v_reserve_write_off 19250 19250 0 0.00 0 3977 6 0 0
x_lock_depdocs_on_canc_invoice 12301 12301 0 0.00 0 17977 127 0 0
x_lock_depdocs_on_canc_sup_ord 448 448 0 0.00 0 6052 319 0 0
x_make_qstorno_1000_1200 5268 5268 0 0.00 2 57848 1214 0 0
x_make_qstorno_1200_2000 5449 5449 0 0.00 2 105213 3456 0 0
x_make_qstorno_2100_3300 434834 434834 0 0.00 0 97795 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-10-2022 01:39:54 to 15-10-2022 01:39:58.

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:37 12 8192000000 8806888208 8821116928 10985744 0.133 5 1 2 0 11094064 65536 42784
23:09:39 16761 8192000000 9899207296 10206187520 982880000 10.712 100 88 176 0 1011841616 5780112 23181504
23:11:10 12002 8192000000 9933554240 10254143488 1019546560 11.068 100 84 168 0 1049083296 7724960 21811776
23:12:24 11182 8192000000 9967061392 10296422400 1051818224 11.378 100 81 159 2 1080991440 9174464 19998752
23:13:43 10682 8192000000 10017451792 10362101760 1103004800 11.866 100 83 166 0 1133733440 10437904 20290736
23:15:00 7357 8192000000 10066194192 10420453376 1150461584 12.314 100 73 146 0 1178672096 11324960 16885552
23:16:09 2036 8192000000 10115050576 10484981760 1207419424 12.845 100 71 143 0 1234712976 11175040 16118512
23:17:14 2986 8192000000 10158353440 10533515264 1247391856 13.214 100 81 160 0 1279042544 14006832 17643856
23:18:19 3597 8192000000 10185485600 10567127040 1273131232 13.450 100 76 152 0 1302996080 12869728 16995120
23:19:24 1327 8192000000 10213352656 10596876288 1297310432 13.671 100 91 179 0 1331915504 15006608 19598464
23:20:27 1579 8192000000 10217231136 10606649344 1307246912 13.761 100 70 139 1 1333958368 11335680 15375776
23:21:31 11656 8192000000 10215646624 10614235136 1311497872 13.800 100 52 101 0 1328876176 6279088 11099216
23:22:44 7576 8192000000 10195823552 10611769344 1315202800 13.833 100 23 46 0 1326177872 5876048 5099024
23:23:54 12027 8192000000 10211753360 10633461760 1329324992 13.961 100 18 36 1 1339111264 5429088 4357184
23:25:07 8949 8192000000 10215336560 10639654912 1334215920 14.005 100 16 32 1 1347615664 10122784 3276960
23:26:18 38742 8192000000 10237078320 10653495296 1338617440 14.045 100 49 97 1 1355343536 5186752 11539344
23:27:58 7341 8192000000 10228626992 10650054656 1335629120 14.018 100 22 39 1 1345220704 5297856 4293728
23:29:05 8021 8192000000 10193566304 10608660480 1266919904 13.393 96 17 33 0 1276382656 6176336 3286416
23:30:17 33260 8192000000 10185306880 10574200832 1251604240 13.253 97 58 119 1 1271485056 5673040 14207776
23:31:53 7805 8192000000 10136485936 10532646912 1208944368 12.859 97 31 62 1 1222298368 6529072 6824928
23:33:03 3960 8192000000 10144775392 10546397184 1244936272 13.192 99 24 47 0 1255784528 5387376 5460880
23:34:07 10273 8192000000 10180537104 10587713536 1283328784 13.543 100 25 50 0 1294947072 5582208 6036080
23:35:19 16649 8192000000 10186747024 10589323264 1285392624 13.562 100 46 91 0 1302319376 5826928 11099824
23:36:39 10519 8192000000 10193672944 10610135040 1301080272 13.705 100 15 30 0 1310028720 5273024 3675424
23:37:51 6549 8192000000 10200864640 10618241024 1310868240 13.794 100 21 42 2 1320727920 5408976 4450704
23:39:01 14562 8192000000 10246034496 10654822400 1338241520 14.042 100 45 90 0 1355459056 6739344 10478192
23:40:20 32318 8192000000 10252003824 10672537600 1353508048 14.179 100 46 90 2 1370854000 5970256 11375696
23:41:57 9459 8192000000 10275664560 10697723904 1370149568 14.328 100 59 115 0 1392561568 8782720 13629280
23:43:13 7824 8192000000 10283838736 10703183872 1375217984 14.374 100 50 98 1 1398067312 11954848 10894480
23:44:24 8360 8192000000 10308908432 10732969984 1397308928 14.571 100 49 98 0 1424148560 14625440 12214192
23:45:37 17598 8192000000 10316589840 10744692736 1401288672 14.606 100 28 56 0 1426289376 18482176 6518528
23:46:57 30625 8192000000 10313563376 10747715584 1406074800 14.649 100 34 66 3 1432759104 18674272 8010032
23:48:36 14314 8192000000 10330164608 10763554816 1414677104 14.725 100 42 84 0 1447064880 21957552 10430224
23:49:55 11004 8192000000 10345538416 10774728704 1422352400 14.794 100 46 89 1 1459300496 25358016 11590080
23:51:14 6768 8192000000 10339441568 10765058048 1414075216 14.720 100 52 103 1 1456793728 29653216 13065296
23:52:25 7477 8192000000 10347572768 10774376448 1414623120 14.725 100 49 95 0 1459029808 31715104 12691584
23:53:37 2868 8192000000 10312500640 10734587904 1373631152 14.360 97 60 113 2 1416868784 28834592 14403040
23:54:48 1366 8192000000 10354856560 10769559552 1408306528 14.669 100 66 127 0 1458818608 34848016 15664064
23:55:51 4004 8192000000 10350122352 10766843904 1405655984 14.645 100 61 120 0 1457082304 36325984 15100336
23:57:00 1318 8192000000 10349463808 10753601536 1386591312 14.475 100 83 164 0 1452285808 45566336 20128160
23:58:07 2530 8192000000 10306136688 10702602240 1342525824 14.080 98 76 153 2 1409967072 48009536 19431712
23:59:17 2263 8192000000 10337504912 10741788672 1360745216 14.244 99 83 166 2 1433047152 51029248 21272688
00:00:22 8320 8192000000 10324973648 10742923264 1364660208 14.279 99 40 78 1 1423487520 48036016 10791296
00:01:40 20836 8192000000 10343306480 10769600512 1378683952 14.405 100 42 85 0 1451271424 59813472 12774000
00:03:09 9906 8192000000 10337349024 10764894208 1374476736 14.367 100 49 97 1 1446570864 58335600 13758528
00:04:21 12569 8192000000 10325032368 10744991744 1356520224 14.206 99 56 112 0 1417958176 45835392 15602560
00:05:40 34473 8192000000 10298378656 10741260288 1366200528 14.293 100 20 40 0 1410474640 38523984 5750128
00:07:16 44546 8192000000 10286715504 10734460928 1358343216 14.222 100 14 28 1 1400372400 38039680 3989504
00:09:02 39276 8192000000 10295032240 10734231552 1350686736 14.154 98 19 37 2 1376325680 21431552 4207392
00:10:46 36880 8192000000 10300415072 10736046080 1371557712 14.341 99 54 107 0 1394203664 9893040 12752912
00:12:56 56636 8192000000 10292199920 10740277248 1360101040 14.238 98 22 42 0 1385772624 20475328 5196256
00:14:53 65873 8192000000 10283270272 10745106432 1352608608 14.171 98 12 24 0 1379797952 24061008 3128336
00:18:04 55152 8192000000 10296167712 10736234496 1354482128 14.188 98 33 64 1 1373593536 11687920 7423488
00:20:00 92703 8192000000 10285267424 10732195840 1354921712 14.192 98 16 32 0 1370454960 11577280 3955968
00:22:41 67446 8192000000 10278040144 10739351552 1347742320 14.127 98 26 51 0 1363137472 10091488 5303664
00:25:01 136758 8192000000 10299535968 10755387392 1354427040 14.187 98 16 32 1 1372964944 14870176 3667728
00:28:19 127199 8192000000 10296453440 10751868928 1381588032 14.431 100 16 32 0 1414274320 28620224 4066064
00:31:26 67719 8192000000 10298991216 10754387968 1388795984 14.495 100 26 53 0 1407934528 13091200 6047344
00:33:51 36109 8192000000 10262882432 10722205696 1361604432 14.252 99 30 60 0 1378725184 9647328 7473424
00:35:40 38840 8192000000 10304151056 10751483904 1372779088 14.352 99 40 76 1 1389510288 7768864 8962336
00:37:32 127453 8192000000 10285889456 10744545280 1343515712 14.089 96 13 26 0 1357400640 11141552 2743376
00:40:46 65380 8192000000 10286751152 10749034496 1374343840 14.366 99 15 30 0 1392077648 14167952 3565856
00:42:56 80437 8192000000 10282879696 10745540608 1346921360 14.120 97 11 20 1 1363061888 13599840 2540688
00:45:23 58565 8192000000 10248693984 10700656640 1287363296 13.580 95 11 22 0 1302780544 13010064 2407184
00:47:21 49137 8192000000 10264776288 10717302784 1315879232 13.839 97 23 46 0 1333051488 11934480 5237776
00:49:16 62663 8192000000 10242090592 10699948032 1278070640 13.495 95 22 44 0 1301489520 18286528 5132352
00:51:28 57254 8192000000 10233937648 10677743616 1273472848 13.453 95 29 57 0 1288424688 9130272 5821568
00:53:31 31457 8192000000 10250649088 10693222400 1320042512 13.877 99 22 44 1 1344034464 18758928 5233024
00:55:03 55277 8192000000 10282734832 10708213760 1314260544 13.825 97 25 49 0 1327991136 8210752 5519840
00:57:01 74953 8192000000 10268277104 10712293376 1332784224 13.992 99 24 48 0 1357823648 19753344 5286080
00:59:21 77608 8192000000 10282861680 10721447936 1366591424 14.296 100 27 54 0 1382397472 9989488 5816560
01:01:39 89444 8192000000 10280607280 10737057792 1364124640 14.274 99 20 40 0 1386383744 17238352 5020752
01:04:12 83578 8192000000 10280360304 10729586688 1345234128 14.105 97 15 28 0 1360239440 11650592 3354720
01:06:35 77650 8192000000 10279402144 10738233344 1364338432 14.276 99 20 40 1 1380363872 11524160 4501280
01:08:57 56490 8192000000 10286748512 10747699200 1370685520 14.333 99 9 19 0 1382412320 9965232 1761568
01:10:54 67905 8192000000 10277603440 10735308800 1333008576 13.994 96 15 30 0 1344663216 8224144 3430496
01:13:03 64109 8192000000 10293769824 10745933824 1358557712 14.224 98 19 38 0 1373357440 10614864 4184864
01:15:10 61721 8192000000 10303819920 10744872960 1352439424 14.169 97 26 51 0 1368261936 10133616 5688896
01:17:17 15950 8192000000 10329662368 10771656704 1395480720 14.555 100 42 84 0 1423725376 19077920 9166736
01:18:35 54124 8192000000 10326219616 10780340224 1405284304 14.642 100 17 34 0 1424283648 15386400 3612944
01:20:33 102337 8192000000 10323275632 10777231360 1384460336 14.456 98 12 25 1 1399506320 12247392 2798592
01:23:32 109450 8192000000 10301754288 10761867264 1393163456 14.534 99 24 48 1 1407763360 9314592 5285312
01:26:46 59318 8192000000 10297283232 10752643072 1372638416 14.351 98 22 44 1 1390378048 12460768 5278864
01:28:49 62809 8192000000 10275228400 10731753472 1356797344 14.209 98 16 30 0 1369485872 9111328 3577200
01:31:16 74191 8192000000 10248495168 10709356544 1344850528 14.101 98 6 12 0 1354046048 7676544 1518976
01:33:31 38096 8192000000 10258756160 10715357184 1362456048 14.259 100 29 58 0 1377947472 9010688 6480736
01:35:12 67518 8192000000 10264539840 10720698368 1328894096 13.957 96 19 38 0 1341297104 8042720 4360288
01:37:26 19328 8192000000 10275569888 10720374784 1357114304 14.211 99 37 71 0 1373744464 9003728 7626432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-10-2022 01:40:00 to 15-10-2022 01:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 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 15-10-2022 01:40:17 to 15-10-2022 01:40:19.

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          232643
MON$OLDEST_ACTIVE               235996
MON$OLDEST_SNAPSHOT             235996
MON$NEXT_TRANSACTION            235996
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                       2029408
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.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
:377: 
:378: mscs-test-161.budzdorov.ru    Sat Oct 15 01:38:36 2022
:379:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:380: 
:381: 
:382: mscs-test-161.budzdorov.ru    Sat Oct 15 01:38:43 2022
:383:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:384: 
--- end of diff output ---

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

15.10.22 01:40:20. 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-15 01:40:22 No severe PSQL-related problems occured
15.10.22 01:40:23. End of report.