Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.10.2021 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 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 15829148 kB
MemAvailable 30707804 kB
Buffers 0 kB
Cached 15838280 kB
SwapCached 4684 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-10-02 22:37:49
DTS_END                         2021-10-02 22:37:49.6060
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/54096
TRN_ID                          247871
ATT_ID                          660
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: 9319.67 22769 1118361
customer order: creation 179.07 355 21488
customer order: refuse 15.93 343 1911
order to supplier: creation 47.22 1359 5666
order to supplier: removal 3.73 96837 448
invoice (draft): creation 48.56 3328 5827
invoice (draft): removal 51.68 910 6201
invoice accept: apply 42.96 17996 5155
invoice accept: cancel 46.18 873 5542
customer reserve: creation 4012.05 176 481446
customer reserve: removal 4566.37 61 547964
realization accept: apply 86.62 172 10394
realization accept: cancel 84.17 119 10100
payment from customer: creation 61.98 117 7438
payment from customer: removal 6.23 108 747
payment to supplier: creation 50.89 147 6107
payment to supplier: removal 3.86 74 463
service: total inventory turnovers 4.43 1227 532
service: total monetary turnovers 6.92 26 830
service: aggregate perf. data 0.83 25830 99
service: refresh index statistics 0.03 305331 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-10-2021 22:38:39 to 02-10-2021 22:38:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4866
TEST_TIME 2 4816
TEST_TIME 3 4877
TEST_TIME 4 4246
TEST_TIME 5 4755
TEST_TIME 6 5855
TEST_TIME 7 6115
TEST_TIME 8 6458
TEST_TIME 9 6310
TEST_TIME 10 6233
TEST_TIME 11 6493
TEST_TIME 12 6398
TEST_TIME 13 6354
TEST_TIME 14 6679
TEST_TIME 15 6765
TEST_TIME 16 6837
TEST_TIME 17 6927
TEST_TIME 18 7040
TEST_TIME 19 7097
TEST_TIME 20 7729
TEST_TIME 21 7694
TEST_TIME 22 7690
TEST_TIME 23 7839
TEST_TIME 24 8168
TEST_TIME 25 8008
TEST_TIME 26 8103
TEST_TIME 27 8428
TEST_TIME 28 8423
TEST_TIME 29 9229
TEST_TIME 30 9794
TEST_TIME 31 10636
TEST_TIME 32 10405
TEST_TIME 33 10302
TEST_TIME 34 10285
TEST_TIME 35 10445
TEST_TIME 36 10422
TEST_TIME 37 10312
TEST_TIME 38 10226
TEST_TIME 39 10158
TEST_TIME 40 10108
TEST_TIME 41 9992
TEST_TIME 42 9965
TEST_TIME 43 9958
TEST_TIME 44 9976
TEST_TIME 45 9934
TEST_TIME 46 9853
TEST_TIME 47 10070
TEST_TIME 48 10135
TEST_TIME 49 9959
TEST_TIME 50 9773
TEST_TIME 51 9832
TEST_TIME 52 9773
TEST_TIME 53 9776
TEST_TIME 54 9712
TEST_TIME 55 9633
TEST_TIME 56 9580
TEST_TIME 57 9631
TEST_TIME 58 9671
TEST_TIME 59 9572
TEST_TIME 60 9587
TEST_TIME 61 9477
TEST_TIME 62 9449
TEST_TIME 63 9572
TEST_TIME 64 9527
TEST_TIME 65 9515
TEST_TIME 66 9633
TEST_TIME 67 9618
TEST_TIME 68 9585
TEST_TIME 69 9666
TEST_TIME 70 9600
TEST_TIME 71 9729
TEST_TIME 72 9683
TEST_TIME 73 9654
TEST_TIME 74 9616
TEST_TIME 75 9747
TEST_TIME 76 9723
TEST_TIME 77 9716
TEST_TIME 78 9618
TEST_TIME 79 9595
TEST_TIME 80 9578
TEST_TIME 81 9529
TEST_TIME 82 9531
TEST_TIME 83 9510
TEST_TIME 84 9461
TEST_TIME 85 9444
TEST_TIME 86 9439
TEST_TIME 87 9364
TEST_TIME 88 9360
TEST_TIME 89 9350
TEST_TIME 90 9326
TEST_TIME 91 9282
TEST_TIME 92 9290
TEST_TIME 93 9256
TEST_TIME 94 9362
TEST_TIME 95 9326
TEST_TIME 96 9368
TEST_TIME 97 9383
TEST_TIME 98 9425
TEST_TIME 99 9417
TEST_TIME 100 9387
TEST_TIME 101 9422
TEST_TIME 102 9474
TEST_TIME 103 9447
TEST_TIME 104 9473
TEST_TIME 105 9481
TEST_TIME 106 9444
TEST_TIME 107 9374
TEST_TIME 108 9391
TEST_TIME 109 9387
TEST_TIME 110 9358
TEST_TIME 111 9335
TEST_TIME 112 9283
TEST_TIME 113 9258
TEST_TIME 114 9275
TEST_TIME 115 9259
TEST_TIME 116 9263
TEST_TIME 117 9265
TEST_TIME 118 9295
TEST_TIME 119 9336
TEST_TIME 120 9326

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-10-2021 22:38:43 to 02-10-2021 22:38:46.

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 1564123 1564123 0 0.00 0 29571 6 0 0
doc_list_biud 1564121 1564121 0 0.00 0 61457 28 0 0
sp_add_invoice_to_stock 5240 5155 85 1.62 33 179741 17996 0 85
sp_cancel_adding_invoice 5542 5542 0 0.00 5 78951 874 0 0
sp_cancel_client_order 1911 1911 0 0.00 4 14502 344 0 0
sp_cancel_customer_reserve 547964 547964 0 0.00 1 24146 62 0 0
sp_cancel_pay_from_customer 747 747 0 0.00 3 11281 109 0 0
sp_cancel_pay_to_supplier 463 463 0 0.00 2 2665 75 0 0
sp_cancel_supplier_invoice 6206 6201 5 0.08 2 61461 911 0 5
sp_cancel_supplier_order 448 448 0 0.00 9 1056423 96838 0 0
sp_cancel_write_off 10100 10100 0 0.00 1 14481 119 0 0
sp_client_order 21488 21488 0 0.00 4 25425 355 0 0
sp_customer_reserve 481446 481446 0 0.00 0 45366 177 0 0
sp_fill_shopping_cart 32964 32964 0 0.00 1 26330 41 0 0
sp_fill_shopping_cart_clo_res 481442 481442 0 0.00 0 15467 13 0 0
sp_get_clo_for_invoice 5144 5144 0 0.00 0 33814 294 0 0
sp_kill_cost_storno 16852 16852 0 0.00 0 11276 61 0 0
sp_kill_qstorno_ret_qs2qd 1112677 1112677 0 0.00 0 61455 34 0 0
sp_kill_qty_storno 587703 587703 0 0.00 0 61456 73 0 0
sp_lock_selected_doc 21803 21803 0 0.00 0 9832 4 0 0
sp_make_cost_storno 29083 29083 0 0.00 0 29569 50 0 0
sp_multiply_rows_for_pdistr 29081 29081 0 0.00 0 10903 31 0 0
sp_multiply_rows_for_qdistr 21488 21488 0 0.00 0 17207 162 0 0
sp_pay_from_customer 7438 7438 0 0.00 4 13443 117 0 0
sp_pay_to_supplier 6107 6107 0 0.00 5 29580 148 0 0
sp_payment_common 13544 13544 0 0.00 1 10942 37 0 0
sp_qd_handle_on_cancel_clo 1911 1911 0 0.00 0 14471 318 0 0
sp_qd_handle_on_invoice_upd_sts 10685 10685 0 0.00 0 16492 351 0 0
sp_qd_handle_on_reserve_upd_sts 10394 10394 0 0.00 0 17525 57 0 0
sp_reserve_write_off 10394 10394 0 0.00 6 18549 173 0 0
sp_supplier_invoice 5827 5827 0 0.00 6 84008 3328 0 0
sp_supplier_order 5666 5666 0 0.00 5 37537 1360 0 0
srv_aggregate_perf_data 99 99 0 0.00 2718 400808 25831 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2019 146139 43746 0 0
srv_find_qd_qs_mism 1068543 1068543 0 0.00 0 19529 5 0 0
srv_make_invnt_saldo 532 532 0 0.00 29 82886 1228 0 0
srv_make_money_saldo 830 830 0 0.00 1 2040 27 0 0
srv_recalc_idx_stat 3 3 0 0.00 189434 443476 305331 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5156 5156 0 0.00 0 9325 163 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 1 9218 96 0 0
t$perf-norm:sp_cancel_client_order 1911 1911 0 0.00 0 3976 20 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 3012 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 747 747 0 0.00 0 2291 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 463 463 0 0.00 0 1399 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 1029 10 0 0
t$perf-norm:sp_cancel_supplier_order 448 448 0 0.00 0 15810 1519 0 0
t$perf-norm:sp_cancel_write_off 1107 1107 0 0.00 0 5367 14 0 0
t$perf-norm:sp_client_order 21488 21488 0 0.00 0 7146 19 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 6792 12 0 0
t$perf-norm:sp_pay_from_customer 7438 7438 0 0.00 0 5801 12 0 0
t$perf-norm:sp_pay_to_supplier 6107 6107 0 0.00 0 4256 9 0 0
t$perf-norm:sp_reserve_write_off 10394 10394 0 0.00 0 5541 11 0 0
t$perf-norm:sp_supplier_invoice 5827 5827 0 0.00 0 7107 18 0 0
t$perf-norm:sp_supplier_order 5666 5666 0 0.00 0 3751 17 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 270 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 355 6 0 0
t$perf-norm:srv_make_invnt_saldo 532 532 0 0.00 0 1580 27 0 0
t$perf-norm:srv_make_money_saldo 830 830 0 0.00 0 952 13 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10456 10286 170 1.62 0 6264 19 0 170
v_all_customers 39076 39076 0 0.00 0 17 1 0 0
v_all_suppliers 31654 31654 0 0.00 0 15 1 0 0
v_all_wares 42968 42968 0 0.00 0 52 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 5790 19 0 0
v_cancel_client_order 3822 3822 0 0.00 0 3245 5 0 0
v_cancel_customer_prepayment 1494 1494 0 0.00 0 1201 5 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 2389 8 0 0
v_cancel_payment_to_supplier 926 926 0 0.00 0 1201 5 0 0
v_cancel_supplier_invoice 900 890 10 1.11 0 5590 24 0 10
v_cancel_supplier_order 896 896 0 0.00 0 1058 5 0 0
v_cancel_write_off 2214 2214 0 0.00 0 5785 17 0 0
v_max_id_clo_ord 5659 5659 0 0.00 0 1128 2 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 2457 11 0 0
v_max_id_ord_sup 5820 5820 0 0.00 0 3040 3 0 0
v_max_non_paid_invoice 1758 1758 0 0.00 0 2141 5 0 0
v_max_non_paid_realizn 3967 3967 0 0.00 0 1647 4 0 0
v_min_id_clo_ord 5659 5659 0 0.00 0 24496 13 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 4856 16 0 0
v_min_id_ord_sup 5820 5820 0 0.00 0 6740 11 0 0
v_min_non_paid_invoice 6106 6106 0 0.00 0 10932 30 0 0
v_min_non_paid_realizn 7438 7438 0 0.00 0 9532 26 0 0
v_our_firm 10834 10834 0 0.00 0 30 1 0 0
v_reserve_write_off 20788 20788 0 0.00 0 6267 7 0 0
x_lock_depdocs_on_canc_invoice 11298 11298 0 0.00 0 20460 121 0 0
x_lock_depdocs_on_canc_sup_ord 448 448 0 0.00 0 4656 298 0 0
x_make_qstorno_1000_1200 5666 5666 0 0.00 1 37526 1270 0 0
x_make_qstorno_1200_2000 5827 5827 0 0.00 3 83993 3189 0 0
x_make_qstorno_2100_3300 481446 481446 0 0.00 0 45364 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-10-2021 22:38:47 to 02-10-2021 22:38:51.

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:07:53 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:54 18913 8192000000 10380760080 10702573568 1438356080 14.935 100 87 174 1 1473828400 6558800 28913520
20:10:18 13897 8192000000 10452211744 10785091584 1510826544 15.570 100 88 175 0 1547578016 8420736 28330736
20:11:36 10374 8192000000 10491097648 10831540224 1543946992 15.858 100 82 164 0 1579047568 9067456 26033120
20:12:55 12986 8192000000 10546917296 10904141824 1602212416 16.358 100 69 139 1 1633374784 10256112 20906256
20:14:11 12586 8192000000 10629336544 10994397184 1682765920 17.041 100 74 147 1 1716493792 12494176 21233696
20:15:31 6087 8192000000 10683981024 11061321728 1748495216 17.589 100 66 132 2 1778802592 11434064 18873312
20:16:39 9685 8192000000 10747430528 11130331136 1808724320 18.085 100 79 158 1 1847512192 17300256 21487616
20:17:53 2538 8192000000 10806292032 11194015744 1861916608 18.519 100 92 183 1 1901083904 13143152 26024144
20:18:57 1632 8192000000 10821334992 11211821056 1878484944 18.653 100 75 151 1 1910270288 10952976 20832368
20:19:59 2200 8192000000 10809131872 11223371776 1889945104 18.745 100 48 96 0 1912051936 8283856 13822976
20:21:05 61096 8192000000 10835934992 11245613056 1901038064 18.835 100 56 114 1 1922947088 4947072 16961952
20:23:07 7211 8192000000 10818832912 11254427648 1909896976 18.906 100 26 52 0 1923904160 6120848 7886336
20:24:15 12629 8192000000 10835935072 11270258688 1918327392 18.973 100 24 49 2 1931488928 5956528 7205008
20:25:28 59750 8192000000 10854859968 11281387520 1928694256 19.056 100 37 75 1 1945249952 5351712 11203984
20:27:29 4780 8192000000 10811304624 11236225024 1889223296 18.740 100 53 106 2 1911056048 6388608 15444144
20:28:36 6080 8192000000 10738766592 11162050560 1791669776 17.946 97 24 47 0 1804440688 6581872 6189040
20:29:44 14744 8192000000 10728449056 11150569472 1773399472 17.795 97 15 31 1 1782867008 5011984 4455552
20:31:00 30491 8192000000 10722375808 11143553024 1742718496 17.541 95 22 44 0 1755128432 5803888 6606048
20:32:31 10457 8192000000 10735116544 11151650816 1792467168 17.952 98 31 62 0 1807138400 6499120 8172112
20:33:44 13017 8192000000 10767342624 11198201856 1856648944 18.476 100 19 38 2 1867648960 5710528 5289488
20:34:58 6066 8192000000 10774944544 11213332480 1872557168 18.605 100 24 48 0 1887685536 7413888 7714480
20:36:05 16616 8192000000 10795589056 11223703552 1876659072 18.638 100 28 54 1 1890747824 6501472 7587280
20:37:26 5654 8192000000 10814463168 11246579712 1904895248 18.866 100 19 38 1 1915106576 5383088 4828240
20:38:34 8093 8192000000 10866257104 11289567232 1937783344 19.129 100 53 107 0 1962450576 8500720 16166512
20:39:46 33705 8192000000 10880113920 11313602560 1955363520 19.269 100 45 90 2 1977557024 9933024 12260480
20:41:32 44683 8192000000 10892948480 11332395008 1964224000 19.340 100 34 68 0 1987539248 12919440 10395808
20:43:23 9084 8192000000 10903518864 11337543680 1954480224 19.262 99 49 97 1 1980526016 12178688 13867104
20:44:38 4493 8192000000 10928200304 11361910784 1985132256 19.505 100 57 114 0 2012518624 11288640 16097728
20:45:45 5836 8192000000 10931848240 11363033088 1982271744 19.483 100 56 110 1 2010752656 13061712 15419200
20:46:55 7491 8192000000 10877623600 11315236864 1887840784 18.728 96 46 92 2 1919317536 18287744 13189008
20:48:08 5219 8192000000 10929293888 11354996736 1966949840 19.361 99 65 130 2 2007534992 20943680 19641472
20:49:15 10141 8192000000 10922981440 11352162304 1963222704 19.332 100 58 113 4 2007936256 27908320 16805232
20:50:33 7104 8192000000 10937251584 11370766336 1974033904 19.417 100 62 124 0 2026813200 33019696 19759600
20:51:46 2389 8192000000 10919553648 11345760256 1948945984 19.218 99 61 122 1 1997030464 29515264 18569216
20:52:54 7046 8192000000 10941677808 11362009088 1974447664 19.421 100 68 134 1 2029060096 34146512 20465920
20:54:05 4650 8192000000 10959445328 11381899264 1986261728 19.514 100 73 146 3 2045968864 37184688 22522448
20:55:14 2649 8192000000 10961739392 11380916224 1981317312 19.475 100 71 142 1 2043731872 40525296 21889264
20:56:23 6726 8192000000 10934330016 11346817024 1951777712 19.241 99 76 153 1 2018342512 43647040 22917760
20:57:36 2019 8192000000 10968641920 11381817344 1967040992 19.362 100 84 166 3 2046113824 53003888 26068944
20:58:41 9970 8192000000 10892652768 11323961344 1909838848 18.905 99 41 79 0 1968783760 46218880 12726032
21:00:11 5018 8192000000 10931890832 11356700672 1944287408 19.181 100 58 114 2 2015440464 53144064 18008992
21:01:24 16682 8192000000 10908974000 11353546752 1926643088 19.040 99 30 60 1 1985552928 48929824 9980016
21:02:47 11343 8192000000 10931490720 11362738176 1936272416 19.117 99 39 76 0 2001101152 51273152 13555584
21:04:02 10765 8192000000 10950007008 11391008768 1960556400 19.310 100 38 76 2 2032877696 59177104 13144192
21:05:15 26747 8192000000 10902877424 11357552640 1900169232 18.828 98 27 54 1 1971358512 61003120 10186160
21:06:55 42230 8192000000 10922038000 11379298304 1940655232 19.152 100 21 42 1 2004740000 56513840 7570928
21:08:45 54798 8192000000 10905853680 11351216128 1962519552 19.326 100 25 50 0 1980401536 10471152 7410832
21:10:40 20075 8192000000 10911087808 11367436288 1977456400 19.445 100 36 73 2 1997288656 9294928 10537328
21:12:58 99804 8192000000 10895583152 11342077952 1880010816 18.665 95 17 34 1 1900339568 16095136 4233616
21:15:38 64151 8192000000 10930690832 11370012672 1982441456 19.484 100 35 71 0 2003710608 10524064 10745088
21:17:46 32051 8192000000 10928749248 11383705600 1995311888 19.586 100 39 79 2 2013035504 6785168 10938448
21:19:23 24411 8192000000 10920340784 11390623744 1992088288 19.560 100 23 46 1 2008439744 9616288 6735168
21:20:48 103185 8192000000 10921432064 11385712640 1958821104 19.297 98 10 18 1 1976958592 15780944 2356544
21:23:36 63662 8192000000 10929179248 11403730944 1976402544 19.436 99 12 24 0 1997036688 17037312 3596832
21:25:40 146137 8192000000 10919591152 11369820160 1973171088 19.411 99 17 34 1 2001621936 23858304 4592544
21:29:15 37329 8192000000 10935471888 11378024448 1941784096 19.161 97 55 105 2 1964328240 7729936 14814208
21:30:54 32458 8192000000 10883686144 11357855744 1938918640 19.138 98 9 18 1 1952200208 10725360 2556208
21:32:36 45774 8192000000 10907028304 11368521728 1951726608 19.240 99 25 50 1 1974111712 14667536 7717568
21:34:30 38218 8192000000 10931544688 11395747840 1992136992 19.561 100 39 78 0 2021072256 16627872 12307392
21:36:19 66944 8192000000 10935951872 11413147648 2009287200 19.696 100 20 40 3 2032476000 17518640 5670160
21:38:45 61436 8192000000 10933382944 11406319616 1997956816 19.607 100 23 46 1 2020603120 15255472 7390832
21:40:48 44375 8192000000 10920981328 11393310720 1971560208 19.398 99 22 44 1 1986851120 9320640 5970272
21:42:49 74896 8192000000 10875035984 11353374720 1837699072 18.322 93 14 28 1 1849993024 8294000 3999952
21:45:06 53114 8192000000 10887421120 11340025856 1849773536 18.420 94 39 77 1 1871947248 11329392 10844320
21:47:00 103606 8192000000 10858927120 11333570560 1822867168 18.201 94 8 16 0 1845263840 20120304 2276368
21:49:43 19308 8192000000 10865678336 11317559296 1928776960 19.057 99 38 76 2 1948262000 9642288 9842752
21:51:03 87325 8192000000 10864212448 11343798272 1876640560 18.638 96 15 30 0 1902889856 21069632 5179664
21:53:34 54587 8192000000 10882854912 11342073856 1903067888 18.851 98 31 62 3 1924168960 12617312 8483760
21:56:19 73050 8192000000 10874020800 11340206080 1915304576 18.949 98 22 44 0 1933583920 12088160 6191184
21:58:35 15539 8192000000 10912204400 11346788352 1957952272 19.290 100 61 122 3 1981977792 7889536 16135984
21:59:52 85531 8192000000 10879661120 11360149504 1945105152 19.187 99 14 26 0 1957164480 8361072 3698256
22:02:18 54554 8192000000 10867644784 11348738048 1926054464 19.035 98 10 20 1 1938203568 8970336 3178768
22:05:05 48638 8192000000 10904227856 11371769856 1940221792 19.149 98 24 49 0 1954265808 7727424 6316592
22:06:54 81543 8192000000 10891291280 11380228096 1976039840 19.433 100 10 20 0 1986801264 8408320 2353104
22:09:17 62568 8192000000 10916199328 11390664704 1961127344 19.315 98 24 48 1 1977560160 10287120 6145696
22:11:21 65941 8192000000 10909699072 11382366208 1954432000 19.262 98 12 24 0 1973932352 15921376 3578976
22:13:32 54273 8192000000 10917854976 11389341696 1942924608 19.170 97 25 50 0 1959786448 10114432 6747408
22:15:35 82677 8192000000 10924316448 11387957248 1901785776 18.841 95 26 52 1 1919248240 10596672 6865792
22:18:00 51655 8192000000 10894798336 11364823040 1910832112 18.913 97 16 32 1 1927511792 12543328 4136352
22:19:53 47077 8192000000 10919128768 11365769216 1938787728 19.137 98 27 50 1 1953086000 6570160 7728112
22:21:40 64504 8192000000 10897411888 11365007360 1932643440 19.088 98 18 34 0 1947273552 10014352 4615760
22:23:46 113189 8192000000 10902048896 11377127424 1968770208 19.376 100 33 65 0 1993938064 15970416 9197440
22:26:40 27931 8192000000 10828324224 11299368960 1874430048 18.620 97 10 20 1 1889058416 11983616 2644752
22:28:11 63554 8192000000 10833272320 11310809088 1904400656 18.862 98 10 20 0 1915592192 7589280 3602256
22:30:17 71587 8192000000 10856394480 11334172672 1886064832 18.714 97 14 28 2 1898702336 9159792 3477712
22:32:41 27975 8192000000 10876111840 11346903040 1930037792 19.067 99 20 40 0 1948251440 12386352 5827296
22:34:15 68675 8192000000 10840779232 11302719488 1796524832 17.985 93 19 37 1 1817330480 15236544 5569104
22:36:27 71161 8192000000 10877305680 11334537216 1888859152 18.737 98 15 30 1 1905280192 12337328 4083712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-10-2021 22:38:53 to 02-10-2021 22:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-10-2021 22:39:09 to 02-10-2021 22:39:11.

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          246598
MON$OLDEST_ACTIVE               248086
MON$OLDEST_SNAPSHOT             248086
MON$NEXT_TRANSACTION            248086
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                       2048544
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.33506 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33506 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33506 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33506 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 ---
:108: 
:109: mscs-test-161.budzdorov.ru    Sat Oct  2 22:37:51 2021
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:111: 
:112: 
:113: mscs-test-161.budzdorov.ru    Sat Oct  2 22:37:58 2021
:114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:115: 
--- end of diff output ---

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

02.10.21 22:39:12. 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-10-02 22:39:15 No severe PSQL-related problems occured
02.10.21 22:39:16. End of report.