Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.11.2021 16:45
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 16861924 kB
MemAvailable 31628256 kB
Buffers 0 kB
Cached 14841880 kB
SwapCached 0 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.8
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 2021-11-17 16:42:27
DTS_END                         2021-11-17 16:42:27.0260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49130
TRN_ID                          233947
ATT_ID                          628
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: 9512.03 21000 1141443
customer order: creation 166.78 364 20013
customer order: refuse 14.25 339 1710
order to supplier: creation 45.44 1236 5453
order to supplier: removal 3.50 108780 420
invoice (draft): creation 44.08 3377 5289
invoice (draft): removal 54.42 805 6530
invoice accept: apply 41.39 16855 4967
invoice accept: cancel 49.68 771 5961
customer reserve: creation 3890.01 164 466801
customer reserve: removal 4915.58 61 589870
realization accept: apply 80.03 177 9603
realization accept: cancel 81.74 105 9809
payment from customer: creation 57.24 124 6869
payment from customer: removal 5.53 51 664
payment to supplier: creation 47.68 125 5721
payment to supplier: removal 3.35 85 402
service: total inventory turnovers 4.63 993 556
service: total monetary turnovers 5.81 18 697
service: aggregate perf. data 0.87 14461 104
service: refresh index statistics 0.03 271100 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-11-2021 16:45:27 to 17-11-2021 16:45:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5685
TEST_TIME 2 4176
TEST_TIME 3 6089
TEST_TIME 4 6122
TEST_TIME 5 6008
TEST_TIME 6 5474
TEST_TIME 7 5775
TEST_TIME 8 5694
TEST_TIME 9 5447
TEST_TIME 10 5960
TEST_TIME 11 5867
TEST_TIME 12 5846
TEST_TIME 13 5938
TEST_TIME 14 6054
TEST_TIME 15 6200
TEST_TIME 16 6523
TEST_TIME 17 6507
TEST_TIME 18 6768
TEST_TIME 19 7356
TEST_TIME 20 7667
TEST_TIME 21 8318
TEST_TIME 22 8568
TEST_TIME 23 9109
TEST_TIME 24 8962
TEST_TIME 25 9273
TEST_TIME 26 9583
TEST_TIME 27 10907
TEST_TIME 28 10826
TEST_TIME 29 10704
TEST_TIME 30 10988
TEST_TIME 31 11046
TEST_TIME 32 10845
TEST_TIME 33 10584
TEST_TIME 34 10371
TEST_TIME 35 10315
TEST_TIME 36 10206
TEST_TIME 37 10137
TEST_TIME 38 9955
TEST_TIME 39 10165
TEST_TIME 40 10175
TEST_TIME 41 10300
TEST_TIME 42 10205
TEST_TIME 43 10079
TEST_TIME 44 9880
TEST_TIME 45 9868
TEST_TIME 46 9837
TEST_TIME 47 9742
TEST_TIME 48 9647
TEST_TIME 49 9720
TEST_TIME 50 9601
TEST_TIME 51 9575
TEST_TIME 52 9588
TEST_TIME 53 9545
TEST_TIME 54 9658
TEST_TIME 55 9719
TEST_TIME 56 9764
TEST_TIME 57 9766
TEST_TIME 58 9861
TEST_TIME 59 9826
TEST_TIME 60 9918
TEST_TIME 61 9941
TEST_TIME 62 9933
TEST_TIME 63 10041
TEST_TIME 64 10032
TEST_TIME 65 10132
TEST_TIME 66 9981
TEST_TIME 67 9986
TEST_TIME 68 9884
TEST_TIME 69 9883
TEST_TIME 70 9858
TEST_TIME 71 9797
TEST_TIME 72 9745
TEST_TIME 73 9736
TEST_TIME 74 9638
TEST_TIME 75 9641
TEST_TIME 76 9609
TEST_TIME 77 9532
TEST_TIME 78 9589
TEST_TIME 79 9563
TEST_TIME 80 9606
TEST_TIME 81 9562
TEST_TIME 82 9608
TEST_TIME 83 9585
TEST_TIME 84 9556
TEST_TIME 85 9700
TEST_TIME 86 9643
TEST_TIME 87 9773
TEST_TIME 88 9836
TEST_TIME 89 9802
TEST_TIME 90 9856
TEST_TIME 91 9878
TEST_TIME 92 9777
TEST_TIME 93 9780
TEST_TIME 94 9755
TEST_TIME 95 9724
TEST_TIME 96 9689
TEST_TIME 97 9702
TEST_TIME 98 9665
TEST_TIME 99 9604
TEST_TIME 100 9545
TEST_TIME 101 9544
TEST_TIME 102 9526
TEST_TIME 103 9507
TEST_TIME 104 9544
TEST_TIME 105 9516
TEST_TIME 106 9560
TEST_TIME 107 9566
TEST_TIME 108 9623
TEST_TIME 109 9604
TEST_TIME 110 9588
TEST_TIME 111 9644
TEST_TIME 112 9676
TEST_TIME 113 9680
TEST_TIME 114 9735
TEST_TIME 115 9737
TEST_TIME 116 9685
TEST_TIME 117 9643
TEST_TIME 118 9607
TEST_TIME 119 9585
TEST_TIME 120 9522

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-11-2021 16:45:31 to 17-11-2021 16:45:34.

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 1572228 1572228 0 0.00 0 34783 6 0 0
doc_list_biud 1572227 1572227 0 0.00 0 42270 29 0 0
sp_add_invoice_to_stock 5066 4967 99 1.95 33 144589 16855 0 99
sp_cancel_adding_invoice 5961 5961 0 0.00 4 106529 772 0 0
sp_cancel_client_order 1710 1710 0 0.00 4 16788 340 0 0
sp_cancel_customer_reserve 589870 589870 0 0.00 1 21389 62 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 2 2365 52 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 2 7076 85 0 0
sp_cancel_supplier_invoice 6540 6530 10 0.15 1 42276 806 0 10
sp_cancel_supplier_order 420 420 0 0.00 9 1142310 108780 0 0
sp_cancel_write_off 9809 9809 0 0.00 1 7614 105 0 0
sp_client_order 20013 20013 0 0.00 3 35550 364 0 0
sp_customer_reserve 466801 466801 0 0.00 0 62312 165 0 0
sp_fill_shopping_cart 30738 30738 0 0.00 1 13299 36 0 0
sp_fill_shopping_cart_clo_res 466779 466779 0 0.00 0 11657 12 0 0
sp_get_clo_for_invoice 4945 4945 0 0.00 0 26306 276 0 0
sp_kill_cost_storno 16836 16836 0 0.00 0 11417 55 0 0
sp_kill_qstorno_ret_qs2qd 1196499 1196499 0 0.00 0 42267 33 0 0
sp_kill_qty_storno 628848 628848 0 0.00 0 42269 72 0 0
sp_lock_selected_doc 20154 20154 0 0.00 0 7854 6 0 0
sp_make_cost_storno 27138 27138 0 0.00 0 34772 49 0 0
sp_multiply_rows_for_pdistr 27138 27138 0 0.00 0 34083 30 0 0
sp_multiply_rows_for_qdistr 20013 20013 0 0.00 0 15759 146 0 0
sp_pay_from_customer 6869 6869 0 0.00 4 34096 124 0 0
sp_pay_to_supplier 5721 5721 0 0.00 4 15050 126 0 0
sp_payment_common 12590 12590 0 0.00 1 15001 31 0 0
sp_qd_handle_on_cancel_clo 1710 1710 0 0.00 0 16780 304 0 0
sp_qd_handle_on_invoice_upd_sts 10906 10906 0 0.00 0 28721 320 0 0
sp_qd_handle_on_reserve_upd_sts 9603 9603 0 0.00 0 15598 61 0 0
sp_reserve_write_off 9603 9603 0 0.00 6 22092 177 0 0
sp_supplier_invoice 5289 5289 0 0.00 6 76278 3378 0 0
sp_supplier_order 5453 5453 0 0.00 5 43578 1237 0 0
srv_aggregate_perf_data 104 104 0 0.00 2697 69686 14462 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1804 146573 46600 0 0
srv_find_qd_qs_mism 1093719 1093719 0 0.00 0 20935 5 0 0
srv_make_invnt_saldo 556 556 0 0.00 35 29251 993 0 0
srv_make_money_saldo 697 697 0 0.00 0 692 19 0 0
srv_recalc_idx_stat 4 4 0 0.00 184995 366399 271100 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4967 4967 0 0.00 0 8729 154 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 3995 79 0 0
t$perf-norm:sp_cancel_client_order 1710 1710 0 0.00 0 7822 20 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 1678 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 5324 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 3391 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 600 11 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 16395 1629 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 4263 12 0 0
t$perf-norm:sp_client_order 20013 20013 0 0.00 0 7687 17 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 3407 15 0 0
t$perf-norm:sp_pay_from_customer 6869 6869 0 0.00 0 7497 16 0 0
t$perf-norm:sp_pay_to_supplier 5721 5721 0 0.00 0 10218 9 0 0
t$perf-norm:sp_reserve_write_off 9603 9603 0 0.00 0 6718 12 0 0
t$perf-norm:sp_supplier_invoice 5289 5289 0 0.00 0 6610 18 0 0
t$perf-norm:sp_supplier_order 5453 5453 0 0.00 0 15336 15 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 280 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 4848 82 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 1489 17 0 0
t$perf-norm:srv_make_money_saldo 697 697 0 0.00 0 12294 29 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10088 9890 198 1.96 0 7385 17 0 198
v_all_customers 36356 36356 0 0.00 0 86 0 0 0
v_all_suppliers 29972 29972 0 0.00 0 28 1 0 0
v_all_wares 40026 40026 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 1199 7 0 0
v_cancel_client_order 3420 3420 0 0.00 0 7069 8 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 982 4 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 6630 13 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 838 4 0 0
v_cancel_supplier_invoice 806 786 20 2.48 0 1519 13 0 20
v_cancel_supplier_order 840 840 0 0.00 0 2440 6 0 0
v_cancel_write_off 1904 1904 0 0.00 0 7193 20 0 0
v_max_id_clo_ord 5453 5453 0 0.00 0 479 1 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 5099 30 0 0
v_max_id_ord_sup 5271 5271 0 0.00 0 4816 5 0 0
v_max_non_paid_invoice 1459 1459 0 0.00 0 318 2 0 0
v_max_non_paid_realizn 3631 3631 0 0.00 0 4211 5 0 0
v_min_id_clo_ord 5453 5453 0 0.00 0 2078 4 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 4030 14 0 0
v_min_id_ord_sup 5271 5271 0 0.00 0 5386 7 0 0
v_min_non_paid_invoice 5721 5721 0 0.00 0 14997 22 0 0
v_min_non_paid_realizn 6869 6869 0 0.00 0 10716 24 0 0
v_our_firm 10146 10146 0 0.00 0 22 1 0 0
v_reserve_write_off 19206 19206 0 0.00 0 7296 7 0 0
x_lock_depdocs_on_canc_invoice 12098 12098 0 0.00 0 12134 101 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 3645 268 0 0
x_make_qstorno_1000_1200 5453 5453 0 0.00 1 43573 1170 0 0
x_make_qstorno_1200_2000 5289 5289 0 0.00 2 68029 3225 0 0
x_make_qstorno_2100_3300 466801 466801 0 0.00 0 62310 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-11-2021 16:45:35 to 17-11-2021 16:45:39.

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:12:30 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:31 25431 8192000000 9897838448 10208165888 978325984 10.668 100 85 170 0 1007393872 5929520 23138368
14:14:59 21613 8192000000 9948334416 10269351936 1027506128 11.144 100 87 174 0 1057983024 8060224 22416672
14:16:25 13471 8192000000 9980808672 10312957952 1066037520 11.514 100 78 156 0 1094926032 9410768 19477744
14:17:42 13269 8192000000 10043624720 10391990272 1125571872 12.080 100 73 146 1 1152795632 10430544 16793216
14:18:59 9977 8192000000 10087434288 10449670144 1174481696 12.539 100 76 152 0 1203754144 11279248 17993200
14:20:11 7264 8192000000 10129324848 10498854912 1221135296 12.972 100 74 146 1 1250931376 12737312 17058768
14:21:22 7750 8192000000 10159414768 10537668608 1250550944 13.243 100 76 152 1 1281141200 13826656 16763600
14:22:36 2648 8192000000 10201392560 10583367680 1281513856 13.527 100 90 181 2 1314999120 13065584 20419680
14:23:41 5929 8192000000 10215993520 10598187008 1293382816 13.635 100 85 169 1 1326168192 14559376 18226000
14:24:49 28551 8192000000 10190379296 10602643456 1307314608 13.762 100 28 54 1 1319852576 6421696 6116272
14:26:23 15271 8192000000 10203640544 10620190720 1319412688 13.871 100 21 42 1 1330216496 6109328 4694480
14:27:38 7485 8192000000 10212926960 10628198400 1324766144 13.920 100 20 38 1 1335328160 6181328 4380688
14:28:50 53920 8192000000 10219002496 10642997248 1329395008 13.962 100 29 58 1 1342215584 5698544 7122032
14:30:45 7157 8192000000 10232219424 10653196288 1336166768 14.023 100 24 48 0 1348559456 7061168 5331520
14:31:54 6577 8192000000 10235959328 10654990336 1336257040 14.024 100 28 56 1 1349306448 6884384 6165024
14:33:01 65264 8192000000 10187603696 10599563264 1262381904 13.352 96 32 64 0 1274968000 5399712 7186384
14:35:09 5439 8192000000 10157689200 10555703296 1235227648 13.102 97 35 69 0 1249024576 6185872 7611056
14:36:17 2197 8192000000 10151862992 10548912128 1248733136 13.227 99 33 67 2 1263736768 7577552 7426080
14:37:21 5637 8192000000 10165222864 10576461824 1275598736 13.473 100 31 62 1 1289359024 6108384 7651904
14:38:27 19633 8192000000 10152245328 10559365120 1235409504 13.104 97 16 32 0 1244432784 5411984 3611296
14:39:49 4722 8192000000 10186746480 10602024960 1300808256 13.703 100 34 66 2 1313154848 4876880 7469712
14:40:55 9974 8192000000 10202862768 10621980672 1310067776 13.787 100 13 26 1 1318670224 5825888 2776560
14:42:05 66024 8192000000 10229904592 10648985600 1328518512 13.954 100 39 78 0 1344283376 5195280 10569584
14:44:18 19441 8192000000 10268876304 10679504896 1356229872 14.203 100 63 126 4 1380443168 9348240 14865056
14:45:51 6154 8192000000 10280614720 10699489280 1368837008 14.317 100 50 100 1 1390547056 10274208 11435840
14:47:17 27739 8192000000 10311380464 10723229696 1383629840 14.449 100 58 115 1 1412312864 15097680 13585344
14:48:51 34456 8192000000 10308540944 10735378432 1395785984 14.557 100 49 98 0 1425874688 18550192 11538512
14:50:33 19383 8192000000 10323939584 10758406144 1398883072 14.585 99 39 77 1 1431114624 22032736 10198816
14:52:01 5876 8192000000 10370064560 10790965248 1430651248 14.867 100 64 128 1 1479096960 32127344 16318368
14:53:12 6111 8192000000 10367691184 10793218048 1429749040 14.859 100 65 127 2 1475916944 30472608 15695296
14:54:22 11412 8192000000 10376761616 10805649408 1428214016 14.845 99 55 108 4 1480135504 36818192 15103296
14:55:47 7494 8192000000 10402944256 10832138240 1451307200 15.049 100 64 129 1 1513826528 44626720 17892608
14:57:05 3970 8192000000 10424403952 10847088640 1459267808 15.119 100 82 165 1 1527719536 46173760 22277968
14:58:13 8979 8192000000 10409703008 10838798336 1450706944 15.044 100 60 120 1 1517524656 50986960 15830752
14:59:35 2698 8192000000 10415846624 10832592896 1425069808 14.818 99 90 179 2 1503000704 55148736 22782160
15:00:43 1803 8192000000 10364117664 10776920064 1392464480 14.528 98 67 133 1 1467892400 58185152 17242768
15:01:50 8354 8192000000 10377547056 10800746496 1398806768 14.584 99 50 99 1 1470753040 57974656 13971616
15:03:06 13974 8192000000 10360685616 10793533440 1393781312 14.540 100 43 86 1 1463772176 58208032 11782832
15:04:23 16456 8192000000 10352845264 10779492352 1381844704 14.433 99 33 66 1 1445979504 54136256 9998544
15:05:41 19651 8192000000 10331293728 10781835264 1384907424 14.460 100 30 60 1 1450734992 57312560 8515008
15:07:03 29094 8192000000 10296834576 10737991680 1311536832 13.800 95 29 58 0 1372534368 52271856 8725680
15:08:53 20424 8192000000 10298762048 10753941504 1345984416 14.111 100 14 28 1 1397727120 47243328 4499376
15:10:19 17508 8192000000 10271157888 10707558400 1352836672 14.173 100 25 50 0 1375365888 16821920 5707296
15:11:38 71442 8192000000 10254222544 10698088448 1290079376 13.605 95 13 26 2 1316331904 22863152 3389376
15:13:51 71992 8192000000 10248276736 10685689856 1288057712 13.587 95 30 60 1 1302303200 7575440 6670048
15:17:35 80962 8192000000 10275241712 10716160000 1311881568 13.803 97 31 61 1 1324246512 6049968 6314976
15:20:00 72633 8192000000 10307617600 10741399552 1343250688 14.087 98 28 56 0 1379402240 29321536 6830016
15:22:25 39264 8192000000 10283566976 10760290304 1361787120 14.253 100 9 18 1 1396091904 32011392 2293392
15:24:23 44408 8192000000 10282091440 10738290688 1378059584 14.399 100 11 22 0 1391465616 11282896 2123136
15:26:42 70823 8192000000 10288636816 10747105280 1373283088 14.356 99 27 54 0 1391380096 12024048 6072960
15:29:00 27171 8192000000 10298265328 10751533056 1383468240 14.448 100 30 60 2 1403897952 13472464 6957248
15:30:28 46629 8192000000 10283865440 10741907456 1384417472 14.456 100 17 33 0 1399943968 11552176 3974320
15:32:15 41799 8192000000 10286817728 10753032192 1384119856 14.453 100 14 27 2 1399833264 11980592 3732816
15:34:03 47410 8192000000 10303827488 10767486976 1398666608 14.583 100 25 49 4 1417140288 12608768 5864912
15:35:51 61311 8192000000 10318939472 10783096832 1411559600 14.698 100 15 30 0 1430055424 14687664 3808160
15:37:53 23717 8192000000 10325393312 10781523968 1413307024 14.713 100 36 72 0 1428908048 7323600 8277424
15:39:20 59808 8192000000 10310115952 10773254144 1370629040 14.333 97 16 31 3 1386812128 12828208 3354880
15:41:24 35952 8192000000 10319762144 10782474240 1410393168 14.687 100 23 46 0 1435429520 19561824 5474528
15:43:08 30704 8192000000 10314310656 10768527360 1399188688 14.588 100 36 72 2 1418004064 9985696 8829680
15:45:37 70370 8192000000 10302087520 10751221760 1305645920 13.747 93 17 34 0 1318045952 8941184 3458848
15:47:49 122892 8192000000 10288948672 10745638912 1355427952 14.196 97 16 32 0 1371911488 12871872 3611664
15:51:00 13238 8192000000 10296531456 10735378432 1372379728 14.348 99 52 105 1 1393048672 9403008 11265936
15:52:20 61002 8192000000 10269546112 10727890944 1327969888 13.949 96 6 12 0 1337747728 8678816 1099024
15:54:24 50274 8192000000 10252640416 10708525056 1339401472 14.052 98 7 14 1 1348809152 7822592 1585088
15:56:24 68773 8192000000 10246202768 10704728064 1326207888 13.933 97 29 58 0 1340848048 7976848 6663312
15:58:39 31230 8192000000 10252358544 10705801216 1352850800 14.173 100 14 28 0 1368313216 12273472 3188944
16:00:16 100279 8192000000 10235918240 10684891136 1289800496 13.602 95 9 18 1 1306432912 14614656 2017760
16:03:03 23045 8192000000 10275903536 10702888960 1312080800 13.805 97 28 54 0 1326274624 7738096 6455728
16:04:29 35756 8192000000 10273717584 10715107328 1352971776 14.174 99 19 37 0 1371464400 14175344 4317280
16:06:05 60248 8192000000 10292248032 10739802112 1371523616 14.341 100 21 42 0 1397206400 20309936 5372848
16:08:06 70887 8192000000 10279041040 10729418752 1330399088 13.971 97 23 46 0 1357947088 22026480 5521520
16:10:28 55902 8192000000 10266238096 10699837440 1293721136 13.638 95 22 44 1 1306929216 8581760 4626320
16:12:26 81599 8192000000 10276674640 10736955392 1367317920 14.303 100 17 34 0 1386662016 15776176 3567920
16:14:48 72562 8192000000 10284291424 10730229760 1352483216 14.170 98 31 62 1 1366950736 7363408 7104112
16:17:02 44369 8192000000 10282144688 10729984000 1350965440 14.156 98 37 74 1 1365953872 5783792 9204640
16:18:49 80895 8192000000 10271329216 10738024448 1366990736 14.300 99 7 14 0 1376551504 7892800 1667968
16:21:15 68537 8192000000 10280622304 10745479168 1378834448 14.406 99 13 26 0 1390100848 7986656 3279744
16:23:35 80214 8192000000 10280597264 10751516672 1366236768 14.293 98 13 26 0 1381354224 12477680 2639776
16:25:58 74501 8192000000 10300131520 10771361792 1392253072 14.526 100 21 42 0 1408844416 11638544 4952800
16:28:21 88723 8192000000 10297779600 10756947968 1340922992 14.066 96 12 24 0 1361282384 17759248 2600144
16:30:53 34242 8192000000 10305992736 10751533056 1370143280 14.328 98 35 70 1 1386664176 8559264 7961632
16:32:30 61549 8192000000 10289253504 10754732032 1352214464 14.167 97 12 24 2 1373176416 18310080 2651872
16:34:55 87190 8192000000 10304821392 10764156928 1367398256 14.304 98 15 30 1 1384711568 13280240 4033072
16:37:34 146573 8192000000 10291588496 10756599808 1379953184 14.416 99 20 40 0 1402905104 18284096 4667824
16:41:01 77827 8192000000 10284679488 10750132224 1328950288 13.958 95 13 26 1 1343799024 11509024 3339712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-11-2021 16:45:41 to 17-11-2021 16:45:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-11-2021 16:45:57 to 17-11-2021 16:45:59.

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          233788
MON$OLDEST_ACTIVE               235875
MON$OLDEST_SNAPSHOT             235875
MON$NEXT_TRANSACTION            235875
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                       2029152
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33535 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33535 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33535 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33535 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Nov 17 16:43:24 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
--- end of diff output ---

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

17.11.21 16:46:00. 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
2021-11-17 16:46:02 No severe PSQL-related problems occured
17.11.21 16:46:04. End of report.