Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.12.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 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 16725452 kB
MemAvailable 31509324 kB
Buffers 0 kB
Cached 14948148 kB
SwapCached 0 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-12-02 22:37:54
DTS_END                         2021-12-02 22:37:54.5070
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/34710
TRN_ID                          239786
ATT_ID                          646
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: 9202.26 23372 1104271
customer order: creation 170.19 366 20423
customer order: refuse 14.91 369 1789
order to supplier: creation 46.03 1266 5524
order to supplier: removal 3.58 86438 430
invoice (draft): creation 44.87 3086 5384
invoice (draft): removal 53.83 694 6459
invoice accept: apply 40.71 17944 4885
invoice accept: cancel 48.63 786 5836
customer reserve: creation 3738.31 178 448597
customer reserve: removal 4741.87 51 569024
realization accept: apply 83.21 215 9985
realization accept: cancel 87.53 108 10503
payment from customer: creation 58.98 116 7078
payment from customer: removal 5.86 88 703
payment to supplier: creation 48.76 126 5851
payment to supplier: removal 3.19 53 383
service: total inventory turnovers 4.85 1074 582
service: total monetary turnovers 6.03 28 723
service: aggregate perf. data 0.91 14369 109
service: refresh index statistics 0.03 340076 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-12-2021 22:39:12 to 02-12-2021 22:39:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5854
TEST_TIME 2 5682
TEST_TIME 3 4256
TEST_TIME 4 4051
TEST_TIME 5 4220
TEST_TIME 6 4936
TEST_TIME 7 5027
TEST_TIME 8 5130
TEST_TIME 9 5362
TEST_TIME 10 5324
TEST_TIME 11 5151
TEST_TIME 12 5099
TEST_TIME 13 5447
TEST_TIME 14 5464
TEST_TIME 15 5636
TEST_TIME 16 5772
TEST_TIME 17 5916
TEST_TIME 18 6283
TEST_TIME 19 6658
TEST_TIME 20 7660
TEST_TIME 21 7562
TEST_TIME 22 7772
TEST_TIME 23 8280
TEST_TIME 24 8634
TEST_TIME 25 8975
TEST_TIME 26 9362
TEST_TIME 27 10195
TEST_TIME 28 10029
TEST_TIME 29 9969
TEST_TIME 30 9943
TEST_TIME 31 10021
TEST_TIME 32 9977
TEST_TIME 33 10265
TEST_TIME 34 10047
TEST_TIME 35 9815
TEST_TIME 36 9663
TEST_TIME 37 9618
TEST_TIME 38 9609
TEST_TIME 39 9626
TEST_TIME 40 9575
TEST_TIME 41 9563
TEST_TIME 42 9495
TEST_TIME 43 9408
TEST_TIME 44 9444
TEST_TIME 45 9374
TEST_TIME 46 9276
TEST_TIME 47 9183
TEST_TIME 48 9081
TEST_TIME 49 9182
TEST_TIME 50 9152
TEST_TIME 51 9233
TEST_TIME 52 9240
TEST_TIME 53 9272
TEST_TIME 54 9259
TEST_TIME 55 9275
TEST_TIME 56 9306
TEST_TIME 57 9379
TEST_TIME 58 9367
TEST_TIME 59 9409
TEST_TIME 60 9433
TEST_TIME 61 9440
TEST_TIME 62 9519
TEST_TIME 63 9438
TEST_TIME 64 9387
TEST_TIME 65 9312
TEST_TIME 66 9314
TEST_TIME 67 9268
TEST_TIME 68 9167
TEST_TIME 69 9143
TEST_TIME 70 9160
TEST_TIME 71 9124
TEST_TIME 72 9212
TEST_TIME 73 9239
TEST_TIME 74 9202
TEST_TIME 75 9211
TEST_TIME 76 9355
TEST_TIME 77 9321
TEST_TIME 78 9351
TEST_TIME 79 9303
TEST_TIME 80 9458
TEST_TIME 81 9435
TEST_TIME 82 9437
TEST_TIME 83 9453
TEST_TIME 84 9365
TEST_TIME 85 9308
TEST_TIME 86 9300
TEST_TIME 87 9360
TEST_TIME 88 9306
TEST_TIME 89 9245
TEST_TIME 90 9208
TEST_TIME 91 9170
TEST_TIME 92 9146
TEST_TIME 93 9192
TEST_TIME 94 9162
TEST_TIME 95 9230
TEST_TIME 96 9273
TEST_TIME 97 9317
TEST_TIME 98 9322
TEST_TIME 99 9333
TEST_TIME 100 9371
TEST_TIME 101 9359
TEST_TIME 102 9332
TEST_TIME 103 9445
TEST_TIME 104 9434
TEST_TIME 105 9417
TEST_TIME 106 9495
TEST_TIME 107 9480
TEST_TIME 108 9398
TEST_TIME 109 9379
TEST_TIME 110 9353
TEST_TIME 111 9362
TEST_TIME 112 9322
TEST_TIME 113 9329
TEST_TIME 114 9332
TEST_TIME 115 9311
TEST_TIME 116 9281
TEST_TIME 117 9249
TEST_TIME 118 9219
TEST_TIME 119 9215
TEST_TIME 120 9206

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-12-2021 22:39:15 to 02-12-2021 22:39:19.

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 1521272 1521272 0 0.00 0 13278 6 0 0
doc_list_biud 1521271 1521271 0 0.00 0 49871 25 0 0
sp_add_invoice_to_stock 5000 4885 115 2.30 51 167158 17944 0 115
sp_cancel_adding_invoice 5836 5836 0 0.00 5 81913 787 0 0
sp_cancel_client_order 1789 1789 0 0.00 4 14183 369 0 0
sp_cancel_customer_reserve 569024 569024 0 0.00 1 49873 52 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 10740 88 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 2779 54 0 0
sp_cancel_supplier_invoice 6470 6459 11 0.17 1 26973 695 0 11
sp_cancel_supplier_order 430 430 0 0.00 8 1136129 86439 0 0
sp_cancel_write_off 10503 10503 0 0.00 1 11608 108 0 0
sp_client_order 20423 20423 0 0.00 3 20942 366 0 0
sp_customer_reserve 448597 448597 0 0.00 0 41056 178 0 0
sp_fill_shopping_cart 31324 31324 0 0.00 1 14942 35 0 0
sp_fill_shopping_cart_clo_res 448591 448591 0 0.00 0 26805 14 0 0
sp_get_clo_for_invoice 4878 4878 0 0.00 0 28428 301 0 0
sp_kill_cost_storno 17425 17425 0 0.00 0 4749 48 0 0
sp_kill_qstorno_ret_qs2qd 1155440 1155440 0 0.00 0 49865 28 0 0
sp_kill_qty_storno 608904 608904 0 0.00 0 49871 62 0 0
sp_lock_selected_doc 20660 20660 0 0.00 0 7545 7 0 0
sp_make_cost_storno 27792 27792 0 0.00 0 12040 58 0 0
sp_multiply_rows_for_pdistr 27792 27792 0 0.00 0 6930 31 0 0
sp_multiply_rows_for_qdistr 20423 20423 0 0.00 0 16101 161 0 0
sp_pay_from_customer 7078 7078 0 0.00 4 19058 116 0 0
sp_pay_to_supplier 5851 5851 0 0.00 4 11524 127 0 0
sp_payment_common 12929 12929 0 0.00 1 19053 32 0 0
sp_qd_handle_on_cancel_clo 1789 1789 0 0.00 0 14163 336 0 0
sp_qd_handle_on_invoice_upd_sts 10714 10714 0 0.00 0 45137 334 0 0
sp_qd_handle_on_reserve_upd_sts 9985 9985 0 0.00 0 20613 69 0 0
sp_reserve_write_off 9985 9985 0 0.00 6 20674 215 0 0
sp_supplier_invoice 5384 5384 0 0.00 6 73256 3086 0 0
sp_supplier_order 5524 5524 0 0.00 5 70366 1267 0 0
srv_aggregate_perf_data 109 109 0 0.00 2714 123712 14369 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1592 95122 45426 0 0
srv_find_qd_qs_mism 1058085 1058085 0 0.00 0 13278 5 0 0
srv_make_invnt_saldo 582 582 0 0.00 46 86709 1074 0 0
srv_make_money_saldo 723 723 0 0.00 1 3410 28 0 0
srv_recalc_idx_stat 3 3 0 0.00 265343 396479 340077 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4886 4886 0 0.00 0 13870 178 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 0 5052 110 0 0
t$perf-norm:sp_cancel_client_order 1789 1789 0 0.00 0 4962 16 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 23733 50 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 2143 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 145 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 25730 87 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 27340 1543 0 0
t$perf-norm:sp_cancel_write_off 958 958 0 0.00 0 4044 8 0 0
t$perf-norm:sp_client_order 20425 20425 0 0.00 0 8577 20 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 2216 8 0 0
t$perf-norm:sp_pay_from_customer 7078 7078 0 0.00 0 15203 14 0 0
t$perf-norm:sp_pay_to_supplier 5851 5851 0 0.00 0 4075 7 0 0
t$perf-norm:sp_reserve_write_off 9985 9985 0 0.00 0 17297 13 0 0
t$perf-norm:sp_supplier_invoice 5384 5384 0 0.00 0 7943 19 0 0
t$perf-norm:sp_supplier_order 5524 5524 0 0.00 0 6353 16 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 400 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 7 1 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 2142 17 0 0
t$perf-norm:srv_make_money_saldo 723 723 0 0.00 0 1813 16 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9984 9754 230 2.30 0 10307 17 0 230
v_all_customers 37254 37254 0 0.00 0 15 0 0 0
v_all_suppliers 30534 30534 0 0.00 0 16 1 0 0
v_all_wares 40846 40846 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 1119 8 0 0
v_cancel_client_order 3578 3578 0 0.00 0 7669 7 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 1226 6 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 1116 5 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 2768 6 0 0
v_cancel_supplier_invoice 840 818 22 2.61 0 3433 18 0 22
v_cancel_supplier_order 860 860 0 0.00 0 4067 11 0 0
v_cancel_write_off 1916 1916 0 0.00 0 7411 23 0 0
v_max_id_clo_ord 5524 5524 0 0.00 0 407 1 0 0
v_max_id_clo_res 985 985 0 0.00 0 6019 17 0 0
v_max_id_ord_sup 5377 5377 0 0.00 0 3862 4 0 0
v_max_non_paid_invoice 1485 1485 0 0.00 0 3387 4 0 0
v_max_non_paid_realizn 3697 3697 0 0.00 0 3389 6 0 0
v_min_id_clo_ord 5524 5524 0 0.00 0 2754 8 0 0
v_min_id_clo_res 985 985 0 0.00 0 878 4 0 0
v_min_id_ord_sup 5377 5377 0 0.00 0 6392 6 0 0
v_min_non_paid_invoice 5851 5851 0 0.00 0 8485 22 0 0
v_min_non_paid_realizn 7078 7078 0 0.00 0 19049 23 0 0
v_our_firm 10354 10354 0 0.00 0 18 1 0 0
v_reserve_write_off 19970 19970 0 0.00 0 7721 9 0 0
x_lock_depdocs_on_canc_invoice 11886 11886 0 0.00 0 22232 105 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 6437 340 0 0
x_make_qstorno_1000_1200 5524 5524 0 0.00 1 70343 1191 0 0
x_make_qstorno_1200_2000 5384 5384 0 0.00 2 73246 2964 0 0
x_make_qstorno_2100_3300 448597 448597 0 0.00 0 37553 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-12-2021 22:39:20 to 02-12-2021 22:39:24.

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:58 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:08:59 39299 8192000000 9907229840 10218196992 985951088 10.742 100 91 182 0 1016199104 6508960 23739056
20:10:45 30368 8192000000 9951476048 10274435072 1033108224 11.198 100 89 178 0 1064978800 9566672 22303904
20:12:20 17676 8192000000 9994330592 10334191616 1076819424 11.617 100 88 176 1 1109155440 10992752 21343264
20:13:50 11483 8192000000 10060418016 10411393024 1140156576 12.217 100 88 174 0 1173080688 11664240 21259872
20:15:04 9653 8192000000 10105394928 10473054208 1193952784 12.720 100 80 160 0 1224962464 12578160 18431520
20:16:17 4794 8192000000 10141206752 10514923520 1227089664 13.027 100 83 167 0 1259273824 14006384 18177776
20:17:23 2160 8192000000 10171402800 10550206464 1256952720 13.302 100 91 182 0 1292051648 15289840 19809088
20:18:26 1943 8192000000 10209750672 10593120256 1290027840 13.604 100 95 192 1 1324837152 13681488 21127824
20:19:30 4380 8192000000 10217076272 10607497216 1305243472 13.743 100 82 167 3 1335432320 10359984 19828864
20:20:34 6433 8192000000 10195925696 10613161984 1309829664 13.785 100 33 66 2 1325342880 8325760 7187456
20:21:41 7097 8192000000 10205766976 10626912256 1322401520 13.898 100 35 70 2 1341501488 11442464 7657504
20:22:52 9379 8192000000 10220971552 10638127104 1331838128 13.984 100 27 54 1 1343425344 5609376 5977840
20:24:02 11116 8192000000 10233549376 10642812928 1339175008 14.050 100 16 32 1 1349045664 6160000 3710656
20:25:17 28095 8192000000 10237641680 10661466112 1342150528 14.077 100 36 72 0 1357490592 6046416 9293648
20:26:48 3227 8192000000 10235393392 10654408704 1331792688 13.983 99 36 72 1 1347579904 6849680 8937536
20:27:54 4842 8192000000 10226521664 10643349504 1314725984 13.829 99 28 57 1 1335719440 14820704 6172752
20:29:03 17404 8192000000 10170496432 10577997824 1215315856 12.918 93 22 44 0 1224994528 4970032 4708640
20:30:24 13624 8192000000 10155670960 10566987776 1232590592 13.078 96 45 90 2 1249504416 5461888 11451936
20:31:38 6788 8192000000 10151387248 10548240384 1221029392 12.971 97 25 50 0 1233178240 5850864 6297984
20:32:47 11453 8192000000 10160300688 10563297280 1251466624 13.252 99 24 48 0 1262049744 5015232 5567888
20:34:00 24386 8192000000 10163120736 10579881984 1271454640 13.435 99 23 46 0 1282523232 6006976 5061616
20:35:26 12287 8192000000 10189815440 10599514112 1293971136 13.640 100 25 50 1 1306350064 7218032 5160896
20:36:39 7888 8192000000 10199997104 10610737152 1307158400 13.760 100 24 47 2 1319066160 7054704 4853056
20:37:50 8269 8192000000 10222492400 10639237120 1324643504 13.919 100 24 50 0 1335986800 6084608 5258688
20:39:04 22651 8192000000 10245771568 10661867520 1343902464 14.093 100 38 76 0 1360399392 8299424 8197504
20:40:31 95122 8192000000 10262522496 10690928640 1362043200 14.256 100 40 80 3 1381757776 10690928 9023648
20:43:09 30727 8192000000 10287372768 10712010752 1367208176 14.302 100 49 95 1 1395308448 17302240 10798032
20:44:53 5644 8192000000 10311921472 10735161344 1395742336 14.557 100 60 120 2 1428249216 18865936 13640944
20:46:01 9781 8192000000 10316610656 10738229248 1390555936 14.511 100 66 131 0 1428030624 22022400 15452288
20:47:17 13538 8192000000 10310398976 10741903360 1386825376 14.478 99 35 70 0 1419696288 24134192 8736720
20:48:44 9263 8192000000 10315624800 10739167232 1393116544 14.534 100 52 104 1 1429797504 23784352 12896608
20:50:00 11216 8192000000 10326866032 10750750720 1387376592 14.482 99 49 98 1 1432202592 32453696 12372304
20:51:15 1591 8192000000 10358446976 10773635072 1416435248 14.741 100 80 158 0 1466540416 29615456 20489712
20:52:22 4372 8192000000 10372696480 10791534592 1429201776 14.854 100 73 147 0 1482354800 34678864 18474160
20:53:30 10262 8192000000 10357702208 10777653248 1412052928 14.702 100 58 115 0 1469260000 41897504 15309568
20:54:47 2335 8192000000 10362030960 10769256448 1383909968 14.451 99 85 170 2 1453326096 48330720 21085408
20:55:54 5535 8192000000 10350337408 10761707520 1382878240 14.442 100 82 165 1 1457373904 51596624 22899040
20:57:07 5873 8192000000 10344713712 10755117056 1371676176 14.342 99 64 127 1 1439262624 51058480 16527968
20:58:15 19575 8192000000 10307824928 10719043584 1347954480 14.129 99 45 90 1 1397964384 37843104 12166800
20:59:37 9996 8192000000 10307129280 10725937152 1344374864 14.097 99 53 106 0 1405649184 47693872 13580448
21:00:48 20590 8192000000 10297296928 10723782656 1356613504 14.207 100 26 48 3 1404791696 40740304 7437888
21:02:29 20046 8192000000 10284908096 10712264704 1338799296 14.047 100 35 69 2 1385096624 36749248 9548080
21:03:56 29726 8192000000 10262244160 10699247616 1336975248 14.030 99 8 16 0 1356231664 17273552 1982864
21:05:28 84370 8192000000 10268740048 10711261184 1327152960 13.941 98 15 30 0 1351634656 20379312 4102384
21:07:59 54182 8192000000 10264481552 10706526208 1348386336 14.133 99 23 44 2 1362509008 9271024 4851648
21:09:54 31146 8192000000 10285235920 10735988736 1363143600 14.266 99 15 30 1 1377355120 10444576 3766944
21:11:27 73629 8192000000 10287344432 10731216896 1364450320 14.277 99 22 44 1 1379018192 10013936 4553936
21:13:42 69290 8192000000 10270113280 10713481216 1329319120 13.961 96 25 50 0 1342649744 7987296 5343328
21:15:55 49616 8192000000 10286740464 10750029824 1389465936 14.501 100 36 70 1 1409535968 11819696 8250336
21:18:03 64228 8192000000 10280021696 10732568576 1360720880 14.244 98 12 24 0 1371759792 8367648 2671264
21:20:09 55629 8192000000 10284253472 10741059584 1372590176 14.350 99 13 26 0 1384694272 8926992 3177104
21:22:05 61040 8192000000 10293983808 10748047360 1377335840 14.393 99 13 26 1 1387562368 7260304 2966224
21:24:46 44006 8192000000 10295532784 10756452352 1380953968 14.425 99 19 38 2 1396426512 11717728 3754816
21:26:31 52298 8192000000 10291426256 10750345216 1377324128 14.393 99 4 8 0 1388414096 10057616 1032352
21:28:29 67629 8192000000 10294710464 10760806400 1346370608 14.115 96 7 14 1 1358357888 10243216 1744064
21:30:53 39315 8192000000 10305646240 10758635520 1380149776 14.418 99 18 36 1 1393208304 8903456 4155072
21:32:34 43816 8192000000 10316919520 10772516864 1400912864 14.603 100 26 51 3 1420880496 13910288 6057344
21:34:23 68426 8192000000 10297264496 10753552384 1338329936 14.042 95 18 36 0 1355950704 13499824 4120944
21:36:33 81192 8192000000 10310347584 10748678144 1369975296 14.327 98 28 56 1 1388417680 12310368 6132016
21:38:58 25713 8192000000 10304158960 10752651264 1351602864 14.162 97 34 68 0 1376631696 17612912 7415920
21:40:25 85523 8192000000 10270468368 10719653888 1333781536 14.001 97 33 65 0 1349655040 8694032 7179472
21:42:57 76267 8192000000 10266099104 10735931392 1368063152 14.310 100 10 20 0 1380896544 10842304 1991088
21:45:29 60758 8192000000 10255411536 10708422656 1313097248 13.814 95 18 36 1 1327898160 10832000 3968912
21:47:32 62860 8192000000 10247549680 10699382784 1297117104 13.669 96 16 32 1 1313834560 12973200 3744256
21:50:04 44096 8192000000 10240215280 10689695744 1317431632 13.853 99 14 28 0 1333251936 12540256 3280048
21:51:50 74854 8192000000 10265027488 10717687808 1333850800 14.002 99 16 32 0 1352928016 14857312 4219904
21:54:37 61836 8192000000 10257462544 10705997824 1324259376 13.915 98 10 20 0 1336092512 9603984 2229152
21:56:40 31942 8192000000 10297411472 10733686784 1361163456 14.248 100 46 92 1 1387448752 15969344 10315952
21:58:18 50357 8192000000 10289033488 10716483584 1340200832 14.059 98 28 56 0 1357377056 11254432 5921792
22:00:09 45586 8192000000 10285562992 10724753408 1329539712 13.963 97 18 36 0 1348840416 15552080 3748624
22:01:56 89361 8192000000 10291006432 10749992960 1375115184 14.373 100 37 74 0 1401893424 17777712 9000528
22:04:31 53471 8192000000 10254206320 10715435008 1346178448 14.113 98 17 34 0 1361584832 11101520 4304864
22:06:25 40902 8192000000 10273117600 10730512384 1360175136 14.239 99 11 23 1 1375628352 12742144 2711072
22:08:06 83944 8192000000 10289776848 10762153984 1390769568 14.513 100 17 34 0 1415452608 21087520 3595520
22:10:31 64681 8192000000 10290927472 10750173184 1375303504 14.375 99 11 22 0 1390119056 12511712 2303840
22:12:36 60439 8192000000 10303282912 10766659584 1382824736 14.442 99 19 38 1 1406223360 18486624 4912000
22:14:37 25878 8192000000 10295791072 10744836096 1373222576 14.356 99 50 101 0 1392267248 7571888 11472784
22:16:04 70201 8192000000 10315007552 10770960384 1399407296 14.590 100 16 32 0 1413024928 10080800 3536832
22:18:27 22949 8192000000 10299652816 10752253952 1381293952 14.428 99 39 76 2 1397858496 6938240 9626304
22:19:50 49567 8192000000 10310534464 10778595328 1393888112 14.541 100 21 42 0 1420068288 21316224 4863952
22:21:40 44441 8192000000 10307143680 10753531904 1340802512 14.065 96 28 55 0 1359318816 12565392 5950912
22:23:35 90065 8192000000 10306743648 10767077376 1379521936 14.412 99 12 24 0 1398370448 15707392 3141120
22:26:47 60883 8192000000 10302547760 10759643136 1360816560 14.245 97 36 72 0 1387260640 18459216 7984864
22:28:54 71545 8192000000 10264817392 10738307072 1367394256 14.304 100 9 18 0 1388188656 18878576 1915824
22:31:12 61320 8192000000 10283918544 10733006848 1346312512 14.114 97 17 34 1 1360371088 10370096 3688480
22:33:14 67805 8192000000 10254949440 10710462464 1323388400 13.907 97 22 44 1 1336327824 7711040 5228384
22:35:23 64716 8192000000 10269897008 10722017280 1345638992 14.108 99 15 30 1 1363251104 13750560 3861552
22:37:27 34757 8192000000 9081828272 9312186368 246732880 2.923 18 3 6 0 254012784 6721120 558784

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-12-2021 22:39:26 to 02-12-2021 22:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 02-12-2021 22:39:42 to 02-12-2021 22:39:43.

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          240141
MON$OLDEST_ACTIVE               240142
MON$OLDEST_SNAPSHOT             240142
MON$NEXT_TRANSACTION            240142
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                       2029152
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.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:728: 
:729: mscs-test-161.budzdorov.ru    Thu Dec  2 22:38:04 2021
:730:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:731: 
:732: 
:733: mscs-test-161.budzdorov.ru    Thu Dec  2 22:38:05 2021
:734:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:735: 
--- end of diff output ---

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

02.12.21 22:39:45. 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-02 22:39:47 No severe PSQL-related problems occured
02.12.21 22:39:48. End of report.