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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16354380 kB
MemAvailable 31325060 kB
Buffers 0 kB
Cached 15324516 kB
SwapCached 2440 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-01 01:38:16
DTS_END                         2022-06-01 01:38:16.9210
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/40816
TRN_ID                          251717
ATT_ID                          738
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: 9412.89 19435 1129547
customer order: creation 185.28 339 22234
customer order: refuse 15.92 311 1910
order to supplier: creation 49.80 1246 5976
order to supplier: removal 3.48 99917 418
invoice (draft): creation 49.64 3437 5957
invoice (draft): removal 50.04 884 6005
invoice accept: apply 44.35 17470 5322
invoice accept: cancel 45.23 862 5427
customer reserve: creation 4166.73 169 500007
customer reserve: removal 4487.87 61 538544
realization accept: apply 89.53 169 10743
realization accept: cancel 85.49 108 10259
payment from customer: creation 63.45 112 7614
payment from customer: removal 6.37 62 764
payment to supplier: creation 53.14 120 6377
payment to supplier: removal 3.51 69 421
service: total inventory turnovers 4.76 1104 571
service: total monetary turnovers 7.28 25 873
service: aggregate perf. data 1.01 16864 121
service: refresh index statistics 0.03 245363 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-06-2022 01:39:20 to 01-06-2022 01:39:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8341
TEST_TIME 2 5777
TEST_TIME 3 5745
TEST_TIME 4 5615
TEST_TIME 5 5581
TEST_TIME 6 5502
TEST_TIME 7 5783
TEST_TIME 8 5701
TEST_TIME 9 6156
TEST_TIME 10 6499
TEST_TIME 11 7265
TEST_TIME 12 7292
TEST_TIME 13 7935
TEST_TIME 14 7989
TEST_TIME 15 8152
TEST_TIME 16 8115
TEST_TIME 17 8169
TEST_TIME 18 8199
TEST_TIME 19 8653
TEST_TIME 20 8634
TEST_TIME 21 9214
TEST_TIME 22 9320
TEST_TIME 23 9650
TEST_TIME 24 9966
TEST_TIME 25 10326
TEST_TIME 26 11268
TEST_TIME 27 11574
TEST_TIME 28 11542
TEST_TIME 29 11521
TEST_TIME 30 11336
TEST_TIME 31 11082
TEST_TIME 32 10977
TEST_TIME 33 10954
TEST_TIME 34 10909
TEST_TIME 35 10759
TEST_TIME 36 10719
TEST_TIME 37 10588
TEST_TIME 38 10504
TEST_TIME 39 10558
TEST_TIME 40 10565
TEST_TIME 41 10463
TEST_TIME 42 10375
TEST_TIME 43 10495
TEST_TIME 44 10335
TEST_TIME 45 10294
TEST_TIME 46 10205
TEST_TIME 47 10107
TEST_TIME 48 10051
TEST_TIME 49 9998
TEST_TIME 50 9981
TEST_TIME 51 9970
TEST_TIME 52 10012
TEST_TIME 53 9941
TEST_TIME 54 9955
TEST_TIME 55 10037
TEST_TIME 56 10054
TEST_TIME 57 10035
TEST_TIME 58 10065
TEST_TIME 59 10125
TEST_TIME 60 10173
TEST_TIME 61 10135
TEST_TIME 62 10013
TEST_TIME 63 9963
TEST_TIME 64 9859
TEST_TIME 65 9855
TEST_TIME 66 9767
TEST_TIME 67 9742
TEST_TIME 68 9664
TEST_TIME 69 9691
TEST_TIME 70 9707
TEST_TIME 71 9719
TEST_TIME 72 9769
TEST_TIME 73 9756
TEST_TIME 74 9760
TEST_TIME 75 9705
TEST_TIME 76 9786
TEST_TIME 77 9800
TEST_TIME 78 9799
TEST_TIME 79 9850
TEST_TIME 80 9846
TEST_TIME 81 9819
TEST_TIME 82 9909
TEST_TIME 83 9810
TEST_TIME 84 9791
TEST_TIME 85 9726
TEST_TIME 86 9759
TEST_TIME 87 9730
TEST_TIME 88 9672
TEST_TIME 89 9667
TEST_TIME 90 9613
TEST_TIME 91 9560
TEST_TIME 92 9517
TEST_TIME 93 9497
TEST_TIME 94 9529
TEST_TIME 95 9524
TEST_TIME 96 9522
TEST_TIME 97 9505
TEST_TIME 98 9613
TEST_TIME 99 9597
TEST_TIME 100 9597
TEST_TIME 101 9674
TEST_TIME 102 9654
TEST_TIME 103 9659
TEST_TIME 104 9662
TEST_TIME 105 9681
TEST_TIME 106 9671
TEST_TIME 107 9702
TEST_TIME 108 9629
TEST_TIME 109 9602
TEST_TIME 110 9609
TEST_TIME 111 9574
TEST_TIME 112 9544
TEST_TIME 113 9500
TEST_TIME 114 9480
TEST_TIME 115 9441
TEST_TIME 116 9481
TEST_TIME 117 9459
TEST_TIME 118 9443
TEST_TIME 119 9476
TEST_TIME 120 9417

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-06-2022 01:39:23 to 01-06-2022 01:39:27.

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 1591276 1591276 0 0.00 0 19528 6 0 0
doc_list_biud 1591276 1591276 0 0.00 0 56953 26 0 0
sp_add_invoice_to_stock 5435 5322 113 2.07 52 194949 17471 0 113
sp_cancel_adding_invoice 5427 5427 0 0.00 5 80057 862 0 0
sp_cancel_client_order 1910 1910 0 0.00 4 8623 312 0 0
sp_cancel_customer_reserve 538544 538544 0 0.00 1 33899 62 0 0
sp_cancel_pay_from_customer 764 764 0 0.00 2 1879 63 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 3321 69 0 0
sp_cancel_supplier_invoice 6017 6005 12 0.19 1 56965 885 0 12
sp_cancel_supplier_order 418 418 0 0.00 5 1016728 99918 0 0
sp_cancel_write_off 10259 10259 0 0.00 1 8769 108 0 0
sp_client_order 22234 22234 0 0.00 3 28338 340 0 0
sp_customer_reserve 500007 500007 0 0.00 0 39456 169 0 0
sp_fill_shopping_cart 34147 34147 0 0.00 1 14550 41 0 0
sp_fill_shopping_cart_clo_res 500001 500001 0 0.00 0 12956 12 0 0
sp_get_clo_for_invoice 5308 5308 0 0.00 0 17170 269 0 0
sp_kill_cost_storno 16871 16871 0 0.00 0 15790 59 0 0
sp_kill_qstorno_ret_qs2qd 1093770 1093770 0 0.00 0 56946 34 0 0
sp_kill_qty_storno 578612 578612 0 0.00 0 56950 72 0 0
sp_lock_selected_doc 22289 22289 0 0.00 0 8799 7 0 0
sp_make_cost_storno 30040 30040 0 0.00 0 19523 49 0 0
sp_multiply_rows_for_pdistr 30040 30040 0 0.00 0 19163 30 0 0
sp_multiply_rows_for_qdistr 22234 22234 0 0.00 0 28326 157 0 0
sp_pay_from_customer 7614 7614 0 0.00 4 19278 113 0 0
sp_pay_to_supplier 6377 6377 0 0.00 4 14348 121 0 0
sp_payment_common 13991 13991 0 0.00 1 14265 26 0 0
sp_qd_handle_on_cancel_clo 1910 1910 0 0.00 0 8611 277 0 0
sp_qd_handle_on_invoice_upd_sts 10733 10733 0 0.00 0 22080 314 0 0
sp_qd_handle_on_reserve_upd_sts 10743 10743 0 0.00 0 25528 56 0 0
sp_reserve_write_off 10743 10743 0 0.00 7 25550 169 0 0
sp_supplier_invoice 5957 5957 0 0.00 7 85150 3438 0 0
sp_supplier_order 5976 5976 0 0.00 5 28654 1246 0 0
srv_aggregate_perf_data 121 121 0 0.00 2329 298637 16865 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1563 102106 42388 0 0
srv_find_qd_qs_mism 1077368 1077368 0 0.00 0 17744 5 0 0
srv_make_invnt_saldo 571 571 0 0.00 17 45847 1105 0 0
srv_make_money_saldo 873 873 0 0.00 0 1779 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 125512 316980 245364 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5322 5322 0 0.00 0 15969 171 0 0
t$perf-norm:sp_cancel_adding_invoice 461 461 0 0.00 1 5203 128 0 0
t$perf-norm:sp_cancel_client_order 1910 1910 0 0.00 0 1452 12 0 0
t$perf-norm:sp_cancel_customer_reserve 781 781 0 0.00 0 1153 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 764 764 0 0.00 0 4443 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 4757 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 2558 20 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 19360 1609 0 0
t$perf-norm:sp_cancel_write_off 1066 1066 0 0.00 0 6456 20 0 0
t$perf-norm:sp_client_order 22234 22234 0 0.00 0 19771 21 0 0
t$perf-norm:sp_customer_reserve 1078 1078 0 0.00 0 1445 4 0 0
t$perf-norm:sp_pay_from_customer 7614 7614 0 0.00 0 11477 10 0 0
t$perf-norm:sp_pay_to_supplier 6377 6377 0 0.00 0 8751 11 0 0
t$perf-norm:sp_reserve_write_off 10743 10743 0 0.00 0 20450 13 0 0
t$perf-norm:sp_supplier_invoice 5957 5957 0 0.00 0 16909 22 0 0
t$perf-norm:sp_supplier_order 5976 5976 0 0.00 0 4997 15 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 442 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 86 2 0 0
t$perf-norm:srv_make_invnt_saldo 571 571 0 0.00 0 2037 22 0 0
t$perf-norm:srv_make_money_saldo 873 873 0 0.00 0 1366 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 23 8 0 0
v_add_invoice_to_stock 10834 10608 226 2.08 0 5380 17 0 226
v_all_customers 40426 40426 0 0.00 0 17 1 0 0
v_all_suppliers 33170 33170 0 0.00 0 87 1 0 0
v_all_wares 44468 44468 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 922 922 0 0.00 0 88 5 0 0
v_cancel_client_order 3820 3820 0 0.00 0 3283 7 0 0
v_cancel_customer_prepayment 1528 1528 0 0.00 0 1869 6 0 0
v_cancel_customer_reserve 1562 1562 0 0.00 0 2559 5 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 2606 8 0 0
v_cancel_supplier_invoice 866 842 24 2.77 0 1160 15 0 24
v_cancel_supplier_order 836 836 0 0.00 0 2484 13 0 0
v_cancel_write_off 2132 2132 0 0.00 0 3408 16 0 0
v_max_id_clo_ord 5976 5976 0 0.00 0 554 1 0 0
v_max_id_clo_res 1078 1078 0 0.00 0 3060 10 0 0
v_max_id_ord_sup 5934 5934 0 0.00 0 6871 7 0 0
v_max_non_paid_invoice 1702 1702 0 0.00 0 10301 9 0 0
v_max_non_paid_realizn 4073 4073 0 0.00 0 4260 5 0 0
v_min_id_clo_ord 5976 5976 0 0.00 0 1683 4 0 0
v_min_id_clo_res 1078 1078 0 0.00 0 2393 8 0 0
v_min_id_ord_sup 5934 5934 0 0.00 0 5451 8 0 0
v_min_non_paid_invoice 6377 6377 0 0.00 0 10140 17 0 0
v_min_non_paid_realizn 7614 7614 0 0.00 0 7067 17 0 0
v_our_firm 11124 11124 0 0.00 0 87 1 0 0
v_reserve_write_off 21486 21486 0 0.00 0 5441 6 0 0
x_lock_depdocs_on_canc_invoice 11011 11011 0 0.00 0 25924 124 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 6689 360 0 0
x_make_qstorno_1000_1200 5976 5976 0 0.00 1 28470 1173 0 0
x_make_qstorno_1200_2000 5957 5957 0 0.00 2 82713 3284 0 0
x_make_qstorno_2100_3300 500007 500007 0 0.00 0 32171 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-06-2022 01:39:28 to 01-06-2022 01:39:32.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:20 2 8192000000 8800405056 8812199936 6660400 0.081 2 1 2 0 6779824 60640 58784
23:09:21 19411 8192000000 9898367824 10211655680 987069904 10.753 100 86 172 1 1016589024 6729744 22789376
23:10:46 10330 8192000000 9943864400 10270994432 1034007760 11.207 100 84 168 1 1064008928 8469792 21531376
23:12:02 13234 8192000000 9982433808 10314215424 1064414208 11.499 100 86 170 1 1095997872 10309920 21273744
23:13:18 20993 8192000000 10008194144 10354065408 1101461248 11.852 100 79 158 1 1131241952 10798320 18982384
23:14:42 6963 8192000000 10066280736 10421563392 1152310256 12.331 100 79 158 2 1181801280 11804560 17686464
23:15:52 6481 8192000000 10122056992 10488078336 1204406928 12.817 100 81 162 0 1236032944 13678368 17947648
23:17:02 3060 8192000000 10153826384 10528731136 1245670960 13.198 100 90 181 0 1278479776 13002528 19806288
23:18:07 1891 8192000000 10191207584 10568986624 1274819552 13.466 100 88 177 2 1308336224 13123680 20392992
23:19:11 2040 8192000000 10219943184 10603380736 1298551152 13.682 100 91 180 2 1332628080 12774720 21302208
23:20:13 4676 8192000000 10199079904 10605195264 1301515696 13.709 100 61 120 1 1322667712 5843856 15308160
23:21:21 7092 8192000000 10201091024 10617036800 1313101904 13.814 100 32 64 1 1327693792 6956784 7635104
23:22:33 10993 8192000000 10219421088 10634113024 1321038336 13.886 100 18 36 1 1330323872 4957920 4327616
23:23:44 71621 8192000000 10219337264 10639028224 1327461296 13.944 100 31 62 2 1341273776 6735824 7076656
23:25:56 6669 8192000000 10232107584 10646536192 1332930576 13.994 100 28 56 2 1349680240 10570320 6179344
23:27:04 5193 8192000000 10216900608 10636959744 1327594880 13.945 100 15 30 1 1336609984 5758960 3256144
23:28:13 50526 8192000000 10191608000 10606170112 1272233808 13.442 97 38 74 2 1287189344 6325184 8630352
23:30:05 4644 8192000000 10163678176 10572627968 1252847616 13.264 98 23 46 3 1263647968 6056144 4744208
23:31:16 5198 8192000000 10145087088 10547539968 1243256096 13.176 99 30 58 2 1255898912 5184112 7458704
23:32:26 15560 8192000000 10138360144 10537086976 1224573840 13.004 98 29 58 1 1237496512 6136208 6786464
23:33:42 14903 8192000000 10155315888 10561810432 1263762176 13.364 100 23 44 1 1275506144 6216832 5527136
23:34:59 11376 8192000000 10166327760 10582736896 1280758704 13.520 100 14 28 0 1289468144 5083072 3626368
23:36:11 9911 8192000000 10187640112 10599555072 1291976192 13.622 100 9 18 0 1299289136 5316752 1996192
23:37:21 62969 8192000000 10220767760 10628521984 1314780624 13.829 100 45 90 0 1335822240 10113888 10927728
23:39:38 7642 8192000000 10252022928 10663485440 1342405680 14.079 100 49 98 0 1362093840 8332624 11355536
23:40:49 15776 8192000000 10268732384 10684116992 1362290528 14.258 100 56 112 1 1387655488 12721904 12643056
23:42:08 34057 8192000000 10287575968 10711666688 1374421536 14.367 100 35 69 3 1393518640 10665456 8431648
23:43:45 17454 8192000000 10303971680 10722959360 1386591280 14.475 100 63 126 2 1418316528 16513744 15211504
23:45:13 19176 8192000000 10313099248 10731622400 1391130016 14.516 100 57 114 1 1425946720 21000496 13816208
23:46:57 9814 8192000000 10334072528 10753335296 1389264464 14.499 99 72 139 2 1434654592 29034368 16355760
23:48:12 5796 8192000000 10350466288 10762616832 1408642992 14.672 100 81 161 0 1455922352 28353664 18925696
23:49:22 1555 8192000000 10339476832 10753990656 1392965456 14.532 100 79 158 0 1445692096 33079888 19646752
23:50:28 7420 8192000000 10338645392 10757947392 1394700640 14.548 100 61 120 2 1447223088 36833936 15688512
23:51:40 9784 8192000000 10327545808 10755538944 1381991600 14.434 99 55 110 3 1429756128 33441856 14322672
23:52:56 1852 8192000000 10340219168 10747486208 1389433440 14.501 100 84 166 3 1443986720 34102560 20450720
23:54:02 7463 8192000000 10341589408 10756739072 1396902896 14.567 100 58 113 3 1444372032 33331888 14137248
23:55:19 3892 8192000000 10362823568 10769350656 1401626960 14.609 100 79 159 1 1461938480 40788672 19522848
23:56:28 1957 8192000000 10357583920 10761162752 1392415968 14.527 100 83 168 2 1458857952 44277152 22164832
23:57:33 3500 8192000000 10355460240 10771935232 1396691184 14.566 100 64 128 1 1460371728 46963888 16716656
23:58:50 13379 8192000000 10340580032 10763276288 1374831616 14.370 99 48 96 1 1438243872 49750880 13661376
00:00:07 15193 8192000000 10316201152 10748235776 1372996080 14.354 100 37 74 0 1429374464 45897552 10480832
00:01:28 17801 8192000000 10336629744 10772688896 1393677760 14.539 100 37 74 0 1446594560 42596992 10319808
00:02:48 18527 8192000000 10328071056 10767310848 1383160736 14.445 100 33 67 1 1431668928 39817296 8690896
00:04:10 23867 8192000000 10307668080 10748448768 1366148256 14.293 100 22 44 2 1411841904 39235104 6458544
00:06:01 74363 8192000000 10284158224 10723688448 1301941296 13.713 96 15 28 2 1326121840 21078640 3101904
00:08:16 102105 8192000000 10267033120 10711523328 1345362096 14.106 99 23 46 1 1359146400 9021168 4763136
00:11:05 66538 8192000000 10280089200 10720669696 1304639840 13.737 94 25 50 1 1321637136 11772432 5224864
00:13:21 19058 8192000000 10274632288 10730438656 1373491616 14.358 100 9 18 2 1384817680 9638000 1688064
00:14:49 9763 8192000000 10288306912 10729119744 1372859584 14.353 100 19 33 2 1385730080 8576464 4294032
00:16:00 37794 8192000000 10311412112 10752184320 1380505280 14.421 99 45 90 0 1405691744 14644048 10542416
00:17:43 57332 8192000000 10300202592 10769051648 1395897424 14.558 100 15 31 1 1414871872 14894032 4080416
00:19:56 26438 8192000000 10284911488 10746605568 1377789072 14.397 99 5 10 0 1394383248 15291152 1303024
00:21:33 42108 8192000000 10290867824 10755907584 1394912336 14.550 100 12 24 1 1409136592 10742496 3481760
00:23:16 81465 8192000000 10299004672 10774343680 1403089088 14.622 100 17 34 1 1420460784 13742544 3629152
00:25:42 46011 8192000000 10297338784 10760032256 1377714592 14.396 98 3 6 0 1387497952 9338432 444928
00:27:29 41775 8192000000 10292535168 10753798144 1383128016 14.445 99 36 72 0 1398551360 7353648 8069696
00:29:11 39390 8192000000 10291402192 10752438272 1371308928 14.339 98 9 18 0 1389125648 16040816 1775904
00:30:51 26719 8192000000 10298634016 10751291392 1382044288 14.435 99 19 38 1 1392806208 6491632 4270288
00:32:25 27961 8192000000 10281886128 10742177792 1365165952 14.284 99 20 40 1 1382408160 12527856 4714352
00:34:07 70154 8192000000 10264221904 10717589504 1285662480 13.565 93 13 26 1 1299755392 10853568 3239344
00:36:26 61136 8192000000 10284836432 10730582016 1327602560 13.945 97 22 44 0 1349249280 16708912 4937808
00:38:29 57611 8192000000 10276584672 10721861632 1320408032 13.880 96 14 26 1 1342197072 18887520 2901520
00:40:27 69199 8192000000 10253895680 10696171520 1346572128 14.117 100 19 38 0 1357733248 7116672 4044448
00:42:38 16929 8192000000 10246852176 10699513856 1342410336 14.079 99 21 42 1 1354657056 7726672 4520048
00:43:57 44520 8192000000 10251237360 10706911232 1345883232 14.110 99 6 11 0 1355396336 8333456 1179648
00:45:42 63149 8192000000 10255090704 10708234240 1326365872 13.934 97 8 17 0 1338747136 10636576 1744688
00:47:49 75264 8192000000 10251119744 10707357696 1300871024 13.703 96 17 34 0 1317225712 12548176 3806512
00:50:05 49555 8192000000 10246694400 10692476928 1281781328 13.529 94 19 38 0 1298669504 12524096 4364080
00:51:55 54229 8192000000 10264397648 10708623360 1324689696 13.919 98 18 36 2 1338467184 10176192 3601296
00:53:52 60488 8192000000 10258469488 10717671424 1333842784 14.002 98 30 60 1 1353194352 12755760 6595808
00:56:15 56853 8192000000 10279181984 10725908480 1350695264 14.154 99 20 40 0 1364334336 9437808 4201264
00:58:13 44464 8192000000 10293129616 10735939584 1359731232 14.235 99 17 34 0 1377601120 13787280 4082608
01:00:05 65619 8192000000 10278871584 10731196416 1349515648 14.143 98 19 38 0 1367919664 13759120 4644896
01:02:56 63403 8192000000 10283174992 10742468608 1377139456 14.391 100 16 32 0 1395519392 14393600 3986336
01:05:03 69907 8192000000 10273148272 10734690304 1369865008 14.326 99 15 30 2 1382425312 9155568 3404736
01:07:13 48298 8192000000 10271758304 10737700864 1366608384 14.297 99 8 16 0 1376893680 8485440 1799856
01:09:07 75948 8192000000 10287562192 10744139776 1368824848 14.317 98 24 48 1 1384961088 11075584 5060656
01:11:25 68188 8192000000 10295962800 10761527296 1399878432 14.594 100 12 25 0 1408282976 5758656 2645888
01:13:35 41559 8192000000 10326290976 10784468992 1406238272 14.651 100 19 38 1 1419723616 9069440 4415904
01:15:18 75871 8192000000 10304123712 10779451392 1378783424 14.406 97 18 36 1 1396713808 13756560 4173824
01:17:37 39688 8192000000 10314418368 10770989056 1411213888 14.695 100 30 60 0 1429863920 11685568 6964464
01:19:26 63615 8192000000 10292859824 10740727808 1326492544 13.935 95 14 28 1 1341068496 11170736 3405216
01:21:32 34272 8192000000 10291254512 10727661568 1359534912 14.233 99 44 88 3 1378637040 9516128 9586000
01:23:12 42833 8192000000 10292904416 10737373184 1337005216 14.030 98 34 68 0 1359001824 14947552 7049056
01:24:56 42874 8192000000 10257712448 10707226624 1324885440 13.921 97 15 30 0 1338911536 10552592 3473504
01:26:42 100231 8192000000 10266880208 10727915520 1365196064 14.284 100 22 44 2 1380218416 9586112 5436240
01:29:25 75573 8192000000 10260061904 10719223808 1344762432 14.100 97 22 44 1 1359812304 9494672 5555200
01:31:42 74457 8192000000 10265811936 10725019648 1340033024 14.058 97 12 24 0 1354774832 11816880 2924928
01:34:02 101381 8192000000 10271960064 10729316352 1358057616 14.220 99 7 14 0 1368339200 8850800 1430784
01:36:44 34952 8192000000 10236822288 10689327104 1319324464 13.871 98 18 34 0 1333062624 9595264 4142896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 01-06-2022 01:39:33 to 01-06-2022 01:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

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

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          250410
MON$OLDEST_ACTIVE               251980
MON$OLDEST_SNAPSHOT             251980
MON$NEXT_TRANSACTION            251980
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                       2031072
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33597 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33597 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33597 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33597 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 ---
:293: 
:294: mscs-test-161.budzdorov.ru    Wed Jun  1 01:38:21 2022
:295:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:296: 
:297: 
:298: mscs-test-161.budzdorov.ru    Wed Jun  1 01:38:21 2022
:299:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:300: 
--- end of diff output ---

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

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