Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.06.2022 01: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 16260760 kB
MemAvailable 31250020 kB
Buffers 0 kB
Cached 15431456 kB
SwapCached 2452 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-15 01:38:21
DTS_END                         2022-06-15 01:38:21.5120
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/57912
TRN_ID                          241157
ATT_ID                          654
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: 9450.73 24634 1134087
customer order: creation 172.68 356 20721
customer order: refuse 15.28 351 1834
order to supplier: creation 46.18 1266 5541
order to supplier: removal 3.48 101220 418
invoice (draft): creation 46.17 3535 5540
invoice (draft): removal 53.60 811 6432
invoice accept: apply 42.42 19084 5090
invoice accept: cancel 48.33 891 5799
customer reserve: creation 3992.85 185 479142
customer reserve: removal 4725.25 58 567030
realization accept: apply 84.57 178 10148
realization accept: cancel 88.04 98 10565
payment from customer: creation 59.00 109 7080
payment from customer: removal 5.79 76 695
payment to supplier: creation 50.36 121 6043
payment to supplier: removal 3.63 77 435
service: total inventory turnovers 5.13 1006 616
service: total monetary turnovers 6.98 25 838
service: aggregate perf. data 0.97 14192 116
service: refresh index statistics 0.03 349039 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-06-2022 01:39:34 to 15-06-2022 01:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8771
TEST_TIME 2 6675
TEST_TIME 3 6186
TEST_TIME 4 5378
TEST_TIME 5 5535
TEST_TIME 6 5840
TEST_TIME 7 5541
TEST_TIME 8 5972
TEST_TIME 9 6308
TEST_TIME 10 6032
TEST_TIME 11 6389
TEST_TIME 12 7170
TEST_TIME 13 7312
TEST_TIME 14 7639
TEST_TIME 15 7927
TEST_TIME 16 8146
TEST_TIME 17 8127
TEST_TIME 18 8714
TEST_TIME 19 8796
TEST_TIME 20 9557
TEST_TIME 21 9695
TEST_TIME 22 9792
TEST_TIME 23 10254
TEST_TIME 24 10528
TEST_TIME 25 10566
TEST_TIME 26 10530
TEST_TIME 27 10391
TEST_TIME 28 10924
TEST_TIME 29 11134
TEST_TIME 30 11106
TEST_TIME 31 11218
TEST_TIME 32 11206
TEST_TIME 33 11082
TEST_TIME 34 10801
TEST_TIME 35 10741
TEST_TIME 36 10545
TEST_TIME 37 10452
TEST_TIME 38 10505
TEST_TIME 39 10491
TEST_TIME 40 10480
TEST_TIME 41 10478
TEST_TIME 42 10339
TEST_TIME 43 10300
TEST_TIME 44 10152
TEST_TIME 45 10001
TEST_TIME 46 9975
TEST_TIME 47 10164
TEST_TIME 48 10236
TEST_TIME 49 10196
TEST_TIME 50 10213
TEST_TIME 51 10259
TEST_TIME 52 10231
TEST_TIME 53 10160
TEST_TIME 54 10164
TEST_TIME 55 10188
TEST_TIME 56 10137
TEST_TIME 57 10109
TEST_TIME 58 10209
TEST_TIME 59 10159
TEST_TIME 60 10076
TEST_TIME 61 10161
TEST_TIME 62 10084
TEST_TIME 63 10077
TEST_TIME 64 10021
TEST_TIME 65 9920
TEST_TIME 66 9992
TEST_TIME 67 9917
TEST_TIME 68 9884
TEST_TIME 69 9864
TEST_TIME 70 9800
TEST_TIME 71 9752
TEST_TIME 72 9670
TEST_TIME 73 9664
TEST_TIME 74 9723
TEST_TIME 75 9698
TEST_TIME 76 9651
TEST_TIME 77 9704
TEST_TIME 78 9686
TEST_TIME 79 9717
TEST_TIME 80 9677
TEST_TIME 81 9678
TEST_TIME 82 9726
TEST_TIME 83 9788
TEST_TIME 84 9786
TEST_TIME 85 9824
TEST_TIME 86 9794
TEST_TIME 87 9835
TEST_TIME 88 9792
TEST_TIME 89 9837
TEST_TIME 90 9751
TEST_TIME 91 9742
TEST_TIME 92 9686
TEST_TIME 93 9704
TEST_TIME 94 9619
TEST_TIME 95 9576
TEST_TIME 96 9612
TEST_TIME 97 9612
TEST_TIME 98 9623
TEST_TIME 99 9609
TEST_TIME 100 9626
TEST_TIME 101 9639
TEST_TIME 102 9632
TEST_TIME 103 9653
TEST_TIME 104 9638
TEST_TIME 105 9693
TEST_TIME 106 9691
TEST_TIME 107 9655
TEST_TIME 108 9723
TEST_TIME 109 9682
TEST_TIME 110 9634
TEST_TIME 111 9577
TEST_TIME 112 9560
TEST_TIME 113 9504
TEST_TIME 114 9468
TEST_TIME 115 9482
TEST_TIME 116 9531
TEST_TIME 117 9497
TEST_TIME 118 9531
TEST_TIME 119 9515
TEST_TIME 120 9455

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-06-2022 01:39:37 to 15-06-2022 01:39:41.

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 1576148 1576148 0 0.00 0 25008 6 0 0
doc_list_biud 1576148 1576148 0 0.00 0 71866 27 0 0
sp_add_invoice_to_stock 5196 5090 106 2.04 35 194743 19084 0 106
sp_cancel_adding_invoice 5799 5799 0 0.00 4 66091 892 0 0
sp_cancel_client_order 1834 1834 0 0.00 4 8675 351 0 0
sp_cancel_customer_reserve 567030 567030 0 0.00 1 71867 59 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 2 5935 76 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 5748 77 0 0
sp_cancel_supplier_invoice 6437 6432 5 0.07 1 28424 812 0 5
sp_cancel_supplier_order 418 418 0 0.00 8 1089943 101220 0 0
sp_cancel_write_off 10565 10565 0 0.00 1 12611 98 0 0
sp_client_order 20721 20721 0 0.00 3 23056 356 0 0
sp_customer_reserve 479142 479142 0 0.00 0 126612 185 0 0
sp_fill_shopping_cart 31801 31801 0 0.00 1 9434 36 0 0
sp_fill_shopping_cart_clo_res 479132 479132 0 0.00 0 12290 13 0 0
sp_get_clo_for_invoice 5073 5073 0 0.00 0 24206 308 0 0
sp_kill_cost_storno 17494 17494 0 0.00 0 5920 56 0 0
sp_kill_qstorno_ret_qs2qd 1151475 1151475 0 0.00 0 71865 32 0 0
sp_kill_qty_storno 607297 607297 0 0.00 0 71866 69 0 0
sp_lock_selected_doc 21128 21128 0 0.00 0 9935 6 0 0
sp_make_cost_storno 28342 28342 0 0.00 0 9581 47 0 0
sp_multiply_rows_for_pdistr 28342 28342 0 0.00 0 6979 30 0 0
sp_multiply_rows_for_qdistr 20721 20721 0 0.00 0 9450 161 0 0
sp_pay_from_customer 7080 7080 0 0.00 4 12475 110 0 0
sp_pay_to_supplier 6043 6043 0 0.00 5 17498 122 0 0
sp_payment_common 13123 13123 0 0.00 1 10685 20 0 0
sp_qd_handle_on_cancel_clo 1834 1834 0 0.00 0 8668 330 0 0
sp_qd_handle_on_invoice_upd_sts 10870 10870 0 0.00 0 33954 354 0 0
sp_qd_handle_on_reserve_upd_sts 10148 10148 0 0.00 0 17261 62 0 0
sp_reserve_write_off 10148 10148 0 0.00 6 20981 178 0 0
sp_supplier_invoice 5540 5540 0 0.00 6 81752 3536 0 0
sp_supplier_order 5541 5541 0 0.00 6 37057 1267 0 0
srv_aggregate_perf_data 116 116 0 0.00 2276 85567 14193 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1048 163045 46050 0 0
srv_find_qd_qs_mism 1084599 1084599 0 0.00 0 25007 5 0 0
srv_make_invnt_saldo 616 616 0 0.00 34 39794 1006 0 0
srv_make_money_saldo 838 838 0 0.00 0 658 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 148960 599433 349039 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 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 5090 5090 0 0.00 0 18528 199 0 0
t$perf-norm:sp_cancel_adding_invoice 451 451 0 0.00 0 9014 115 0 0
t$perf-norm:sp_cancel_client_order 1834 1834 0 0.00 0 7498 15 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 705 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 1513 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 1786 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 1180 18 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 22548 1513 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 1055 6 0 0
t$perf-norm:sp_client_order 20721 20721 0 0.00 0 14575 22 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 7562 15 0 0
t$perf-norm:sp_pay_from_customer 7080 7080 0 0.00 0 7760 14 0 0
t$perf-norm:sp_pay_to_supplier 6043 6043 0 0.00 0 20533 14 0 0
t$perf-norm:sp_reserve_write_off 10148 10148 0 0.00 0 22352 14 0 0
t$perf-norm:sp_supplier_invoice 5540 5540 0 0.00 0 5668 22 0 0
t$perf-norm:sp_supplier_order 5541 5541 0 0.00 0 8449 21 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 627 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 116 3 0 0
t$perf-norm:srv_make_invnt_saldo 616 616 0 0.00 0 1041 26 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 2833 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 113 30 0 0
v_add_invoice_to_stock 10354 10142 212 2.04 0 6522 14 0 212
v_all_customers 37268 37268 0 0.00 0 17 1 0 0
v_all_suppliers 31286 31286 0 0.00 0 17 1 0 0
v_all_wares 41442 41442 0 0.00 0 128 1 0 0
v_cancel_adding_invoice 902 902 0 0.00 0 238 6 0 0
v_cancel_client_order 3668 3668 0 0.00 0 1737 3 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 806 4 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 10591 12 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 1179 5 0 0
v_cancel_supplier_invoice 874 864 10 1.14 0 4458 18 0 10
v_cancel_supplier_order 836 836 0 0.00 0 378 4 0 0
v_cancel_write_off 1938 1938 0 0.00 0 3432 17 0 0
v_max_id_clo_ord 5541 5541 0 0.00 0 246 1 0 0
v_max_id_clo_res 964 964 0 0.00 0 2185 13 0 0
v_max_id_ord_sup 5539 5539 0 0.00 0 1447 3 0 0
v_max_non_paid_invoice 1480 1480 0 0.00 0 3289 4 0 0
v_max_non_paid_realizn 3876 3876 0 0.00 0 3980 5 0 0
v_min_id_clo_ord 5541 5541 0 0.00 0 2245 6 0 0
v_min_id_clo_res 964 964 0 0.00 0 3684 8 0 0
v_min_id_ord_sup 5539 5539 0 0.00 0 5626 10 0 0
v_min_non_paid_invoice 6043 6043 0 0.00 0 4142 12 0 0
v_min_non_paid_realizn 7080 7080 0 0.00 0 7019 15 0 0
v_our_firm 10582 10582 0 0.00 0 34 1 0 0
v_reserve_write_off 20296 20296 0 0.00 0 20901 9 0 0
x_lock_depdocs_on_canc_invoice 11799 11799 0 0.00 0 29412 115 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 17760 384 0 0
x_make_qstorno_1000_1200 5541 5541 0 0.00 1 37050 1184 0 0
x_make_qstorno_1200_2000 5540 5540 0 0.00 3 81684 3413 0 0
x_make_qstorno_2100_3300 479142 479142 0 0.00 0 126579 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-06-2022 01:39:42 to 15-06-2022 01:39:46.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:25 38 8192000000 8824142736 8843931648 21705712 0.264 6 4 5 0 21939856 94848 139296
23:09:26 15370 8192000000 9908437040 10219622400 990026672 10.782 100 88 177 1 1019508080 6441056 23040352
23:10:50 26810 8192000000 9963925872 10291384320 1044538912 11.308 100 89 177 1 1075719968 8853008 22328048
23:12:30 16869 8192000000 10007660656 10344972288 1085993488 11.705 100 84 168 0 1117801792 11077360 20730944
23:13:51 12709 8192000000 10043509280 10392973312 1128992560 12.112 100 77 154 0 1159687440 12386896 18307984
23:15:06 12396 8192000000 10077106448 10436050944 1166919472 12.468 100 82 164 0 1198725600 12224512 19581616
23:16:21 8060 8192000000 10108367488 10479247360 1201876384 12.794 100 73 146 0 1230552432 12374720 16301328
23:17:30 4486 8192000000 10160820016 10535108608 1248971920 13.229 100 82 165 1 1280119072 12789712 18357440
23:18:36 2530 8192000000 10194872272 10575654912 1279534256 13.509 100 87 176 1 1311680464 12483696 19662512
23:19:39 1403 8192000000 10223446672 10610061312 1307191920 13.761 100 92 184 0 1341055840 13656096 20207824
23:20:42 5573 8192000000 10218609776 10620424192 1319660352 13.874 100 52 102 3 1338833856 7110704 12062800
23:21:50 9625 8192000000 10207930960 10630004736 1327564368 13.945 100 18 36 0 1337479328 6395600 3519360
23:23:01 6612 8192000000 10221805808 10639966208 1336204656 14.023 100 27 54 0 1349175472 6691904 6278912
23:24:13 25985 8192000000 10234446608 10656165888 1345056512 14.103 100 39 77 1 1359592544 6397824 8138208
23:25:40 5035 8192000000 10252925120 10668134400 1348313616 14.132 100 37 73 2 1363476928 7203312 7960000
23:26:48 9670 8192000000 10235281792 10662293504 1337665168 14.036 99 26 53 0 1349616976 5674160 6277648
23:27:58 9530 8192000000 10209895216 10631094272 1284123472 13.551 96 17 34 0 1293329440 5150848 4055120
23:29:08 3457 8192000000 10242042640 10640351232 1329207136 13.960 100 73 148 3 1353194640 5765824 18221680
23:30:12 6499 8192000000 10163420736 10558046208 1226384848 13.021 96 32 64 1 1239638160 6128736 7124576
23:31:20 4617 8192000000 10133118896 10537779200 1244556240 13.188 100 31 61 2 1258285888 6354288 7375360
23:32:24 61663 8192000000 10108751536 10505875456 1195698272 12.736 95 35 71 3 1208688160 4622048 8367840
23:34:29 4852 8192000000 10177507264 10581245952 1278544976 13.500 100 38 74 2 1293725536 6722016 8458544
23:35:36 8469 8192000000 10194245616 10605527040 1298369504 13.680 100 30 60 1 1312010880 5063472 8577904
23:36:46 7953 8192000000 10196839664 10614382592 1307519840 13.764 100 22 44 2 1318537312 5957600 5059872
23:37:55 34042 8192000000 10239052832 10644361216 1324940384 13.921 100 49 98 0 1341703264 5410496 11352384
23:39:32 13486 8192000000 10247905920 10666352640 1349520032 14.143 100 39 76 1 1366075296 7666448 8888816
23:40:52 21564 8192000000 10285420640 10704515072 1375447408 14.376 100 54 108 1 1399334480 11285552 12601520
23:42:19 35828 8192000000 10302702928 10724646912 1384419040 14.456 100 55 110 1 1418952464 21926448 12606976
23:44:03 20782 8192000000 10306825936 10730295296 1389805200 14.504 100 45 88 3 1417826656 17452064 10569392
23:45:29 10142 8192000000 10320934368 10742640640 1396975392 14.568 100 55 106 1 1438160336 27550320 13634624
23:46:46 7415 8192000000 10323549952 10741796864 1380290320 14.419 99 48 90 2 1414857344 23517024 11050000
23:47:58 8681 8192000000 10342536976 10759249920 1398549024 14.582 100 72 143 1 1448963088 33326800 17087264
23:49:21 6565 8192000000 10344005248 10763608064 1400551040 14.600 100 67 132 2 1450604288 34060512 15992736
23:50:31 1376 8192000000 10349579328 10758152192 1394902784 14.550 100 86 167 0 1453103440 37788640 20412016
23:51:39 2467 8192000000 10331501968 10740719616 1377824304 14.397 99 71 142 3 1433237536 37951072 17462160
23:52:48 1935 8192000000 10342613952 10747953152 1385842592 14.469 100 82 158 2 1444928352 39302944 19782816
23:53:56 1048 8192000000 10354557728 10758254592 1390032528 14.506 100 91 180 0 1451905232 39183856 22688848
23:55:01 3632 8192000000 10324649184 10731470848 1364847008 14.281 99 73 146 1 1424279456 41344736 18087712
23:56:09 1459 8192000000 10327877520 10733809664 1351842064 14.164 99 86 173 1 1422475856 48058832 22574960
23:57:15 14702 8192000000 10296383808 10712469504 1338033952 14.040 98 39 78 1 1390406576 41521760 10850864
23:58:37 9228 8192000000 10286968688 10717716480 1352070464 14.166 100 44 89 1 1403380624 39225872 12084288
23:59:51 26037 8192000000 10286768288 10717519872 1348291872 14.132 99 35 70 0 1395058144 36839824 9926448
00:01:19 17143 8192000000 10296770160 10742206464 1352680688 14.172 99 33 66 0 1399652464 37853856 9117920
00:02:41 18483 8192000000 10322430048 10758504448 1387101952 14.480 100 40 80 3 1432536144 34327600 11106592
00:04:02 25084 8192000000 10311309952 10756677632 1383291952 14.446 100 16 32 0 1416939136 29143280 4503904
00:05:30 34423 8192000000 10306246176 10756059136 1375604560 14.377 100 25 50 0 1416306464 33856320 6845584
00:07:18 51078 8192000000 10294423888 10743566336 1366240592 14.293 99 17 34 1 1388339664 18268160 3830912
00:09:33 43092 8192000000 10290481216 10741280768 1367200992 14.302 99 10 20 0 1384876192 15476016 2199184
00:11:19 66783 8192000000 10292540944 10741133312 1370028448 14.327 99 15 30 0 1383562160 9815520 3718192
00:14:44 40469 8192000000 10297792240 10747367424 1378717552 14.405 99 18 36 1 1395289536 12146624 4425360
00:16:26 71772 8192000000 10316155824 10762801152 1395071264 14.551 100 31 63 0 1418999568 16546608 7381696
00:18:44 22918 8192000000 10296786000 10750898176 1385888624 14.469 100 38 76 2 1409210016 14116928 9204464
00:20:14 107520 8192000000 10319875008 10785464320 1403147888 14.623 100 18 36 0 1431021136 23641088 4232160
00:23:02 88224 8192000000 10324562496 10779082752 1406953312 14.657 100 15 30 0 1430811440 19804384 4053744
00:25:31 46510 8192000000 10324856592 10790993920 1417828736 14.753 100 9 18 2 1432925792 12891424 2205632
00:27:22 75836 8192000000 10325636992 10784677888 1381660720 14.431 98 18 36 1 1401217536 15355232 4201584
00:29:40 80575 8192000000 10316498336 10772639744 1391712400 14.521 99 16 31 0 1405138944 9832144 3594400
00:32:36 81359 8192000000 10317813264 10780794880 1386152608 14.472 98 18 36 0 1398914384 8800000 3961776
00:35:03 45550 8192000000 10288112896 10743463936 1327422720 13.944 95 14 28 0 1342495984 11933872 3139392
00:37:28 47391 8192000000 10299912064 10733584384 1349320816 14.141 98 27 54 1 1365694032 10588448 5784768
00:39:16 109839 8192000000 10299900416 10744709120 1380480720 14.421 100 24 48 1 1407372048 21550032 5341296
00:42:12 61800 8192000000 10315059216 10762592256 1396307664 14.562 100 41 82 0 1419954624 14526816 9120144
00:44:17 37751 8192000000 10280428432 10740068352 1372523536 14.350 99 32 64 1 1389760816 9842112 7395168
00:46:05 44976 8192000000 10272810240 10713808896 1360743824 14.244 99 38 76 0 1380891792 11558000 8589968
00:47:54 52715 8192000000 10273652656 10730921984 1362326528 14.258 99 9 18 1 1377330944 12910880 2093536
00:49:52 64049 8192000000 10261010512 10711543808 1337584688 14.036 98 13 26 1 1355070576 14541408 2944480
00:52:01 47011 8192000000 10247741968 10710167552 1347258800 14.123 99 11 23 1 1361007744 11388112 2360832
00:53:55 88002 8192000000 10259543984 10726010880 1345466800 14.107 99 7 14 0 1358293520 11049536 1777184
00:56:23 64385 8192000000 10269335312 10721116160 1339816160 14.056 98 20 40 0 1355368912 11402128 4150624
00:58:34 47913 8192000000 10284577648 10732990464 1362532048 14.260 99 26 51 0 1380134688 11933728 5668912
01:00:39 85121 8192000000 10273210704 10739695616 1346050480 14.112 98 13 26 1 1367751472 18838512 2862480
01:03:05 66954 8192000000 10275210448 10720878592 1347514080 14.125 99 28 56 1 1371006720 17571104 5921536
01:05:13 77058 8192000000 10291515632 10740396032 1363843824 14.272 99 13 26 0 1379458752 12772528 2842400
01:07:30 124131 8192000000 10294225600 10737926144 1366009888 14.291 99 27 54 1 1379405408 7663552 5731968
01:10:39 19357 8192000000 10286410848 10737381376 1374559232 14.368 99 24 48 2 1390259344 10377568 5322544
01:11:59 44439 8192000000 10280896528 10748846080 1364199744 14.275 98 6 12 2 1378112528 12790896 1121888
01:14:00 39627 8192000000 10298006944 10767851520 1400435424 14.599 100 10 20 0 1414709584 11868368 2405792
01:15:48 7773 8192000000 10308835088 10755936256 1395305104 14.553 100 38 77 2 1411408672 7450512 8653056
01:17:02 39667 8192000000 10304340736 10758311936 1386006384 14.470 99 45 90 1 1405672176 9725472 9940320
01:18:43 79016 8192000000 10299424736 10757623808 1356708080 14.208 97 16 32 0 1370706336 9634320 4363936
01:21:05 35162 8192000000 10281502848 10734981120 1356393360 14.205 98 22 44 0 1372068544 10893920 4781264
01:22:43 74139 8192000000 10300138736 10771922944 1382013184 14.435 99 23 46 1 1398737792 11707776 5016832
01:24:57 39883 8192000000 10290661632 10732535808 1335656528 14.018 96 14 28 0 1346105248 7289152 3159568
01:26:38 163045 8192000000 10275421968 10725490688 1303513312 13.727 94 27 54 0 1320808896 11101568 6194016
01:30:23 78067 8192000000 10272387872 10732462080 1334784352 14.010 97 8 16 0 1350688320 13984560 1919408
01:32:41 64518 8192000000 10273976880 10724339712 1344843072 14.101 97 16 33 2 1367426752 18560736 4022944
01:34:49 56950 8192000000 10260504800 10711855104 1328562016 13.954 97 10 20 1 1339937680 9590192 1785472
01:36:54 49131 8192000000 10259347456 10715668480 1334779504 14.010 98 19 38 0 1351392736 11912096 4701136

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-06-2022 01:39:48 to 15-06-2022 01:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 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 15-06-2022 01:40:03 to 15-06-2022 01:40:05.

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          239257
MON$OLDEST_ACTIVE               241364
MON$OLDEST_SNAPSHOT             241364
MON$NEXT_TRANSACTION            241364
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                       2029344
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:613: 
:614: mscs-test-161.budzdorov.ru    Wed Jun 15 01:38:27 2022
:615:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:616: 
:617: 
:618: mscs-test-161.budzdorov.ru    Wed Jun 15 01:38:27 2022
:619:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:620: 
:621: 
:622: mscs-test-161.budzdorov.ru    Wed Jun 15 01:38:27 2022
:623:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:624: 
:625: 
:626: mscs-test-161.budzdorov.ru    Wed Jun 15 01:38:27 2022
:627:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:628: 
:629: 
:630: mscs-test-161.budzdorov.ru    Wed Jun 15 01:38:27 2022
:631:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:632: 
:633: 
:634: mscs-test-161.budzdorov.ru    Wed Jun 15 01:38:27 2022
:635:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:636: 
:637: 
:638: mscs-test-161.budzdorov.ru    Wed Jun 15 01:38:27 2022
:639:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:640: 
:641: 
:642: mscs-test-161.budzdorov.ru    Wed Jun 15 01:38:27 2022
:643:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:644: 
:645: 
:646: mscs-test-161.budzdorov.ru    Wed Jun 15 01:38:27 2022
:647:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:648: 
:649: 
:650: mscs-test-161.budzdorov.ru    Wed Jun 15 01:38:27 2022
:651:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:652: 
--- end of diff output ---

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

15.06.22 01:40:06. 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-15 01:40:09 No severe PSQL-related problems occured
15.06.22 01:40:10. End of report.