Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.05.2022 19: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 16459848 kB
MemAvailable 31393992 kB
Buffers 0 kB
Cached 15221720 kB
SwapCached 2588 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.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-20 19:38:21
DTS_END                         2022-05-20 19:38:21.6470
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/37164
TRN_ID                          237840
ATT_ID                          656
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: 9173.72 23695 1100846
customer order: creation 166.85 337 20022
customer order: refuse 14.26 374 1711
order to supplier: creation 43.78 1206 5254
order to supplier: removal 3.50 109135 420
invoice (draft): creation 43.33 3368 5200
invoice (draft): removal 53.01 860 6361
invoice accept: apply 40.21 17138 4825
invoice accept: cancel 48.44 798 5813
customer reserve: creation 3738.84 168 448661
customer reserve: removal 4718.80 62 566256
realization accept: apply 81.24 212 9749
realization accept: cancel 95.72 123 11486
payment from customer: creation 57.36 130 6883
payment from customer: removal 5.53 79 664
payment to supplier: creation 47.63 161 5715
payment to supplier: removal 3.49 65 419
service: total inventory turnovers 4.77 991 572
service: total monetary turnovers 6.07 39 728
service: aggregate perf. data 0.86 16833 103
service: refresh index statistics 0.03 321811 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-05-2022 19:39:14 to 20-05-2022 19:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4424
TEST_TIME 2 4648
TEST_TIME 3 4669
TEST_TIME 4 4934
TEST_TIME 5 4749
TEST_TIME 6 5097
TEST_TIME 7 5317
TEST_TIME 8 5192
TEST_TIME 9 5227
TEST_TIME 10 5075
TEST_TIME 11 5118
TEST_TIME 12 5396
TEST_TIME 13 5571
TEST_TIME 14 5537
TEST_TIME 15 5809
TEST_TIME 16 5785
TEST_TIME 17 6003
TEST_TIME 18 6495
TEST_TIME 19 6842
TEST_TIME 20 7036
TEST_TIME 21 7466
TEST_TIME 22 7669
TEST_TIME 23 7866
TEST_TIME 24 7907
TEST_TIME 25 8441
TEST_TIME 26 8771
TEST_TIME 27 9175
TEST_TIME 28 10289
TEST_TIME 29 10526
TEST_TIME 30 10819
TEST_TIME 31 10892
TEST_TIME 32 10625
TEST_TIME 33 10441
TEST_TIME 34 10285
TEST_TIME 35 10139
TEST_TIME 36 10057
TEST_TIME 37 10097
TEST_TIME 38 10082
TEST_TIME 39 9924
TEST_TIME 40 9862
TEST_TIME 41 9859
TEST_TIME 42 9739
TEST_TIME 43 9759
TEST_TIME 44 9665
TEST_TIME 45 9584
TEST_TIME 46 9471
TEST_TIME 47 9347
TEST_TIME 48 9398
TEST_TIME 49 9407
TEST_TIME 50 9430
TEST_TIME 51 9333
TEST_TIME 52 9265
TEST_TIME 53 9270
TEST_TIME 54 9186
TEST_TIME 55 9122
TEST_TIME 56 9069
TEST_TIME 57 9022
TEST_TIME 58 8944
TEST_TIME 59 9022
TEST_TIME 60 8996
TEST_TIME 61 8996
TEST_TIME 62 9087
TEST_TIME 63 9067
TEST_TIME 64 9135
TEST_TIME 65 9137
TEST_TIME 66 9266
TEST_TIME 67 9265
TEST_TIME 68 9264
TEST_TIME 69 9347
TEST_TIME 70 9372
TEST_TIME 71 9417
TEST_TIME 72 9446
TEST_TIME 73 9450
TEST_TIME 74 9377
TEST_TIME 75 9311
TEST_TIME 76 9334
TEST_TIME 77 9301
TEST_TIME 78 9267
TEST_TIME 79 9275
TEST_TIME 80 9252
TEST_TIME 81 9270
TEST_TIME 82 9249
TEST_TIME 83 9175
TEST_TIME 84 9097
TEST_TIME 85 9078
TEST_TIME 86 9201
TEST_TIME 87 9161
TEST_TIME 88 9236
TEST_TIME 89 9241
TEST_TIME 90 9256
TEST_TIME 91 9257
TEST_TIME 92 9268
TEST_TIME 93 9351
TEST_TIME 94 9364
TEST_TIME 95 9434
TEST_TIME 96 9434
TEST_TIME 97 9435
TEST_TIME 98 9390
TEST_TIME 99 9411
TEST_TIME 100 9504
TEST_TIME 101 9516
TEST_TIME 102 9426
TEST_TIME 103 9391
TEST_TIME 104 9369
TEST_TIME 105 9324
TEST_TIME 106 9264
TEST_TIME 107 9241
TEST_TIME 108 9281
TEST_TIME 109 9226
TEST_TIME 110 9240
TEST_TIME 111 9230
TEST_TIME 112 9225
TEST_TIME 113 9173
TEST_TIME 114 9172
TEST_TIME 115 9164
TEST_TIME 116 9160
TEST_TIME 117 9123
TEST_TIME 118 9173
TEST_TIME 119 9228
TEST_TIME 120 9176

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-05-2022 19:39:18 to 20-05-2022 19:39:22.

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 1515589 1515589 0 0.00 0 50976 6 0 0
doc_list_biud 1515577 1515577 0 0.00 0 36714 29 0 0
sp_add_invoice_to_stock 4936 4825 111 2.24 29 154038 17139 0 111
sp_cancel_adding_invoice 5813 5813 0 0.00 5 64027 799 0 0
sp_cancel_client_order 1711 1711 0 0.00 4 15378 374 0 0
sp_cancel_customer_reserve 566256 566256 0 0.00 1 35687 63 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 2 3634 79 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 2457 65 0 0
sp_cancel_supplier_invoice 6368 6361 7 0.10 2 30034 860 0 7
sp_cancel_supplier_order 420 420 0 0.00 5 1198519 109135 0 0
sp_cancel_write_off 11486 11486 0 0.00 1 19309 123 0 0
sp_client_order 20022 20022 0 0.00 3 15292 337 0 0
sp_customer_reserve 448661 448661 0 0.00 0 54030 168 0 0
sp_fill_shopping_cart 30475 30475 0 0.00 1 10787 42 0 0
sp_fill_shopping_cart_clo_res 448660 448660 0 0.00 0 16775 16 0 0
sp_get_clo_for_invoice 4822 4822 0 0.00 0 28789 281 0 0
sp_kill_cost_storno 18382 18382 0 0.00 0 7795 62 0 0
sp_kill_qstorno_ret_qs2qd 1150779 1150779 0 0.00 0 36713 34 0 0
sp_kill_qty_storno 606611 606611 0 0.00 0 36714 73 0 0
sp_lock_selected_doc 20206 20206 0 0.00 0 9429 6 0 0
sp_make_cost_storno 27169 27169 0 0.00 0 50942 71 0 0
sp_multiply_rows_for_pdistr 27162 27162 0 0.00 0 7267 33 0 0
sp_multiply_rows_for_qdistr 20022 20022 0 0.00 0 10450 158 0 0
sp_pay_from_customer 6883 6883 0 0.00 4 16586 130 0 0
sp_pay_to_supplier 5715 5715 0 0.00 5 50983 161 0 0
sp_payment_common 12593 12593 0 0.00 2 16581 38 0 0
sp_qd_handle_on_cancel_clo 1711 1711 0 0.00 0 15368 338 0 0
sp_qd_handle_on_invoice_upd_sts 10631 10631 0 0.00 0 20305 331 0 0
sp_qd_handle_on_reserve_upd_sts 9746 9746 0 0.00 0 12732 61 0 0
sp_reserve_write_off 9749 9749 0 0.00 6 22248 212 0 0
sp_supplier_invoice 5200 5200 0 0.00 6 92060 3369 0 0
sp_supplier_order 5254 5254 0 0.00 5 43283 1206 0 0
srv_aggregate_perf_data 103 103 0 0.00 2178 146140 16833 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2405 151630 50791 0 0
srv_find_qd_qs_mism 1054257 1054257 0 0.00 0 20879 5 0 0
srv_make_invnt_saldo 572 572 0 0.00 34 72015 991 0 0
srv_make_money_saldo 728 728 0 0.00 1 6218 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 165952 511099 321812 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 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 4825 4825 0 0.00 0 16384 172 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 1 7930 146 0 0
t$perf-norm:sp_cancel_client_order 1711 1711 0 0.00 0 4961 14 0 0
t$perf-norm:sp_cancel_customer_reserve 636 636 0 0.00 0 2329 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 1546 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 2093 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 704 13 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 22533 1554 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 3359 8 0 0
t$perf-norm:sp_client_order 20022 20022 0 0.00 0 8253 17 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 4368 10 0 0
t$perf-norm:sp_pay_from_customer 6883 6883 0 0.00 0 11618 13 0 0
t$perf-norm:sp_pay_to_supplier 5715 5715 0 0.00 0 6928 12 0 0
t$perf-norm:sp_reserve_write_off 9749 9749 0 0.00 0 8791 13 0 0
t$perf-norm:sp_supplier_invoice 5200 5200 0 0.00 0 4956 23 0 0
t$perf-norm:sp_supplier_order 5254 5254 0 0.00 0 3596 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 593 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 3303 55 0 0
t$perf-norm:srv_make_invnt_saldo 572 572 0 0.00 0 1988 21 0 0
t$perf-norm:srv_make_money_saldo 728 728 0 0.00 0 5531 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 11 6 0 0
v_add_invoice_to_stock 9850 9628 222 2.25 0 3688 15 0 222
v_all_customers 36408 36408 0 0.00 0 19 1 0 0
v_all_suppliers 29344 29344 0 0.00 0 164 1 0 0
v_all_wares 40042 40042 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 464 6 0 0
v_cancel_client_order 3422 3422 0 0.00 0 3628 8 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 2579 7 0 0
v_cancel_customer_reserve 1272 1272 0 0.00 0 4534 10 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 958 5 0 0
v_cancel_supplier_invoice 748 734 14 1.87 0 483 12 0 14
v_cancel_supplier_order 840 840 0 0.00 0 6075 18 0 0
v_cancel_write_off 2016 2016 0 0.00 0 2625 14 0 0
v_max_id_clo_ord 5254 5254 0 0.00 0 264 1 0 0
v_max_id_clo_res 995 995 0 0.00 0 7722 29 0 0
v_max_id_ord_sup 5200 5200 0 0.00 0 3245 4 0 0
v_max_non_paid_invoice 1494 1494 0 0.00 0 612 3 0 0
v_max_non_paid_realizn 3747 3747 0 0.00 0 3350 6 0 0
v_min_id_clo_ord 5254 5254 0 0.00 0 5578 8 0 0
v_min_id_clo_res 995 995 0 0.00 0 7140 13 0 0
v_min_id_ord_sup 5200 5200 0 0.00 0 10784 9 0 0
v_min_non_paid_invoice 5712 5712 0 0.00 0 9799 26 0 0
v_min_non_paid_realizn 6880 6880 0 0.00 0 16558 34 0 0
v_our_firm 9900 9900 0 0.00 0 18 1 0 0
v_reserve_write_off 19492 19492 0 0.00 0 4708 6 0 0
x_lock_depdocs_on_canc_invoice 11807 11807 0 0.00 0 13915 124 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 21874 496 0 0
x_make_qstorno_1000_1200 5254 5254 0 0.00 2 39512 1125 0 0
x_make_qstorno_1200_2000 5200 5200 0 0.00 3 91950 3208 0 0
x_make_qstorno_2100_3300 448661 448661 0 0.00 0 54029 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-05-2022 19:39:23 to 20-05-2022 19:39:27.

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
17:08:25 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:27 17145 8192000000 9894174976 10199896064 979878912 10.683 100 88 177 0 1008076624 5195840 23001872
17:10:49 24275 8192000000 9948382288 10266640384 1028382656 11.153 100 89 178 0 1058860912 7567184 22911072
17:12:19 24730 8192000000 9989082272 10318364672 1058082320 11.438 100 79 158 1 1087203056 9348368 19772368
17:13:50 15228 8192000000 10031798544 10379534336 1113278736 11.963 100 75 150 0 1142079744 10941680 17859328
17:15:14 9260 8192000000 10084666208 10443952128 1176397360 12.557 100 83 166 0 1207304064 12255296 18651408
17:16:26 5197 8192000000 10128936624 10499772416 1216893840 12.933 100 81 162 0 1247272016 12659104 17719072
17:17:36 2690 8192000000 10170022080 10544439296 1254724528 13.282 100 91 182 0 1288478688 13606896 20147264
17:18:40 1598 8192000000 10193020416 10572034048 1278045024 13.495 100 90 177 2 1310733936 13096992 19591920
17:19:43 9416 8192000000 10189590720 10594283520 1297685664 13.674 100 57 114 0 1321623120 11591616 12345840
17:21:01 3226 8192000000 10202957824 10604142592 1303596672 13.728 100 50 101 2 1323327888 8624128 11107088
17:22:10 4618 8192000000 10200240960 10614317056 1316702768 13.847 100 37 71 1 1332179856 7136144 8340944
17:23:18 9097 8192000000 10199548960 10616168448 1318214912 13.861 100 24 48 1 1333896384 10698912 4982560
17:24:27 59202 8192000000 10235678640 10638286848 1314306496 13.825 99 51 103 5 1332822864 5874592 12641776
17:26:27 3498 8192000000 10235437184 10648702976 1334614784 14.009 100 38 76 1 1350492448 5833072 10044592
17:27:34 9767 8192000000 10220648976 10633392128 1313185840 13.815 99 20 41 0 1323963024 5659072 5118112
17:28:48 64178 8192000000 10193147072 10611802112 1260460928 13.334 95 19 38 0 1270054448 4174912 5418608
17:30:53 5612 8192000000 10159020032 10565353472 1262225936 13.350 99 27 55 1 1275140160 6644768 6269456
17:32:01 8699 8192000000 10124624816 10520883200 1193379104 12.715 96 15 30 2 1205887392 9206912 3301376
17:33:11 10588 8192000000 10134583360 10535518208 1230481856 13.058 98 26 52 1 1243109744 6190896 6436992
17:34:22 14475 8192000000 10146959888 10551226368 1258823152 13.319 100 19 38 1 1268322800 5058064 4441584
17:35:38 8398 8192000000 10175156736 10583822336 1283510768 13.545 100 16 32 0 1292146640 4741424 3894448
17:36:49 7065 8192000000 10183689072 10595938304 1294194288 13.642 100 24 48 1 1305712224 5100816 6417120
17:37:56 14907 8192000000 10198791184 10613948416 1304241104 13.734 100 13 26 0 1311916176 4601376 3073696
17:39:17 14549 8192000000 10235435872 10642436096 1330965408 13.976 100 41 82 1 1347480304 7154144 9360752
17:40:36 37652 8192000000 10245522512 10667679744 1340767376 14.064 100 44 89 1 1364077984 12981744 10328864
17:42:18 22738 8192000000 10262731648 10684207104 1356512304 14.206 100 46 92 1 1378804496 11482000 10810192
17:43:44 14565 8192000000 10288890928 10705080320 1374732032 14.369 100 53 106 1 1405367312 18457152 12178128
17:45:07 7738 8192000000 10308010288 10721030144 1379792128 14.415 100 68 136 0 1417887696 21771616 16323952
17:46:18 66489 8192000000 10299888128 10738565120 1392369056 14.527 100 22 44 3 1420314208 22020336 5924816
17:48:32 12746 8192000000 10306951200 10733662208 1383227904 14.445 99 40 80 1 1416443024 23820864 9394256
17:49:52 3174 8192000000 10350684368 10775953408 1413649712 14.716 100 54 108 0 1465135856 37743392 13742752
17:51:04 6787 8192000000 10349823664 10772586496 1401391248 14.607 99 49 95 2 1449747600 36379648 11976704
17:52:38 4988 8192000000 10363693232 10780401664 1413706912 14.717 100 79 158 1 1475106176 40934752 20464512
17:53:46 5866 8192000000 10329465568 10751361024 1373030960 14.354 98 65 129 2 1432150832 42357408 16762464
17:54:54 3013 8192000000 10345264256 10754043904 1374563984 14.368 99 71 142 2 1449484192 56364432 18555776
17:56:04 4145 8192000000 10358891056 10770378752 1390412128 14.510 100 70 136 1 1465921296 57687408 17821760
17:57:13 5897 8192000000 10332607360 10743967744 1371689808 14.342 99 60 121 0 1435713040 48350944 15672288
17:58:31 3075 8192000000 10358265072 10763718656 1362197888 14.257 98 87 172 2 1443390176 59163568 22028720
17:59:41 2403 8192000000 10366974976 10775126016 1384562880 14.457 100 70 140 1 1466427632 63039776 18824976
18:00:49 19312 8192000000 10335853392 10763694080 1369737712 14.325 99 36 72 1 1434189904 53974384 10477808
18:02:12 11544 8192000000 10344596432 10771521536 1379037328 14.408 100 37 74 0 1450754672 60842992 10874352
18:03:28 15172 8192000000 10346000240 10787000320 1379330464 14.411 100 33 66 1 1456464912 66440192 10694256
18:04:50 14322 8192000000 10309998816 10744217600 1359217472 14.230 100 24 48 3 1422235968 55610240 7408256
18:06:16 43801 8192000000 10289006512 10734174208 1357867984 14.218 100 17 34 2 1418878320 55840112 5170224
18:08:00 52799 8192000000 10282880656 10727624704 1369841008 14.326 100 12 24 1 1384459568 11914528 2704032
18:10:11 82701 8192000000 10264440816 10707988480 1347486752 14.125 98 21 42 0 1360478816 8311344 4680720
18:12:47 33864 8192000000 10279125936 10732122112 1357057536 14.211 98 18 36 1 1368705488 7934160 3713792
18:14:22 53877 8192000000 10262301344 10714046464 1359129072 14.230 100 16 32 0 1374235088 11382048 3723968
18:16:16 45008 8192000000 10260235504 10717052928 1347187872 14.122 98 11 22 0 1360607552 10785296 2634384
18:18:01 60968 8192000000 10282938528 10730647552 1374009936 14.363 100 27 53 1 1394175488 13350608 6814944
18:21:01 85243 8192000000 10293108784 10746609664 1386784176 14.477 100 13 26 0 1401420208 11838528 2797504
18:23:27 50541 8192000000 10291755888 10745454592 1373177552 14.356 99 21 41 4 1397910432 19377568 5355312
18:25:25 81900 8192000000 10289988720 10760085504 1382996192 14.443 100 20 40 0 1409710288 21816640 4897456
18:28:01 31191 8192000000 10297911648 10755821568 1393163888 14.534 100 44 84 1 1413477168 10300000 10013280
18:29:37 49120 8192000000 10303173456 10759823360 1398186640 14.579 100 19 38 0 1413521920 10843760 4491520
18:31:32 100030 8192000000 10265438464 10722447360 1345436992 14.106 96 28 55 2 1363367280 11046720 6883568
18:34:20 29451 8192000000 10273183856 10730213376 1356873856 14.209 98 27 53 1 1370848912 8459424 5515632
18:35:53 67050 8192000000 10279641872 10749743104 1386017312 14.470 100 15 30 0 1403092912 13868560 3207040
18:38:08 54125 8192000000 10274525056 10734059520 1325437872 13.926 95 11 21 0 1333069296 5603408 2028016
18:40:13 55325 8192000000 10274756448 10737950720 1338789696 14.046 96 13 26 0 1353615696 11959376 2866624
18:42:08 62621 8192000000 10282219344 10739429376 1340611104 14.063 97 18 36 0 1354856112 10304928 3940080
18:44:15 80194 8192000000 10265021952 10715598848 1286863184 13.576 94 8 16 0 1305068800 16503840 1701776
18:47:00 84982 8192000000 10283396720 10747469824 1315281568 13.834 95 6 12 0 1336132208 19368784 1481856
18:49:25 76410 8192000000 10264479328 10711744512 1282453456 13.535 94 17 34 1 1299093744 12813376 3826912
18:51:42 120938 8192000000 10269585568 10705653760 1308089440 13.769 96 22 43 0 1323417888 10398944 4929504
18:54:49 88536 8192000000 10256330688 10703720448 1291932064 13.622 95 21 42 0 1305259328 8680960 4646304
18:57:21 75336 8192000000 10262789520 10722594816 1355399568 14.196 99 18 35 2 1372058192 12183744 4474880
18:59:46 84377 8192000000 10274840704 10735403008 1374034960 14.363 100 20 40 0 1391285184 12918384 4331840
19:02:16 101525 8192000000 10283037360 10746109952 1364791872 14.280 98 22 43 0 1387108368 17209504 5106992
19:05:17 98620 8192000000 10276460000 10741202944 1324650112 13.919 96 6 12 0 1343392944 17171552 1571280
19:07:56 7523 8192000000 10288561728 10728378368 1370980160 14.336 100 53 100 3 1390724384 8304960 11439264
19:09:04 82819 8192000000 10301124912 10761879552 1354144336 14.185 97 24 49 0 1378443440 18841408 5457696
19:11:33 99671 8192000000 10302702608 10767831040 1397286320 14.571 100 11 22 1 1413593136 14103744 2203072
19:14:14 58848 8192000000 10308228144 10755334144 1372971552 14.354 98 24 48 1 1386647520 8610096 5065872
19:16:13 86208 8192000000 10332903344 10772799488 1362598480 14.261 97 26 52 1 1389308752 20799152 5911120
19:18:40 151630 8192000000 10307489936 10754772992 1361824896 14.254 97 15 30 1 1380043952 14515696 3703360
19:22:20 57055 8192000000 10286800672 10751508480 1368969424 14.318 98 15 30 1 1382114000 9472672 3671904
19:24:18 73216 8192000000 10297932048 10754899968 1362413792 14.259 97 15 30 0 1381258752 15125248 3719712
19:26:33 62220 8192000000 10277604160 10741035008 1357998832 14.219 97 9 19 0 1370191008 10369952 1822224
19:28:45 19209 8192000000 10281768336 10738237440 1376093056 14.382 100 23 46 2 1394835088 13834736 4907296
19:30:05 62136 8192000000 10255918048 10712588288 1336264448 14.024 97 11 23 1 1346941328 7986576 2690304
19:32:23 64444 8192000000 10267083680 10716192768 1350084768 14.148 98 11 20 0 1358576720 6487232 2004720
19:34:32 62206 8192000000 10281355344 10734510080 1334209232 14.005 97 18 36 0 1353031664 14607392 4215040
19:36:35 84268 8192000000 10263708560 10714148864 1319070320 13.868 96 14 28 0 1333668320 11589296 3008704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-05-2022 19:39:28 to 20-05-2022 19:39:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 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 20-05-2022 19:39:44 to 20-05-2022 19:39:46.

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          237030
MON$OLDEST_ACTIVE               237999
MON$OLDEST_SNAPSHOT             237999
MON$NEXT_TRANSACTION            237999
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                       2030176
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33593 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33593 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33593 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 ---
:55: 
:56: mscs-test-161.budzdorov.ru    Fri May 20 19:38:24 2022
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:58: 
:59: 
:60: mscs-test-161.budzdorov.ru    Fri May 20 19:38:24 2022
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:62: 
:63: 
:64: mscs-test-161.budzdorov.ru    Fri May 20 19:38:24 2022
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:66: 
--- end of diff output ---

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

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