Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.06.2022 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16269408 kB
MemAvailable 31289448 kB
Buffers 0 kB
Cached 15477912 kB
SwapCached 2460 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-06-18 19:38:19
DTS_END                         2022-06-18 19:38:19.5600
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/59372
TRN_ID                          235887
ATT_ID                          688
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: 9048.89 23084 1085867
customer order: creation 162.82 377 19538
customer order: refuse 14.38 373 1725
order to supplier: creation 43.38 1402 5206
order to supplier: removal 3.29 105758 395
invoice (draft): creation 42.34 3570 5081
invoice (draft): removal 53.51 774 6421
invoice accept: apply 38.67 18980 4641
invoice accept: cancel 48.94 773 5873
customer reserve: creation 3577.18 187 429262
customer reserve: removal 4778.27 57 573392
realization accept: apply 78.22 171 9387
realization accept: cancel 86.91 108 10429
payment from customer: creation 55.28 99 6634
payment from customer: removal 5.36 85 643
payment to supplier: creation 45.63 139 5476
payment to supplier: removal 3.21 58 385
service: total inventory turnovers 4.24 1375 509
service: total monetary turnovers 6.36 23 763
service: aggregate perf. data 0.86 18152 103
service: refresh index statistics 0.03 309217 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-06-2022 19:40:18 to 18-06-2022 19:40:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3137
TEST_TIME 2 3091
TEST_TIME 3 4563
TEST_TIME 4 4793
TEST_TIME 5 5162
TEST_TIME 6 4981
TEST_TIME 7 5451
TEST_TIME 8 5515
TEST_TIME 9 5828
TEST_TIME 10 5721
TEST_TIME 11 5886
TEST_TIME 12 6405
TEST_TIME 13 6304
TEST_TIME 14 6622
TEST_TIME 15 6752
TEST_TIME 16 6967
TEST_TIME 17 7276
TEST_TIME 18 7563
TEST_TIME 19 7745
TEST_TIME 20 8386
TEST_TIME 21 8897
TEST_TIME 22 9380
TEST_TIME 23 9421
TEST_TIME 24 9546
TEST_TIME 25 9508
TEST_TIME 26 9633
TEST_TIME 27 10118
TEST_TIME 28 10886
TEST_TIME 29 10695
TEST_TIME 30 10466
TEST_TIME 31 10362
TEST_TIME 32 10578
TEST_TIME 33 10400
TEST_TIME 34 10207
TEST_TIME 35 10037
TEST_TIME 36 9883
TEST_TIME 37 9816
TEST_TIME 38 9722
TEST_TIME 39 9697
TEST_TIME 40 9672
TEST_TIME 41 9451
TEST_TIME 42 9517
TEST_TIME 43 9584
TEST_TIME 44 9427
TEST_TIME 45 9351
TEST_TIME 46 9511
TEST_TIME 47 9429
TEST_TIME 48 9305
TEST_TIME 49 9304
TEST_TIME 50 9136
TEST_TIME 51 9136
TEST_TIME 52 9167
TEST_TIME 53 9155
TEST_TIME 54 9106
TEST_TIME 55 9249
TEST_TIME 56 9188
TEST_TIME 57 9152
TEST_TIME 58 9146
TEST_TIME 59 9282
TEST_TIME 60 9317
TEST_TIME 61 9341
TEST_TIME 62 9471
TEST_TIME 63 9541
TEST_TIME 64 9498
TEST_TIME 65 9523
TEST_TIME 66 9498
TEST_TIME 67 9368
TEST_TIME 68 9385
TEST_TIME 69 9307
TEST_TIME 70 9298
TEST_TIME 71 9248
TEST_TIME 72 9177
TEST_TIME 73 9122
TEST_TIME 74 9144
TEST_TIME 75 9110
TEST_TIME 76 9068
TEST_TIME 77 9017
TEST_TIME 78 9138
TEST_TIME 79 9105
TEST_TIME 80 9146
TEST_TIME 81 9217
TEST_TIME 82 9242
TEST_TIME 83 9226
TEST_TIME 84 9336
TEST_TIME 85 9306
TEST_TIME 86 9352
TEST_TIME 87 9323
TEST_TIME 88 9353
TEST_TIME 89 9330
TEST_TIME 90 9334
TEST_TIME 91 9342
TEST_TIME 92 9340
TEST_TIME 93 9299
TEST_TIME 94 9271
TEST_TIME 95 9219
TEST_TIME 96 9211
TEST_TIME 97 9219
TEST_TIME 98 9204
TEST_TIME 99 9256
TEST_TIME 100 9224
TEST_TIME 101 9210
TEST_TIME 102 9217
TEST_TIME 103 9300
TEST_TIME 104 9273
TEST_TIME 105 9326
TEST_TIME 106 9344
TEST_TIME 107 9333
TEST_TIME 108 9358
TEST_TIME 109 9399
TEST_TIME 110 9387
TEST_TIME 111 9320
TEST_TIME 112 9273
TEST_TIME 113 9251
TEST_TIME 114 9248
TEST_TIME 115 9210
TEST_TIME 116 9195
TEST_TIME 117 9158
TEST_TIME 118 9146
TEST_TIME 119 9116
TEST_TIME 120 9055

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-06-2022 19:40:22 to 18-06-2022 19:40:25.

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 1485638 1485638 0 0.00 0 16451 6 0 0
doc_list_biud 1485638 1485638 0 0.00 0 48460 28 0 0
sp_add_invoice_to_stock 4797 4641 156 3.25 30 173424 18980 0 156
sp_cancel_adding_invoice 5873 5873 0 0.00 4 52342 773 0 0
sp_cancel_client_order 1725 1725 0 0.00 4 15159 373 0 0
sp_cancel_customer_reserve 573392 573392 0 0.00 1 48462 58 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 3 6507 85 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 3 3521 59 0 0
sp_cancel_supplier_invoice 6433 6421 12 0.18 1 34458 774 0 12
sp_cancel_supplier_order 395 395 0 0.00 8 892558 105759 0 0
sp_cancel_write_off 10429 10429 0 0.00 1 8744 109 0 0
sp_client_order 19538 19538 0 0.00 4 76769 377 0 0
sp_customer_reserve 429262 429262 0 0.00 0 65994 188 0 0
sp_fill_shopping_cart 29821 29821 0 0.00 1 45578 43 0 0
sp_fill_shopping_cart_clo_res 429250 429250 0 0.00 0 29684 14 0 0
sp_get_clo_for_invoice 4628 4628 0 0.00 0 23329 352 0 0
sp_kill_cost_storno 17330 17330 0 0.00 0 8741 53 0 0
sp_kill_qstorno_ret_qs2qd 1164029 1164029 0 0.00 0 48459 31 0 0
sp_kill_qty_storno 612250 612250 0 0.00 0 48460 67 0 0
sp_lock_selected_doc 19540 19540 0 0.00 0 6966 4 0 0
sp_make_cost_storno 26125 26125 0 0.00 0 16272 55 0 0
sp_multiply_rows_for_pdistr 26125 26125 0 0.00 0 12046 29 0 0
sp_multiply_rows_for_qdistr 19538 19538 0 0.00 0 76759 173 0 0
sp_pay_from_customer 6634 6634 0 0.00 4 18327 99 0 0
sp_pay_to_supplier 5476 5476 0 0.00 5 21972 139 0 0
sp_payment_common 12110 12110 0 0.00 2 18299 26 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 0 15154 330 0 0
sp_qd_handle_on_invoice_upd_sts 10501 10501 0 0.00 0 25536 317 0 0
sp_qd_handle_on_reserve_upd_sts 9387 9387 0 0.00 0 22804 58 0 0
sp_reserve_write_off 9387 9387 0 0.00 6 22855 171 0 0
sp_supplier_invoice 5081 5081 0 0.00 7 121710 3570 0 0
sp_supplier_order 5206 5206 0 0.00 5 43585 1403 0 0
srv_aggregate_perf_data 103 103 0 0.00 3033 124460 18153 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1438 177194 48613 0 0
srv_find_qd_qs_mism 1042380 1042380 0 0.00 0 14562 5 0 0
srv_make_invnt_saldo 509 509 0 0.00 35 127443 1375 0 0
srv_make_money_saldo 763 763 0 0.00 1 533 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 172010 522024 309217 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4641 4641 0 0.00 0 22534 167 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 0 3178 85 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 4118 17 0 0
t$perf-norm:sp_cancel_customer_reserve 637 637 0 0.00 0 4217 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 9851 29 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 1012 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 5816 35 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 20884 1762 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 2771 9 0 0
t$perf-norm:sp_client_order 19538 19538 0 0.00 0 9931 20 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 2214 6 0 0
t$perf-norm:sp_pay_from_customer 6634 6634 0 0.00 0 7807 10 0 0
t$perf-norm:sp_pay_to_supplier 5476 5476 0 0.00 0 7380 11 0 0
t$perf-norm:sp_reserve_write_off 9387 9387 0 0.00 0 4674 11 0 0
t$perf-norm:sp_supplier_invoice 5081 5081 0 0.00 0 10650 21 0 0
t$perf-norm:sp_supplier_order 5206 5206 0 0.00 0 9518 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 655 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1176 27 0 0
t$perf-norm:srv_make_invnt_saldo 509 509 0 0.00 0 1806 26 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 2148 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 9568 9256 312 3.26 0 5677 19 0 312
v_all_customers 36168 36168 0 0.00 0 25 1 0 0
v_all_suppliers 28584 28584 0 0.00 0 18 1 0 0
v_all_wares 39072 39072 0 0.00 0 48 1 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 2853 8 0 0
v_cancel_client_order 3450 3450 0 0.00 0 5446 10 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 1102 5 0 0
v_cancel_customer_reserve 1274 1274 0 0.00 0 4520 6 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 3516 9 0 0
v_cancel_supplier_invoice 820 796 24 2.92 0 2707 18 0 24
v_cancel_supplier_order 790 790 0 0.00 0 1363 6 0 0
v_cancel_write_off 1912 1912 0 0.00 0 3402 13 0 0
v_max_id_clo_ord 5206 5206 0 0.00 0 813 1 0 0
v_max_id_clo_res 934 934 0 0.00 0 4396 26 0 0
v_max_id_ord_sup 5079 5079 0 0.00 0 5647 5 0 0
v_max_non_paid_invoice 1468 1468 0 0.00 0 436 3 0 0
v_max_non_paid_realizn 3342 3342 0 0.00 0 714 3 0 0
v_min_id_clo_ord 5206 5206 0 0.00 0 3714 7 0 0
v_min_id_clo_res 934 934 0 0.00 0 1541 7 0 0
v_min_id_ord_sup 5079 5079 0 0.00 0 28976 36 0 0
v_min_non_paid_invoice 5476 5476 0 0.00 0 15201 20 0 0
v_min_non_paid_realizn 6634 6634 0 0.00 0 18273 19 0 0
v_our_firm 9488 9488 0 0.00 0 16 1 0 0
v_reserve_write_off 18774 18774 0 0.00 0 5260 5 0 0
x_lock_depdocs_on_canc_invoice 11896 11896 0 0.00 0 20971 128 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 18907 446 0 0
x_make_qstorno_1000_1200 5206 5206 0 0.00 1 43575 1318 0 0
x_make_qstorno_1200_2000 5081 5081 0 0.00 3 121686 3408 0 0
x_make_qstorno_2100_3300 429262 429262 0 0.00 0 65992 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-06-2022 19:40:26 to 18-06-2022 19:40:30.

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:23 3 8192000000 8798329824 8809512960 4742224 0.057 2 1 2 0 4841824 56816 42784
17:09:24 28701 8192000000 9903205296 10214346752 986623520 10.749 100 87 174 0 1015761680 5912992 23225168
17:10:59 14746 8192000000 9956074640 10277609472 1033192560 11.199 100 88 176 1 1064388624 8460624 22735440
17:12:20 20514 8192000000 9991295584 10323812352 1067880064 11.532 100 87 173 1 1099767968 9767152 22120752
17:13:51 14708 8192000000 10052747232 10406367232 1137429904 12.191 100 75 150 2 1165551536 10931392 17190240
17:15:08 8938 8192000000 10104523856 10468311040 1191404400 12.696 100 85 170 1 1223898288 13206784 19287104
17:16:19 5422 8192000000 10151466192 10527076352 1241109664 13.156 100 87 170 1 1271715584 12440848 18165072
17:17:27 6672 8192000000 10174016192 10555940864 1261268416 13.342 100 86 172 1 1295505408 15529760 18707232
17:18:37 2819 8192000000 10209106320 10589769728 1288935760 13.595 100 94 190 1 1325784256 15282016 21566480
17:19:42 1640 8192000000 10219144592 10603626496 1304809040 13.739 100 90 181 0 1337977552 12453408 20715104
17:20:43 1698 8192000000 10223594064 10612412416 1309015168 13.777 100 84 169 0 1337122576 8506256 19601152
17:21:46 7158 8192000000 10201758912 10614194176 1311533536 13.800 100 23 46 1 1323117216 6018864 5564816
17:22:54 17497 8192000000 10205475360 10622640128 1318529136 13.863 100 18 35 3 1332168176 9564176 4074864
17:24:15 18085 8192000000 10226660608 10639958016 1327884144 13.948 100 26 52 1 1340010080 5721120 6404816
17:25:38 10311 8192000000 10220216400 10639167488 1326548016 13.936 100 17 34 0 1336418928 6402272 3468640
17:26:49 2978 8192000000 10230751968 10645016576 1333991696 14.003 100 41 80 1 1349884176 6744160 9148320
17:27:52 7781 8192000000 10207401232 10626256896 1307983824 13.768 99 28 56 0 1324935376 10373920 6577632
17:29:01 55319 8192000000 10069167856 10450976768 1121141344 12.038 87 27 54 1 1132776384 5053776 6581264
17:30:58 7785 8192000000 10134558320 10532757504 1222918544 12.989 98 27 54 0 1235644688 6613680 6112464
17:32:08 7601 8192000000 10136307648 10534006784 1233929376 13.090 99 20 40 1 1243838400 4725824 5183200
17:33:20 61631 8192000000 10206502704 10598883328 1292540848 13.627 100 56 113 2 1311083504 5334496 13208160
17:35:23 8326 8192000000 10195098960 10606145536 1298725840 13.684 100 36 72 2 1317793872 11377488 7690544
17:36:31 8852 8192000000 10202664080 10616872960 1307753088 13.766 100 20 40 0 1317758688 5317472 4688128
17:37:41 9129 8192000000 10203621984 10618605568 1311715024 13.802 100 18 36 1 1321467488 5502960 4249504
17:38:56 96998 8192000000 10243574304 10658910208 1340590416 14.063 100 40 78 0 1359470928 9656096 9224416
17:41:37 12976 8192000000 10274898192 10693685248 1363119616 14.265 100 55 108 1 1386855840 10371440 13364784
17:43:02 20816 8192000000 10297029120 10710585344 1364007040 14.273 99 60 120 1 1394693744 17043440 13643264
17:44:27 17094 8192000000 10313359280 10729738240 1395941168 14.559 100 62 124 1 1430175824 20228112 14006544
17:45:53 13661 8192000000 10310028080 10738311168 1395151952 14.552 100 46 92 0 1427554960 21135104 11267904
17:47:15 16536 8192000000 10328862160 10754412544 1400606128 14.600 99 42 85 0 1436816272 25733648 10476496
17:48:38 3492 8192000000 10344657312 10763395072 1401106656 14.605 99 60 120 1 1445807424 29538688 15162080
17:49:46 3078 8192000000 10339287504 10758713344 1406827344 14.656 100 62 124 1 1453854400 31267424 15759632
17:50:52 7142 8192000000 10331614784 10757410816 1388376224 14.491 99 59 118 2 1436248512 33394304 14477984
17:52:04 3046 8192000000 10328504832 10736033792 1380035056 14.417 100 69 136 0 1434563024 37955088 16572880
17:53:10 3972 8192000000 10341112144 10744737792 1385805712 14.468 100 70 138 1 1444552624 41358928 17387984
17:54:19 9721 8192000000 10310010032 10720751616 1341520720 14.071 97 63 126 1 1400094032 43152400 15420912
17:55:35 2909 8192000000 10325537840 10725560320 1359846304 14.236 100 85 170 2 1427298912 45795984 21656624
17:56:40 9770 8192000000 10313847248 10725507072 1349253696 14.141 99 57 111 1 1404696624 41156576 14286352
17:58:02 1438 8192000000 10349473472 10753773568 1377024592 14.390 100 85 167 3 1448847056 50095248 21727216
17:59:06 15543 8192000000 10323037520 10749001728 1375412704 14.376 100 39 78 2 1434308240 48065024 10830512
18:00:25 15205 8192000000 10310163696 10742292480 1372904864 14.353 99 39 78 0 1420117088 36350336 10861888
18:01:42 12918 8192000000 10303515280 10743066624 1365047120 14.283 99 24 48 2 1404417632 32765792 6604720
18:03:01 28450 8192000000 10301025552 10732519424 1355095728 14.193 98 23 46 0 1392240432 30653184 6491520
18:04:31 32981 8192000000 10318877920 10753937408 1367049536 14.301 100 23 44 2 1416777920 43746400 5981984
18:06:09 30598 8192000000 10307613456 10748203008 1365178560 14.284 100 21 42 1 1401595824 30927824 5489440
18:07:41 41445 8192000000 10286360672 10728321024 1352111472 14.166 98 32 64 0 1379369040 19369456 7888112
18:09:54 19824 8192000000 10284978208 10739757056 1372956000 14.354 100 8 16 0 1395644032 20728208 1959824
18:11:21 84228 8192000000 10278473392 10727993344 1361434096 14.250 98 38 76 0 1379229360 9442864 8352400
18:13:46 52261 8192000000 10290545968 10741555200 1387982448 14.488 100 9 18 0 1401636416 11604496 2049472
18:15:40 64757 8192000000 10302728432 10753433600 1370472240 14.331 98 17 35 0 1388426608 13811248 4143120
18:17:45 22050 8192000000 10317742096 10767765504 1396550384 14.564 100 28 56 1 1420618896 17208448 6860064
18:19:20 177193 8192000000 10306214128 10773864448 1399287472 14.589 99 28 56 0 1425093760 18796576 7009712
18:23:18 30948 8192000000 10315869408 10771017728 1405246256 14.642 99 39 79 2 1426754576 12895200 8613120
18:24:57 115256 8192000000 10322171712 10791407616 1415799840 14.735 100 10 20 0 1436364896 18442944 2122112
18:28:16 67676 8192000000 10332342304 10798661632 1423014800 14.799 100 21 40 2 1442351056 14806416 4529840
18:30:42 55071 8192000000 10308590448 10791829504 1394795056 14.549 98 4 8 0 1407144912 11522032 827824
18:32:37 71467 8192000000 10311688240 10778562560 1392966080 14.532 99 15 30 0 1416460992 20074064 3420848
18:35:58 103210 8192000000 10310049040 10782863360 1397199824 14.570 99 22 44 0 1417806192 15763616 4842752
18:38:46 115714 8192000000 10305750688 10769444864 1345538592 14.107 96 13 26 1 1362554576 14180416 2835568
18:41:46 48276 8192000000 10286810704 10733318144 1337476560 14.035 96 14 28 1 1349498944 8732512 3289872
18:43:37 29603 8192000000 10303563312 10755661824 1388717872 14.494 100 35 70 0 1408170480 11818176 7634432
18:45:07 102858 8192000000 10284296400 10743177216 1350477280 14.152 97 22 44 0 1364403296 9293936 4632080
18:47:53 23263 8192000000 10254043120 10691272704 1325349920 13.925 97 39 77 1 1343817232 9026016 9441296
18:49:17 74153 8192000000 10257358288 10705281024 1311277520 13.798 96 12 24 0 1321278448 7135680 2865248
18:51:39 77467 8192000000 10263233328 10724683776 1349789136 14.146 98 18 36 0 1366408048 11962144 4656768
18:53:57 7122 8192000000 10255590224 10691801088 1349520352 14.143 99 39 79 4 1364657120 5460672 9676096
18:55:07 88428 8192000000 10242638576 10710286336 1279261760 13.506 94 8 16 0 1298351616 17265536 1824320
18:57:36 23960 8192000000 10252117968 10693931008 1327120448 13.941 98 32 63 0 1344143680 10182080 6841152
18:59:00 73684 8192000000 10273828432 10736996352 1333703984 14.001 98 25 50 1 1349129456 9706880 5718592
19:01:13 49512 8192000000 10291669024 10731884544 1351632832 14.162 99 48 94 1 1385161056 23014368 10513856
19:03:12 26453 8192000000 10280768144 10724278272 1362002720 14.255 100 23 44 1 1375834608 8036528 5795360
19:04:50 77442 8192000000 10298786240 10747002880 1355130592 14.194 98 36 72 2 1376035168 13438496 7466080
19:07:12 46910 8192000000 10303088096 10750676992 1367785680 14.307 99 16 32 0 1392815008 20875600 4153728
19:09:25 118667 8192000000 10292371840 10751176704 1348053408 14.130 97 19 38 0 1365911328 13844064 4013856
19:12:24 33732 8192000000 10288681696 10748633088 1372213392 14.347 99 13 26 0 1385546640 10337456 2995792
19:14:00 61406 8192000000 10284324528 10748477440 1354655712 14.189 97 11 22 0 1368712272 11582464 2474096
19:16:03 87468 8192000000 10302020496 10763476992 1385031808 14.462 99 13 26 1 1403041008 15024016 2985184
19:19:08 44853 8192000000 10314028928 10763022336 1400751440 14.602 100 40 80 1 1423617424 13906992 8958992
19:20:54 51092 8192000000 10301934512 10767196160 1382168832 14.436 99 23 45 0 1405241216 18158784 4913600
19:22:48 69058 8192000000 10304295088 10766520320 1370796784 14.334 98 22 44 0 1388810560 12717024 5296752
19:25:01 24188 8192000000 10306971280 10754785280 1372294912 14.348 98 34 67 1 1389323440 9397664 7630864
19:27:10 137472 8192000000 10296244160 10759897088 1370081968 14.328 98 9 18 0 1385746304 13890560 1773776
19:30:28 33251 8192000000 10298576112 10759008256 1401146992 14.605 100 12 24 0 1410813648 6754032 2912624
19:32:01 77562 8192000000 10286163536 10749849600 1359622288 14.234 97 8 16 0 1373556608 12416112 1518208
19:34:19 54990 8192000000 10250575744 10703937536 1284605808 13.555 92 12 25 0 1298083104 10993568 2483728
19:36:16 91624 8192000000 10265412528 10730283008 1356810192 14.209 99 15 30 1 1373724144 13597328 3316624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-06-2022 19:40:32 to 18-06-2022 19:40:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 156 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-06-2022 19:40:48 to 18-06-2022 19:40:50.

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          231235
MON$OLDEST_ACTIVE               236256
MON$OLDEST_SNAPSHOT             236256
MON$NEXT_TRANSACTION            236256
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                       2033056
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:67: 
:68: mscs-test-161.budzdorov.ru    Sat Jun 18 19:38:33 2022
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:70: 
:71: 
:72: mscs-test-161.budzdorov.ru    Sat Jun 18 19:38:33 2022
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:74: 
:75: 
:76: mscs-test-161.budzdorov.ru    Sat Jun 18 19:38:34 2022
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:78: 
--- end of diff output ---

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

18.06.22 19:40:51. 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-06-18 19:40:53 No severe PSQL-related problems occured
18.06.22 19:40:54. End of report.