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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16121328 kB
MemAvailable 31299548 kB
Buffers 0 kB
Cached 15565732 kB
SwapCached 2448 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-06 16:38:21
DTS_END                         2022-06-06 16:38:21.3450
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/41880
TRN_ID                          248033
ATT_ID                          687
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: 9669.24 20486 1160309
customer order: creation 181.72 318 21806
customer order: refuse 15.40 341 1848
order to supplier: creation 47.99 1201 5759
order to supplier: removal 3.60 93430 432
invoice (draft): creation 49.53 3282 5944
invoice (draft): removal 53.32 772 6398
invoice accept: apply 45.14 18279 5417
invoice accept: cancel 48.48 735 5818
customer reserve: creation 4201.12 181 504134
customer reserve: removal 4710.60 56 565272
realization accept: apply 89.13 161 10696
realization accept: cancel 84.29 101 10115
payment from customer: creation 63.37 108 7604
payment from customer: removal 6.35 87 762
payment to supplier: creation 52.24 111 6269
payment to supplier: removal 3.73 101 447
service: total inventory turnovers 5.34 972 641
service: total monetary turnovers 7.03 25 843
service: aggregate perf. data 0.83 20206 100
service: refresh index statistics 0.03 269243 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-06-2022 16:39:03 to 06-06-2022 16:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5020
TEST_TIME 2 5318
TEST_TIME 3 5352
TEST_TIME 4 5221
TEST_TIME 5 5129
TEST_TIME 6 5737
TEST_TIME 7 5982
TEST_TIME 8 5852
TEST_TIME 9 6469
TEST_TIME 10 6561
TEST_TIME 11 6770
TEST_TIME 12 7004
TEST_TIME 13 7084
TEST_TIME 14 7374
TEST_TIME 15 7271
TEST_TIME 16 7574
TEST_TIME 17 7670
TEST_TIME 18 7686
TEST_TIME 19 7765
TEST_TIME 20 8272
TEST_TIME 21 8368
TEST_TIME 22 8955
TEST_TIME 23 9064
TEST_TIME 24 9447
TEST_TIME 25 9648
TEST_TIME 26 9827
TEST_TIME 27 10020
TEST_TIME 28 10349
TEST_TIME 29 10999
TEST_TIME 30 11022
TEST_TIME 31 10868
TEST_TIME 32 11039
TEST_TIME 33 11102
TEST_TIME 34 10974
TEST_TIME 35 10869
TEST_TIME 36 10834
TEST_TIME 37 10690
TEST_TIME 38 10653
TEST_TIME 39 10630
TEST_TIME 40 10776
TEST_TIME 41 10778
TEST_TIME 42 10623
TEST_TIME 43 10458
TEST_TIME 44 10415
TEST_TIME 45 10334
TEST_TIME 46 10236
TEST_TIME 47 10197
TEST_TIME 48 10263
TEST_TIME 49 10183
TEST_TIME 50 10242
TEST_TIME 51 10258
TEST_TIME 52 10180
TEST_TIME 53 10099
TEST_TIME 54 10130
TEST_TIME 55 10009
TEST_TIME 56 9947
TEST_TIME 57 9955
TEST_TIME 58 9900
TEST_TIME 59 9828
TEST_TIME 60 9759
TEST_TIME 61 9850
TEST_TIME 62 9821
TEST_TIME 63 9860
TEST_TIME 64 9837
TEST_TIME 65 9800
TEST_TIME 66 9920
TEST_TIME 67 9944
TEST_TIME 68 9936
TEST_TIME 69 9918
TEST_TIME 70 10008
TEST_TIME 71 10138
TEST_TIME 72 10095
TEST_TIME 73 10068
TEST_TIME 74 10116
TEST_TIME 75 10060
TEST_TIME 76 9974
TEST_TIME 77 9956
TEST_TIME 78 9925
TEST_TIME 79 9920
TEST_TIME 80 9870
TEST_TIME 81 9833
TEST_TIME 82 9788
TEST_TIME 83 9748
TEST_TIME 84 9738
TEST_TIME 85 9698
TEST_TIME 86 9678
TEST_TIME 87 9655
TEST_TIME 88 9711
TEST_TIME 89 9741
TEST_TIME 90 9715
TEST_TIME 91 9699
TEST_TIME 92 9800
TEST_TIME 93 9901
TEST_TIME 94 9866
TEST_TIME 95 9864
TEST_TIME 96 9875
TEST_TIME 97 9886
TEST_TIME 98 9931
TEST_TIME 99 9935
TEST_TIME 100 9931
TEST_TIME 101 9889
TEST_TIME 102 9817
TEST_TIME 103 9808
TEST_TIME 104 9786
TEST_TIME 105 9763
TEST_TIME 106 9729
TEST_TIME 107 9686
TEST_TIME 108 9645
TEST_TIME 109 9618
TEST_TIME 110 9581
TEST_TIME 111 9563
TEST_TIME 112 9639
TEST_TIME 113 9666
TEST_TIME 114 9700
TEST_TIME 115 9684
TEST_TIME 116 9703
TEST_TIME 117 9679
TEST_TIME 118 9743
TEST_TIME 119 9723
TEST_TIME 120 9674

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-06-2022 16:39:06 to 06-06-2022 16:39:10.

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 1625263 1625263 0 0.00 0 12296 6 0 0
doc_list_biud 1625262 1625262 0 0.00 0 25756 25 0 0
sp_add_invoice_to_stock 5520 5417 103 1.86 36 203316 18280 0 103
sp_cancel_adding_invoice 5818 5818 0 0.00 5 70240 735 0 0
sp_cancel_client_order 1848 1848 0 0.00 4 10896 342 0 0
sp_cancel_customer_reserve 565272 565272 0 0.00 1 25783 57 0 0
sp_cancel_pay_from_customer 762 762 0 0.00 2 6815 87 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 6790 102 0 0
sp_cancel_supplier_invoice 6411 6398 13 0.20 1 19340 773 0 13
sp_cancel_supplier_order 432 432 0 0.00 6 945758 93430 0 0
sp_cancel_write_off 10115 10115 0 0.00 1 10876 101 0 0
sp_client_order 21806 21806 0 0.00 4 18290 318 0 0
sp_customer_reserve 504134 504134 0 0.00 0 56127 181 0 0
sp_fill_shopping_cart 33499 33499 0 0.00 1 10474 35 0 0
sp_fill_shopping_cart_clo_res 504117 504117 0 0.00 0 15227 12 0 0
sp_get_clo_for_invoice 5399 5399 0 0.00 0 29856 251 0 0
sp_kill_cost_storno 17142 17142 0 0.00 0 10870 50 0 0
sp_kill_qstorno_ret_qs2qd 1147489 1147489 0 0.00 0 25751 31 0 0
sp_kill_qty_storno 605978 605978 0 0.00 0 25755 66 0 0
sp_lock_selected_doc 22177 22177 0 0.00 0 11231 6 0 0
sp_make_cost_storno 29968 29968 0 0.00 0 8960 47 0 0
sp_multiply_rows_for_pdistr 29968 29968 0 0.00 0 12293 26 0 0
sp_multiply_rows_for_qdistr 21806 21806 0 0.00 0 13317 141 0 0
sp_pay_from_customer 7604 7604 0 0.00 4 17505 109 0 0
sp_pay_to_supplier 6269 6269 0 0.00 4 10700 112 0 0
sp_payment_common 13873 13873 0 0.00 1 17498 22 0 0
sp_qd_handle_on_cancel_clo 1848 1848 0 0.00 0 10884 300 0 0
sp_qd_handle_on_invoice_upd_sts 11217 11217 0 0.00 0 17234 310 0 0
sp_qd_handle_on_reserve_upd_sts 10696 10696 0 0.00 0 14411 49 0 0
sp_reserve_write_off 10696 10696 0 0.00 6 14434 162 0 0
sp_supplier_invoice 5944 5944 0 0.00 6 95139 3283 0 0
sp_supplier_order 5759 5759 0 0.00 5 25388 1201 0 0
srv_aggregate_perf_data 100 100 0 0.00 2590 285745 20206 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1249 92036 39627 0 0
srv_find_qd_qs_mism 1108088 1108088 0 0.00 0 11846 5 0 0
srv_make_invnt_saldo 641 641 0 0.00 35 29321 973 0 0
srv_make_money_saldo 843 843 0 0.00 1 1483 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 176512 363885 269244 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5417 5417 0 0.00 0 9153 160 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 4897 94 0 0
t$perf-norm:sp_cancel_client_order 1848 1848 0 0.00 0 5026 16 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 1224 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 762 762 0 0.00 0 950 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 1320 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 747 15 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 22536 1442 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 2116 5 0 0
t$perf-norm:sp_client_order 21806 21806 0 0.00 0 11818 17 0 0
t$perf-norm:sp_customer_reserve 1040 1040 0 0.00 0 5529 11 0 0
t$perf-norm:sp_pay_from_customer 7604 7604 0 0.00 0 4952 9 0 0
t$perf-norm:sp_pay_to_supplier 6269 6269 0 0.00 0 9508 9 0 0
t$perf-norm:sp_reserve_write_off 10696 10696 0 0.00 0 11811 12 0 0
t$perf-norm:sp_supplier_invoice 5944 5944 0 0.00 0 9037 21 0 0
t$perf-norm:sp_supplier_order 5759 5759 0 0.00 0 4984 16 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 2414 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 195 4 0 0
t$perf-norm:srv_make_invnt_saldo 641 641 0 0.00 0 2872 27 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 4929 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 11000 10794 206 1.87 0 8135 23 0 206
v_all_customers 39742 39742 0 0.00 0 116 1 0 0
v_all_suppliers 32232 32232 0 0.00 0 23 1 0 0
v_all_wares 43612 43612 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 7990 25 0 0
v_cancel_client_order 3696 3696 0 0.00 0 5137 14 0 0
v_cancel_customer_prepayment 1524 1524 0 0.00 0 6803 15 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 589 3 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 2866 10 0 0
v_cancel_supplier_invoice 894 868 26 2.90 0 5401 35 0 26
v_cancel_supplier_order 864 864 0 0.00 0 1199 7 0 0
v_cancel_write_off 2018 2018 0 0.00 0 5255 17 0 0
v_max_id_clo_ord 5758 5758 0 0.00 0 2303 2 0 0
v_max_id_clo_res 1040 1040 0 0.00 0 5425 21 0 0
v_max_id_ord_sup 5935 5935 0 0.00 0 5323 5 0 0
v_max_non_paid_invoice 1846 1846 0 0.00 0 703 3 0 0
v_max_non_paid_realizn 4150 4150 0 0.00 0 3292 5 0 0
v_min_id_clo_ord 5758 5758 0 0.00 0 1826 6 0 0
v_min_id_clo_res 1040 1040 0 0.00 0 4493 10 0 0
v_min_id_ord_sup 5935 5935 0 0.00 0 6963 6 0 0
v_min_non_paid_invoice 6269 6269 0 0.00 0 9342 12 0 0
v_min_non_paid_realizn 7604 7604 0 0.00 0 9065 16 0 0
v_our_firm 10776 10776 0 0.00 0 13 1 0 0
v_reserve_write_off 21392 21392 0 0.00 0 9167 10 0 0
x_lock_depdocs_on_canc_invoice 11782 11782 0 0.00 0 16992 110 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 12545 404 0 0
x_make_qstorno_1000_1200 5759 5759 0 0.00 1 25355 1124 0 0
x_make_qstorno_1200_2000 5944 5944 0 0.00 3 95069 3163 0 0
x_make_qstorno_2100_3300 504134 504134 0 0.00 0 56125 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-06-2022 16:39:11 to 06-06-2022 16:39:15.

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
14:08:25 5 8192000000 8798902176 8810233856 5315664 0.064 2 1 2 0 5415264 56816 42784
14:09:25 32827 8192000000 9906599504 10215157760 985796816 10.741 100 92 184 0 1016447584 6676256 23974512
14:11:02 8894 8192000000 9939138496 10258161664 1021270912 11.084 100 91 182 0 1053717904 8901616 23545376
14:12:21 17376 8192000000 9976188080 10305724416 1050764256 11.368 100 82 164 0 1081198352 10053664 20380432
14:13:42 11273 8192000000 10030072384 10371866624 1106201424 11.896 100 83 166 0 1138412128 12630032 19580672
14:14:56 7668 8192000000 10068292640 10428342272 1157792016 12.383 100 82 165 2 1190550144 13339904 19418224
14:16:08 5537 8192000000 10117285232 10484662272 1206225056 12.834 100 83 166 0 1237884736 13197952 18461728
14:17:16 2679 8192000000 10161867616 10536615936 1240437328 13.150 100 83 167 1 1276479472 17649440 18392704
14:18:21 2689 8192000000 10196947040 10574639104 1275223792 13.469 100 94 188 0 1309630160 12562368 21844000
14:19:25 1333 8192000000 10200945904 10585497600 1289122160 13.596 100 90 180 1 1323302208 13517376 20662672
14:20:29 9176 8192000000 10217672176 10604195840 1300697728 13.702 100 84 169 0 1332944064 13469200 18777136
14:21:39 6417 8192000000 10196353920 10606661632 1307905792 13.767 100 30 62 1 1321680368 7129872 6644704
14:22:47 8112 8192000000 10203589904 10613620736 1312827296 13.812 100 27 52 0 1325815536 7323648 5664592
14:23:58 7742 8192000000 10202330320 10620186624 1319335792 13.871 100 22 44 0 1330659280 6822896 4500592
14:25:08 31013 8192000000 10231665728 10638303232 1325669392 13.928 100 53 108 1 1344179488 6080720 12429376
14:26:39 4908 8192000000 10219952848 10629636096 1316527472 13.845 99 33 66 2 1330425584 5998176 7899936
14:27:46 8135 8192000000 10219096448 10629099520 1288773296 13.593 97 22 45 2 1300059984 6335696 4950992
14:28:58 9011 8192000000 10233846688 10626519040 1300649456 13.701 98 57 111 2 1319874464 6050288 13174720
14:30:08 3288 8192000000 10163557680 10562715648 1251220096 13.249 98 33 66 1 1266282304 6686352 8375856
14:31:13 6373 8192000000 10162598576 10566496256 1271499680 13.435 100 36 71 3 1285582208 5280160 8802368
14:32:20 48168 8192000000 10164554320 10549878784 1228042560 13.036 98 44 88 1 1243839328 5034064 10762704
14:34:10 4594 8192000000 10166667568 10567086080 1270651152 13.428 100 37 74 0 1285545296 6289888 8604256
14:35:15 6976 8192000000 10182202672 10594381824 1292697472 13.629 100 21 40 1 1303164592 5538768 4928352
14:36:25 6136 8192000000 10193019376 10609287168 1301717056 13.711 100 24 47 1 1312550560 5228336 5605168
14:37:32 25902 8192000000 10202226144 10617159680 1304654912 13.738 100 28 56 1 1321426592 10630496 6141184
14:39:02 16223 8192000000 10238672640 10648244224 1332401984 13.989 100 51 99 2 1352388256 9272592 10713680
14:40:41 6321 8192000000 10258338160 10672406528 1351347792 14.160 100 41 82 2 1371731840 11132176 9251872
14:41:50 3720 8192000000 10274484416 10688368640 1362448976 14.259 100 50 99 1 1386240768 12047296 11744496
14:42:59 19606 8192000000 10286640256 10704146432 1372012576 14.345 100 58 115 0 1401233040 16480000 12740464
14:44:34 17988 8192000000 10281968208 10712813568 1367957664 14.309 99 29 58 0 1390084592 14963536 7163392
14:45:56 11503 8192000000 10309914128 10732281856 1390546384 14.511 100 62 124 2 1425660640 20271776 14842480
14:47:12 2894 8192000000 10344304112 10753863680 1401410848 14.608 100 75 151 0 1446947392 27704112 17832432
14:48:17 8809 8192000000 10334428816 10755706880 1403530112 14.626 100 54 106 0 1443599312 27355584 12713616
14:49:33 6377 8192000000 10361121344 10781495296 1423463568 14.803 100 74 147 1 1467825920 26683872 17678480
14:50:44 9738 8192000000 10341247360 10760966144 1402126608 14.614 99 64 128 3 1445170528 27407040 15636880
14:52:13 4989 8192000000 10347966240 10773651456 1414633216 14.725 100 59 117 2 1455936224 27148208 14154800
14:53:23 3392 8192000000 10333126496 10750803968 1391767600 14.522 100 73 147 2 1438357760 28539984 18050176
14:54:38 3311 8192000000 10340915248 10748190720 1390874800 14.514 99 74 150 2 1440773392 31864512 18034080
14:55:46 2851 8192000000 10341708944 10747932672 1384429040 14.456 100 84 165 0 1439646256 35579392 19637824
14:56:58 4401 8192000000 10336483152 10751660032 1385505984 14.466 100 62 124 4 1442758624 40907472 16345168
14:58:07 1247 8192000000 10363574896 10766876672 1388303808 14.491 100 93 186 2 1462135984 50256928 23575248
14:59:12 11201 8192000000 10336113248 10762301440 1391941824 14.523 100 42 84 0 1442368096 38635792 11790480
15:00:30 15841 8192000000 10333440016 10759458816 1365780976 14.289 98 38 76 0 1425961376 49059008 11121392
15:01:48 9000 8192000000 10337305520 10762280960 1371923600 14.344 99 37 74 2 1428549488 45785344 10840544
15:03:29 7263 8192000000 10348757904 10775863296 1405568608 14.645 100 55 111 1 1452726288 32286352 14871328
15:04:46 24108 8192000000 10332275056 10783158272 1392629712 14.529 100 32 64 0 1444373344 42371488 9372144
15:06:14 33442 8192000000 10330924976 10782474240 1362584272 14.261 99 17 34 1 1424559152 56635648 5339232
15:07:55 60403 8192000000 10296053136 10756640768 1347668208 14.126 98 10 20 0 1371363760 21256592 2438960
15:10:34 41970 8192000000 10305915200 10764197888 1374460224 14.367 99 12 24 1 1397429696 20093376 2876096
15:12:39 57992 8192000000 10290000912 10731978752 1341391552 14.070 97 16 31 3 1357652496 12167680 4093264
15:14:38 81600 8192000000 10321254160 10757959680 1391994880 14.524 100 30 60 1 1413828144 14914640 6918624
15:17:00 57393 8192000000 10316796752 10775891968 1397540928 14.573 100 16 32 1 1415936368 15005856 3389584
15:19:16 69414 8192000000 10297545408 10757644288 1368282528 14.312 98 7 14 2 1388581056 18705024 1593504
15:21:33 45290 8192000000 10308742416 10752425984 1377643696 14.395 99 37 74 1 1395780096 9393072 8743328
15:23:30 15542 8192000000 10319707360 10761740288 1395239920 14.553 100 53 106 1 1421608496 14298336 12070240
15:24:47 16735 8192000000 10301477696 10759614464 1397279296 14.571 100 18 36 1 1413386304 11960144 4146864
15:26:05 31712 8192000000 10292467376 10743410688 1374630720 14.369 99 22 44 0 1396039360 16119376 5289264
15:27:38 59485 8192000000 10286775680 10752958464 1372218032 14.347 99 21 42 0 1395534304 17962576 5353696
15:29:38 30342 8192000000 10286005888 10749767680 1356004880 14.201 98 21 40 0 1378678832 17933248 4740704
15:31:09 52602 8192000000 10269085888 10726699008 1349667072 14.144 98 10 20 0 1360491808 8327856 2496880
15:33:06 61976 8192000000 10268742816 10736967680 1369328512 14.321 100 15 30 0 1380931504 8001264 3601728
15:35:09 41916 8192000000 10259535232 10714701824 1297612704 13.674 94 20 40 0 1310304672 8360576 4331392
15:37:05 49816 8192000000 10266886128 10727182336 1347823712 14.128 98 16 32 0 1365109104 13399296 3886096
15:38:58 60851 8192000000 10276332976 10752012288 1373854192 14.362 100 12 23 0 1393151952 16564544 2733216
15:41:13 57367 8192000000 10281294784 10740453376 1341012480 14.067 97 14 28 1 1351048128 7164240 2871408
15:43:11 31555 8192000000 10293557792 10740903936 1356877008 14.209 98 38 76 4 1378976592 13717968 8381616
15:44:48 54400 8192000000 10263133072 10702462976 1262633520 13.354 92 28 56 1 1281085040 12547664 5903856
15:46:48 58663 8192000000 10288756320 10740031488 1358170496 14.221 99 25 50 2 1381257408 17403168 5683744
15:48:47 67781 8192000000 10265937888 10728771584 1309775104 13.784 96 8 16 0 1342148656 30226736 2146816
15:50:58 24694 8192000000 10268559536 10710118400 1350080960 14.148 99 34 68 1 1368234768 10585056 7568752
15:52:24 31954 8192000000 10286007728 10734325760 1348140096 14.131 98 41 82 0 1370108992 12930704 9038192
15:54:01 65033 8192000000 10277219280 10726621184 1356663344 14.207 99 42 84 1 1380031632 13698912 9669376
15:56:14 49422 8192000000 10255543456 10712526848 1328647952 13.955 97 27 54 1 1346290672 11780736 5861984
15:58:33 83415 8192000000 10260728400 10721828864 1354384800 14.187 99 10 20 0 1365907088 8986272 2536016
16:00:58 86919 8192000000 10266869744 10727784448 1357957712 14.219 99 14 28 0 1372299376 11108560 3233104
16:03:31 36751 8192000000 10281741104 10742042624 1367827040 14.308 99 36 73 0 1396750384 20077760 8845584
16:05:09 77063 8192000000 10269485504 10747969536 1339510192 14.053 97 10 20 0 1357122000 15480672 2131136
16:07:32 62163 8192000000 10296645248 10741932032 1354641392 14.189 98 21 42 0 1370615904 10865856 5108656
16:09:53 74113 8192000000 10308562672 10781261824 1397140000 14.570 100 19 38 0 1421594512 19261632 5192880
16:12:08 64736 8192000000 10298545200 10750730240 1343731776 14.091 96 14 28 0 1354116432 7610160 2774496
16:14:18 6174 8192000000 10301051424 10736603136 1367437232 14.304 98 46 91 1 1388020928 9966512 10617184
16:15:26 24815 8192000000 10321631328 10761793536 1378221696 14.401 99 55 111 2 1408138352 17135600 12781056
16:16:52 78754 8192000000 10288642336 10735767552 1308247008 13.770 94 11 22 0 1319431696 8337888 2846800
16:19:11 92036 8192000000 10258131808 10703118336 1303525024 13.727 95 23 46 0 1316543760 7881200 5137536
16:21:55 18512 8192000000 10266069360 10717265920 1356173264 14.203 100 19 38 0 1366366544 6296960 3896320
16:23:14 74661 8192000000 10252671424 10710028288 1334109296 14.004 98 13 26 0 1346529888 9207600 3212992
16:25:31 65521 8192000000 10254322784 10706264064 1310564240 13.791 96 14 28 0 1324676752 10792000 3320512
16:27:37 67422 8192000000 10269105616 10722406400 1337082176 14.031 98 10 19 1 1351953488 12336144 2535168
16:29:44 53525 8192000000 10274938928 10737655808 1354702320 14.190 98 13 26 1 1379758992 21875312 3181360
16:31:38 81236 8192000000 10275873264 10737676288 1347591872 14.126 98 11 22 0 1361037216 10957888 2487456
16:34:01 54617 8192000000 10277753648 10731134976 1340302352 14.060 98 28 56 1 1358785264 11703312 6779600
16:35:56 52226 8192000000 10261571776 10726670336 1321132448 13.887 97 20 40 2 1340373184 14856464 4384272
16:37:50 39795 8192000000 9033098592 9242853376 196599840 2.343 15 2 4 0 200938704 4085648 253216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-06-2022 16:39:17 to 06-06-2022 16:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-06-2022 16:39:33 to 06-06-2022 16:39:35.

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          248977
MON$OLDEST_ACTIVE               248978
MON$OLDEST_SNAPSHOT             248978
MON$NEXT_TRANSACTION            248978
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                       2056800
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 ---
:110: 
:111: mscs-test-161.budzdorov.ru    Mon Jun  6 16:38:35 2022
:112:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:113: 
--- end of diff output ---

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

06.06.22 16:39:36. 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-06 16:39:38 No severe PSQL-related problems occured
06.06.22 16:39:40. End of report.