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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16055324 kB
MemAvailable 30907984 kB
Buffers 0 kB
Cached 15663692 kB
SwapCached 4264 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-10-24 16:37:54
DTS_END                         2021-10-24 16:37:54.5950
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/55058
TRN_ID                          245945
ATT_ID                          636
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: 9477.98 20973 1137358
customer order: creation 181.13 357 21736
customer order: refuse 15.57 351 1868
order to supplier: creation 47.29 1281 5675
order to supplier: removal 3.43 95903 412
invoice (draft): creation 46.83 3496 5619
invoice (draft): removal 53.56 789 6427
invoice accept: apply 43.82 17281 5258
invoice accept: cancel 48.52 756 5822
customer reserve: creation 3984.68 174 478161
customer reserve: removal 4745.48 54 569457
realization accept: apply 88.56 183 10627
realization accept: cancel 82.88 106 9945
payment from customer: creation 62.21 110 7465
payment from customer: removal 6.26 92 751
payment to supplier: creation 51.12 150 6134
payment to supplier: removal 3.45 67 414
service: total inventory turnovers 5.12 1075 614
service: total monetary turnovers 7.18 25 862
service: aggregate perf. data 0.89 17873 107
service: refresh index statistics 0.03 279339 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-10-2021 16:39:36 to 24-10-2021 16:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4229
TEST_TIME 2 4541
TEST_TIME 3 6284
TEST_TIME 4 5510
TEST_TIME 5 6084
TEST_TIME 6 6047
TEST_TIME 7 6020
TEST_TIME 8 6602
TEST_TIME 9 6663
TEST_TIME 10 6949
TEST_TIME 11 6743
TEST_TIME 12 6567
TEST_TIME 13 6891
TEST_TIME 14 7026
TEST_TIME 15 7024
TEST_TIME 16 7354
TEST_TIME 17 7800
TEST_TIME 18 8411
TEST_TIME 19 9094
TEST_TIME 20 9159
TEST_TIME 21 9127
TEST_TIME 22 9317
TEST_TIME 23 9676
TEST_TIME 24 9899
TEST_TIME 25 9847
TEST_TIME 26 9936
TEST_TIME 27 10695
TEST_TIME 28 10517
TEST_TIME 29 10498
TEST_TIME 30 11125
TEST_TIME 31 11017
TEST_TIME 32 10761
TEST_TIME 33 10688
TEST_TIME 34 10396
TEST_TIME 35 10528
TEST_TIME 36 10432
TEST_TIME 37 10259
TEST_TIME 38 10090
TEST_TIME 39 10028
TEST_TIME 40 10211
TEST_TIME 41 10221
TEST_TIME 42 10269
TEST_TIME 43 10170
TEST_TIME 44 10057
TEST_TIME 45 9936
TEST_TIME 46 9822
TEST_TIME 47 9792
TEST_TIME 48 9794
TEST_TIME 49 9708
TEST_TIME 50 9648
TEST_TIME 51 9599
TEST_TIME 52 9664
TEST_TIME 53 9676
TEST_TIME 54 9606
TEST_TIME 55 9551
TEST_TIME 56 9689
TEST_TIME 57 9770
TEST_TIME 58 9751
TEST_TIME 59 9801
TEST_TIME 60 9808
TEST_TIME 61 9796
TEST_TIME 62 9793
TEST_TIME 63 10024
TEST_TIME 64 10051
TEST_TIME 65 10096
TEST_TIME 66 10010
TEST_TIME 67 9982
TEST_TIME 68 9886
TEST_TIME 69 9864
TEST_TIME 70 9847
TEST_TIME 71 9804
TEST_TIME 72 9824
TEST_TIME 73 9752
TEST_TIME 74 9681
TEST_TIME 75 9693
TEST_TIME 76 9672
TEST_TIME 77 9642
TEST_TIME 78 9601
TEST_TIME 79 9711
TEST_TIME 80 9658
TEST_TIME 81 9630
TEST_TIME 82 9714
TEST_TIME 83 9746
TEST_TIME 84 9737
TEST_TIME 85 9704
TEST_TIME 86 9702
TEST_TIME 87 9745
TEST_TIME 88 9780
TEST_TIME 89 9842
TEST_TIME 90 9767
TEST_TIME 91 9723
TEST_TIME 92 9716
TEST_TIME 93 9663
TEST_TIME 94 9647
TEST_TIME 95 9591
TEST_TIME 96 9652
TEST_TIME 97 9624
TEST_TIME 98 9624
TEST_TIME 99 9649
TEST_TIME 100 9618
TEST_TIME 101 9645
TEST_TIME 102 9649
TEST_TIME 103 9664
TEST_TIME 104 9656
TEST_TIME 105 9684
TEST_TIME 106 9696
TEST_TIME 107 9662
TEST_TIME 108 9738
TEST_TIME 109 9763
TEST_TIME 110 9734
TEST_TIME 111 9655
TEST_TIME 112 9611
TEST_TIME 113 9578
TEST_TIME 114 9563
TEST_TIME 115 9573
TEST_TIME 116 9541
TEST_TIME 117 9529
TEST_TIME 118 9493
TEST_TIME 119 9444
TEST_TIME 120 9485

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-10-2021 16:39:40 to 24-10-2021 16:39:43.

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 1579661 1579661 0 0.00 0 19174 6 0 0
doc_list_biud 1579650 1579650 0 0.00 0 38776 25 0 0
sp_add_invoice_to_stock 5359 5258 101 1.88 37 164129 17281 0 101
sp_cancel_adding_invoice 5822 5822 0 0.00 5 54222 757 0 0
sp_cancel_client_order 1868 1868 0 0.00 4 10063 352 0 0
sp_cancel_customer_reserve 569457 569457 0 0.00 1 20460 54 0 0
sp_cancel_pay_from_customer 751 751 0 0.00 3 6044 93 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 3250 68 0 0
sp_cancel_supplier_invoice 6436 6427 9 0.13 1 18011 789 0 9
sp_cancel_supplier_order 412 412 0 0.00 6 1051580 95904 0 0
sp_cancel_write_off 9945 9945 0 0.00 1 16468 107 0 0
sp_client_order 21736 21736 0 0.00 3 18231 358 0 0
sp_customer_reserve 478161 478161 0 0.00 0 92999 174 0 0
sp_fill_shopping_cart 33019 33019 0 0.00 1 14760 37 0 0
sp_fill_shopping_cart_clo_res 478143 478143 0 0.00 0 11057 12 0 0
sp_get_clo_for_invoice 5240 5240 0 0.00 0 17155 272 0 0
sp_kill_cost_storno 16932 16932 0 0.00 0 6496 51 0 0
sp_kill_qstorno_ret_qs2qd 1155698 1155698 0 0.00 0 20456 30 0 0
sp_kill_qty_storno 609797 609797 0 0.00 0 38774 64 0 0
sp_lock_selected_doc 21928 21928 0 0.00 0 6507 7 0 0
sp_make_cost_storno 29466 29466 0 0.00 0 13486 49 0 0
sp_multiply_rows_for_pdistr 29465 29465 0 0.00 0 18355 28 0 0
sp_multiply_rows_for_qdistr 21736 21736 0 0.00 0 13725 155 0 0
sp_pay_from_customer 7465 7465 0 0.00 4 11080 111 0 0
sp_pay_to_supplier 6134 6134 0 0.00 5 19183 150 0 0
sp_payment_common 13599 13599 0 0.00 1 8712 27 0 0
sp_qd_handle_on_cancel_clo 1868 1868 0 0.00 0 10053 315 0 0
sp_qd_handle_on_invoice_upd_sts 11061 11061 0 0.00 0 38770 311 0 0
sp_qd_handle_on_reserve_upd_sts 10627 10627 0 0.00 0 16037 68 0 0
sp_reserve_write_off 10627 10627 0 0.00 6 16054 184 0 0
sp_supplier_invoice 5619 5619 0 0.00 6 108542 3497 0 0
sp_supplier_order 5675 5675 0 0.00 5 81079 1282 0 0
srv_aggregate_perf_data 107 107 0 0.00 2772 149024 17874 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1075 127428 43703 0 0
srv_find_qd_qs_mism 1087354 1087354 0 0.00 0 18571 5 0 0
srv_make_invnt_saldo 614 614 0 0.00 36 46658 1076 0 0
srv_make_money_saldo 862 862 0 0.00 1 537 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 113480 436683 279339 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5258 5258 0 0.00 0 11154 172 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 2763 65 0 0
t$perf-norm:sp_cancel_client_order 1868 1868 0 0.00 0 3520 14 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 1068 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 751 751 0 0.00 0 3576 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 1359 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 1317 16 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 19243 1474 0 0
t$perf-norm:sp_cancel_write_off 1106 1106 0 0.00 0 3509 16 0 0
t$perf-norm:sp_client_order 21736 21736 0 0.00 0 12242 19 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 1498 4 0 0
t$perf-norm:sp_pay_from_customer 7465 7465 0 0.00 0 11812 11 0 0
t$perf-norm:sp_pay_to_supplier 6134 6134 0 0.00 0 9470 10 0 0
t$perf-norm:sp_reserve_write_off 10627 10627 0 0.00 0 3996 8 0 0
t$perf-norm:sp_supplier_invoice 5619 5619 0 0.00 0 15787 18 0 0
t$perf-norm:sp_supplier_order 5675 5675 0 0.00 0 13231 22 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 749 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 38 1 0 0
t$perf-norm:srv_make_invnt_saldo 614 614 0 0.00 0 1065 16 0 0
t$perf-norm:srv_make_money_saldo 862 862 0 0.00 0 5009 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 25 9 0 0
v_add_invoice_to_stock 10678 10476 202 1.89 0 15209 21 0 202
v_all_customers 39924 39924 0 0.00 0 48 1 0 0
v_all_suppliers 31460 31460 0 0.00 0 47 1 0 0
v_all_wares 43472 43472 0 0.00 0 48 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 15330 28 0 0
v_cancel_client_order 3736 3736 0 0.00 0 3753 6 0 0
v_cancel_customer_prepayment 1502 1502 0 0.00 0 6009 14 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 1860 7 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 3224 14 0 0
v_cancel_supplier_invoice 802 784 18 2.24 0 3539 18 0 18
v_cancel_supplier_order 824 824 0 0.00 0 2279 9 0 0
v_cancel_write_off 2212 2212 0 0.00 0 4425 18 0 0
v_max_id_clo_ord 5671 5671 0 0.00 0 1745 2 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 1879 9 0 0
v_max_id_ord_sup 5612 5612 0 0.00 0 7396 5 0 0
v_max_non_paid_invoice 1687 1687 0 0.00 0 4434 6 0 0
v_max_non_paid_realizn 3880 3880 0 0.00 0 4439 4 0 0
v_min_id_clo_ord 5671 5671 0 0.00 0 4720 8 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 7922 20 0 0
v_min_id_ord_sup 5612 5612 0 0.00 0 6961 8 0 0
v_min_non_paid_invoice 6134 6134 0 0.00 0 8708 19 0 0
v_min_non_paid_realizn 7465 7465 0 0.00 0 5829 18 0 0
v_our_firm 10718 10718 0 0.00 0 47 1 0 0
v_reserve_write_off 21254 21254 0 0.00 0 15330 8 0 0
x_lock_depdocs_on_canc_invoice 11857 11857 0 0.00 0 28836 112 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 10866 397 0 0
x_make_qstorno_1000_1200 5675 5675 0 0.00 2 80225 1199 0 0
x_make_qstorno_1200_2000 5619 5619 0 0.00 2 108426 3365 0 0
x_make_qstorno_2100_3300 478161 478161 0 0.00 0 92997 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-10-2021 16:39:44 to 24-10-2021 16:39:48.

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:07:58 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:08:58 28303 8192000000 9908727072 10222637056 982391072 10.707 100 88 176 0 1011465952 5981504 23093376
14:10:33 25576 8192000000 9956336640 10280046592 1037203712 11.238 100 91 182 0 1068912016 8588960 23119344
14:12:01 21152 8192000000 9988151280 10321948672 1062140640 11.477 100 84 168 0 1093322144 10267152 20914352
14:13:27 13653 8192000000 10037475792 10385059840 1117218048 12.001 100 81 162 0 1147904736 11417808 19268880
14:14:45 7355 8192000000 10067853760 10428723200 1154764816 12.354 100 68 135 1 1182403584 11614368 16024400
14:15:55 2963 8192000000 10115125904 10485149696 1208224448 12.853 100 81 162 2 1239883872 13421520 18237904
14:17:00 2924 8192000000 10161450704 10538172416 1247613296 13.216 100 91 180 1 1280439152 13394608 19431248
14:18:04 2874 8192000000 10203465792 10584547328 1283473184 13.545 100 87 173 1 1316545488 14042768 19029536
14:19:08 6945 8192000000 10224431504 10609176576 1304549600 13.737 100 92 183 1 1338119296 13324048 20245648
14:20:15 6573 8192000000 10202778416 10610659328 1310682016 13.792 100 33 65 1 1324979440 7037472 7259952
14:21:24 9214 8192000000 10207614784 10627297280 1316815984 13.848 100 25 50 1 1333965312 11509984 5639344
14:22:35 12818 8192000000 10208563136 10628169728 1323285280 13.906 100 12 24 1 1331571744 5715056 2571408
14:23:54 23330 8192000000 10245610448 10644729856 1328441088 13.953 100 72 145 3 1351488960 5487824 17560048
14:25:18 5403 8192000000 10231200896 10644611072 1318584736 13.864 99 42 85 1 1335213984 6651056 9978192
14:26:23 7237 8192000000 10223443232 10642927616 1319292992 13.870 99 25 49 3 1331173200 5814896 6065312
14:27:33 10103 8192000000 10216425408 10640957440 1327369744 13.943 100 24 49 1 1338695264 6043824 5281696
14:28:45 8019 8192000000 10179788592 10594267136 1272632656 13.446 98 20 40 0 1283299104 6331648 4334800
14:29:57 9459 8192000000 10153736832 10559688704 1232363152 13.076 97 16 32 2 1242187632 6287920 3536560
14:31:07 57674 8192000000 10162267728 10567176192 1233865360 13.090 97 21 42 1 1248373120 9208944 5298816
14:33:04 5051 8192000000 10143656992 10544709632 1254373952 13.278 100 31 60 1 1266900176 4896064 7630160
14:34:10 9991 8192000000 10178225104 10586824704 1264856944 13.375 99 23 46 0 1280294688 9961536 5476208
14:35:21 13904 8192000000 10184851344 10599309312 1294797824 13.648 100 15 30 1 1304186880 5828016 3561040
14:36:37 32097 8192000000 10192345424 10611408896 1302996224 13.722 100 39 79 3 1317281568 4624480 9660864
14:38:17 14873 8192000000 10229101840 10640429056 1326536032 13.936 100 39 78 1 1343984512 8408160 9040320
14:39:38 15956 8192000000 10255938384 10667614208 1342412240 14.079 100 58 116 1 1367680288 12491824 12776224
14:40:57 2484 8192000000 10277476240 10687963136 1357608432 14.216 100 72 145 2 1386828720 11624352 17595936
14:42:02 8733 8192000000 10279755312 10698313728 1359980832 14.237 100 53 103 2 1389817936 18137680 11699424
14:43:15 28774 8192000000 10306819376 10723696640 1372855968 14.353 100 65 130 1 1412574784 24830384 14888432
14:44:52 10200 8192000000 10302070720 10724397056 1385436320 14.465 100 56 112 2 1422341520 23570144 13335056
14:46:06 5740 8192000000 10315331392 10731184128 1387960048 14.488 100 60 119 0 1427270064 25577776 13732240
14:47:17 2999 8192000000 10333994048 10753863680 1402103792 14.614 100 60 120 0 1443715232 26293648 15317792
14:48:24 33672 8192000000 10324899488 10749865984 1393685776 14.539 99 49 97 0 1431812896 26186144 11940976
14:50:00 5600 8192000000 10332688080 10752757760 1397775088 14.575 100 52 105 0 1440596432 29591888 13229456
14:51:10 1074 8192000000 10309495872 10714542080 1360762432 14.244 99 78 156 1 1410854160 29495488 20596240
14:52:15 8029 8192000000 10321425104 10737602560 1379928624 14.416 100 68 132 3 1430208224 33020192 17259408
14:53:29 2473 8192000000 10338189824 10743128064 1376755248 14.388 100 87 173 3 1439427680 41065440 21606992
14:54:36 3730 8192000000 10341541968 10751799296 1381428016 14.429 100 73 145 1 1441680400 42024848 18227536
14:55:46 10608 8192000000 10321013104 10749120512 1375088112 14.373 99 37 72 0 1420889360 35580016 10221232
14:57:00 5731 8192000000 10332291120 10745978880 1384095424 14.453 100 63 125 2 1425494112 24977168 16421520
14:58:07 13266 8192000000 10303077888 10730885120 1360920448 14.246 99 34 68 0 1400286592 30317648 9048496
14:59:24 4041 8192000000 10315525120 10740662272 1381756272 14.432 100 45 90 1 1420822192 27038144 12027776
15:00:35 14830 8192000000 10313778432 10750005248 1375481584 14.376 99 46 91 1 1415179744 27749936 11948224
15:01:56 21657 8192000000 10306560208 10743504896 1357806592 14.218 98 19 38 0 1388625040 25518576 5299872
15:03:19 17940 8192000000 10297891840 10736173056 1356360432 14.205 100 28 52 1 1395950368 31842032 7747904
15:04:39 34754 8192000000 10290139296 10732331008 1361614608 14.252 99 16 32 0 1389978272 23744672 4618992
15:06:28 23644 8192000000 10301771312 10748260352 1361756960 14.253 99 19 38 0 1397303776 30396224 5150592
15:07:54 54083 8192000000 10276350720 10722091008 1344123936 14.095 98 6 12 0 1361499328 16247488 1127904
15:09:49 25725 8192000000 10301794640 10722271232 1374756992 14.370 100 56 108 2 1397425024 10348128 12319904
15:11:15 99765 8192000000 10289354704 10735276032 1381074416 14.426 100 33 66 1 1400323456 10852416 8396624
15:13:56 99180 8192000000 10296101696 10725728256 1326967984 13.940 95 27 55 0 1348966912 15354624 6644304
15:16:37 25243 8192000000 10306895312 10750574592 1379277088 14.410 99 24 48 0 1406175344 20627056 6271200
15:18:04 42610 8192000000 10283470784 10738356224 1357390160 14.214 98 30 60 2 1385038880 20038144 7610576
15:19:48 39989 8192000000 10275061248 10734448640 1383272144 14.446 100 14 30 0 1394842768 7405552 4165072
15:21:35 18372 8192000000 10289509600 10742775808 1382791280 14.441 99 11 22 0 1393786320 7584752 3410288
15:22:54 88716 8192000000 10300851200 10769428480 1406228400 14.650 100 21 42 1 1424627344 12735360 5663584
15:26:04 7383 8192000000 10287066400 10743369728 1385874656 14.469 100 22 44 0 1399538816 8715664 4948496
15:27:13 54805 8192000000 10290994784 10745978880 1365516640 14.287 98 26 52 1 1383548176 12296976 5734560
15:29:48 43860 8192000000 10280196656 10736066560 1328095328 13.950 96 19 38 0 1343818928 11269744 4453856
15:31:42 47022 8192000000 10298196080 10750730240 1376269680 14.383 99 20 39 1 1395341248 14751728 4319840
15:33:34 27251 8192000000 10301109904 10754416640 1393360896 14.536 100 17 34 0 1412584272 14987840 4235536
15:35:21 37357 8192000000 10307574896 10758848512 1391143008 14.516 100 33 67 0 1407853168 8608864 8101296
15:37:02 28244 8192000000 10304811632 10755973120 1384657648 14.458 99 25 50 0 1401708768 11660192 5390928
15:38:35 74915 8192000000 10315386864 10773819392 1375030736 14.372 99 14 28 0 1398526720 20422176 3073808
15:42:09 81784 8192000000 10291052560 10726760448 1307199232 13.761 94 23 46 0 1320214064 7595648 5419184
15:44:31 86796 8192000000 10272669792 10720440320 1349440608 14.142 98 20 40 0 1361061808 7097184 4524016
15:47:34 9403 8192000000 10280507568 10734669824 1364333072 14.276 99 26 52 1 1382893024 12220768 6339184
15:49:04 84722 8192000000 10255826208 10715561984 1313417584 13.817 96 11 22 1 1330312256 14438144 2456528
15:51:29 57669 8192000000 10253465232 10716311552 1345449456 14.107 98 19 38 2 1363141344 13155568 4536320
15:53:28 87298 8192000000 10278549936 10733907968 1366491312 14.296 100 15 30 0 1381617936 11720256 3406368
15:55:58 103792 8192000000 10271123008 10727399424 1323994640 13.913 96 8 16 0 1339716096 14144240 1577216
15:58:42 89391 8192000000 10276576128 10733539328 1320718496 13.883 96 12 24 1 1339700976 16209792 2772688
16:01:12 62531 8192000000 10287632432 10731819008 1357943232 14.219 99 38 76 1 1375284000 9273856 8066912
16:03:18 25829 8192000000 10287487440 10730954752 1370401600 14.331 100 34 68 1 1388500048 10665632 7432816
16:04:52 98120 8192000000 10288066672 10747727872 1348524368 14.134 98 14 28 1 1369481008 17909536 3047104
16:07:35 125876 8192000000 10266442608 10724302848 1356972656 14.210 98 23 46 0 1369291328 7299984 5018688
16:10:59 59337 8192000000 10285182608 10745094144 1371815504 14.343 99 16 32 0 1391172048 15643216 3713328
16:14:07 45706 8192000000 10277124752 10735370240 1366406048 14.295 99 13 26 1 1378882016 9356592 3119376
16:17:40 24494 8192000000 10277555872 10724569088 1326358160 13.934 96 23 46 0 1339182304 7140720 5683424
16:19:04 63126 8192000000 10273380672 10708738048 1317392320 13.853 96 19 38 0 1330735312 9181232 4161760
16:21:09 42183 8192000000 10282560704 10732867584 1365095200 14.283 100 25 50 1 1381753216 11073024 5584992
16:22:59 127428 8192000000 10278815600 10724827136 1282389696 13.535 93 18 36 0 1299518480 13166640 3962144
16:26:07 100063 8192000000 10263230800 10717339648 1305812432 13.748 96 9 18 0 1322238928 14664368 1762128
16:28:57 74937 8192000000 10248873376 10698231808 1310067072 13.787 96 21 42 0 1321442784 6052496 5323216
16:31:39 58910 8192000000 10246028816 10717687808 1348264032 14.132 99 9 18 0 1361623248 11576976 1782240
16:33:38 70387 8192000000 10255315280 10708529152 1300517488 13.700 95 12 24 0 1312596112 9274080 2804544
16:35:52 68125 8192000000 10256842048 10708717568 1343659152 14.090 99 19 38 1 1359439312 11582496 4197664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-10-2021 16:39:50 to 24-10-2021 16:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-10-2021 16:40:06 to 24-10-2021 16:40:08.

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          244889
MON$OLDEST_ACTIVE               246097
MON$OLDEST_SNAPSHOT             246097
MON$NEXT_TRANSACTION            246097
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                       2038752
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.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 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 ---
--- end of diff output ---

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

24.10.21 16:40:09. 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-10-24 16:40:11 No severe PSQL-related problems occured
24.10.21 16:40:12. End of report.