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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16581436 kB
MemAvailable 31279912 kB
Buffers 0 kB
Cached 15055772 kB
SwapCached 1200 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-01-04 22:38:06
DTS_END                         2022-01-04 22:38:06.1680
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/42260
TRN_ID                          242545
ATT_ID                          664
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: 9082.81 20778 1089937
customer order: creation 173.41 374 20809
customer order: refuse 14.64 341 1757
order to supplier: creation 46.21 1206 5545
order to supplier: removal 3.49 91121 419
invoice (draft): creation 46.54 3505 5585
invoice (draft): removal 51.72 743 6206
invoice accept: apply 42.28 15702 5073
invoice accept: cancel 46.43 806 5571
customer reserve: creation 3781.53 158 453784
customer reserve: removal 4586.68 54 550401
realization accept: apply 84.97 182 10196
realization accept: cancel 75.50 117 9060
payment from customer: creation 59.63 131 7156
payment from customer: removal 5.84 121 701
payment to supplier: creation 48.85 129 5862
payment to supplier: removal 3.14 69 377
service: total inventory turnovers 4.71 1145 565
service: total monetary turnovers 6.36 38 763
service: aggregate perf. data 0.86 15218 103
service: refresh index statistics 0.03 284406 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-01-2022 22:39:08 to 04-01-2022 22:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3985
TEST_TIME 2 5404
TEST_TIME 3 4644
TEST_TIME 4 3993
TEST_TIME 5 3961
TEST_TIME 6 4157
TEST_TIME 7 4192
TEST_TIME 8 4446
TEST_TIME 9 4562
TEST_TIME 10 4548
TEST_TIME 11 5045
TEST_TIME 12 5389
TEST_TIME 13 5877
TEST_TIME 14 6260
TEST_TIME 15 6863
TEST_TIME 16 7897
TEST_TIME 17 8145
TEST_TIME 18 8228
TEST_TIME 19 8297
TEST_TIME 20 8593
TEST_TIME 21 9071
TEST_TIME 22 9474
TEST_TIME 23 9547
TEST_TIME 24 10053
TEST_TIME 25 10036
TEST_TIME 26 9989
TEST_TIME 27 10237
TEST_TIME 28 10024
TEST_TIME 29 9868
TEST_TIME 30 9738
TEST_TIME 31 9546
TEST_TIME 32 9495
TEST_TIME 33 9535
TEST_TIME 34 9485
TEST_TIME 35 9547
TEST_TIME 36 9480
TEST_TIME 37 9280
TEST_TIME 38 9214
TEST_TIME 39 9151
TEST_TIME 40 9129
TEST_TIME 41 9176
TEST_TIME 42 9131
TEST_TIME 43 9201
TEST_TIME 44 9286
TEST_TIME 45 9365
TEST_TIME 46 9328
TEST_TIME 47 9330
TEST_TIME 48 9526
TEST_TIME 49 9495
TEST_TIME 50 9393
TEST_TIME 51 9603
TEST_TIME 52 9549
TEST_TIME 53 9492
TEST_TIME 54 9483
TEST_TIME 55 9475
TEST_TIME 56 9467
TEST_TIME 57 9316
TEST_TIME 58 9351
TEST_TIME 59 9257
TEST_TIME 60 9234
TEST_TIME 61 9263
TEST_TIME 62 9276
TEST_TIME 63 9188
TEST_TIME 64 9140
TEST_TIME 65 9115
TEST_TIME 66 9076
TEST_TIME 67 9061
TEST_TIME 68 9234
TEST_TIME 69 9214
TEST_TIME 70 9177
TEST_TIME 71 9185
TEST_TIME 72 9199
TEST_TIME 73 9239
TEST_TIME 74 9285
TEST_TIME 75 9347
TEST_TIME 76 9426
TEST_TIME 77 9388
TEST_TIME 78 9502
TEST_TIME 79 9400
TEST_TIME 80 9342
TEST_TIME 81 9307
TEST_TIME 82 9352
TEST_TIME 83 9337
TEST_TIME 84 9328
TEST_TIME 85 9279
TEST_TIME 86 9248
TEST_TIME 87 9215
TEST_TIME 88 9147
TEST_TIME 89 9060
TEST_TIME 90 9119
TEST_TIME 91 9116
TEST_TIME 92 9118
TEST_TIME 93 9111
TEST_TIME 94 9077
TEST_TIME 95 9185
TEST_TIME 96 9237
TEST_TIME 97 9257
TEST_TIME 98 9246
TEST_TIME 99 9362
TEST_TIME 100 9312
TEST_TIME 101 9293
TEST_TIME 102 9352
TEST_TIME 103 9387
TEST_TIME 104 9349
TEST_TIME 105 9472
TEST_TIME 106 9411
TEST_TIME 107 9337
TEST_TIME 108 9273
TEST_TIME 109 9310
TEST_TIME 110 9277
TEST_TIME 111 9223
TEST_TIME 112 9198
TEST_TIME 113 9155
TEST_TIME 114 9190
TEST_TIME 115 9151
TEST_TIME 116 9166
TEST_TIME 117 9157
TEST_TIME 118 9147
TEST_TIME 119 9156
TEST_TIME 120 9086

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-01-2022 22:39:12 to 04-01-2022 22:39:15.

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 1511858 1511858 0 0.00 0 17702 6 0 0
doc_list_biud 1511857 1511857 0 0.00 0 30548 25 0 0
sp_add_invoice_to_stock 5182 5073 109 2.10 25 158572 15702 0 109
sp_cancel_adding_invoice 5571 5571 0 0.00 5 58043 807 0 0
sp_cancel_client_order 1757 1757 0 0.00 4 11216 342 0 0
sp_cancel_customer_reserve 550401 550401 0 0.00 1 16311 54 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 2 5227 121 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 2929 69 0 0
sp_cancel_supplier_invoice 6210 6206 4 0.06 2 30553 744 0 4
sp_cancel_supplier_order 419 419 0 0.00 7 1080359 91122 0 0
sp_cancel_write_off 9060 9060 0 0.00 1 11008 118 0 0
sp_client_order 20809 20809 0 0.00 3 22102 375 0 0
sp_customer_reserve 453784 453784 0 0.00 0 63023 158 0 0
sp_fill_shopping_cart 31935 31935 0 0.00 1 25434 42 0 0
sp_fill_shopping_cart_clo_res 453778 453778 0 0.00 0 35367 14 0 0
sp_get_clo_for_invoice 5067 5067 0 0.00 0 59410 369 0 0
sp_kill_cost_storno 15709 15709 0 0.00 0 7339 56 0 0
sp_kill_qstorno_ret_qs2qd 1116487 1116487 0 0.00 0 30546 30 0 0
sp_kill_qty_storno 588677 588677 0 0.00 0 30547 65 0 0
sp_lock_selected_doc 21088 21088 0 0.00 0 6837 5 0 0
sp_make_cost_storno 28281 28281 0 0.00 0 15974 56 0 0
sp_multiply_rows_for_pdistr 28281 28281 0 0.00 0 14060 30 0 0
sp_multiply_rows_for_qdistr 20809 20809 0 0.00 0 17736 192 0 0
sp_pay_from_customer 7156 7156 0 0.00 4 14910 132 0 0
sp_pay_to_supplier 5862 5862 0 0.00 4 14257 130 0 0
sp_payment_common 13018 13018 0 0.00 1 13916 38 0 0
sp_qd_handle_on_cancel_clo 1757 1757 0 0.00 0 11210 309 0 0
sp_qd_handle_on_invoice_upd_sts 10638 10638 0 0.00 0 21676 345 0 0
sp_qd_handle_on_reserve_upd_sts 10196 10196 0 0.00 0 14393 56 0 0
sp_reserve_write_off 10196 10196 0 0.00 6 18439 182 0 0
sp_supplier_invoice 5585 5585 0 0.00 6 122362 3505 0 0
sp_supplier_order 5545 5545 0 0.00 5 23178 1207 0 0
srv_aggregate_perf_data 103 103 0 0.00 3000 162739 15219 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1530 143003 49194 0 0
srv_find_qd_qs_mism 1043223 1043223 0 0.00 0 17701 5 0 0
srv_make_invnt_saldo 565 565 0 0.00 37 121284 1145 0 0
srv_make_money_saldo 763 763 0 0.00 1 5126 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 227830 408246 284406 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5073 5073 0 0.00 0 14104 152 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 1 5656 136 0 0
t$perf-norm:sp_cancel_client_order 1757 1757 0 0.00 0 8924 16 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 2522 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 4105 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 111 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 4379 31 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 32099 1507 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 2880 9 0 0
t$perf-norm:sp_client_order 20809 20809 0 0.00 0 12869 18 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 2587 10 0 0
t$perf-norm:sp_pay_from_customer 7156 7156 0 0.00 0 6371 11 0 0
t$perf-norm:sp_pay_to_supplier 5862 5862 0 0.00 0 6371 14 0 0
t$perf-norm:sp_reserve_write_off 10196 10196 0 0.00 0 7169 14 0 0
t$perf-norm:sp_supplier_invoice 5585 5585 0 0.00 0 6631 19 0 0
t$perf-norm:sp_supplier_order 5545 5545 0 0.00 0 4893 15 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 256 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 261 6 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 5973 35 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 2812 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 33 10 0 0
v_add_invoice_to_stock 10352 10134 218 2.10 0 7504 21 0 218
v_all_customers 37704 37704 0 0.00 0 16 1 0 0
v_all_suppliers 30582 30582 0 0.00 0 24 1 0 0
v_all_wares 41618 41618 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 7762 28 0 0
v_cancel_client_order 3514 3514 0 0.00 0 4474 7 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 4175 11 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 7746 11 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 2906 11 0 0
v_cancel_supplier_invoice 852 844 8 0.93 0 6448 21 0 8
v_cancel_supplier_order 838 838 0 0.00 0 1467 4 0 0
v_cancel_write_off 2010 2010 0 0.00 0 2754 16 0 0
v_max_id_clo_ord 5545 5545 0 0.00 0 6912 3 0 0
v_max_id_clo_res 954 954 0 0.00 0 1790 9 0 0
v_max_id_ord_sup 5581 5581 0 0.00 0 8196 6 0 0
v_max_non_paid_invoice 1697 1697 0 0.00 0 76 2 0 0
v_max_non_paid_realizn 3912 3912 0 0.00 0 4413 5 0 0
v_min_id_clo_ord 5545 5545 0 0.00 0 6438 13 0 0
v_min_id_clo_res 954 954 0 0.00 0 7394 19 0 0
v_min_id_ord_sup 5581 5581 0 0.00 0 6791 11 0 0
v_min_non_paid_invoice 5862 5862 0 0.00 0 11809 28 0 0
v_min_non_paid_realizn 7156 7156 0 0.00 0 13488 32 0 0
v_our_firm 10402 10402 0 0.00 0 14 1 0 0
v_reserve_write_off 20392 20392 0 0.00 0 5182 8 0 0
x_lock_depdocs_on_canc_invoice 11355 11355 0 0.00 0 13294 106 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 8868 370 0 0
x_make_qstorno_1000_1200 5545 5545 0 0.00 2 23058 1113 0 0
x_make_qstorno_1200_2000 5585 5585 0 0.00 2 122341 3358 0 0
x_make_qstorno_2100_3300 453784 453784 0 0.00 0 63020 142 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-01-2022 22:39:16 to 04-01-2022 22:39:20.

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
20:08:09 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:10 26898 8192000000 9893711904 10204803072 977847376 10.663 100 87 174 1 1006425072 5751760 22825936
20:10:40 14405 8192000000 9932469536 10251251712 1012606672 11.001 100 89 178 1 1044875616 8763392 23505552
20:12:06 22712 8192000000 9982159440 10313383936 1055578528 11.414 100 84 168 0 1087390416 10134656 21677232
20:13:32 11572 8192000000 10028037072 10376343552 1113280640 11.963 100 75 150 0 1142793472 10997968 18514864
20:14:48 6246 8192000000 10087689344 10449055744 1177213280 12.564 100 84 166 1 1209842336 13325040 19304016
20:15:56 4488 8192000000 10145150736 10514604032 1229928272 13.053 100 81 161 0 1260510480 12446144 18136064
20:17:03 3279 8192000000 10167051392 10544672768 1251100480 13.248 100 82 161 3 1282173632 14210368 16862784
20:18:08 1880 8192000000 10200791024 10580721664 1284820944 13.557 100 84 169 3 1316124624 12071856 19231824
20:19:12 2260 8192000000 10217853088 10602016768 1300660704 13.701 100 87 174 0 1333499744 13247104 19591936
20:20:14 1812 8192000000 10227562544 10614886400 1302941056 13.722 100 92 184 0 1334678240 10463440 21273744
20:21:17 4081 8192000000 10206503872 10616659968 1312997680 13.813 100 47 94 2 1333854704 10272176 10584848
20:22:22 8427 8192000000 10212636960 10626568192 1322318592 13.898 100 25 50 1 1334425136 6951952 5154592
20:23:31 10687 8192000000 10216691136 10630807552 1327156080 13.941 100 20 38 3 1337802608 6162608 4483920
20:24:43 61933 8192000000 10229031504 10641342464 1332908848 13.993 100 29 55 2 1344539456 5408992 6221616
20:26:46 6723 8192000000 10234229264 10649235456 1333288432 13.997 100 31 62 1 1347850736 7672576 6889728
20:27:55 7549 8192000000 10200447328 10614939648 1306968032 13.759 99 20 40 2 1317482304 5546032 4968240
20:29:07 9272 8192000000 10179236784 10587578368 1268974640 13.412 98 16 31 2 1278079648 5593696 3511312
20:30:18 34879 8192000000 10124065152 10513481728 1111636544 11.948 88 30 60 2 1122793264 4111248 7045472
20:31:56 4680 8192000000 10130589888 10527309824 1219846016 12.960 98 33 65 0 1234249568 6809936 7593616
20:33:03 6655 8192000000 10157357472 10558369792 1252044592 13.257 99 29 58 0 1265071312 5475472 7551248
20:34:10 20274 8192000000 10192571312 10597482496 1292735408 13.629 100 34 66 0 1307434416 5560576 9138432
20:36:10 9266 8192000000 10197796576 10605932544 1300193600 13.697 100 34 69 0 1314944192 7583040 7167552
20:37:20 12321 8192000000 10213381568 10627735552 1311100368 13.796 100 23 46 0 1321737216 5555312 5081536
20:38:40 47694 8192000000 10226378512 10642915328 1327615808 13.946 100 37 72 1 1340837040 4877632 8343600
20:40:29 6123 8192000000 10271902480 10686414848 1359632320 14.234 100 48 97 0 1385935536 15203648 11099568
20:41:37 70011 8192000000 10280823952 10701582336 1367500096 14.305 100 32 64 0 1389836112 14775088 7560928
20:43:52 7926 8192000000 10297932656 10717011968 1383480128 14.448 100 49 96 1 1411656608 17266912 10909568
20:45:03 9004 8192000000 10295929120 10716921856 1376885776 14.389 99 42 84 1 1406494656 19210352 10398528
20:46:28 6457 8192000000 10316837680 10742124544 1401020128 14.604 100 48 96 1 1433838816 20726336 12092352
20:47:39 12695 8192000000 10325916160 10751033344 1404160960 14.632 100 45 90 0 1440111520 24809488 11141072
20:48:57 4911 8192000000 10348766192 10765856768 1413056912 14.711 100 65 130 2 1459637536 29827968 16752656
20:50:05 6584 8192000000 10343556576 10760781824 1404940672 14.639 100 66 132 1 1453555248 32249152 16365424
20:51:17 2096 8192000000 10359776624 10774777856 1408742032 14.673 100 88 174 1 1466422112 35406336 22273744
20:52:26 6489 8192000000 10308424224 10711019520 1332223408 13.987 97 70 139 1 1390223680 40086208 17914064
20:53:36 1529 8192000000 10306611200 10713972736 1346710096 14.118 99 78 154 0 1403141456 36531760 19899600
20:54:45 1672 8192000000 10314574528 10723782656 1372005072 14.345 100 84 168 1 1419144016 27099280 20039664
20:55:51 11423 8192000000 10271597072 10690273280 1331589120 13.982 98 35 70 1 1372405680 31827632 8988928
20:57:07 6955 8192000000 10303476288 10718916608 1357343712 14.213 99 57 112 0 1400761952 28860144 14558096
20:58:20 4159 8192000000 10311090352 10723840000 1364153600 14.275 100 61 122 0 1411935328 30830416 16951312
20:59:27 5405 8192000000 10307209088 10723581952 1366562080 14.296 100 39 78 1 1406142000 28402688 11177232
21:00:34 17515 8192000000 10274259776 10704146432 1352291680 14.168 99 26 52 1 1377393056 18229280 6872096
21:01:57 20731 8192000000 10288525824 10721533952 1360324768 14.240 100 22 44 0 1391020240 24464736 6230736
21:03:21 57192 8192000000 10260593712 10693758976 1327907840 13.948 98 18 36 1 1349652928 17316288 4428800
21:05:36 17970 8192000000 10246802240 10691059712 1350422832 14.151 100 14 26 1 1362516288 9135392 2958064
21:06:58 100071 8192000000 10269251120 10710708224 1352225888 14.167 99 24 47 0 1363970064 6742320 5001856
21:09:41 96866 8192000000 10290909792 10746609664 1381461280 14.430 100 38 76 2 1415163696 24010176 9692240
21:12:23 119655 8192000000 10292517680 10750332928 1388526560 14.493 100 15 30 1 1407398368 15603200 3268608
21:15:29 20074 8192000000 10298034928 10744782848 1386648288 14.476 99 25 49 1 1404718688 11552448 6517952
21:16:50 13278 8192000000 10310901856 10757259264 1400742928 14.602 100 30 60 1 1424018496 15486576 7788992
21:18:04 41304 8192000000 10326792864 10776141824 1412293184 14.704 100 26 52 0 1440404800 21955712 6155904
21:19:47 32054 8192000000 10322248080 10781741056 1402227312 14.615 100 13 26 0 1436529440 31299728 3002400
21:21:23 68036 8192000000 10319591136 10773458944 1394949792 14.550 99 14 28 1 1416851104 18713840 3187472
21:23:40 45110 8192000000 10308509792 10757578752 1367325776 14.303 97 22 44 0 1386512224 14291376 4895072
21:25:26 143003 8192000000 10305419040 10754899968 1361974656 14.255 97 19 38 0 1384028192 18095936 3957600
21:28:58 69650 8192000000 10315619408 10788196352 1394937280 14.550 99 18 36 1 1419704512 20735024 4032208
21:31:08 34536 8192000000 10317058720 10751545344 1379013296 14.408 98 39 78 0 1395504112 7493008 8997808
21:32:50 39981 8192000000 10293362128 10734342144 1324522048 13.918 95 28 56 1 1341513024 11195808 5795168
21:34:31 123425 8192000000 10267699952 10718154752 1349018240 14.139 99 31 62 2 1371689088 15410368 7260480
21:37:35 30890 8192000000 10272525984 10720866304 1363320512 14.267 99 29 58 1 1382190352 12485712 6384128
21:39:08 54161 8192000000 10263978736 10710720512 1343299824 14.087 98 21 42 0 1354941872 7288400 4353648
21:41:03 44483 8192000000 10261636496 10715656192 1359933184 14.237 99 11 22 1 1368618384 6705520 1979680
21:42:54 53576 8192000000 10247765664 10696957952 1265613664 13.381 92 19 38 1 1277445824 7663744 4168416
21:44:52 48054 8192000000 10242061968 10681872384 1287224944 13.579 96 29 57 2 1305818960 11932544 6661472
21:46:41 94506 8192000000 10233672800 10670669824 1259276544 13.323 93 19 39 1 1276642272 11496096 5869632
21:49:16 65625 8192000000 10276086576 10717270016 1358391040 14.223 100 18 36 0 1376136880 13681792 4064048
21:51:22 66060 8192000000 10285609616 10720681984 1322054224 13.895 97 23 46 0 1351042992 22904080 6084688
21:53:29 59631 8192000000 10272325168 10724929536 1343706496 14.091 99 15 29 0 1367172192 20582640 2883056
21:55:34 79516 8192000000 10283231904 10736648192 1350703584 14.154 99 30 60 0 1375865456 17955888 7205984
21:59:29 86489 8192000000 10249698352 10703171584 1339882176 14.056 99 26 52 0 1358544352 12589680 6072496
22:01:56 80092 8192000000 10259159600 10713182208 1346160160 14.113 99 15 30 0 1360018976 10278880 3579936
22:04:45 46521 8192000000 10267326320 10723741696 1374128928 14.364 100 25 50 1 1388330560 8297264 5904368
22:06:46 71612 8192000000 10288238560 10739388416 1376378896 14.384 99 17 34 0 1393960112 13345136 4236080
22:09:33 77292 8192000000 10285375456 10745028608 1371523344 14.341 99 6 12 1 1381835648 9278064 1034240
22:11:51 82307 8192000000 10307796016 10762588160 1388174112 14.490 99 20 40 1 1409914624 16653472 5087040
22:14:14 86333 8192000000 10315227040 10774597632 1403351328 14.625 100 9 18 0 1418557568 13307888 1898352
22:16:43 38676 8192000000 10307355888 10770317312 1401908624 14.612 100 15 30 2 1420099744 13964096 4227024
22:18:39 27082 8192000000 10304109952 10737950720 1351582048 14.162 97 48 96 0 1370558912 7388640 11588224
22:20:08 86908 8192000000 10293076992 10751516672 1359082336 14.229 98 10 20 0 1375657872 14539920 2035616
22:22:35 131444 8192000000 10298729968 10771255296 1364896304 14.281 98 11 22 0 1386739824 19216896 2626624
22:26:38 72047 8192000000 10283022240 10750881792 1374827024 14.370 99 18 36 1 1394725296 15692240 4206032
22:28:54 65157 8192000000 10259891440 10715168768 1364922432 14.282 100 12 24 0 1375491568 7487792 3081344
22:30:59 68530 8192000000 10264277008 10715529216 1333559552 13.999 97 17 34 1 1349074720 11296368 4218800
22:33:25 59853 8192000000 10243384112 10698715136 1291340464 13.616 94 10 20 1 1304983952 11422256 2221232
22:36:23 52284 8192000000 10252329504 10698125312 1316969520 13.849 97 30 60 0 1332754784 8864448 6920816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-01-2022 22:39:22 to 04-01-2022 22:39:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-01-2022 22:39:38 to 04-01-2022 22:39:40.

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          240867
MON$OLDEST_ACTIVE               242893
MON$OLDEST_SNAPSHOT             242893
MON$NEXT_TRANSACTION            242893
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                       2021056
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:432: 
:433: mscs-test-161.budzdorov.ru    Tue Jan  4 22:38:08 2022
:434:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:435: 
:436: 
:437: mscs-test-161.budzdorov.ru    Tue Jan  4 22:38:12 2022
:438:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:439: 
:440: 
:441: mscs-test-161.budzdorov.ru    Tue Jan  4 22:38:12 2022
:442:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:443: 
--- end of diff output ---

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

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