Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.09.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16171888 kB
MemAvailable 30907280 kB
Buffers 0 kB
Cached 15537392 kB
SwapCached 340 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.8
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 2021-09-07 01:37:48
DTS_END                         2021-09-07 01:37:48.3850
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/46288
TRN_ID                          239233
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: 9448.30 20491 1133796
customer order: creation 175.51 377 21061
customer order: refuse 15.01 354 1801
order to supplier: creation 45.89 1400 5507
order to supplier: removal 3.47 124739 416
invoice (draft): creation 45.36 3374 5443
invoice (draft): removal 54.46 963 6535
invoice accept: apply 41.58 17967 4990
invoice accept: cancel 49.17 792 5900
customer reserve: creation 3862.03 177 463443
customer reserve: removal 4852.81 70 582337
realization accept: apply 84.58 195 10150
realization accept: cancel 84.24 117 10109
payment from customer: creation 62.16 140 7459
payment from customer: removal 5.85 65 702
payment to supplier: creation 50.26 151 6031
payment to supplier: removal 3.74 60 449
service: total inventory turnovers 4.49 1215 539
service: total monetary turnovers 6.85 27 822
service: aggregate perf. data 0.82 24786 98
service: refresh index statistics 0.03 232854 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-09-2021 01:39:56 to 07-09-2021 01:39:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3332
TEST_TIME 2 2912
TEST_TIME 3 3963
TEST_TIME 4 4029
TEST_TIME 5 3899
TEST_TIME 6 4077
TEST_TIME 7 4356
TEST_TIME 8 4716
TEST_TIME 9 5012
TEST_TIME 10 5095
TEST_TIME 11 5459
TEST_TIME 12 5508
TEST_TIME 13 5648
TEST_TIME 14 6468
TEST_TIME 15 7300
TEST_TIME 16 7157
TEST_TIME 17 7329
TEST_TIME 18 7359
TEST_TIME 19 7747
TEST_TIME 20 7742
TEST_TIME 21 7815
TEST_TIME 22 7743
TEST_TIME 23 7656
TEST_TIME 24 7915
TEST_TIME 25 8579
TEST_TIME 26 9155
TEST_TIME 27 9830
TEST_TIME 28 11393
TEST_TIME 29 11134
TEST_TIME 30 11047
TEST_TIME 31 10916
TEST_TIME 32 10973
TEST_TIME 33 10775
TEST_TIME 34 10562
TEST_TIME 35 10452
TEST_TIME 36 10315
TEST_TIME 37 10158
TEST_TIME 38 10227
TEST_TIME 39 10158
TEST_TIME 40 10130
TEST_TIME 41 9927
TEST_TIME 42 9880
TEST_TIME 43 9881
TEST_TIME 44 9830
TEST_TIME 45 9686
TEST_TIME 46 9602
TEST_TIME 47 9554
TEST_TIME 48 9502
TEST_TIME 49 9537
TEST_TIME 50 9623
TEST_TIME 51 9582
TEST_TIME 52 9447
TEST_TIME 53 9340
TEST_TIME 54 9368
TEST_TIME 55 9399
TEST_TIME 56 9368
TEST_TIME 57 9316
TEST_TIME 58 9344
TEST_TIME 59 9290
TEST_TIME 60 9335
TEST_TIME 61 9391
TEST_TIME 62 9453
TEST_TIME 63 9478
TEST_TIME 64 9493
TEST_TIME 65 9551
TEST_TIME 66 9509
TEST_TIME 67 9639
TEST_TIME 68 9554
TEST_TIME 69 9693
TEST_TIME 70 9736
TEST_TIME 71 9775
TEST_TIME 72 9709
TEST_TIME 73 9591
TEST_TIME 74 9554
TEST_TIME 75 9577
TEST_TIME 76 9507
TEST_TIME 77 9480
TEST_TIME 78 9450
TEST_TIME 79 9409
TEST_TIME 80 9432
TEST_TIME 81 9366
TEST_TIME 82 9345
TEST_TIME 83 9350
TEST_TIME 84 9405
TEST_TIME 85 9463
TEST_TIME 86 9464
TEST_TIME 87 9429
TEST_TIME 88 9493
TEST_TIME 89 9452
TEST_TIME 90 9455
TEST_TIME 91 9558
TEST_TIME 92 9544
TEST_TIME 93 9532
TEST_TIME 94 9562
TEST_TIME 95 9574
TEST_TIME 96 9595
TEST_TIME 97 9562
TEST_TIME 98 9474
TEST_TIME 99 9436
TEST_TIME 100 9383
TEST_TIME 101 9430
TEST_TIME 102 9428
TEST_TIME 103 9390
TEST_TIME 104 9349
TEST_TIME 105 9291
TEST_TIME 106 9314
TEST_TIME 107 9318
TEST_TIME 108 9289
TEST_TIME 109 9361
TEST_TIME 110 9331
TEST_TIME 111 9367
TEST_TIME 112 9374
TEST_TIME 113 9350
TEST_TIME 114 9395
TEST_TIME 115 9356
TEST_TIME 116 9360
TEST_TIME 117 9398
TEST_TIME 118 9456
TEST_TIME 119 9479
TEST_TIME 120 9450

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-09-2021 01:39:59 to 07-09-2021 01:40:03.

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 1562744 1562744 0 0.00 0 26712 7 0 0
doc_list_biud 1562744 1562744 0 0.00 0 49887 33 0 0
sp_add_invoice_to_stock 5132 4990 142 2.76 26 169818 17967 0 142
sp_cancel_adding_invoice 5900 5900 0 0.00 5 79050 793 0 0
sp_cancel_client_order 1801 1801 0 0.00 4 15636 355 0 0
sp_cancel_customer_reserve 582337 582337 0 0.00 1 49892 70 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 3628 66 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 3 2754 60 0 0
sp_cancel_supplier_invoice 6541 6535 6 0.09 2 47247 963 0 6
sp_cancel_supplier_order 416 416 0 0.00 7 1124661 124740 0 0
sp_cancel_write_off 10109 10109 0 0.00 1 11927 117 0 0
sp_client_order 21061 21061 0 0.00 4 31216 378 0 0
sp_customer_reserve 463443 463443 0 0.00 0 49210 177 0 0
sp_fill_shopping_cart 32003 32003 0 0.00 1 44428 42 0 0
sp_fill_shopping_cart_clo_res 463440 463440 0 0.00 0 11578 14 0 0
sp_get_clo_for_invoice 4986 4986 0 0.00 0 27568 306 0 0
sp_kill_cost_storno 17160 17160 0 0.00 0 7980 59 0 0
sp_kill_qstorno_ret_qs2qd 1181734 1181734 0 0.00 0 47173 38 0 0
sp_kill_qty_storno 622234 622234 0 0.00 0 49886 81 0 0
sp_lock_selected_doc 21074 21074 0 0.00 0 11091 6 0 0
sp_make_cost_storno 28626 28626 0 0.00 0 26706 69 0 0
sp_multiply_rows_for_pdistr 28626 28626 0 0.00 0 7767 27 0 0
sp_multiply_rows_for_qdistr 21061 21061 0 0.00 0 31200 169 0 0
sp_pay_from_customer 7459 7459 0 0.00 4 18344 140 0 0
sp_pay_to_supplier 6031 6031 0 0.00 5 20392 152 0 0
sp_payment_common 13490 13490 0 0.00 1 18332 37 0 0
sp_qd_handle_on_cancel_clo 1801 1801 0 0.00 0 15631 317 0 0
sp_qd_handle_on_invoice_upd_sts 10886 10886 0 0.00 0 30209 344 0 0
sp_qd_handle_on_reserve_upd_sts 10150 10150 0 0.00 0 17517 62 0 0
sp_reserve_write_off 10150 10150 0 0.00 6 26738 195 0 0
sp_supplier_invoice 5443 5443 0 0.00 6 96271 3375 0 0
sp_supplier_order 5507 5507 0 0.00 5 73407 1401 0 0
srv_aggregate_perf_data 98 98 0 0.00 2716 706300 24787 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1636 139912 43650 0 0
srv_find_qd_qs_mism 1085173 1085173 0 0.00 0 12913 5 0 0
srv_make_invnt_saldo 539 539 0 0.00 52 30988 1216 0 0
srv_make_money_saldo 822 822 0 0.00 1 4223 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 80050 441793 232855 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4990 4990 0 0.00 0 27465 149 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 18866 123 0 0
t$perf-norm:sp_cancel_client_order 1801 1801 0 0.00 0 7195 19 0 0
t$perf-norm:sp_cancel_customer_reserve 715 715 0 0.00 0 2000 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 1185 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 5158 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 4343 44 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 26137 1703 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 3765 7 0 0
t$perf-norm:sp_client_order 21061 21061 0 0.00 0 13379 19 0 0
t$perf-norm:sp_customer_reserve 1048 1048 0 0.00 0 1769 7 0 0
t$perf-norm:sp_pay_from_customer 7459 7459 0 0.00 0 19111 16 0 0
t$perf-norm:sp_pay_to_supplier 6031 6031 0 0.00 0 21845 18 0 0
t$perf-norm:sp_reserve_write_off 10150 10150 0 0.00 0 15536 15 0 0
t$perf-norm:sp_supplier_invoice 5443 5443 0 0.00 0 5229 18 0 0
t$perf-norm:sp_supplier_order 5507 5507 0 0.00 0 13613 19 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 72 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 539 539 0 0.00 0 1848 27 0 0
t$perf-norm:srv_make_money_saldo 822 822 0 0.00 0 4961 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10256 9972 284 2.76 0 6011 21 0 284
v_all_customers 39004 39004 0 0.00 0 27 1 0 0
v_all_suppliers 31030 31030 0 0.00 0 24 1 0 0
v_all_wares 42122 42122 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 4873 22 0 0
v_cancel_client_order 3602 3602 0 0.00 0 4412 7 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 3311 8 0 0
v_cancel_customer_reserve 1430 1430 0 0.00 0 4550 18 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 2746 8 0 0
v_cancel_supplier_invoice 828 816 12 1.44 0 1374 18 0 12
v_cancel_supplier_order 832 832 0 0.00 0 2103 7 0 0
v_cancel_write_off 2072 2072 0 0.00 0 3388 19 0 0
v_max_id_clo_ord 5506 5506 0 0.00 0 525 1 0 0
v_max_id_clo_res 1048 1048 0 0.00 0 5281 16 0 0
v_max_id_ord_sup 5436 5436 0 0.00 0 4018 3 0 0
v_max_non_paid_invoice 1458 1458 0 0.00 0 1785 6 0 0
v_max_non_paid_realizn 3796 3796 0 0.00 0 4747 7 0 0
v_min_id_clo_ord 5506 5506 0 0.00 0 4302 8 0 0
v_min_id_clo_res 1048 1048 0 0.00 0 4735 12 0 0
v_min_id_ord_sup 5436 5436 0 0.00 0 7484 7 0 0
v_min_non_paid_invoice 6031 6031 0 0.00 0 6534 19 0 0
v_min_non_paid_realizn 7459 7459 0 0.00 0 13595 25 0 0
v_our_firm 10444 10444 0 0.00 0 25 1 0 0
v_reserve_write_off 20300 20300 0 0.00 0 5651 11 0 0
x_lock_depdocs_on_canc_invoice 12027 12027 0 0.00 0 20459 136 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 11135 441 0 0
x_make_qstorno_1000_1200 5507 5507 0 0.00 2 73387 1313 0 0
x_make_qstorno_1200_2000 5443 5443 0 0.00 2 72922 3224 0 0
x_make_qstorno_2100_3300 463443 463443 0 0.00 0 49209 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-09-2021 01:40:04 to 07-09-2021 01:40:08.

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:07:52 11 8192000000 8811677664 8825769984 16196576 0.197 3 3 4 0 16436832 78240 162016
23:08:53 31068 8192000000 10391970064 10710310912 1419878960 14.772 100 90 180 1 1455032512 6087344 29066208
23:10:31 16624 8192000000 10441461440 10770804736 1493557312 15.420 100 91 182 0 1531319056 8607648 29154096
23:11:59 16926 8192000000 10487740624 10831581184 1544640336 15.864 100 86 172 1 1581662176 10221760 26800080
23:13:21 15003 8192000000 10575890928 10936246272 1625282784 16.555 100 80 160 3 1660566128 11085728 24197616
23:14:40 11746 8192000000 10632404976 11004928000 1695665840 17.149 100 77 155 2 1730690192 13238112 21786240
23:15:56 7055 8192000000 10713411680 11090759680 1774992640 17.808 100 84 167 3 1811980016 12789856 24197520
23:17:05 3579 8192000000 10761952096 11139289088 1821614528 18.191 100 90 180 3 1860843920 14223008 25006384
23:18:11 1984 8192000000 10788624128 11175874560 1853286224 18.449 100 88 176 0 1891854288 14554848 24013216
23:19:15 2549 8192000000 10819118800 11213881344 1886468752 18.717 100 88 173 2 1922619328 10400464 25750112
23:20:20 11017 8192000000 10793602336 11214598144 1877288528 18.643 100 30 60 0 1892634304 6254224 9091552
23:21:32 5549 8192000000 10800636864 11226329088 1889803216 18.744 100 36 71 3 1906794304 6580672 10410416
23:22:38 6835 8192000000 10804273168 11237167104 1896544688 18.798 100 17 34 1 1907262384 5719152 4998544
23:23:48 12475 8192000000 10807948848 11244343296 1903927600 18.858 100 17 34 0 1914609424 5358432 5323392
23:25:02 36213 8192000000 10827406400 11262332928 1914181952 18.940 100 19 38 0 1924454624 5129376 5143296
23:26:43 6295 8192000000 10830980592 11271409664 1921286352 18.997 100 24 48 1 1934686528 6024288 7375888
23:27:52 12756 8192000000 10817704624 11251765248 1886483584 18.717 99 23 46 0 1898679712 5713520 6482608
23:29:10 7679 8192000000 10746505536 11162312704 1808783264 18.086 98 32 64 0 1825703952 6694768 10225920
23:30:19 27867 8192000000 10718722320 11136106496 1635579920 16.642 88 26 52 1 1648457136 5582128 7295088
23:31:50 9340 8192000000 10704771840 11113541632 1736052928 17.486 96 21 42 0 1747209504 4980064 6176512
23:33:01 7414 8192000000 10720598480 11141623808 1768696064 17.756 97 18 36 1 1779891520 5795968 5399488
23:34:12 71109 8192000000 10741663920 11171598336 1836906512 18.316 100 33 66 1 1852820288 6471776 9442000
23:36:24 7370 8192000000 10764813392 11192176640 1853336016 18.449 100 16 32 3 1863144528 5227248 4581264
23:37:33 7749 8192000000 10804704576 11231420416 1889282240 18.740 100 19 38 1 1899849568 4986256 5581072
23:38:44 44497 8192000000 10868204512 11283210240 1926449952 19.038 100 60 117 0 1957310080 14495248 16364880
23:40:33 16604 8192000000 10869980528 11295621120 1937330624 19.125 100 45 90 3 1967120736 16977808 12812304
23:41:54 19787 8192000000 10884211360 11317161984 1949311632 19.221 100 49 99 2 1980255776 17178576 13765568
23:43:20 11515 8192000000 10911674640 11341287424 1967541312 19.366 100 50 98 1 2003180528 21121568 14517648
23:44:58 3475 8192000000 10944303024 11372834816 1991039792 19.552 100 63 123 2 2034680960 25810704 17830464
23:46:05 6020 8192000000 10953392096 11385880576 2000254032 19.625 100 66 132 5 2048802000 28104912 20443056
23:47:12 8375 8192000000 10952529632 11388755968 2001568624 19.635 100 55 110 0 2048152976 29763104 16821248
23:48:27 8904 8192000000 10989128176 11422085120 2020864720 19.787 100 56 110 2 2072454864 34723488 16866656
23:49:38 1635 8192000000 11003023136 11422396416 2002415072 19.642 99 89 178 2 2076189792 46685968 27088752
23:50:45 15577 8192000000 11006797840 11448856576 2023799776 19.810 100 48 96 1 2096303376 56299488 16204112
23:52:06 3412 8192000000 11003684768 11436519424 2016235536 19.751 100 60 117 1 2087764224 52987312 18541376
23:53:18 3796 8192000000 11019616784 11452297216 2035274048 19.900 100 66 128 2 2101523632 47012208 19237376
23:54:27 10658 8192000000 10993050816 11428888576 1982491664 19.484 98 56 112 1 2050187088 50616976 17078448
23:55:42 5591 8192000000 11022837136 11455729664 2026114592 19.828 100 65 130 3 2103799024 57144224 20540208
23:56:51 12126 8192000000 10951194864 11384897536 1946668864 19.200 97 43 86 1 2010035072 49460880 13905328
23:58:08 4340 8192000000 10955671936 11377651712 1954428352 19.262 99 67 134 1 2035224496 58400464 22395680
23:59:20 4220 8192000000 10975392352 11406450688 1984778080 19.503 100 48 97 1 2058438592 57414592 16245920
00:00:30 11774 8192000000 10980991712 11417174016 1975723600 19.431 100 45 90 1 2063524672 71774192 16026880
00:01:56 14377 8192000000 10978656208 11414056960 1966768592 19.360 100 43 86 0 2065462096 82174496 16519008
00:03:14 15211 8192000000 10927864272 11400384512 1951064064 19.235 100 31 62 1 2031345872 68908032 11373776
00:04:30 20995 8192000000 10907648608 11360337920 1887780640 18.728 98 22 44 1 1975271584 78586544 8904400
00:05:53 26916 8192000000 10867428400 11310333952 1880496400 18.669 97 15 30 1 1915367904 29816528 5054976
00:07:25 28140 8192000000 10879474272 11342303232 1942661104 19.168 100 30 60 1 1968699952 17680240 8358608
00:09:18 33972 8192000000 10872641152 11344273408 1949181120 19.220 100 12 24 2 1961493040 9199472 3112448
00:10:53 80205 8192000000 10887925216 11347640320 1950648592 19.232 99 19 38 0 1964144864 8213616 5282656
00:13:26 57082 8192000000 10908979344 11365732352 1943584048 19.175 98 16 32 1 1957664880 9773440 4307392
00:15:36 76960 8192000000 10907202816 11366940672 1941618416 19.160 98 11 22 0 1956224576 11847344 2758816
00:17:53 79478 8192000000 10892725472 11351650304 1939680400 19.144 98 29 50 3 1955228000 8372496 7175104
00:20:19 53011 8192000000 10919861680 11364454400 1961745904 19.320 99 38 75 1 1985421472 13282400 10393168
00:22:19 13277 8192000000 10903305888 11365421056 1964695136 19.343 99 21 42 2 1984474336 13431616 6347584
00:23:40 17980 8192000000 10930991008 11387080704 1990731184 19.550 100 42 81 3 2017987008 15697712 11558112
00:25:12 18599 8192000000 10896253296 11346259968 1943093776 19.171 98 46 90 1 1970381312 14652416 12635120
00:26:49 61577 8192000000 10876109024 11360215040 1957393104 19.285 99 16 32 1 1974109680 12179760 4536816
00:29:11 81247 8192000000 10901979984 11368099840 1977169792 19.442 100 21 42 1 1990553728 7663824 5720112
00:31:53 108917 8192000000 10907007616 11381325824 1967215280 19.363 99 7 14 0 1983199552 14387792 1596480
00:34:42 60074 8192000000 10913604752 11381489664 1964343552 19.341 99 19 38 0 1976517936 7214928 4959456
00:36:42 18738 8192000000 10907366656 11360874496 1953446368 19.254 99 31 62 2 1972042112 9502976 9092768
00:38:33 61281 8192000000 10900847680 11376222208 1946742000 19.201 99 7 14 1 1963133664 14968336 1423328
00:40:34 44470 8192000000 10916622944 11379560448 1959046912 19.298 99 13 26 1 1972784016 10469664 3267440
00:42:26 61461 8192000000 10897333840 11342413824 1816041456 18.145 92 21 42 0 1833752592 12686080 5025056
00:44:34 52169 8192000000 10881500992 11345932288 1917874320 18.970 99 9 18 0 1932426064 12279248 2272496
00:46:43 62949 8192000000 10896515280 11377987584 1921951040 19.002 98 13 26 1 1947876672 22211808 3713824
00:48:46 115068 8192000000 10871653488 11326443520 1875566976 18.629 96 17 34 2 1889247472 8809136 4871360
00:51:48 47534 8192000000 10863447152 11316469760 1874754832 18.623 97 28 56 2 1894214976 11906192 7553952
00:53:39 20677 8192000000 10843516144 11291033600 1895389248 18.789 98 40 80 0 1916710080 9729520 11591312
00:55:02 42608 8192000000 10858833456 11318775808 1917761904 18.969 99 42 84 0 1944206160 13935328 12508928
00:56:48 90231 8192000000 10865939632 11345985536 1925694720 19.032 99 25 50 0 1951249584 17833440 7721424
00:59:19 64987 8192000000 10874088608 11352350720 1929231632 19.061 99 18 36 0 1958690880 23412352 6046896
01:01:24 68117 8192000000 10867027104 11347271680 1907143968 18.884 98 19 38 1 1924540656 12296544 5100144
01:03:35 45794 8192000000 10911333648 11376431104 1964801024 19.344 100 25 50 0 1987591456 15123008 7667424
01:05:23 34248 8192000000 10907035392 11373088768 1970235488 19.387 100 24 48 0 1987941488 11078816 6627184
01:07:00 65866 8192000000 10900568624 11375976448 1958751008 19.296 99 25 50 1 1978459728 12749008 6959712
01:09:09 56611 8192000000 10919996896 11380109312 1953540880 19.255 98 32 64 3 1972865648 10273632 9051136
01:11:06 29961 8192000000 10940348608 11394420736 1986870688 19.519 100 39 76 1 2012645072 15162848 10611536
01:12:38 77884 8192000000 10886302224 11346325504 1830956736 18.267 92 22 44 3 1846826944 9577488 6292720
01:15:01 123424 8192000000 10897252576 11368497152 1937304208 19.125 98 20 40 0 1955005872 12152832 5548832
01:18:22 46346 8192000000 10882555616 11368996864 1939324064 19.141 98 11 22 0 1952641088 10231280 3085744
01:20:11 54710 8192000000 10882355312 11358810112 1935573136 19.111 98 18 36 0 1949944816 8592496 5779184
01:22:11 79018 8192000000 10871735232 11343978496 1921451728 18.998 98 8 16 0 1937047360 13132608 2463024
01:24:34 60671 8192000000 10882855600 11351162880 1907427936 18.886 97 17 34 0 1923863456 11421056 5014464
01:26:41 45628 8192000000 10867067920 11331919872 1913001680 18.931 98 14 28 1 1928370560 11496640 3872240
01:28:33 80075 8192000000 10871458688 11341733888 1877273440 18.643 96 21 42 0 1897130848 13738816 6118592
01:30:56 57608 8192000000 10871161104 11342135296 1926411872 19.038 99 16 32 1 1947765792 16001360 5352560
01:32:55 48719 8192000000 10870269040 11325280256 1881295888 18.676 97 27 54 1 1899432480 10500672 7635920
01:34:45 139912 8192000000 10862841248 11337584640 1860481360 18.507 96 4 8 1 1876086784 14652256 953168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-09-2021 01:40:09 to 07-09-2021 01:40:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-09-2021 01:40:25 to 07-09-2021 01:40:27.

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          238466
MON$OLDEST_ACTIVE               239888
MON$OLDEST_SNAPSHOT             239888
MON$NEXT_TRANSACTION            239888
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                       2029600
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 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 ---
:301: 
:302: mscs-test-161.budzdorov.ru    Tue Sep  7 01:37:49 2021
:303:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:304: 
:305: 
:306: mscs-test-161.budzdorov.ru    Tue Sep  7 01:38:26 2021
:307:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:308: 
:309: 
:310: mscs-test-161.budzdorov.ru    Tue Sep  7 01:38:28 2021
:311:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:312: 
:313: 
:314: mscs-test-161.budzdorov.ru    Tue Sep  7 01:38:33 2021
:315:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:316: 
:317: 
:318: mscs-test-161.budzdorov.ru    Tue Sep  7 01:38:40 2021
:319:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:320: 
--- end of diff output ---

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

07.09.21 01:40:28. 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
2021-09-07 01:40:31 No severe PSQL-related problems occured
07.09.21 01:40:32. End of report.