Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.12.2021 01: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 16611724 kB
MemAvailable 31372616 kB
Buffers 0 kB
Cached 15032876 kB
SwapCached 444 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 2021-12-21 01:37:59
DTS_END                         2021-12-21 01:37:59.7250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34434
TRN_ID                          245166
ATT_ID                          678
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: 9458.50 20687 1135020
customer order: creation 177.68 347 21322
customer order: refuse 15.32 338 1838
order to supplier: creation 46.08 1319 5529
order to supplier: removal 3.35 112723 402
invoice (draft): creation 47.69 3284 5723
invoice (draft): removal 53.92 876 6470
invoice accept: apply 42.83 17805 5140
invoice accept: cancel 48.27 781 5792
customer reserve: creation 3984.82 175 478178
customer reserve: removal 4730.49 62 567659
realization accept: apply 85.95 178 10314
realization accept: cancel 88.83 120 10659
payment from customer: creation 59.63 115 7155
payment from customer: removal 5.91 83 709
payment to supplier: creation 51.63 137 6195
payment to supplier: removal 3.63 98 436
service: total inventory turnovers 5.02 884 602
service: total monetary turnovers 6.63 21 795
service: aggregate perf. data 0.82 22612 98
service: refresh index statistics 0.03 251790 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-12-2021 01:39:03 to 21-12-2021 01:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5280
TEST_TIME 2 4712
TEST_TIME 3 4590
TEST_TIME 4 5341
TEST_TIME 5 5314
TEST_TIME 6 5513
TEST_TIME 7 5390
TEST_TIME 8 5768
TEST_TIME 9 6142
TEST_TIME 10 6562
TEST_TIME 11 6595
TEST_TIME 12 6456
TEST_TIME 13 6775
TEST_TIME 14 6999
TEST_TIME 15 7081
TEST_TIME 16 7406
TEST_TIME 17 7760
TEST_TIME 18 8044
TEST_TIME 19 8293
TEST_TIME 20 8297
TEST_TIME 21 8531
TEST_TIME 22 9162
TEST_TIME 23 8974
TEST_TIME 24 9230
TEST_TIME 25 9221
TEST_TIME 26 9708
TEST_TIME 27 10541
TEST_TIME 28 11441
TEST_TIME 29 11268
TEST_TIME 30 10914
TEST_TIME 31 10737
TEST_TIME 32 10713
TEST_TIME 33 10530
TEST_TIME 34 10472
TEST_TIME 35 10512
TEST_TIME 36 10280
TEST_TIME 37 10249
TEST_TIME 38 10330
TEST_TIME 39 10374
TEST_TIME 40 10395
TEST_TIME 41 10373
TEST_TIME 42 10242
TEST_TIME 43 10148
TEST_TIME 44 10199
TEST_TIME 45 10122
TEST_TIME 46 10079
TEST_TIME 47 10118
TEST_TIME 48 10065
TEST_TIME 49 10063
TEST_TIME 50 10047
TEST_TIME 51 10039
TEST_TIME 52 10052
TEST_TIME 53 10082
TEST_TIME 54 10061
TEST_TIME 55 10221
TEST_TIME 56 10192
TEST_TIME 57 10217
TEST_TIME 58 10144
TEST_TIME 59 10085
TEST_TIME 60 9946
TEST_TIME 61 9930
TEST_TIME 62 9975
TEST_TIME 63 9873
TEST_TIME 64 9820
TEST_TIME 65 9796
TEST_TIME 66 9837
TEST_TIME 67 9824
TEST_TIME 68 9720
TEST_TIME 69 9683
TEST_TIME 70 9601
TEST_TIME 71 9548
TEST_TIME 72 9522
TEST_TIME 73 9503
TEST_TIME 74 9470
TEST_TIME 75 9550
TEST_TIME 76 9550
TEST_TIME 77 9503
TEST_TIME 78 9483
TEST_TIME 79 9619
TEST_TIME 80 9596
TEST_TIME 81 9601
TEST_TIME 82 9717
TEST_TIME 83 9762
TEST_TIME 84 9793
TEST_TIME 85 9749
TEST_TIME 86 9683
TEST_TIME 87 9654
TEST_TIME 88 9618
TEST_TIME 89 9640
TEST_TIME 90 9585
TEST_TIME 91 9538
TEST_TIME 92 9492
TEST_TIME 93 9511
TEST_TIME 94 9502
TEST_TIME 95 9541
TEST_TIME 96 9508
TEST_TIME 97 9563
TEST_TIME 98 9557
TEST_TIME 99 9510
TEST_TIME 100 9515
TEST_TIME 101 9641
TEST_TIME 102 9600
TEST_TIME 103 9634
TEST_TIME 104 9650
TEST_TIME 105 9651
TEST_TIME 106 9670
TEST_TIME 107 9642
TEST_TIME 108 9648
TEST_TIME 109 9579
TEST_TIME 110 9573
TEST_TIME 111 9560
TEST_TIME 112 9536
TEST_TIME 113 9504
TEST_TIME 114 9499
TEST_TIME 115 9468
TEST_TIME 116 9416
TEST_TIME 117 9430
TEST_TIME 118 9413
TEST_TIME 119 9449
TEST_TIME 120 9467

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-12-2021 01:39:07 to 21-12-2021 01:39:10.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1575873 1575873 0 0.00 0 18723 6 0 0
doc_list_biud 1575872 1575872 0 0.00 0 51447 28 0 0
sp_add_invoice_to_stock 5235 5140 95 1.81 18 174533 17805 0 95
sp_cancel_adding_invoice 5792 5792 0 0.00 5 82195 782 0 0
sp_cancel_client_order 1838 1838 0 0.00 4 11259 338 0 0
sp_cancel_customer_reserve 567659 567659 0 0.00 1 51450 62 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 2 10638 83 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 7146 98 0 0
sp_cancel_supplier_invoice 6477 6470 7 0.10 1 16161 876 0 7
sp_cancel_supplier_order 402 402 0 0.00 10 1165734 112724 0 0
sp_cancel_write_off 10659 10659 0 0.00 1 16569 120 0 0
sp_client_order 21322 21322 0 0.00 3 31498 348 0 0
sp_customer_reserve 478178 478178 0 0.00 0 74139 176 0 0
sp_fill_shopping_cart 32573 32573 0 0.00 1 17536 37 0 0
sp_fill_shopping_cart_clo_res 478163 478163 0 0.00 0 14774 13 0 0
sp_get_clo_for_invoice 5113 5113 0 0.00 0 34539 288 0 0
sp_kill_cost_storno 17596 17596 0 0.00 0 8183 58 0 0
sp_kill_qstorno_ret_qs2qd 1152849 1152849 0 0.00 0 41069 34 0 0
sp_kill_qty_storno 608243 608243 0 0.00 0 51446 73 0 0
sp_lock_selected_doc 21458 21458 0 0.00 0 10631 6 0 0
sp_make_cost_storno 28773 28773 0 0.00 0 18718 57 0 0
sp_multiply_rows_for_pdistr 28772 28772 0 0.00 0 8618 28 0 0
sp_multiply_rows_for_qdistr 21322 21322 0 0.00 0 11639 150 0 0
sp_pay_from_customer 7155 7155 0 0.00 4 16663 115 0 0
sp_pay_to_supplier 6195 6195 0 0.00 4 15600 137 0 0
sp_payment_common 13349 13349 0 0.00 1 12100 25 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 0 11252 309 0 0
sp_qd_handle_on_invoice_upd_sts 10901 10901 0 0.00 0 21070 314 0 0
sp_qd_handle_on_reserve_upd_sts 10314 10314 0 0.00 0 14665 56 0 0
sp_reserve_write_off 10314 10314 0 0.00 6 17930 179 0 0
sp_supplier_invoice 5723 5723 0 0.00 6 80313 3285 0 0
sp_supplier_order 5529 5529 0 0.00 4 40390 1319 0 0
srv_aggregate_perf_data 98 98 0 0.00 2651 572925 22613 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2571 143568 45350 0 0
srv_find_qd_qs_mism 1084812 1084812 0 0.00 0 18022 5 0 0
srv_make_invnt_saldo 602 602 0 0.00 18 15925 885 0 0
srv_make_money_saldo 795 795 0 0.00 1 639 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 154154 345982 251790 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5140 5140 0 0.00 0 17012 172 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 4304 99 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 4130 21 0 0
t$perf-norm:sp_cancel_customer_reserve 737 737 0 0.00 0 3342 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 2586 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 653 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 450 12 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 20087 1689 0 0
t$perf-norm:sp_cancel_write_off 1078 1078 0 0.00 0 4954 16 0 0
t$perf-norm:sp_client_order 21323 21323 0 0.00 0 11568 17 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 1127 6 0 0
t$perf-norm:sp_pay_from_customer 7155 7155 0 0.00 0 4974 12 0 0
t$perf-norm:sp_pay_to_supplier 6195 6195 0 0.00 0 8230 12 0 0
t$perf-norm:sp_reserve_write_off 10314 10314 0 0.00 0 17784 12 0 0
t$perf-norm:sp_supplier_invoice 5723 5723 0 0.00 0 3012 14 0 0
t$perf-norm:sp_supplier_order 5529 5529 0 0.00 0 4099 13 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 591 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 80 2 0 0
t$perf-norm:srv_make_invnt_saldo 602 602 0 0.00 0 3755 25 0 0
t$perf-norm:srv_make_money_saldo 795 795 0 0.00 0 605 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10408 10218 190 1.82 0 7532 20 0 190
v_all_customers 38846 38846 0 0.00 0 19 0 0 0
v_all_suppliers 31714 31714 0 0.00 0 18 1 0 0
v_all_wares 42644 42644 0 0.00 0 64 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 1136 8 0 0
v_cancel_client_order 3676 3676 0 0.00 0 3141 6 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 1995 9 0 0
v_cancel_customer_reserve 1474 1474 0 0.00 0 3156 7 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 3758 12 0 0
v_cancel_supplier_invoice 878 864 14 1.59 0 3194 24 0 14
v_cancel_supplier_order 804 804 0 0.00 0 1012 6 0 0
v_cancel_write_off 2156 2156 0 0.00 0 6588 18 0 0
v_max_id_clo_ord 5529 5529 0 0.00 0 1309 1 0 0
v_max_id_clo_res 990 990 0 0.00 0 2023 20 0 0
v_max_id_ord_sup 5722 5722 0 0.00 0 5021 4 0 0
v_max_non_paid_invoice 1588 1588 0 0.00 0 2144 4 0 0
v_max_non_paid_realizn 3754 3754 0 0.00 0 2985 4 0 0
v_min_id_clo_ord 5529 5529 0 0.00 0 3386 7 0 0
v_min_id_clo_res 990 990 0 0.00 0 2708 7 0 0
v_min_id_ord_sup 5722 5722 0 0.00 0 7207 8 0 0
v_min_non_paid_invoice 6194 6194 0 0.00 0 12095 17 0 0
v_min_non_paid_realizn 7155 7155 0 0.00 0 11024 20 0 0
v_our_firm 10600 10600 0 0.00 0 69 1 0 0
v_reserve_write_off 20628 20628 0 0.00 0 6661 8 0 0
x_lock_depdocs_on_canc_invoice 11830 11830 0 0.00 0 26119 116 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 10246 415 0 0
x_make_qstorno_1000_1200 5529 5529 0 0.00 2 40382 1242 0 0
x_make_qstorno_1200_2000 5723 5723 0 0.00 3 71283 3151 0 0
x_make_qstorno_2100_3300 478178 478178 0 0.00 0 74139 160 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 21-12-2021 01:39:11 to 21-12-2021 01:39:16.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:03 5 8192000000 8799031136 8810369024 5314912 0.064 2 1 2 0 5414512 56816 42784
23:09:04 29137 8192000000 9908546720 10219737088 988511840 10.767 100 92 184 0 1018952048 6115168 24325040
23:10:42 18124 8192000000 9948432880 10270928896 1028935248 11.158 100 88 176 1 1059988128 8523104 22529776
23:12:15 19985 8192000000 10004168928 10341523456 1078470720 11.633 100 84 168 0 1108999280 10037552 20491008
23:13:38 10169 8192000000 10043609056 10397544448 1136916816 12.187 100 81 162 0 1167285776 11241504 19127456
23:14:51 9703 8192000000 10102859296 10464600064 1192076336 12.703 100 86 172 0 1223344096 11673216 19594544
23:16:03 4406 8192000000 10132942544 10505613312 1227904000 13.035 100 85 170 0 1261168288 14241776 19022512
23:17:09 2724 8192000000 10177043984 10558849024 1265066016 13.376 100 88 173 1 1296724128 11910464 19747648
23:18:13 2114 8192000000 10207950160 10591526912 1296997584 13.668 100 87 173 0 1327899712 11443056 19459072
23:19:17 7900 8192000000 10202831056 10601308160 1300815648 13.703 100 44 90 1 1318240272 6576960 10847664
23:20:26 6611 8192000000 10199061232 10611335168 1310089600 13.787 100 31 62 0 1324477584 7579632 6808352
23:21:34 10916 8192000000 10198982048 10615869440 1316424240 13.844 100 11 22 0 1324752224 5901840 2426144
23:22:45 61088 8192000000 10214929232 10639273984 1326134240 13.932 100 30 60 1 1338624016 5687376 6802400
23:24:49 5423 8192000000 10239066320 10651750400 1332640912 13.991 100 43 86 3 1351512560 9739488 9132160
23:25:56 3932 8192000000 10220339472 10641272832 1327601984 13.945 100 32 64 1 1341788688 6397472 7789232
23:27:02 15354 8192000000 10212231696 10626658304 1289073888 13.596 97 35 70 2 1304234400 7101120 8059392
23:28:22 18968 8192000000 10187107744 10597957632 1259329488 13.324 96 22 44 1 1270648096 6133104 5185504
23:29:43 7796 8192000000 10144368480 10538704896 1208030640 12.851 95 24 47 1 1219634464 6258288 5345536
23:30:51 8252 8192000000 10129781616 10531106816 1218344960 12.946 97 13 26 0 1226682016 5143984 3193072
23:31:59 16372 8192000000 10162887120 10562482176 1251972176 13.256 99 20 40 0 1261856896 5000704 4884016
23:34:02 4269 8192000000 10182316176 10590212096 1287730240 13.584 100 34 66 2 1302417520 7566016 7121264
23:35:09 4993 8192000000 10204356096 10613563392 1306894528 13.758 100 30 60 3 1319457488 5388400 7174560
23:36:24 8460 8192000000 10199971664 10619006976 1314781040 13.829 100 34 68 2 1327989952 5120448 8088464
23:37:35 32284 8192000000 10228312656 10638495744 1321696768 13.892 100 34 68 2 1335657456 5612272 8348416
23:39:13 25316 8192000000 10253020304 10668077056 1344711920 14.100 100 44 88 0 1368254592 14116672 9426000
23:40:43 14827 8192000000 10284984416 10700472320 1369683088 14.324 100 57 114 1 1395526816 12692560 13151168
23:42:02 31887 8192000000 10300871360 10720063488 1386872816 14.478 100 60 119 0 1414775392 14236080 13666496
23:43:39 12442 8192000000 10306235776 10731192320 1396343680 14.562 100 46 92 0 1422474064 14752624 11377760
23:44:55 14189 8192000000 10311782448 10739503104 1397429744 14.572 100 38 74 1 1428387232 22325504 8631984
23:46:15 9111 8192000000 10318975632 10738683904 1394663472 14.547 100 48 94 1 1428978192 22768064 11546656
23:47:29 6166 8192000000 10337137440 10757595136 1406350368 14.652 100 57 114 0 1445346448 25195520 13800560
23:48:39 7010 8192000000 10352957696 10766974976 1413973344 14.719 100 75 149 0 1461065584 29373568 17718672
23:49:58 8139 8192000000 10349196272 10765754368 1400542928 14.600 99 63 124 2 1447781120 31367728 15870464
23:51:12 3010 8192000000 10373344720 10787983360 1424445008 14.812 100 86 172 1 1477697744 32763312 20489424
23:52:18 5958 8192000000 10358309376 10777145344 1412627584 14.707 99 65 128 1 1463742432 35698064 15416784
23:53:31 2836 8192000000 10369472720 10780164096 1398150256 14.579 99 75 150 1 1461393360 44487856 18755248
23:54:46 2569 8192000000 10366188096 10782375936 1412033184 14.702 100 65 131 1 1471614032 42664272 16916576
23:55:54 8851 8192000000 10338571776 10760511488 1392287248 14.526 100 42 82 2 1444912160 40728400 11896512
23:57:10 9966 8192000000 10345537872 10770817024 1401077776 14.605 100 43 86 2 1452325232 39478208 11769248
23:58:23 13513 8192000000 10345899824 10771816448 1396491360 14.564 100 39 78 1 1453633760 46386752 10755648
23:59:43 10251 8192000000 10338657600 10762829824 1391270832 14.517 100 46 92 2 1444116064 40773904 12071328
00:01:03 13276 8192000000 10336694512 10766884864 1363156560 14.266 98 34 68 1 1422257760 49477184 9624016
00:02:18 17470 8192000000 10327328224 10755735552 1367933968 14.309 100 29 58 1 1428736160 52041264 8760928
00:03:42 18990 8192000000 10313607360 10758811648 1354996832 14.192 99 30 60 0 1417749440 53520784 9231824
00:05:08 51573 8192000000 10263246480 10712612864 1320159136 13.878 97 14 28 1 1345378144 21666656 3552352
00:07:02 143567 8192000000 10300641152 10743451648 1370672352 14.333 99 29 58 2 1387724416 10631856 6420208
00:10:38 46715 8192000000 10302584208 10758815744 1391764720 14.522 100 21 42 2 1412823856 16097632 4961504
00:12:28 18331 8192000000 10331627088 10771955712 1396035536 14.560 100 42 83 0 1433464016 27881232 9547248
00:14:04 34196 8192000000 10333037280 10773573632 1399531088 14.591 100 44 84 1 1434421600 24807408 10083104
00:15:39 32213 8192000000 10314083408 10780037120 1378900448 14.407 99 18 36 0 1415294240 31983312 4410480
00:17:21 34297 8192000000 10288196736 10741727232 1358521056 14.224 98 13 26 1 1375519056 13804960 3193040
00:18:56 100900 8192000000 10294853056 10755600384 1393358272 14.536 100 9 18 0 1409485600 14007488 2119840
00:22:05 62831 8192000000 10307574560 10773262336 1384766752 14.459 99 6 12 0 1405012208 18877584 1367872
00:24:21 96190 8192000000 10318312720 10773516288 1375611472 14.377 98 14 28 0 1393914016 14734592 3567952
00:27:02 33735 8192000000 10324419920 10773164032 1404269744 14.633 100 18 36 1 1417865168 9700384 3895040
00:28:55 39225 8192000000 10320772672 10776645632 1407588704 14.663 100 39 78 0 1427378752 11025040 8765008
00:30:39 33016 8192000000 10311670992 10759057408 1373040064 14.354 98 22 44 0 1385755120 8055184 4659872
00:32:14 81556 8192000000 10314480864 10786131968 1385475888 14.465 99 18 36 0 1413868848 24261904 4131056
00:34:37 93225 8192000000 10283884720 10728300544 1269364448 13.416 91 8 16 0 1280708928 9184608 2159872
00:37:11 81421 8192000000 10264309056 10716749824 1337342320 14.033 97 29 57 1 1353750352 9748368 6659664
00:39:39 49840 8192000000 10281779808 10736295936 1360674592 14.243 99 19 38 1 1377230832 12145232 4411008
00:41:44 63599 8192000000 10270761984 10735378432 1367567104 14.305 99 17 34 0 1388232256 17048128 3617024
00:43:49 45749 8192000000 10263821888 10716213248 1343191152 14.086 97 15 30 0 1356756192 10399664 3165376
00:45:43 72341 8192000000 10247681152 10705440768 1335472944 14.017 97 25 49 0 1348516208 6764672 6278592
00:47:56 75342 8192000000 10239813680 10698579968 1323753856 13.911 97 11 22 0 1333196192 6948832 2493504
00:50:12 38546 8192000000 10282551024 10722103296 1353029152 14.175 99 14 28 0 1363360480 6841648 3489680
00:51:59 36450 8192000000 10274673584 10728415232 1337518480 14.035 97 30 60 0 1357043472 12799328 6725664
00:53:53 35037 8192000000 10273314896 10721849344 1349317744 14.141 98 21 42 0 1364404544 10398304 4688496
00:55:29 35697 8192000000 10269610912 10720657408 1343962064 14.093 99 25 50 0 1366065440 16366880 5736496
00:57:13 66334 8192000000 10291025472 10731954176 1354802688 14.191 99 20 41 0 1371619040 12184528 4631824
00:59:23 118245 8192000000 10290111248 10725744640 1346154496 14.113 98 20 39 0 1364759360 13899216 4705648
01:02:55 94036 8192000000 10254692928 10708611072 1355969232 14.201 99 19 38 1 1368110352 7597040 4544080
01:05:48 31330 8192000000 10287654432 10741587968 1380329632 14.419 100 27 54 2 1397062848 10890704 5842512
01:07:30 35617 8192000000 10256245616 10718035968 1345772720 14.109 98 8 16 0 1356683024 9161488 1748816
01:09:06 92992 8192000000 10281537680 10747297792 1356718640 14.208 98 16 32 0 1381141392 20344496 4078256
01:12:28 89518 8192000000 10296136160 10753273856 1370821216 14.334 99 11 22 0 1390230496 16788688 2620592
01:15:26 55227 8192000000 10317034160 10760105984 1369894800 14.326 98 21 42 0 1384954896 10224016 4836080
01:17:22 65395 8192000000 10301695760 10778267648 1393785056 14.540 100 11 20 1 1417435312 20820512 2829744
01:19:29 25273 8192000000 10315697056 10758012928 1368951600 14.318 98 40 77 1 1384896912 7082496 8862816
01:20:54 115307 8192000000 10283914496 10739736576 1319701200 13.874 95 25 49 0 1337911520 13126368 5083952
01:23:57 45876 8192000000 10285190800 10720092160 1323814640 13.911 96 36 72 1 1338914768 7428448 7671680
01:25:46 92512 8192000000 10265807696 10712883200 1346855232 14.119 99 22 44 0 1359382800 7813696 4713872
01:28:28 25200 8192000000 10268232432 10724716544 1350571936 14.153 99 27 54 0 1369261696 12539920 6149840
01:29:56 69500 8192000000 10189612464 10634719232 1241000640 13.155 91 14 28 1 1250367200 6297184 3069376
01:32:14 64216 8192000000 10229179392 10679406592 1316458896 13.845 98 15 30 1 1327274832 7425056 3390880
01:34:23 57502 8192000000 10242946352 10702983168 1331017536 13.976 99 9 18 0 1341927984 8474352 2436096
01:36:21 54645 8192000000 10256430176 10708295680 1343808080 14.092 99 15 30 0 1361051856 13871344 3372432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-12-2021 01:39:17 to 21-12-2021 01:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-12-2021 01:39:33 to 21-12-2021 01:39:35.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243590
MON$OLDEST_ACTIVE               245278
MON$OLDEST_SNAPSHOT             245278
MON$NEXT_TRANSACTION            245278
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                       2024192
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.33546 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33546 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33546 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 ---
:205: 
:206: mscs-test-161.budzdorov.ru    Tue Dec 21 01:38:05 2021
:207:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:208: 
:209: 
:210: mscs-test-161.budzdorov.ru    Tue Dec 21 01:38:05 2021
:211:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:212: 
:213: 
:214: mscs-test-161.budzdorov.ru    Tue Dec 21 01:38:05 2021
:215:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:216: 
:217: 
:218: mscs-test-161.budzdorov.ru    Tue Dec 21 01:38:05 2021
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:220: 
--- end of diff output ---

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

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