Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.07.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 16103896 kB
MemAvailable 31019632 kB
Buffers 0 kB
Cached 15597388 kB
SwapCached 2552 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.11
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-07-25 19:38:24
DTS_END                         2022-07-25 19:38:24.6540
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/58014
TRN_ID                          249178
ATT_ID                          680
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: 9405.37 21202 1128644
customer order: creation 176.45 357 21174
customer order: refuse 15.19 331 1823
order to supplier: creation 45.97 1198 5516
order to supplier: removal 3.24 107399 389
invoice (draft): creation 48.20 3307 5784
invoice (draft): removal 50.49 788 6059
invoice accept: apply 44.31 16537 5317
invoice accept: cancel 45.73 721 5487
customer reserve: creation 4121.59 163 494591
customer reserve: removal 4556.83 61 546819
realization accept: apply 86.77 178 10412
realization accept: cancel 77.28 107 9274
payment from customer: creation 61.35 105 7362
payment from customer: removal 5.74 64 689
payment to supplier: creation 50.55 144 6066
payment to supplier: removal 3.64 120 437
service: total inventory turnovers 4.49 1180 539
service: total monetary turnovers 6.69 33 803
service: aggregate perf. data 0.83 24717 99
service: refresh index statistics 0.03 266538 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-07-2022 19:39:31 to 25-07-2022 19:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4296
TEST_TIME 2 5816
TEST_TIME 3 4666
TEST_TIME 4 5254
TEST_TIME 5 5138
TEST_TIME 6 4964
TEST_TIME 7 5028
TEST_TIME 8 5043
TEST_TIME 9 5598
TEST_TIME 10 5845
TEST_TIME 11 5558
TEST_TIME 12 5752
TEST_TIME 13 5915
TEST_TIME 14 6000
TEST_TIME 15 6346
TEST_TIME 16 6498
TEST_TIME 17 6748
TEST_TIME 18 7030
TEST_TIME 19 7105
TEST_TIME 20 7613
TEST_TIME 21 7877
TEST_TIME 22 8123
TEST_TIME 23 8300
TEST_TIME 24 8538
TEST_TIME 25 8688
TEST_TIME 26 8806
TEST_TIME 27 9025
TEST_TIME 28 9776
TEST_TIME 29 10647
TEST_TIME 30 10766
TEST_TIME 31 10644
TEST_TIME 32 10331
TEST_TIME 33 10276
TEST_TIME 34 10133
TEST_TIME 35 10118
TEST_TIME 36 10088
TEST_TIME 37 10051
TEST_TIME 38 10070
TEST_TIME 39 9998
TEST_TIME 40 9954
TEST_TIME 41 9992
TEST_TIME 42 9956
TEST_TIME 43 9863
TEST_TIME 44 9905
TEST_TIME 45 9824
TEST_TIME 46 9741
TEST_TIME 47 9578
TEST_TIME 48 9569
TEST_TIME 49 9538
TEST_TIME 50 9455
TEST_TIME 51 9510
TEST_TIME 52 9485
TEST_TIME 53 9446
TEST_TIME 54 9516
TEST_TIME 55 9507
TEST_TIME 56 9601
TEST_TIME 57 9559
TEST_TIME 58 9581
TEST_TIME 59 9630
TEST_TIME 60 9630
TEST_TIME 61 9660
TEST_TIME 62 9659
TEST_TIME 63 9770
TEST_TIME 64 9782
TEST_TIME 65 9712
TEST_TIME 66 9736
TEST_TIME 67 9646
TEST_TIME 68 9618
TEST_TIME 69 9529
TEST_TIME 70 9578
TEST_TIME 71 9522
TEST_TIME 72 9456
TEST_TIME 73 9404
TEST_TIME 74 9382
TEST_TIME 75 9372
TEST_TIME 76 9328
TEST_TIME 77 9407
TEST_TIME 78 9371
TEST_TIME 79 9374
TEST_TIME 80 9414
TEST_TIME 81 9392
TEST_TIME 82 9456
TEST_TIME 83 9420
TEST_TIME 84 9566
TEST_TIME 85 9539
TEST_TIME 86 9548
TEST_TIME 87 9582
TEST_TIME 88 9558
TEST_TIME 89 9504
TEST_TIME 90 9494
TEST_TIME 91 9484
TEST_TIME 92 9448
TEST_TIME 93 9406
TEST_TIME 94 9364
TEST_TIME 95 9372
TEST_TIME 96 9386
TEST_TIME 97 9402
TEST_TIME 98 9418
TEST_TIME 99 9431
TEST_TIME 100 9446
TEST_TIME 101 9489
TEST_TIME 102 9515
TEST_TIME 103 9497
TEST_TIME 104 9522
TEST_TIME 105 9516
TEST_TIME 106 9536
TEST_TIME 107 9549
TEST_TIME 108 9626
TEST_TIME 109 9605
TEST_TIME 110 9528
TEST_TIME 111 9481
TEST_TIME 112 9472
TEST_TIME 113 9479
TEST_TIME 114 9443
TEST_TIME 115 9435
TEST_TIME 116 9410
TEST_TIME 117 9373
TEST_TIME 118 9355
TEST_TIME 119 9416
TEST_TIME 120 9406

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-07-2022 19:39:34 to 25-07-2022 19:39:38.

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 1584119 1584119 0 0.00 0 19922 6 0 0
doc_list_biud 1584108 1584108 0 0.00 0 21752 26 0 0
sp_add_invoice_to_stock 5425 5317 108 1.99 40 237393 16538 0 108
sp_cancel_adding_invoice 5487 5487 0 0.00 3 58157 722 0 0
sp_cancel_client_order 1823 1823 0 0.00 4 8750 332 0 0
sp_cancel_customer_reserve 546819 546819 0 0.00 0 21754 61 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 2 4894 64 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 7726 120 0 0
sp_cancel_supplier_invoice 6064 6059 5 0.08 1 15611 789 0 5
sp_cancel_supplier_order 389 389 0 0.00 6 1030410 107399 0 0
sp_cancel_write_off 9274 9274 0 0.00 1 9271 107 0 0
sp_client_order 21174 21174 0 0.00 3 22286 357 0 0
sp_customer_reserve 494591 494591 0 0.00 0 154288 163 0 0
sp_fill_shopping_cart 32473 32473 0 0.00 1 24244 42 0 0
sp_fill_shopping_cart_clo_res 494590 494590 0 0.00 0 27298 15 0 0
sp_get_clo_for_invoice 5316 5316 0 0.00 0 34059 288 0 0
sp_kill_cost_storno 15887 15887 0 0.00 0 5586 55 0 0
sp_kill_qstorno_ret_qs2qd 1109360 1109360 0 0.00 0 21750 33 0 0
sp_kill_qty_storno 585578 585578 0 0.00 0 21752 70 0 0
sp_lock_selected_doc 21606 21606 0 0.00 0 7431 6 0 0
sp_make_cost_storno 29156 29156 0 0.00 0 19919 56 0 0
sp_multiply_rows_for_pdistr 29156 29156 0 0.00 0 5929 26 0 0
sp_multiply_rows_for_qdistr 21174 21174 0 0.00 0 22273 175 0 0
sp_pay_from_customer 7362 7362 0 0.00 4 13859 105 0 0
sp_pay_to_supplier 6066 6066 0 0.00 5 17407 144 0 0
sp_payment_common 13418 13418 0 0.00 1 13823 24 0 0
sp_qd_handle_on_cancel_clo 1823 1823 0 0.00 0 8744 294 0 0
sp_qd_handle_on_invoice_upd_sts 10803 10803 0 0.00 0 14556 291 0 0
sp_qd_handle_on_reserve_upd_sts 10411 10411 0 0.00 0 19868 64 0 0
sp_reserve_write_off 10412 10412 0 0.00 6 19936 178 0 0
sp_supplier_invoice 5784 5784 0 0.00 5 133110 3307 0 0
sp_supplier_order 5516 5516 0 0.00 5 50520 1198 0 0
srv_aggregate_perf_data 99 99 0 0.00 2350 386827 24718 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1359 166758 51437 0 0
srv_find_qd_qs_mism 1077572 1077572 0 0.00 0 12547 5 0 0
srv_make_invnt_saldo 539 539 0 0.00 60 103709 1181 0 0
srv_make_money_saldo 803 803 0 0.00 1 8042 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 122806 373432 266538 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5317 5317 0 0.00 0 12900 165 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 2593 94 0 0
t$perf-norm:sp_cancel_client_order 1823 1823 0 0.00 0 8193 23 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 16800 53 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 3355 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 497 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 1926 13 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 20396 1893 0 0
t$perf-norm:sp_cancel_write_off 1042 1042 0 0.00 0 2091 9 0 0
t$perf-norm:sp_client_order 21174 21174 0 0.00 0 8012 19 0 0
t$perf-norm:sp_customer_reserve 1063 1063 0 0.00 0 3853 11 0 0
t$perf-norm:sp_pay_from_customer 7362 7362 0 0.00 0 8115 14 0 0
t$perf-norm:sp_pay_to_supplier 6066 6066 0 0.00 0 23148 21 0 0
t$perf-norm:sp_reserve_write_off 10412 10412 0 0.00 0 12941 15 0 0
t$perf-norm:sp_supplier_invoice 5784 5784 0 0.00 0 9405 19 0 0
t$perf-norm:sp_supplier_order 5516 5516 0 0.00 0 5752 17 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 332 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 38 1 0 0
t$perf-norm:srv_make_invnt_saldo 539 539 0 0.00 0 3678 30 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 8566 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10844 10628 216 1.99 0 8272 21 0 216
v_all_customers 38760 38760 0 0.00 0 22 0 0 0
v_all_suppliers 31276 31276 0 0.00 0 118 1 0 0
v_all_wares 42348 42348 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 6854 21 0 0
v_cancel_client_order 3646 3646 0 0.00 0 6356 10 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 2255 5 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 6039 10 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 5469 18 0 0
v_cancel_supplier_invoice 762 752 10 1.31 0 2014 16 0 10
v_cancel_supplier_order 778 778 0 0.00 0 1945 8 0 0
v_cancel_write_off 2084 2084 0 0.00 0 3455 18 0 0
v_max_id_clo_ord 5516 5516 0 0.00 0 2636 2 0 0
v_max_id_clo_res 1063 1063 0 0.00 0 1889 12 0 0
v_max_id_ord_sup 5781 5781 0 0.00 0 5231 6 0 0
v_max_non_paid_invoice 1717 1717 0 0.00 0 536 3 0 0
v_max_non_paid_realizn 3907 3907 0 0.00 0 1868 3 0 0
v_min_id_clo_ord 5516 5516 0 0.00 0 3206 6 0 0
v_min_id_clo_res 1063 1063 0 0.00 0 5312 20 0 0
v_min_id_ord_sup 5781 5781 0 0.00 0 7458 12 0 0
v_min_non_paid_invoice 6058 6058 0 0.00 0 6276 17 0 0
v_min_non_paid_realizn 7360 7360 0 0.00 0 13016 16 0 0
v_our_firm 10494 10494 0 0.00 0 21 1 0 0
v_reserve_write_off 20822 20822 0 0.00 0 6726 8 0 0
x_lock_depdocs_on_canc_invoice 11170 11170 0 0.00 0 12349 108 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 8599 328 0 0
x_make_qstorno_1000_1200 5516 5516 0 0.00 1 50496 1131 0 0
x_make_qstorno_1200_2000 5784 5784 0 0.00 2 129222 3135 0 0
x_make_qstorno_2100_3300 494591 494591 0 0.00 0 154286 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-07-2022 19:39:39 to 25-07-2022 19:39:43.

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:28 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:29 23767 8192000000 9907752432 10220785664 991142016 10.793 100 86 172 0 1019953488 6259744 22551728
17:11:00 14198 8192000000 9943223744 10266632192 1029842320 11.167 100 86 172 0 1060435760 8480208 22113232
17:12:18 3087 8192000000 9976887360 10304897024 1062096480 11.477 100 88 176 0 1093929040 9421472 22411088
17:13:23 12470 8192000000 10014254736 10356137984 1094550352 11.786 100 74 149 0 1122892592 10090624 18251616
17:14:40 6967 8192000000 10051832128 10409054208 1143344032 12.247 100 74 147 1 1171637520 11075536 17217952
17:15:53 3551 8192000000 10118507680 10486046720 1206745232 12.839 100 81 162 0 1237526688 12360640 18420816
17:17:00 2905 8192000000 10153485632 10528268288 1248537792 13.225 100 81 160 0 1278064000 12439680 17086528
17:18:07 1927 8192000000 10186778896 10571493376 1275765008 13.474 100 77 154 0 1306894816 13969552 17160256
17:19:11 740 8192000000 10204854752 10589278208 1290927120 13.613 100 89 179 0 1323408368 12330544 20150704
17:20:12 15452 8192000000 10193235904 10596917248 1298788304 13.684 100 53 107 1 1318052496 6370688 12893504
17:21:28 4706 8192000000 10200535104 10608820224 1307102672 13.760 100 36 72 0 1323749136 8171872 8474592
17:22:34 10290 8192000000 10193323824 10614366208 1313339424 13.816 100 11 22 0 1321714400 5606640 2768336
17:23:46 7848 8192000000 10205198128 10628886528 1326227744 13.933 100 20 43 0 1336711472 5990416 4493312
17:24:58 42626 8192000000 10248516896 10648920064 1330968320 13.976 100 63 126 0 1352646400 6420528 15257552
17:26:41 6148 8192000000 10228259296 10649014272 1336573808 14.027 100 23 46 0 1348362784 6328912 5460064
17:27:51 4465 8192000000 10219656416 10641559552 1329103280 13.959 100 26 52 1 1340726736 5796480 5826976
17:28:58 74631 8192000000 10151782624 10556092416 1115328224 11.983 85 29 58 0 1126829280 4765600 6735456
17:31:14 6657 8192000000 10142596128 10537578496 1203561680 12.809 95 23 46 0 1213648688 4896144 5190864
17:32:21 8905 8192000000 10130943152 10530603008 1231080576 13.064 99 21 42 0 1241467632 5768112 4618944
17:33:30 6440 8192000000 10157991072 10552295424 1261196832 13.341 100 23 46 0 1272625440 5973728 5454880
17:34:37 21921 8192000000 10169262528 10579918848 1276728400 13.483 100 23 46 0 1288102016 6405424 4968192
17:36:00 7022 8192000000 10176428672 10593890304 1292575920 13.628 100 20 42 0 1302226208 5077536 4572752
17:37:07 11223 8192000000 10195609744 10612776960 1307791008 13.766 100 26 53 0 1318764816 5077424 5896384
17:38:20 17530 8192000000 10229300832 10641629184 1333110688 13.995 100 54 104 1 1350673808 4739552 12823568
17:39:42 106828 8192000000 10252539920 10676404224 1347672144 14.127 100 40 78 0 1364623968 7605984 9345840
17:42:43 4123 8192000000 10285144224 10703523840 1373397552 14.357 100 49 98 0 1396365936 11268544 11699840
17:43:55 3664 8192000000 10297259568 10715037696 1374565680 14.368 100 57 112 0 1406224576 19333920 12324976
17:45:11 22971 8192000000 10296410800 10720260096 1384209696 14.454 100 48 95 0 1412285168 16986208 11089264
17:46:40 5453 8192000000 10300927824 10719801344 1380557392 14.422 100 45 90 0 1409564816 17815472 11191952
17:47:50 30478 8192000000 10310139856 10742816768 1396130304 14.561 100 30 60 0 1427237376 23551664 7555408
17:49:29 7400 8192000000 10315700208 10740068352 1393938928 14.541 100 46 93 0 1426491536 20989536 11563072
17:50:53 3884 8192000000 10335527024 10753941504 1396156464 14.561 100 52 104 0 1442260384 32541488 13562432
17:52:02 4658 8192000000 10330019120 10746736640 1387153680 14.480 99 49 96 0 1431022512 31627120 12241712
17:53:12 3176 8192000000 10333900320 10746445824 1383968176 14.452 100 64 127 1 1439797824 39265440 16564208
17:54:25 1412 8192000000 10342262544 10747129856 1369713984 14.324 99 74 148 0 1431668560 43596320 18358256
17:55:33 5939 8192000000 10332027584 10743205888 1380759008 14.423 100 60 118 0 1438429504 42424560 15245936
17:56:46 1359 8192000000 10339536496 10744422400 1374417104 14.367 100 77 154 1 1442785248 47888896 20479248
17:57:51 1736 8192000000 10339617472 10742185984 1369126720 14.319 100 83 163 2 1444108272 53677424 21304128
17:59:00 7133 8192000000 10349342528 10772160512 1393055760 14.533 100 61 119 4 1459352928 51244880 15052288
18:00:17 11744 8192000000 10351291472 10778505216 1389980528 14.506 99 46 92 0 1452299296 49655152 12663616
18:01:47 7665 8192000000 10336517712 10758422528 1376712032 14.387 99 52 103 1 1439044592 48824864 13507696
18:03:05 21442 8192000000 10318903696 10747924480 1357387920 14.214 99 28 56 1 1417475744 52179408 7908416
18:04:27 21192 8192000000 10301754320 10730958848 1347253936 14.123 100 29 56 0 1408257344 52345488 8657920
18:05:53 30015 8192000000 10301463504 10744827904 1338490560 14.044 100 23 46 0 1405416224 59727376 7198288
18:08:09 51486 8192000000 10281237008 10729947136 1367739152 14.307 100 18 36 0 1386878896 15142368 3997376
18:10:01 74016 8192000000 10283084016 10721521664 1359194128 14.230 99 41 81 0 1376178752 7642640 9341984
18:12:15 37826 8192000000 10295797824 10737143808 1371507536 14.341 99 32 64 1 1390140944 11770032 6863376
18:13:54 166758 8192000000 10291286848 10736734208 1345992800 14.111 97 22 43 1 1367184432 16193536 4998096
18:17:42 31797 8192000000 10315687792 10749448192 1395844112 14.558 100 33 65 0 1416498048 12833584 7820352
18:19:16 44640 8192000000 10298102656 10763444224 1397305024 14.571 100 14 28 0 1414611904 13861920 3444960
18:21:01 80633 8192000000 10293040576 10753724416 1386248384 14.472 99 17 34 0 1400948304 10686464 4013456
18:23:22 53437 8192000000 10298744320 10763632640 1403636576 14.627 100 11 22 0 1413420784 7162736 2621472
18:25:45 29963 8192000000 10310159344 10780053504 1412011632 14.702 100 20 40 0 1431497328 14710544 4775152
18:27:15 56727 8192000000 10305902096 10774343680 1410717824 14.690 100 13 26 0 1422163520 8618816 2826880
18:29:55 97991 8192000000 10312294352 10768965632 1359036176 14.229 96 16 30 0 1373278528 10692880 3549472
18:32:33 55173 8192000000 10298379968 10767253504 1376513264 14.385 98 13 26 1 1393201440 13164864 3523312
18:34:30 93240 8192000000 10313863392 10778779648 1397877856 14.576 99 16 31 0 1411882528 10812480 3192192
18:37:04 72501 8192000000 10302534880 10757447680 1329803664 13.965 94 20 38 0 1346274592 11770896 4700032
18:39:17 76032 8192000000 10299723424 10735493120 1269856208 13.420 91 19 38 0 1281948048 8309808 3782032
18:41:34 99471 8192000000 10247810096 10697224192 1173522864 12.530 86 12 24 0 1186556992 10386000 2648128
18:44:14 74897 8192000000 10251337568 10698072064 1322116800 13.896 98 36 72 0 1345651152 14775168 8759184
18:46:35 67903 8192000000 10255710608 10694193152 1340606768 14.063 100 31 62 0 1360657424 12833504 7217152
18:48:44 106872 8192000000 10238794352 10692026368 1288843488 13.594 95 8 16 0 1298913056 8355472 1714096
18:51:31 53664 8192000000 10237361984 10698469376 1347507424 14.125 100 18 36 1 1360697600 9134096 4056080
18:53:29 74146 8192000000 10274544896 10718294016 1321752688 13.893 97 18 36 0 1335790944 9881680 4156576
18:55:50 45313 8192000000 10254145760 10698481664 1318828224 13.866 97 28 56 0 1334617968 9653152 6136592
18:57:37 78421 8192000000 10264198560 10715525120 1336852208 14.029 99 32 64 0 1355927520 11560848 7514464
19:00:06 48939 8192000000 10266949952 10720333824 1339076752 14.049 98 30 60 0 1358779936 13134832 6568352
19:01:56 78329 8192000000 10294351776 10743967744 1372883680 14.353 100 20 39 0 1392578656 15266960 4428016
19:04:18 62603 8192000000 10289902176 10743250944 1367677168 14.306 99 24 48 0 1389202064 16281008 5243888
19:06:22 94205 8192000000 10294189664 10749607936 1383719808 14.450 100 33 67 2 1405147344 13606320 7821216
19:09:20 36598 8192000000 10294013424 10763726848 1394097680 14.542 100 9 18 0 1413060480 17359792 1603008
19:10:57 58947 8192000000 10285683696 10749890560 1397431616 14.572 100 11 22 0 1405165408 5552384 2181408
19:12:57 96759 8192000000 10329221328 10789040128 1407517072 14.662 100 5 10 0 1416138160 7773168 847920
19:15:37 59444 8192000000 10322634752 10769965056 1370658304 14.333 97 26 52 1 1387569296 10747888 6163104
19:17:38 36083 8192000000 10315229024 10769440768 1400004448 14.595 100 24 48 2 1414109504 9037616 5067440
19:19:17 67521 8192000000 10307207248 10768560128 1363223184 14.266 97 19 38 0 1379384000 11776384 4384432
19:21:25 126867 8192000000 10279452160 10741129216 1267249712 13.396 91 10 20 0 1282569296 13557168 1762416
19:24:37 89513 8192000000 10262660032 10715947008 1258519632 13.316 92 17 34 0 1274947680 13146464 3281584
19:27:58 78723 8192000000 10252997984 10696503296 1315478224 13.836 97 22 44 0 1330885360 10097232 5309904
19:30:32 53866 8192000000 10249273984 10703831040 1327930512 13.948 98 12 24 3 1341114704 10759232 2424960
19:32:26 48890 8192000000 10244408288 10706296832 1328095904 13.950 98 3 6 0 1338565984 9861744 608336
19:34:15 66138 8192000000 10266783088 10725900288 1348362816 14.133 100 14 28 0 1367666384 16229136 3074432
19:36:51 91439 8192000000 10256626032 10712530944 1299206176 13.688 96 21 42 0 1322398432 18134448 5057808

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 25-07-2022 19:39:45 to 25-07-2022 19:39:59.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-07-2022 19:40:00 to 25-07-2022 19:40:02.

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          248740
MON$OLDEST_ACTIVE               249383
MON$OLDEST_SNAPSHOT             249383
MON$NEXT_TRANSACTION            249383
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                       2022208
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:171: 
:172: mscs-test-161.budzdorov.ru    Mon Jul 25 19:38:30 2022
:173:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:174: 
:175: 
:176: mscs-test-161.budzdorov.ru    Mon Jul 25 19:38:30 2022
:177:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:178: 
:179: 
:180: mscs-test-161.budzdorov.ru    Mon Jul 25 19:38:33 2022
:181:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:182: 
--- end of diff output ---

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

25.07.22 19:40:03. 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-07-25 19:40:05 No severe PSQL-related problems occured
25.07.22 19:40:07. End of report.