Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.07.2022 01: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 16216252 kB
MemAvailable 31069328 kB
Buffers 0 kB
Cached 15487240 kB
SwapCached 2484 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-18 01:38:23
DTS_END                         2022-07-18 01:38:23.6710
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/51700
TRN_ID                          240984
ATT_ID                          638
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: 9167.38 21291 1100086
customer order: creation 170.24 375 20429
customer order: refuse 14.24 360 1709
order to supplier: creation 46.58 1268 5589
order to supplier: removal 3.40 92232 408
invoice (draft): creation 46.66 3215 5599
invoice (draft): removal 52.61 732 6313
invoice accept: apply 41.93 17321 5032
invoice accept: cancel 47.69 785 5723
customer reserve: creation 3783.22 176 453986
customer reserve: removal 4663.48 53 559617
realization accept: apply 84.36 179 10123
realization accept: cancel 81.66 102 9799
payment from customer: creation 60.29 111 7235
payment from customer: removal 5.99 100 719
payment to supplier: creation 49.27 143 5912
payment to supplier: removal 3.61 113 433
service: total inventory turnovers 4.69 1257 563
service: total monetary turnovers 6.56 79 787
service: aggregate perf. data 0.88 19128 106
service: refresh index statistics 0.03 288087 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-07-2022 01:39:21 to 18-07-2022 01:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1390
TEST_TIME 2 1589
TEST_TIME 3 2406
TEST_TIME 4 2863
TEST_TIME 5 4240
TEST_TIME 6 4182
TEST_TIME 7 4654
TEST_TIME 8 5210
TEST_TIME 9 5306
TEST_TIME 10 5233
TEST_TIME 11 5334
TEST_TIME 12 5662
TEST_TIME 13 6214
TEST_TIME 14 6430
TEST_TIME 15 7189
TEST_TIME 16 7737
TEST_TIME 17 7704
TEST_TIME 18 8108
TEST_TIME 19 8226
TEST_TIME 20 8644
TEST_TIME 21 8741
TEST_TIME 22 8823
TEST_TIME 23 9077
TEST_TIME 24 9414
TEST_TIME 25 9946
TEST_TIME 26 10246
TEST_TIME 27 10386
TEST_TIME 28 10188
TEST_TIME 29 10264
TEST_TIME 30 10438
TEST_TIME 31 10440
TEST_TIME 32 10234
TEST_TIME 33 10170
TEST_TIME 34 9955
TEST_TIME 35 10050
TEST_TIME 36 9888
TEST_TIME 37 9914
TEST_TIME 38 9783
TEST_TIME 39 9829
TEST_TIME 40 9731
TEST_TIME 41 9744
TEST_TIME 42 9683
TEST_TIME 43 9571
TEST_TIME 44 9445
TEST_TIME 45 9368
TEST_TIME 46 9293
TEST_TIME 47 9253
TEST_TIME 48 9503
TEST_TIME 49 9435
TEST_TIME 50 9308
TEST_TIME 51 9225
TEST_TIME 52 9221
TEST_TIME 53 9187
TEST_TIME 54 9122
TEST_TIME 55 9002
TEST_TIME 56 9003
TEST_TIME 57 8927
TEST_TIME 58 9038
TEST_TIME 59 8970
TEST_TIME 60 8944
TEST_TIME 61 9116
TEST_TIME 62 9152
TEST_TIME 63 9140
TEST_TIME 64 9091
TEST_TIME 65 9320
TEST_TIME 66 9379
TEST_TIME 67 9362
TEST_TIME 68 9455
TEST_TIME 69 9459
TEST_TIME 70 9454
TEST_TIME 71 9450
TEST_TIME 72 9551
TEST_TIME 73 9435
TEST_TIME 74 9388
TEST_TIME 75 9380
TEST_TIME 76 9362
TEST_TIME 77 9345
TEST_TIME 78 9347
TEST_TIME 79 9283
TEST_TIME 80 9237
TEST_TIME 81 9191
TEST_TIME 82 9226
TEST_TIME 83 9203
TEST_TIME 84 9192
TEST_TIME 85 9227
TEST_TIME 86 9173
TEST_TIME 87 9133
TEST_TIME 88 9185
TEST_TIME 89 9197
TEST_TIME 90 9152
TEST_TIME 91 9202
TEST_TIME 92 9206
TEST_TIME 93 9276
TEST_TIME 94 9283
TEST_TIME 95 9245
TEST_TIME 96 9317
TEST_TIME 97 9366
TEST_TIME 98 9375
TEST_TIME 99 9393
TEST_TIME 100 9391
TEST_TIME 101 9444
TEST_TIME 102 9362
TEST_TIME 103 9326
TEST_TIME 104 9271
TEST_TIME 105 9261
TEST_TIME 106 9293
TEST_TIME 107 9252
TEST_TIME 108 9250
TEST_TIME 109 9211
TEST_TIME 110 9155
TEST_TIME 111 9110
TEST_TIME 112 9103
TEST_TIME 113 9151
TEST_TIME 114 9187
TEST_TIME 115 9190
TEST_TIME 116 9184
TEST_TIME 117 9198
TEST_TIME 118 9234
TEST_TIME 119 9231
TEST_TIME 120 9170

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-07-2022 01:39:25 to 18-07-2022 01:39:28.

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 1522196 1522196 0 0.00 0 17275 6 0 0
doc_list_biud 1522187 1522187 0 0.00 0 24068 25 0 0
sp_add_invoice_to_stock 5139 5032 107 2.08 33 189254 17322 0 107
sp_cancel_adding_invoice 5723 5723 0 0.00 5 118198 785 0 0
sp_cancel_client_order 1709 1709 0 0.00 3 17278 361 0 0
sp_cancel_customer_reserve 559617 559617 0 0.00 0 24069 53 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 2 16612 100 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 11973 113 0 0
sp_cancel_supplier_invoice 6320 6313 7 0.11 2 22740 733 0 7
sp_cancel_supplier_order 408 408 0 0.00 6 885795 92233 0 0
sp_cancel_write_off 9799 9799 0 0.00 1 13003 103 0 0
sp_client_order 20429 20429 0 0.00 3 25017 375 0 0
sp_customer_reserve 453986 453986 0 0.00 0 41269 176 0 0
sp_fill_shopping_cart 31617 31617 0 0.00 1 12614 39 0 0
sp_fill_shopping_cart_clo_res 453981 453981 0 0.00 0 9703 14 0 0
sp_get_clo_for_invoice 5027 5027 0 0.00 0 26423 296 0 0
sp_kill_cost_storno 16674 16674 0 0.00 0 12024 49 0 0
sp_kill_qstorno_ret_qs2qd 1135754 1135754 0 0.00 0 24065 29 0 0
sp_kill_qty_storno 598713 598713 0 0.00 0 24068 63 0 0
sp_lock_selected_doc 20913 20913 0 0.00 0 5467 4 0 0
sp_make_cost_storno 28297 28297 0 0.00 0 17265 57 0 0
sp_multiply_rows_for_pdistr 28288 28288 0 0.00 0 6769 28 0 0
sp_multiply_rows_for_qdistr 20429 20429 0 0.00 0 17199 166 0 0
sp_pay_from_customer 7235 7235 0 0.00 4 13061 112 0 0
sp_pay_to_supplier 5912 5912 0 0.00 5 16932 143 0 0
sp_payment_common 13144 13144 0 0.00 2 7367 23 0 0
sp_qd_handle_on_cancel_clo 1709 1709 0 0.00 0 6843 303 0 0
sp_qd_handle_on_invoice_upd_sts 10746 10746 0 0.00 0 17995 319 0 0
sp_qd_handle_on_reserve_upd_sts 10121 10121 0 0.00 0 13916 59 0 0
sp_reserve_write_off 10123 10123 0 0.00 6 16938 180 0 0
sp_supplier_invoice 5599 5599 0 0.00 5 86449 3215 0 0
sp_supplier_order 5589 5589 0 0.00 5 32796 1269 0 0
srv_aggregate_perf_data 106 106 0 0.00 2808 132649 19128 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1520 124127 46757 0 0
srv_find_qd_qs_mism 1053302 1053302 0 0.00 0 10957 5 0 0
srv_make_invnt_saldo 563 563 0 0.00 26 52481 1258 0 0
srv_make_money_saldo 787 787 0 0.00 0 36642 79 0 0
srv_recalc_idx_stat 4 4 0 0.00 220803 386340 288088 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5032 5032 0 0.00 0 12417 159 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 2804 84 0 0
t$perf-norm:sp_cancel_client_order 1709 1709 0 0.00 0 2043 12 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 1412 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 1112 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 313 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 3352 28 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 25450 1733 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 9525 14 0 0
t$perf-norm:sp_client_order 20429 20429 0 0.00 0 9655 17 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 864 4 0 0
t$perf-norm:sp_pay_from_customer 7235 7235 0 0.00 0 10237 13 0 0
t$perf-norm:sp_pay_to_supplier 5912 5912 0 0.00 0 4953 9 0 0
t$perf-norm:sp_reserve_write_off 10123 10123 0 0.00 0 7848 11 0 0
t$perf-norm:sp_supplier_invoice 5599 5599 0 0.00 0 8510 18 0 0
t$perf-norm:sp_supplier_order 5589 5589 0 0.00 0 9086 16 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 103 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 693 14 0 0
t$perf-norm:srv_make_invnt_saldo 563 563 0 0.00 0 1981 28 0 0
t$perf-norm:srv_make_money_saldo 787 787 0 0.00 0 4055 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10260 10046 214 2.08 0 9251 21 0 214
v_all_customers 37336 37336 0 0.00 0 24 1 0 0
v_all_suppliers 30582 30582 0 0.00 0 42 1 0 0
v_all_wares 40858 40858 0 0.00 0 78 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 3673 16 0 0
v_cancel_client_order 3418 3418 0 0.00 0 16915 22 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 16271 19 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 10976 12 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 3791 19 0 0
v_cancel_supplier_invoice 782 768 14 1.79 0 1873 14 0 14
v_cancel_supplier_order 816 816 0 0.00 0 1534 7 0 0
v_cancel_write_off 2024 2024 0 0.00 0 5024 17 0 0
v_max_id_clo_ord 5589 5589 0 0.00 0 2135 2 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 2956 16 0 0
v_max_id_ord_sup 5599 5599 0 0.00 0 2850 5 0 0
v_max_non_paid_invoice 1807 1807 0 0.00 0 3881 8 0 0
v_max_non_paid_realizn 3896 3896 0 0.00 0 5437 6 0 0
v_min_id_clo_ord 5589 5589 0 0.00 0 9888 11 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 812 2 0 0
v_min_id_ord_sup 5599 5599 0 0.00 0 7293 6 0 0
v_min_non_paid_invoice 5910 5910 0 0.00 0 4684 15 0 0
v_min_non_paid_realizn 7234 7234 0 0.00 0 4185 13 0 0
v_our_firm 10198 10198 0 0.00 0 26 1 0 0
v_reserve_write_off 20240 20240 0 0.00 0 16589 10 0 0
x_lock_depdocs_on_canc_invoice 11652 11652 0 0.00 0 19882 110 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 4189 339 0 0
x_make_qstorno_1000_1200 5589 5589 0 0.00 2 32787 1188 0 0
x_make_qstorno_1200_2000 5599 5599 0 0.00 2 79094 3076 0 0
x_make_qstorno_2100_3300 453986 453986 0 0.00 0 41268 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-07-2022 01:39:29 to 18-07-2022 01:39:33.

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:27 7 8192000000 8798879696 8810233856 5307904 0.064 2 1 2 0 5407504 56816 42784
23:09:28 29263 8192000000 9897430048 10207522816 979129456 10.676 100 91 182 0 1009890480 6248384 24512640
23:10:59 25466 8192000000 9944525536 10264817664 1025258480 11.123 100 89 178 0 1057146224 8654224 23233520
23:12:30 13643 8192000000 9978548544 10310066176 1061242080 11.468 100 79 158 0 1090459376 9560816 19656480
23:13:58 6731 8192000000 10037643520 10385879040 1122334944 12.049 100 80 161 0 1151704656 10708752 18660960
23:15:09 6339 8192000000 10076485472 10438656000 1165685696 12.456 100 86 169 1 1198005200 12838128 19481376
23:16:18 3686 8192000000 10108800128 10481647616 1206229552 12.834 100 72 143 0 1235945280 13411856 16303872
23:17:24 1721 8192000000 10158496128 10531471360 1241888032 13.164 100 89 178 0 1275375520 13705216 19782272
23:18:27 1437 8192000000 10193396016 10571583488 1273262176 13.451 100 90 180 1 1307161504 14393456 19505872
23:19:30 1146 8192000000 10208252528 10594766848 1298179344 13.679 100 89 178 1 1328997984 12223344 18595296
23:20:34 1953 8192000000 10217918608 10614394880 1310838832 13.794 100 78 157 1 1340768208 12445680 17483696
23:21:36 1580 8192000000 10219639456 10616438784 1311940000 13.804 100 63 127 0 1334763600 7082224 15741376
23:22:39 10829 8192000000 10205380912 10620051456 1321185456 13.887 100 20 39 1 1331364224 5614048 4564720
23:23:50 9591 8192000000 10216311904 10633052160 1327844736 13.948 100 18 37 0 1338110272 6445472 3820064
23:25:04 51648 8192000000 10244208208 10652680192 1338324640 14.042 100 46 89 1 1354016448 5270176 10421632
23:26:57 3438 8192000000 10247839216 10662334464 1345727264 14.109 100 50 97 1 1365732080 8926272 11078544
23:28:01 7663 8192000000 10221053136 10647044096 1326397904 13.935 99 9 16 0 1333893200 5061024 2434272
23:29:09 51695 8192000000 10164046224 10570297344 1196654256 12.745 91 34 68 2 1209686480 5053744 7978480
23:31:01 4646 8192000000 10130974608 10531950592 1223544416 12.994 98 29 59 0 1236904368 6622368 6737584
23:32:07 10303 8192000000 10144015840 10545545216 1212256432 12.890 96 22 44 0 1223512816 6431856 4824528
23:33:18 74023 8192000000 10163276304 10572201984 1244090896 13.184 97 30 57 0 1256536496 5772096 6673504
23:35:33 6288 8192000000 10187665216 10593902592 1290190288 13.606 100 31 62 0 1302934448 5556800 7187360
23:36:42 5781 8192000000 10200354096 10617208832 1308981616 13.777 100 25 50 0 1320559696 6152288 5425792
23:37:51 10440 8192000000 10200586672 10619953152 1313303792 13.816 100 15 30 1 1322077216 5713200 3060224
23:39:07 85157 8192000000 10256044016 10661695488 1341073312 14.067 100 57 111 1 1363415744 9190512 13151920
23:41:42 23984 8192000000 10270253056 10690547712 1365326688 14.285 100 39 76 1 1388754016 14634016 8793312
23:43:11 12890 8192000000 10281948896 10711195648 1373473920 14.358 100 36 72 1 1400722944 19169520 8079504
23:45:07 9453 8192000000 10305095392 10722947072 1384896672 14.460 100 57 114 0 1417788336 19678144 13213520
23:46:28 2089 8192000000 10300554912 10717696000 1380900944 14.425 100 48 94 1 1407865408 16470336 10494128
23:47:35 5009 8192000000 10316311968 10739961856 1396833216 14.567 100 54 106 0 1428096592 18228832 13034544
23:48:42 12722 8192000000 10339341808 10761732096 1412350848 14.705 100 61 122 0 1450681280 23754976 14575456
23:50:01 8154 8192000000 10330174272 10756042752 1392982464 14.532 99 47 94 0 1431522224 26369744 12170016
23:51:19 3773 8192000000 10338166624 10749861888 1390156496 14.507 99 74 145 0 1438437824 30756528 17524800
23:52:27 6995 8192000000 10343921664 10768330752 1400670560 14.601 100 61 122 0 1452876608 37082656 15123392
23:53:37 1515 8192000000 10318112576 10729119744 1377555952 14.395 100 81 162 1 1425703488 29001680 19145856
23:54:42 4412 8192000000 10289525472 10710032384 1352487424 14.170 100 60 117 0 1400793504 33478624 14827456
23:56:07 2113 8192000000 10312780288 10728779776 1355892000 14.200 99 70 137 0 1411092672 37542304 17658368
23:57:15 1715 8192000000 10326591984 10738425856 1376260112 14.383 100 75 150 1 1430236864 34655136 19321616
23:58:22 4084 8192000000 10326631488 10743791616 1373929600 14.362 100 56 109 0 1427771216 39801936 14039680
23:59:32 33263 8192000000 10299971920 10729140224 1362157088 14.257 100 29 56 1 1405420960 36106912 7156960
00:01:10 47640 8192000000 10282873472 10713219072 1326940208 13.939 98 25 50 0 1373791456 39917104 6934144
00:03:04 28389 8192000000 10285644816 10721529856 1344560832 14.099 99 24 49 0 1393058800 41944240 6553728
00:04:37 35414 8192000000 10280759056 10725351424 1349796848 14.146 99 20 40 0 1394305552 39362016 5146688
00:06:14 58934 8192000000 10285351712 10712039424 1328699664 13.955 98 47 91 0 1359899088 19695072 11504352
00:08:19 22244 8192000000 10288637824 10730393600 1371192848 14.338 100 34 66 3 1397318864 18667616 7458400
00:09:42 70698 8192000000 10285452704 10719510528 1352389728 14.169 99 25 50 0 1376304416 18295184 5619504
00:11:58 61062 8192000000 10279638288 10724384768 1358759472 14.226 99 15 28 0 1381870928 20362992 2748464
00:14:00 53345 8192000000 10290077696 10737131520 1375118528 14.373 100 13 26 0 1395554480 17486576 2949376
00:16:07 124127 8192000000 10279748544 10734186496 1378855664 14.406 100 17 34 0 1403131616 20370736 3905216
00:19:25 52670 8192000000 10297329568 10741952512 1377700160 14.396 99 25 50 1 1393155744 9743600 5711984
00:21:23 72495 8192000000 10279462576 10725498880 1372253104 14.347 99 25 48 2 1388229072 10280800 5695168
00:23:41 29841 8192000000 10294706864 10748100608 1395905376 14.559 100 24 47 0 1411266352 10020864 5340112
00:25:20 28707 8192000000 10304562688 10749034496 1374888464 14.371 98 44 86 2 1399807168 14560416 10358288
00:26:49 43112 8192000000 10297378000 10763378688 1399856400 14.594 100 8 17 0 1415409056 13579552 1973104
00:28:44 65004 8192000000 10304746240 10767667200 1401242000 14.606 100 11 21 1 1415300944 12050848 2008096
00:31:03 50325 8192000000 10291082272 10756399104 1397435696 14.572 100 15 30 0 1412864000 12194304 3234000
00:32:55 71126 8192000000 10297371008 10760732672 1399929584 14.594 100 15 30 0 1416127904 12556736 3641584
00:35:40 38576 8192000000 10306592416 10769981440 1392749616 14.530 99 24 48 0 1410710592 11940608 6020368
00:37:24 40690 8192000000 10296382832 10747187200 1365731376 14.289 98 29 58 0 1384822736 12336752 6754608
00:39:06 73374 8192000000 10291623728 10758905856 1364755408 14.280 98 20 40 0 1385584768 16329296 4500064
00:41:19 55291 8192000000 10283343040 10730352640 1316971312 13.849 95 15 31 0 1335970336 15544448 3454576
00:43:16 111852 8192000000 10273739792 10721562624 1282693328 13.538 94 8 16 0 1307070864 22496592 1880944
00:46:12 109955 8192000000 10265449424 10704596992 1285937840 13.567 94 18 36 0 1302406928 12472704 3996384
00:49:03 61438 8192000000 10275183520 10723790848 1320005120 13.877 97 25 50 0 1338350096 12907776 5437200
00:51:17 100783 8192000000 10248086976 10695012352 1300490000 13.700 95 27 54 0 1318286960 11521344 6275616
00:54:05 46660 8192000000 10253215808 10707132416 1351366480 14.160 100 23 46 2 1369358032 13097120 4894432
00:55:53 66772 8192000000 10241165216 10703798272 1336318352 14.024 99 9 19 0 1349735008 11263904 2152752
00:58:00 64270 8192000000 10260805600 10712064000 1353279200 14.177 99 20 40 1 1366843808 9056096 4508512
01:00:31 60693 8192000000 10262406608 10722836480 1365722384 14.289 100 12 24 0 1382276272 13873408 2680480
01:02:38 56334 8192000000 10258223344 10719027200 1354566928 14.189 99 20 40 0 1371495056 12396656 4531472
01:04:39 43033 8192000000 10244616608 10691883008 1279785488 13.511 94 12 24 0 1288420816 6169744 2465584
01:06:22 52106 8192000000 10254853984 10698661888 1348195632 14.131 99 21 42 0 1361627776 8854000 4578144
01:08:24 49045 8192000000 10288031040 10735734784 1369296768 14.321 99 29 58 0 1384532160 9168688 6066704
01:10:20 52563 8192000000 10302954960 10760761344 1393222272 14.535 100 22 44 0 1410643376 12409824 5011280
01:12:13 56142 8192000000 10308532912 10760744960 1393805248 14.540 100 15 30 0 1410350624 13692752 2852624
01:14:10 32136 8192000000 10314424912 10766053376 1392351360 14.527 99 37 74 0 1416431536 15639040 8441136
01:15:46 55911 8192000000 10313428896 10770423808 1377838720 14.397 98 12 24 1 1396741952 15859648 3043584
01:17:44 23100 8192000000 10312957760 10758721536 1387628640 14.485 99 35 70 1 1405752384 10585376 7538368
01:19:10 95582 8192000000 10306737856 10769920000 1407384880 14.661 100 11 22 1 1420073552 10237952 2450720
01:22:16 33983 8192000000 10320972784 10777907200 1415738864 14.735 100 24 48 0 1433039040 11743408 5556768
01:24:06 57546 8192000000 10297111248 10770219008 1385258880 14.464 98 15 31 1 1404153712 14904672 3990160
01:26:15 72718 8192000000 10292020640 10751033344 1354891696 14.191 97 9 17 0 1363895744 7498992 1505056
01:28:29 70290 8192000000 10293032624 10746839040 1378326816 14.402 99 28 56 1 1400855984 15591824 6937344
01:30:42 61579 8192000000 10278165360 10735431680 1300546352 13.700 94 20 40 0 1328600080 23426704 4627024
01:32:46 52388 8192000000 10252210320 10690662400 1287762720 13.584 95 20 40 0 1304393680 12340752 4290208
01:34:39 42462 8192000000 10267938192 10708377600 1325437568 13.926 98 35 70 0 1342972400 9855232 7679600
01:36:44 75875 8192000000 10257157120 10702848000 1308447152 13.772 96 15 30 0 1323366976 11993824 2926000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-07-2022 01:39:35 to 18-07-2022 01:39:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 18-07-2022 01:39:51 to 18-07-2022 01:39:52.

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          239914
MON$OLDEST_ACTIVE               241960
MON$OLDEST_SNAPSHOT             241960
MON$NEXT_TRANSACTION            241960
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                       2017408
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.33609 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33609 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33609 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 ---
:89: 
:90: mscs-test-161.budzdorov.ru    Mon Jul 18 01:38:25 2022
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:92: 
:93: 
:94: mscs-test-161.budzdorov.ru    Mon Jul 18 01:38:28 2022
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:96: 
:97: 
:98: mscs-test-161.budzdorov.ru    Mon Jul 18 01:38:34 2022
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:100: 
--- end of diff output ---

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

18.07.22 01:39:54. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-07-18 01:39:56 No severe PSQL-related problems occured
18.07.22 01:39:57. End of report.