Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.10.2022 22: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 15233408 kB
MemAvailable 30523924 kB
Buffers 0 kB
Cached 16501664 kB
SwapCached 2160 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-10-15 22:38:29
DTS_END                         2022-10-15 22:38:29.2000
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/60364
TRN_ID                          249398
ATT_ID                          707
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: 9598.80 20917 1151856
customer order: creation 176.81 352 21217
customer order: refuse 15.39 341 1847
order to supplier: creation 46.31 1108 5557
order to supplier: removal 3.43 99136 411
invoice (draft): creation 47.99 3064 5759
invoice (draft): removal 54.83 783 6579
invoice accept: apply 42.64 16167 5117
invoice accept: cancel 49.46 691 5935
customer reserve: creation 4021.43 157 482571
customer reserve: removal 4839.44 55 580733
realization accept: apply 86.53 197 10384
realization accept: cancel 80.30 101 9636
payment from customer: creation 61.33 116 7359
payment from customer: removal 5.83 109 700
payment to supplier: creation 51.08 134 6130
payment to supplier: removal 3.53 80 424
service: total inventory turnovers 4.90 1156 588
service: total monetary turnovers 6.68 27 802
service: aggregate perf. data 0.86 18849 103
service: refresh index statistics 0.03 275712 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-10-2022 22:40:00 to 15-10-2022 22:40:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 874
TEST_TIME 2 1172
TEST_TIME 3 2522
TEST_TIME 4 2957
TEST_TIME 5 3504
TEST_TIME 6 3491
TEST_TIME 7 3820
TEST_TIME 8 4113
TEST_TIME 9 4237
TEST_TIME 10 4446
TEST_TIME 11 5153
TEST_TIME 12 5670
TEST_TIME 13 6109
TEST_TIME 14 6181
TEST_TIME 15 6946
TEST_TIME 16 7327
TEST_TIME 17 7430
TEST_TIME 18 7508
TEST_TIME 19 7707
TEST_TIME 20 8081
TEST_TIME 21 8682
TEST_TIME 22 9438
TEST_TIME 23 9537
TEST_TIME 24 9543
TEST_TIME 25 10406
TEST_TIME 26 10515
TEST_TIME 27 10667
TEST_TIME 28 10513
TEST_TIME 29 10540
TEST_TIME 30 10578
TEST_TIME 31 10634
TEST_TIME 32 10549
TEST_TIME 33 10601
TEST_TIME 34 10735
TEST_TIME 35 10661
TEST_TIME 36 10422
TEST_TIME 37 10304
TEST_TIME 38 10214
TEST_TIME 39 10226
TEST_TIME 40 10059
TEST_TIME 41 10053
TEST_TIME 42 9994
TEST_TIME 43 9848
TEST_TIME 44 9859
TEST_TIME 45 10040
TEST_TIME 46 10139
TEST_TIME 47 10103
TEST_TIME 48 10089
TEST_TIME 49 9983
TEST_TIME 50 10072
TEST_TIME 51 10018
TEST_TIME 52 9982
TEST_TIME 53 10044
TEST_TIME 54 9950
TEST_TIME 55 10046
TEST_TIME 56 10207
TEST_TIME 57 10187
TEST_TIME 58 10218
TEST_TIME 59 10190
TEST_TIME 60 10201
TEST_TIME 61 10212
TEST_TIME 62 10234
TEST_TIME 63 10217
TEST_TIME 64 10088
TEST_TIME 65 10045
TEST_TIME 66 10117
TEST_TIME 67 10016
TEST_TIME 68 9965
TEST_TIME 69 9948
TEST_TIME 70 9937
TEST_TIME 71 9976
TEST_TIME 72 9930
TEST_TIME 73 9908
TEST_TIME 74 9825
TEST_TIME 75 9895
TEST_TIME 76 9901
TEST_TIME 77 9930
TEST_TIME 78 9912
TEST_TIME 79 9885
TEST_TIME 80 9900
TEST_TIME 81 9887
TEST_TIME 82 9928
TEST_TIME 83 9966
TEST_TIME 84 10022
TEST_TIME 85 10117
TEST_TIME 86 10097
TEST_TIME 87 10098
TEST_TIME 88 10035
TEST_TIME 89 10013
TEST_TIME 90 9998
TEST_TIME 91 9910
TEST_TIME 92 9857
TEST_TIME 93 9915
TEST_TIME 94 9864
TEST_TIME 95 9867
TEST_TIME 96 9852
TEST_TIME 97 9844
TEST_TIME 98 9851
TEST_TIME 99 9872
TEST_TIME 100 9870
TEST_TIME 101 9856
TEST_TIME 102 9854
TEST_TIME 103 9846
TEST_TIME 104 9873
TEST_TIME 105 9839
TEST_TIME 106 9914
TEST_TIME 107 9891
TEST_TIME 108 9868
TEST_TIME 109 9913
TEST_TIME 110 9885
TEST_TIME 111 9833
TEST_TIME 112 9803
TEST_TIME 113 9775
TEST_TIME 114 9774
TEST_TIME 115 9734
TEST_TIME 116 9693
TEST_TIME 117 9678
TEST_TIME 118 9648
TEST_TIME 119 9640
TEST_TIME 120 9599

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-10-2022 22:40:03 to 15-10-2022 22:40:07.

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 1596909 1596909 0 0.00 0 16507 6 0 0
doc_list_biud 1596909 1596909 0 0.00 0 75519 26 0 0
sp_add_invoice_to_stock 5298 5117 181 3.41 29 198209 16168 0 181
sp_cancel_adding_invoice 5935 5935 0 0.00 5 49080 691 0 0
sp_cancel_client_order 1847 1847 0 0.00 4 21376 341 0 0
sp_cancel_customer_reserve 580733 580733 0 0.00 0 75520 55 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 2 20955 110 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 5439 81 0 0
sp_cancel_supplier_invoice 6596 6579 17 0.25 1 43241 784 0 17
sp_cancel_supplier_order 411 411 0 0.00 15 1204088 99136 0 0
sp_cancel_write_off 9636 9636 0 0.00 1 15951 101 0 0
sp_client_order 21217 21217 0 0.00 3 32476 353 0 0
sp_customer_reserve 482571 482571 0 0.00 0 50155 157 0 0
sp_fill_shopping_cart 32506 32506 0 0.00 1 26890 37 0 0
sp_fill_shopping_cart_clo_res 482571 482571 0 0.00 0 20901 13 0 0
sp_get_clo_for_invoice 5117 5117 0 0.00 0 32322 302 0 0
sp_kill_cost_storno 16695 16695 0 0.00 0 8176 49 0 0
sp_kill_qstorno_ret_qs2qd 1178092 1178092 0 0.00 0 75515 30 0 0
sp_kill_qty_storno 620642 620642 0 0.00 0 75518 66 0 0
sp_lock_selected_doc 21476 21476 0 0.00 0 9378 6 0 0
sp_make_cost_storno 28990 28990 0 0.00 0 16501 51 0 0
sp_multiply_rows_for_pdistr 28990 28990 0 0.00 0 6969 23 0 0
sp_multiply_rows_for_qdistr 21217 21217 0 0.00 0 11673 153 0 0
sp_pay_from_customer 7359 7359 0 0.00 4 13034 116 0 0
sp_pay_to_supplier 6130 6130 0 0.00 4 25320 135 0 0
sp_payment_common 13489 13489 0 0.00 1 14629 30 0 0
sp_qd_handle_on_cancel_clo 1847 1847 0 0.00 0 21366 312 0 0
sp_qd_handle_on_invoice_upd_sts 11052 11052 0 0.00 0 30123 319 0 0
sp_qd_handle_on_reserve_upd_sts 10384 10384 0 0.00 0 22933 69 0 0
sp_reserve_write_off 10384 10384 0 0.00 6 30536 198 0 0
sp_supplier_invoice 5759 5759 0 0.00 5 91881 3065 0 0
sp_supplier_order 5557 5557 0 0.00 5 23461 1108 0 0
srv_aggregate_perf_data 103 103 0 0.00 2822 188886 18850 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2531 131539 45640 0 0
srv_find_qd_qs_mism 1101478 1101478 0 0.00 0 9439 5 0 0
srv_make_invnt_saldo 588 588 0 0.00 41 83180 1156 0 0
srv_make_money_saldo 802 802 0 0.00 1 3519 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 143394 499514 275713 0 0
t$perf-abend:sp_add_invoice_to_stock 181 181 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5117 5117 0 0.00 0 19708 207 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 10577 194 0 0
t$perf-norm:sp_cancel_client_order 1847 1847 0 0.00 0 7091 19 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 2434 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 7861 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 1324 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 17902 55 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 20949 1687 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 6278 19 0 0
t$perf-norm:sp_client_order 21217 21217 0 0.00 0 11009 20 0 0
t$perf-norm:sp_customer_reserve 1074 1074 0 0.00 0 5203 10 0 0
t$perf-norm:sp_pay_from_customer 7359 7359 0 0.00 0 5675 14 0 0
t$perf-norm:sp_pay_to_supplier 6130 6130 0 0.00 0 10897 17 0 0
t$perf-norm:sp_reserve_write_off 10384 10384 0 0.00 0 10862 13 0 0
t$perf-norm:sp_supplier_invoice 5759 5759 0 0.00 0 8453 22 0 0
t$perf-norm:sp_supplier_order 5557 5557 0 0.00 0 10817 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 63 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 31 1 0 0
t$perf-norm:srv_make_invnt_saldo 588 588 0 0.00 0 5856 30 0 0
t$perf-norm:srv_make_money_saldo 802 802 0 0.00 0 4247 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 117 31 0 0
v_add_invoice_to_stock 10596 10234 362 3.41 0 17199 23 0 362
v_all_customers 38496 38496 0 0.00 0 17 0 0 0
v_all_suppliers 31484 31484 0 0.00 0 21 1 0 0
v_all_wares 42428 42428 0 0.00 0 50 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 18439 32 0 0
v_cancel_client_order 3694 3694 0 0.00 0 2870 7 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 20882 24 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 12192 18 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 5400 10 0 0
v_cancel_supplier_invoice 906 872 34 3.75 0 244 14 0 34
v_cancel_supplier_order 822 822 0 0.00 0 5348 16 0 0
v_cancel_write_off 2064 2064 0 0.00 0 5038 14 0 0
v_max_id_clo_ord 5556 5556 0 0.00 0 433 1 0 0
v_max_id_clo_res 1074 1074 0 0.00 0 3741 14 0 0
v_max_id_ord_sup 5727 5727 0 0.00 0 21267 9 0 0
v_max_non_paid_invoice 1670 1670 0 0.00 0 2798 4 0 0
v_max_non_paid_realizn 4010 4010 0 0.00 0 11041 8 0 0
v_min_id_clo_ord 5556 5556 0 0.00 0 3093 5 0 0
v_min_id_clo_res 1074 1074 0 0.00 0 2162 11 0 0
v_min_id_ord_sup 5727 5727 0 0.00 0 5336 8 0 0
v_min_non_paid_invoice 6130 6130 0 0.00 0 14627 20 0 0
v_min_non_paid_realizn 7359 7359 0 0.00 0 6513 22 0 0
v_our_firm 10630 10630 0 0.00 0 22 1 0 0
v_reserve_write_off 20768 20768 0 0.00 0 30426 14 0 0
x_lock_depdocs_on_canc_invoice 12078 12078 0 0.00 0 14238 102 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 13612 323 0 0
x_make_qstorno_1000_1200 5557 5557 0 0.00 1 23444 1044 0 0
x_make_qstorno_1200_2000 5759 5759 0 0.00 2 86959 2903 0 0
x_make_qstorno_2100_3300 482571 482571 0 0.00 0 39710 142 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-10-2022 22:40:08 to 15-10-2022 22:40:12.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:33 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:34 27102 8192000000 9901628928 10210918400 983684864 10.720 100 87 174 0 1012975968 6128336 23162768
20:11:03 12063 8192000000 9937114848 10255859712 1021740320 11.089 100 87 172 1 1051645680 7729056 22176304
20:12:19 14331 8192000000 9966305472 10293731328 1045620576 11.319 100 83 166 0 1076303056 8980256 21702224
20:13:40 7065 8192000000 10029365088 10371977216 1108261536 11.916 100 88 176 0 1140488912 10947664 21279712
20:14:52 4976 8192000000 10073230240 10426822656 1156019312 12.366 100 82 164 0 1187815616 12983616 18812688
20:15:59 3275 8192000000 10108930336 10473082880 1195017904 12.730 100 78 154 1 1225514640 13594464 16902272
20:17:04 2166 8192000000 10156455968 10524327936 1236454272 13.114 100 89 172 0 1268530192 13874512 18201408
20:18:08 1759 8192000000 10183077744 10564374528 1271555024 13.436 100 82 164 0 1301343840 11740368 18048448
20:19:11 892 8192000000 10208800432 10596065280 1298769648 13.684 100 85 172 1 1331677200 12875312 20032240
20:20:13 4156 8192000000 10200369424 10602500096 1302611968 13.719 100 38 77 2 1317565648 5500912 9452768
20:21:18 17101 8192000000 10202599360 10616250368 1312536144 13.809 100 13 26 0 1320963360 5480848 2946368
20:22:36 8720 8192000000 10201582256 10621804544 1318761776 13.865 100 17 33 1 1327781392 5212848 3806768
20:23:48 33333 8192000000 10210586240 10632781824 1323639920 13.910 100 25 49 0 1335311488 6213792 5457776
20:25:26 3776 8192000000 10231837712 10650214400 1337035632 14.031 100 29 58 0 1350412592 6869584 6507376
20:26:30 6718 8192000000 10228454192 10645389312 1327147424 13.941 99 16 32 1 1336244976 5753856 3343696
20:27:37 17982 8192000000 10203264096 10610315264 1240661488 13.152 93 24 49 0 1252331680 5431632 6238560
20:28:55 15085 8192000000 10201330816 10607489024 1281671472 13.528 98 45 89 0 1299903328 5550784 12681072
20:30:10 7782 8192000000 10164145680 10562592768 1226138496 13.018 96 23 46 1 1236922256 5506528 5277232
20:31:20 5214 8192000000 10140210224 10541199360 1209768112 12.867 96 36 72 1 1224674048 6212304 8693632
20:32:26 13178 8192000000 10151956880 10557149184 1243142064 13.175 98 19 38 1 1253080864 5354416 4584384
20:33:41 35050 8192000000 10155951360 10562502656 1265556176 13.381 100 30 58 1 1277464080 5346816 6561088
20:35:16 6796 8192000000 10182680272 10590781440 1287372592 13.580 100 38 76 0 1303747504 7731632 8643280
20:36:28 3265 8192000000 10193772128 10608312320 1302404512 13.717 100 33 67 0 1315570496 5605600 7560384
20:37:32 7461 8192000000 10199158672 10617577472 1310055280 13.787 100 11 22 1 1317368928 4856976 2456672
20:38:41 131539 8192000000 10251793824 10668679168 1337502144 14.035 100 36 72 0 1353399296 7069152 8828000
20:42:06 21980 8192000000 10287743248 10706399232 1369376320 14.321 100 50 100 0 1397914336 17468080 11069936
20:43:47 33038 8192000000 10304157056 10728173568 1370581744 14.332 99 40 78 0 1407469552 27317040 9570768
20:45:36 18164 8192000000 10316316880 10741985280 1393724912 14.539 100 40 82 0 1430317200 26386256 10206032
20:46:59 9482 8192000000 10329278400 10750738432 1400016400 14.595 100 55 110 1 1440564768 26674912 13873456
20:48:11 7783 8192000000 10339770784 10761236480 1407485360 14.662 100 63 121 2 1453269344 29992848 15791136
20:49:30 3736 8192000000 10323142880 10726187008 1372078064 14.346 99 85 169 0 1427937184 35952288 19906832
20:50:38 2530 8192000000 10350055104 10767843328 1409377552 14.678 100 73 146 0 1465476400 37110592 18988256
20:51:47 6764 8192000000 10333896880 10753269760 1391320096 14.518 100 61 122 1 1443824064 36503280 16000688
20:53:04 5647 8192000000 10324493248 10737283072 1365418848 14.286 99 64 128 0 1423754608 42104080 16231680
20:54:13 6658 8192000000 10330288784 10744139776 1383233776 14.445 100 59 118 0 1434334816 36286128 14814912
20:55:26 10477 8192000000 10324693696 10751983616 1377730272 14.396 99 31 62 0 1421234880 34765920 8738688
20:56:43 7637 8192000000 10327161488 10750943232 1373184944 14.356 99 47 94 0 1426818048 41042736 12590368
20:57:53 5493 8192000000 10325315120 10746015744 1366674512 14.297 100 53 106 0 1431229232 50022784 14531936
20:59:04 8312 8192000000 10331297280 10752561152 1366488864 14.296 99 53 105 0 1428012640 46979088 14544688
21:00:14 25609 8192000000 10317459088 10749939712 1371131568 14.337 100 25 50 0 1421361904 42846384 7383952
21:01:59 38468 8192000000 10291970512 10734317568 1353392816 14.178 99 19 38 0 1395940208 37069984 5477408
21:03:59 50237 8192000000 10264900400 10707537920 1335975648 14.021 99 13 26 0 1362645280 23245456 3424176
21:06:00 36411 8192000000 10265571664 10703826944 1354230752 14.186 100 17 34 0 1368491616 10429056 3831808
21:07:52 22064 8192000000 10268264768 10710228992 1356998736 14.210 100 15 31 0 1369721392 10012192 2710464
21:09:15 82897 8192000000 10289816224 10726408192 1336252224 14.024 97 17 34 0 1351101504 11503104 3346176
21:11:39 84207 8192000000 10286661808 10737565696 1363246688 14.266 99 10 18 0 1377819424 13041136 1531600
21:14:04 84749 8192000000 10301995184 10749607936 1387088000 14.480 100 34 66 1 1401781312 6571824 8121488
21:16:31 14027 8192000000 10315040752 10750345216 1386081232 14.471 100 52 103 1 1406564880 8984016 11499632
21:17:47 95272 8192000000 10297751856 10749128704 1384014896 14.452 99 12 24 0 1402276528 15184784 3076848
21:20:24 17161 8192000000 10329055184 10778955776 1409176848 14.677 100 18 36 0 1429315680 15003200 5135632
21:21:41 51110 8192000000 10344453280 10803798016 1419138720 14.765 100 43 85 0 1455603264 25874240 10590304
21:24:03 10324 8192000000 10312006528 10784325632 1387791072 14.486 100 15 30 0 1416632048 24962048 3878928
21:25:15 45619 8192000000 10314383888 10769911808 1399078464 14.587 99 14 28 0 1414421328 11530624 3812240
21:27:03 72699 8192000000 10307024800 10774581248 1392982320 14.532 99 16 32 1 1411764816 15018464 3764032
21:29:16 21460 8192000000 10315706304 10768723968 1397119696 14.569 100 32 64 2 1410183632 6360880 6703056
21:30:44 77096 8192000000 10318345984 10783260672 1363921392 14.273 97 14 28 0 1379171872 11730480 3520000
21:33:01 70754 8192000000 10300352624 10768531456 1374711712 14.369 98 13 27 0 1399898480 21917568 3269200
21:35:25 74637 8192000000 10286032704 10754912256 1339942880 14.057 96 12 24 0 1355052512 12509856 2599776
21:37:40 37162 8192000000 10271407392 10719371264 1318891664 13.867 96 22 44 0 1337792928 13879696 5021568
21:39:17 55898 8192000000 10287464512 10734755840 1335241584 14.014 97 12 24 0 1346769776 9265760 2262432
21:41:14 108925 8192000000 10278584848 10732187648 1307353712 13.762 95 16 32 0 1330399264 19563824 3481728
21:44:22 36583 8192000000 10278082688 10732687360 1356933488 14.210 99 38 72 0 1376544096 9894016 9716592
21:45:59 17589 8192000000 10287778784 10734252032 1368871808 14.317 100 18 33 0 1383175728 10718576 3585344
21:47:40 55805 8192000000 10259586992 10713124864 1324925168 13.921 97 16 32 0 1343088400 14448096 3715136
21:49:47 36449 8192000000 10249912096 10705940480 1329258224 13.960 98 14 29 0 1345923680 13276800 3388656
21:51:24 54401 8192000000 10250594672 10704527360 1319835008 13.875 97 15 30 0 1337103472 13293648 3974816
21:53:21 71336 8192000000 10271429072 10725371904 1341517104 14.071 99 11 22 0 1358899984 14896784 2486096
21:55:35 33637 8192000000 10262195008 10702077952 1317862928 13.857 97 23 46 0 1334496432 11457232 5176272
21:57:20 31515 8192000000 10261346752 10697834496 1316865488 13.848 98 33 66 0 1337179856 13235184 7079184
21:59:00 64568 8192000000 10284353440 10736082944 1365639872 14.288 100 24 48 0 1385119616 14066368 5413376
22:01:09 57278 8192000000 10282780624 10752663552 1377239376 14.392 100 10 20 0 1395165936 15419200 2507360
22:03:14 60096 8192000000 10302124672 10762539008 1384986096 14.461 100 11 22 0 1402457888 14628768 2843024
22:05:20 42674 8192000000 10312696032 10760626176 1391820816 14.522 100 22 44 0 1407031232 10708816 4501600
22:07:03 117642 8192000000 10305968624 10766659584 1387548368 14.484 99 27 54 0 1407497712 13910096 6039248
22:10:07 48145 8192000000 10311353248 10769113088 1394700704 14.548 99 29 58 0 1412231120 11033056 6497360
22:12:22 65564 8192000000 10278833392 10744225792 1353641616 14.180 97 8 16 1 1367868304 12723392 1503296
22:14:28 58431 8192000000 10293383984 10754301952 1364753376 14.280 98 17 33 0 1373506976 5611904 3141696
22:16:27 36668 8192000000 10292098624 10754211840 1378437424 14.403 99 31 62 0 1394702992 8873584 7391984
22:18:22 51117 8192000000 10296621616 10740559872 1348051424 14.130 97 26 52 0 1361969248 7530288 6387536
22:20:14 29542 8192000000 10292914784 10746007552 1380585296 14.422 100 28 56 0 1395973344 9249616 6138432
22:21:56 125208 8192000000 10301097792 10760282112 1333470512 13.998 96 18 36 0 1351108448 13870352 3767584
22:25:01 51006 8192000000 10309143072 10754514944 1367001472 14.300 98 27 52 0 1382660768 10027216 5632080
22:26:56 25803 8192000000 10293453328 10735394816 1359837616 14.236 99 46 92 0 1382934336 12875664 10221056
22:28:28 102971 8192000000 10255796240 10712322048 1334354448 14.006 98 17 34 1 1348380160 9989520 4036192
22:31:18 11334 8192000000 10244091440 10691244032 1316325920 13.843 98 30 60 0 1332654016 10052752 6275344
22:32:30 51258 8192000000 10247250512 10708828160 1338153120 14.041 99 17 34 0 1353924368 11824336 3946912
22:34:22 71471 8192000000 10257638704 10713972736 1337913232 14.039 98 13 25 0 1352229536 11146352 3169952
22:36:34 56693 8192000000 10261772240 10721447936 1343100640 14.085 99 13 26 0 1361462992 15115568 3246784

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-10-2022 22:40:14 to 15-10-2022 22:40:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 181 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-10-2022 22:40:30 to 15-10-2022 22:40:32.

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          248804
MON$OLDEST_ACTIVE               249513
MON$OLDEST_SNAPSHOT             249513
MON$NEXT_TRANSACTION            249513
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                       2058336
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.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
:408: 
:409: mscs-test-161.budzdorov.ru    Sat Oct 15 22:38:38 2022
:410:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:411: 
:412: 
:413: mscs-test-161.budzdorov.ru    Sat Oct 15 22:38:39 2022
:414:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:415: 
--- end of diff output ---

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

15.10.22 22:40:33. 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-10-15 22:40:36 No severe PSQL-related problems occured
15.10.22 22:40:37. End of report.