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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16656204 kB
MemAvailable 31502040 kB
Buffers 0 kB
Cached 15044400 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.9
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-12-08 19:38:01
DTS_END                         2021-12-08 19:38:01.1560
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/42050
TRN_ID                          247669
ATT_ID                          691
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: 9427.28 17978 1131274
customer order: creation 182.03 350 21844
customer order: refuse 15.18 320 1822
order to supplier: creation 46.91 1295 5629
order to supplier: removal 3.61 92892 433
invoice (draft): creation 47.79 3063 5735
invoice (draft): removal 53.68 700 6442
invoice accept: apply 43.10 16904 5172
invoice accept: cancel 48.33 739 5799
customer reserve: creation 3911.55 169 469386
customer reserve: removal 4766.78 54 572014
realization accept: apply 88.26 179 10591
realization accept: cancel 84.92 117 10190
payment from customer: creation 63.13 120 7576
payment from customer: removal 5.78 79 693
payment to supplier: creation 49.91 132 5989
payment to supplier: removal 3.65 111 438
service: total inventory turnovers 5.08 1066 609
service: total monetary turnovers 6.73 31 807
service: aggregate perf. data 0.84 20125 101
service: refresh index statistics 0.03 221114 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-12-2021 19:39:41 to 08-12-2021 19:39:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6766
TEST_TIME 2 5389
TEST_TIME 3 5493
TEST_TIME 4 4709
TEST_TIME 5 4621
TEST_TIME 6 4771
TEST_TIME 7 4933
TEST_TIME 8 4993
TEST_TIME 9 4960
TEST_TIME 10 5737
TEST_TIME 11 6108
TEST_TIME 12 6904
TEST_TIME 13 7000
TEST_TIME 14 7282
TEST_TIME 15 7513
TEST_TIME 16 7714
TEST_TIME 17 7801
TEST_TIME 18 8373
TEST_TIME 19 8575
TEST_TIME 20 8522
TEST_TIME 21 8820
TEST_TIME 22 9166
TEST_TIME 23 9670
TEST_TIME 24 9409
TEST_TIME 25 9488
TEST_TIME 26 9640
TEST_TIME 27 10112
TEST_TIME 28 10793
TEST_TIME 29 10600
TEST_TIME 30 10513
TEST_TIME 31 10291
TEST_TIME 32 10449
TEST_TIME 33 10519
TEST_TIME 34 10464
TEST_TIME 35 10445
TEST_TIME 36 10526
TEST_TIME 37 10479
TEST_TIME 38 10515
TEST_TIME 39 10354
TEST_TIME 40 10152
TEST_TIME 41 10116
TEST_TIME 42 10227
TEST_TIME 43 10076
TEST_TIME 44 10018
TEST_TIME 45 9922
TEST_TIME 46 9950
TEST_TIME 47 9963
TEST_TIME 48 9936
TEST_TIME 49 9901
TEST_TIME 50 9795
TEST_TIME 51 9741
TEST_TIME 52 9702
TEST_TIME 53 9684
TEST_TIME 54 9637
TEST_TIME 55 9618
TEST_TIME 56 9685
TEST_TIME 57 9631
TEST_TIME 58 9718
TEST_TIME 59 9741
TEST_TIME 60 9805
TEST_TIME 61 9795
TEST_TIME 62 9780
TEST_TIME 63 9894
TEST_TIME 64 9850
TEST_TIME 65 9878
TEST_TIME 66 9926
TEST_TIME 67 10058
TEST_TIME 68 10023
TEST_TIME 69 10064
TEST_TIME 70 10018
TEST_TIME 71 9925
TEST_TIME 72 9906
TEST_TIME 73 9852
TEST_TIME 74 9778
TEST_TIME 75 9786
TEST_TIME 76 9724
TEST_TIME 77 9648
TEST_TIME 78 9623
TEST_TIME 79 9555
TEST_TIME 80 9526
TEST_TIME 81 9608
TEST_TIME 82 9614
TEST_TIME 83 9620
TEST_TIME 84 9589
TEST_TIME 85 9670
TEST_TIME 86 9661
TEST_TIME 87 9753
TEST_TIME 88 9746
TEST_TIME 89 9698
TEST_TIME 90 9794
TEST_TIME 91 9752
TEST_TIME 92 9775
TEST_TIME 93 9822
TEST_TIME 94 9780
TEST_TIME 95 9703
TEST_TIME 96 9711
TEST_TIME 97 9682
TEST_TIME 98 9654
TEST_TIME 99 9632
TEST_TIME 100 9602
TEST_TIME 101 9580
TEST_TIME 102 9593
TEST_TIME 103 9536
TEST_TIME 104 9523
TEST_TIME 105 9512
TEST_TIME 106 9480
TEST_TIME 107 9529
TEST_TIME 108 9531
TEST_TIME 109 9574
TEST_TIME 110 9582
TEST_TIME 111 9589
TEST_TIME 112 9562
TEST_TIME 113 9571
TEST_TIME 114 9600
TEST_TIME 115 9652
TEST_TIME 116 9622
TEST_TIME 117 9629
TEST_TIME 118 9587
TEST_TIME 119 9520
TEST_TIME 120 9441

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-12-2021 19:39:44 to 08-12-2021 19:39:48.

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 1568469 1568469 0 0.00 0 22172 6 0 0
doc_list_biud 1568468 1568468 0 0.00 0 49860 25 0 0
sp_add_invoice_to_stock 5269 5172 97 1.84 45 214917 16904 0 97
sp_cancel_adding_invoice 5799 5799 0 0.00 5 53510 740 0 0
sp_cancel_client_order 1822 1822 0 0.00 3 7705 320 0 0
sp_cancel_customer_reserve 572014 572014 0 0.00 1 49861 55 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 3 3053 79 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 7046 112 0 0
sp_cancel_supplier_invoice 6452 6442 10 0.15 2 22922 701 0 10
sp_cancel_supplier_order 433 433 0 0.00 7 1074996 92893 0 0
sp_cancel_write_off 10190 10190 0 0.00 1 12002 117 0 0
sp_client_order 21844 21844 0 0.00 3 21374 350 0 0
sp_customer_reserve 469386 469386 0 0.00 0 81257 169 0 0
sp_fill_shopping_cart 33204 33204 0 0.00 1 22694 39 0 0
sp_fill_shopping_cart_clo_res 469363 469363 0 0.00 0 16920 12 0 0
sp_get_clo_for_invoice 5149 5149 0 0.00 0 42503 284 0 0
sp_kill_cost_storno 17120 17120 0 0.00 0 11896 58 0 0
sp_kill_qstorno_ret_qs2qd 1161093 1161093 0 0.00 0 49858 30 0 0
sp_kill_qty_storno 612440 612440 0 0.00 0 49860 64 0 0
sp_lock_selected_doc 21784 21784 0 0.00 0 9753 6 0 0
sp_make_cost_storno 29305 29305 0 0.00 0 22163 57 0 0
sp_multiply_rows_for_pdistr 29305 29305 0 0.00 0 16314 33 0 0
sp_multiply_rows_for_qdistr 21844 21844 0 0.00 0 19682 166 0 0
sp_pay_from_customer 7576 7576 0 0.00 4 12461 120 0 0
sp_pay_to_supplier 5989 5989 0 0.00 4 16396 132 0 0
sp_payment_common 13565 13565 0 0.00 2 7547 29 0 0
sp_qd_handle_on_cancel_clo 1822 1822 0 0.00 0 7697 276 0 0
sp_qd_handle_on_invoice_upd_sts 10948 10948 0 0.00 0 14474 302 0 0
sp_qd_handle_on_reserve_upd_sts 10591 10591 0 0.00 0 17035 57 0 0
sp_reserve_write_off 10591 10591 0 0.00 6 17078 180 0 0
sp_supplier_invoice 5735 5735 0 0.00 4 73983 3064 0 0
sp_supplier_order 5629 5629 0 0.00 5 57633 1295 0 0
srv_aggregate_perf_data 101 101 0 0.00 2816 132000 20125 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1949 138435 50336 0 0
srv_find_qd_qs_mism 1083119 1083119 0 0.00 0 19576 5 0 0
srv_make_invnt_saldo 609 609 0 0.00 48 87483 1067 0 0
srv_make_money_saldo 807 807 0 0.00 1 4926 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 154079 364337 221115 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 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 5172 5172 0 0.00 0 13945 170 0 0
t$perf-norm:sp_cancel_adding_invoice 470 470 0 0.00 1 1875 58 0 0
t$perf-norm:sp_cancel_client_order 1822 1822 0 0.00 0 6716 21 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 843 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 2065 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 10006 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 615 10 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 26036 1520 0 0
t$perf-norm:sp_cancel_write_off 1044 1044 0 0.00 0 2651 10 0 0
t$perf-norm:sp_client_order 21844 21844 0 0.00 0 9422 19 0 0
t$perf-norm:sp_customer_reserve 1084 1084 0 0.00 0 6797 16 0 0
t$perf-norm:sp_pay_from_customer 7576 7576 0 0.00 0 8276 11 0 0
t$perf-norm:sp_pay_to_supplier 5989 5989 0 0.00 0 4903 10 0 0
t$perf-norm:sp_reserve_write_off 10591 10591 0 0.00 0 5843 11 0 0
t$perf-norm:sp_supplier_invoice 5735 5735 0 0.00 0 8757 19 0 0
t$perf-norm:sp_supplier_order 5629 5629 0 0.00 0 6804 17 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 452 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 609 609 0 0.00 0 1489 24 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 7529 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10492 10298 194 1.84 0 5656 17 0 194
v_all_customers 39832 39832 0 0.00 0 51 1 0 0
v_all_suppliers 31468 31468 0 0.00 0 16 1 0 0
v_all_wares 43688 43688 0 0.00 0 257 1 0 0
v_cancel_adding_invoice 940 940 0 0.00 0 1180 8 0 0
v_cancel_client_order 3644 3644 0 0.00 0 2979 7 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 2875 10 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 3172 10 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 2826 11 0 0
v_cancel_supplier_invoice 872 852 20 2.29 0 4454 30 0 20
v_cancel_supplier_order 866 866 0 0.00 0 1922 7 0 0
v_cancel_write_off 2088 2088 0 0.00 0 3526 15 0 0
v_max_id_clo_ord 5629 5629 0 0.00 0 6073 3 0 0
v_max_id_clo_res 1084 1084 0 0.00 0 2248 12 0 0
v_max_id_ord_sup 5731 5731 0 0.00 0 2885 2 0 0
v_max_non_paid_invoice 1615 1615 0 0.00 0 2157 4 0 0
v_max_non_paid_realizn 3970 3970 0 0.00 0 6316 6 0 0
v_min_id_clo_ord 5629 5629 0 0.00 0 19629 23 0 0
v_min_id_clo_res 1084 1084 0 0.00 0 7157 16 0 0
v_min_id_ord_sup 5731 5731 0 0.00 0 7669 6 0 0
v_min_non_paid_invoice 5989 5989 0 0.00 0 5465 20 0 0
v_min_non_paid_realizn 7576 7576 0 0.00 0 5366 19 0 0
v_our_firm 11068 11068 0 0.00 0 130 1 0 0
v_reserve_write_off 21182 21182 0 0.00 0 3174 7 0 0
x_lock_depdocs_on_canc_invoice 11815 11815 0 0.00 0 38710 134 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 10073 338 0 0
x_make_qstorno_1000_1200 5629 5629 0 0.00 1 57589 1201 0 0
x_make_qstorno_1200_2000 5735 5735 0 0.00 2 72451 2928 0 0
x_make_qstorno_2100_3300 469386 469386 0 0.00 0 81255 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-12-2021 19:39:49 to 08-12-2021 19:39:53.

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
17:08:04 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:05 15161 8192000000 9876955792 10186661888 966559040 10.553 100 86 172 0 995619520 5672064 23388416
17:10:28 32398 8192000000 9941444352 10261512192 1022101760 11.092 100 87 174 0 1052457280 8180816 22174704
17:12:06 13248 8192000000 9987475584 10323910656 1072534720 11.576 100 83 167 0 1102524240 9464416 20525104
17:13:24 9814 8192000000 10036945568 10384584704 1120397280 12.031 100 81 161 0 1151326224 11672464 19256480
17:14:42 7550 8192000000 10088864192 10450034688 1171220256 12.508 100 86 172 0 1204114544 13155488 19738800
17:15:53 4815 8192000000 10125959024 10492960768 1209477264 12.864 100 87 174 0 1242593216 13658048 19457904
17:17:01 2760 8192000000 10160948400 10536878080 1249388576 13.233 100 85 170 1 1280406128 12239680 18777872
17:18:05 1846 8192000000 10203940688 10586599424 1285650000 13.565 100 93 186 0 1320344048 14752208 19941840
17:19:08 30808 8192000000 10202529872 10606505984 1309527440 13.782 100 52 104 0 1327700208 5251504 12921264
17:20:39 10615 8192000000 10194537120 10613858304 1313364960 13.817 100 22 44 0 1325069392 6723168 4981264
17:21:50 11257 8192000000 10212186960 10628362240 1325264496 13.924 100 18 35 1 1334602960 5394288 3944176
17:23:03 9245 8192000000 10218256992 10642812928 1337452576 14.034 100 14 28 0 1346428688 5803616 3172496
17:24:13 11671 8192000000 10221503744 10646278144 1341739936 14.073 100 12 24 0 1349195808 5086112 2369760
17:25:28 31986 8192000000 10244506496 10666049536 1344518656 14.098 100 26 52 1 1356380672 6211136 5650880
17:27:00 8425 8192000000 10231500864 10649370624 1326178528 13.933 99 30 61 0 1339780992 6246496 7355968
17:28:11 7551 8192000000 10208893872 10625613824 1309699728 13.783 99 21 40 0 1319873952 5528960 4645264
17:29:22 9148 8192000000 10167806352 10571038720 1229452624 13.049 95 28 56 1 1242008176 5859552 6696000
17:30:33 13778 8192000000 10171734736 10581729280 1270296864 13.424 99 43 86 2 1286325200 5567792 10460544
17:31:47 4676 8192000000 10130375024 10527608832 1231145760 13.065 99 35 71 0 1245427856 6067856 8214240
17:32:55 5081 8192000000 10166441792 10563076096 1261994848 13.348 100 39 78 0 1275187680 4351280 8841552
17:34:01 11753 8192000000 10183446544 10592497664 1287377552 13.580 100 18 36 1 1296813552 5124624 4311376
17:35:13 27012 8192000000 10187098816 10601906176 1295502464 13.654 100 44 89 1 1312008688 5474848 11031376
17:36:40 6751 8192000000 10205431392 10617516032 1310054864 13.787 100 35 71 0 1325577952 7736272 7786816
17:37:50 6204 8192000000 10215255104 10630881280 1320995552 13.886 100 31 62 2 1334373376 6317184 7060640
17:39:10 25998 8192000000 10258965856 10669625344 1349975136 14.147 100 43 85 3 1366712656 7427168 9310352
17:40:41 38221 8192000000 10281754880 10702409728 1379797584 14.415 100 45 90 1 1401375184 11081312 10496288
17:42:26 120851 8192000000 10295368544 10735423488 1385981056 14.470 100 23 45 1 1403585728 11682496 5922176
17:45:38 9114 8192000000 10324728096 10745008128 1401008016 14.604 100 55 109 1 1433792016 19264400 13519600
17:46:54 14563 8192000000 10322507488 10743607296 1388149584 14.489 99 44 89 0 1421104992 21505536 11449872
17:48:15 4651 8192000000 10339294512 10756263936 1400421104 14.599 100 74 146 3 1446170192 27399248 18349840
17:49:25 2987 8192000000 10336276112 10759626752 1402884080 14.621 100 65 128 0 1444463616 25892112 15687424
17:50:31 1947 8192000000 10351858544 10771542016 1413666080 14.717 100 77 153 1 1453755920 21117808 18972032
17:51:40 5505 8192000000 10360511216 10781835264 1419927488 14.772 100 68 134 0 1464694016 28445104 16321424
17:52:56 3063 8192000000 10349047584 10764640256 1403964672 14.630 100 77 153 1 1451592464 28157904 19469888
17:54:03 5548 8192000000 10338623680 10747613184 1386476272 14.474 100 86 167 0 1439738960 32529984 20732704
17:55:15 7278 8192000000 10338061648 10749710336 1386163616 14.472 100 68 137 0 1440624832 37174032 17287184
17:56:26 16967 8192000000 10318765392 10742640640 1367669648 14.306 99 38 76 1 1416844288 38544464 10630176
17:57:47 2405 8192000000 10345532080 10756534272 1385402400 14.465 100 79 156 1 1448344256 41906848 21035008
17:58:53 15059 8192000000 10317895104 10734870528 1336086880 14.022 96 34 68 2 1394257488 48384432 9786176
18:00:18 17619 8192000000 10320545568 10749657088 1365174912 14.284 99 28 56 0 1420272160 46995664 8101584
18:01:42 30000 8192000000 10324805152 10758840320 1381664976 14.431 100 28 56 1 1435280560 45880496 7735088
18:03:16 26547 8192000000 10304944048 10741264384 1372047200 14.345 100 18 36 0 1412141328 35213392 4880736
18:05:19 26126 8192000000 10303248000 10747002880 1349390592 14.142 99 14 28 0 1397550192 43621248 4538352
18:06:47 51351 8192000000 10287757408 10722553856 1365941024 14.291 100 23 46 1 1382829040 11576016 5312000
18:08:52 81826 8192000000 10285426256 10721370112 1343179568 14.086 97 23 47 1 1369071232 20533824 5357840
18:11:17 122392 8192000000 10292911264 10741493760 1347834176 14.128 97 14 27 0 1366265712 15576720 2854816
18:14:23 138435 8192000000 10304779648 10752643072 1309660272 13.783 94 5 10 0 1322995888 12581584 754032
18:17:45 101694 8192000000 10306364400 10752991232 1384618688 14.458 100 27 54 1 1410368240 19326352 6423200
18:20:39 50558 8192000000 10299593920 10749870080 1390532992 14.511 100 36 72 0 1410585920 11950720 8102208
18:22:48 13504 8192000000 10311776016 10758938624 1389703056 14.503 99 33 65 1 1407728640 10110640 7914944
18:24:07 23893 8192000000 10323008464 10771251200 1402643488 14.619 100 31 62 0 1423821360 13556496 7621376
18:25:32 65504 8192000000 10300360976 10757287936 1387620480 14.485 99 15 30 1 1406406336 15038176 3747680
18:27:38 39105 8192000000 10312813488 10779230208 1410799648 14.691 100 8 16 1 1426467968 13434736 2233584
18:29:18 57115 8192000000 10308611776 10773639168 1408152416 14.668 100 17 34 0 1425824672 13935856 3736400
18:31:21 48532 8192000000 10295344880 10758266880 1377555728 14.395 98 19 38 1 1390964048 9067376 4340944
18:33:11 64156 8192000000 10302696784 10776473600 1383935296 14.452 99 12 24 1 1407207936 20515776 2756864
18:35:15 47759 8192000000 10292355120 10754375680 1345103728 14.103 96 18 36 2 1361195904 11844080 4248096
18:37:05 63361 8192000000 10278993648 10739933184 1338771008 14.046 97 20 40 0 1358249264 14749088 4729168
18:39:49 16163 8192000000 10276797472 10711805952 1349771824 14.145 98 51 102 0 1369434496 8878208 10784464
18:41:09 78130 8192000000 10267938192 10718105600 1305176624 13.742 95 20 40 0 1323265728 13582544 4506560
18:43:30 69344 8192000000 10257138160 10705768448 1271466976 13.435 93 14 28 0 1286867808 12149968 3250864
18:45:40 64920 8192000000 10270224384 10710351872 1327714464 13.946 98 16 32 0 1342924592 11680848 3529280
18:48:04 17758 8192000000 10275881712 10714054656 1360206576 14.239 100 33 66 0 1379433888 11048896 8178416
18:49:24 122397 8192000000 10263885072 10716143616 1339965024 14.057 98 29 58 0 1360413520 13442288 7006208
18:52:35 37478 8192000000 10254620688 10709901312 1353059280 14.175 99 30 56 5 1367508192 8195088 6253824
18:54:15 51883 8192000000 10262762544 10714615808 1340160960 14.059 98 15 30 0 1351035808 6794672 4080176
18:56:11 93800 8192000000 10248654000 10703450112 1339615808 14.054 98 18 36 1 1357933792 12841392 5476592
18:59:02 65900 8192000000 10255786624 10717769728 1329848160 13.966 98 11 22 0 1350190112 17237616 3104336
19:01:08 45421 8192000000 10273617472 10726739968 1351765248 14.163 99 16 32 0 1363573120 8637328 3170544
19:02:57 38241 8192000000 10270008976 10718371840 1351848480 14.164 99 24 48 1 1371644768 13711696 6084592
19:04:38 59958 8192000000 10288275360 10749390848 1377087840 14.391 100 24 48 0 1394378640 12136864 5153936
19:06:39 59040 8192000000 10306161888 10747592704 1387924432 14.487 100 26 52 0 1403719824 10243168 5552224
19:08:43 57219 8192000000 10296451616 10737713152 1336927984 14.030 96 18 34 3 1347932112 7550368 3453760
19:10:46 86588 8192000000 10284088784 10749075456 1318134352 13.860 95 9 18 1 1342907056 22789648 1983056
19:14:03 91723 8192000000 10290355072 10754736128 1370586496 14.332 99 15 30 0 1383929424 10041744 3301184
19:16:35 65820 8192000000 10288827216 10751492096 1391873520 14.523 100 17 34 0 1402545424 7084640 3587264
19:18:43 83478 8192000000 10288581264 10755305472 1374530064 14.368 98 10 20 1 1384068016 7411200 2126752
19:23:27 50308 8192000000 10267453040 10716127232 1301191200 13.706 93 12 24 0 1311002336 7007680 2803456
19:25:17 88195 8192000000 10275781856 10739277824 1344173680 14.095 98 16 32 0 1360181888 12187520 3820688
19:27:45 118117 8192000000 10259635888 10698342400 1252383808 13.260 91 7 14 0 1262497472 8829120 1284544
19:30:44 21040 8192000000 10290962560 10722926592 1351081344 14.157 99 43 84 3 1370619536 10451536 9086656
19:32:10 42777 8192000000 10280418096 10722570240 1325509440 13.927 97 33 66 0 1347859408 15016048 7333920
19:33:53 45824 8192000000 10264157760 10701664256 1322540272 13.900 97 18 36 1 1333931200 7790624 3600304
19:35:40 104930 8192000000 10274284848 10730708992 1375609680 14.377 100 16 32 1 1389545920 10525248 3410992

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 08-12-2021 19:39:55 to 08-12-2021 19:40:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 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 08-12-2021 19:40:10 to 08-12-2021 19:40:12.

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          246963
MON$OLDEST_ACTIVE               247908
MON$OLDEST_SNAPSHOT             247908
MON$NEXT_TRANSACTION            247908
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                       2035680
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33542 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33542 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33542 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33542 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 ---
:103: 
:104: mscs-test-161.budzdorov.ru    Wed Dec  8 19:38:19 2021
:105:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:106: 
:107: 
:108: mscs-test-161.budzdorov.ru    Wed Dec  8 19:38:19 2021
:109:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:110: 
:111: 
:112: mscs-test-161.budzdorov.ru    Wed Dec  8 19:38:19 2021
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:114: 
:115: 
:116: mscs-test-161.budzdorov.ru    Wed Dec  8 19:38:19 2021
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:118: 
--- end of diff output ---

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

08.12.21 19:40:13. 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-12-08 19:40:16 No severe PSQL-related problems occured
08.12.21 19:40:17. End of report.