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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-08-15 01:38:22
DTS_END                         2022-08-15 01:38:22.7600
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/42982
TRN_ID                          249369
ATT_ID                          714
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: 9730.29 19043 1167635
customer order: creation 186.93 325 22431
customer order: refuse 15.56 320 1867
order to supplier: creation 50.25 1206 6030
order to supplier: removal 3.54 93917 425
invoice (draft): creation 48.93 3021 5871
invoice (draft): removal 56.12 722 6734
invoice accept: apply 44.33 17466 5319
invoice accept: cancel 50.73 692 6088
customer reserve: creation 3997.70 177 479724
customer reserve: removal 4953.18 53 594382
realization accept: apply 91.58 177 10990
realization accept: cancel 91.71 100 11005
payment from customer: creation 64.00 95 7680
payment from customer: removal 6.05 83 726
payment to supplier: creation 53.03 107 6363
payment to supplier: removal 3.59 73 431
service: total inventory turnovers 5.24 1219 629
service: total monetary turnovers 7.13 30 855
service: aggregate perf. data 0.68 28518 81
service: refresh index statistics 0.03 232566 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-08-2022 01:39:10 to 15-08-2022 01:39:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5064
TEST_TIME 2 5142
TEST_TIME 3 6494
TEST_TIME 4 6122
TEST_TIME 5 5866
TEST_TIME 6 5587
TEST_TIME 7 5784
TEST_TIME 8 5814
TEST_TIME 9 6004
TEST_TIME 10 6606
TEST_TIME 11 6683
TEST_TIME 12 7748
TEST_TIME 13 7932
TEST_TIME 14 8129
TEST_TIME 15 8753
TEST_TIME 16 8708
TEST_TIME 17 8980
TEST_TIME 18 8824
TEST_TIME 19 8754
TEST_TIME 20 9294
TEST_TIME 21 9208
TEST_TIME 22 9188
TEST_TIME 23 9440
TEST_TIME 24 9690
TEST_TIME 25 9938
TEST_TIME 26 10094
TEST_TIME 27 10284
TEST_TIME 28 10248
TEST_TIME 29 11054
TEST_TIME 30 11136
TEST_TIME 31 10974
TEST_TIME 32 11090
TEST_TIME 33 11050
TEST_TIME 34 10787
TEST_TIME 35 10873
TEST_TIME 36 10705
TEST_TIME 37 10607
TEST_TIME 38 10503
TEST_TIME 39 10588
TEST_TIME 40 10449
TEST_TIME 41 10611
TEST_TIME 42 10410
TEST_TIME 43 10327
TEST_TIME 44 10314
TEST_TIME 45 10214
TEST_TIME 46 10250
TEST_TIME 47 10452
TEST_TIME 48 10392
TEST_TIME 49 10385
TEST_TIME 50 10279
TEST_TIME 51 10208
TEST_TIME 52 10131
TEST_TIME 53 9971
TEST_TIME 54 9918
TEST_TIME 55 9919
TEST_TIME 56 9840
TEST_TIME 57 9800
TEST_TIME 58 9709
TEST_TIME 59 9711
TEST_TIME 60 9631
TEST_TIME 61 9652
TEST_TIME 62 9647
TEST_TIME 63 9675
TEST_TIME 64 9796
TEST_TIME 65 9804
TEST_TIME 66 9831
TEST_TIME 67 9868
TEST_TIME 68 9917
TEST_TIME 69 9899
TEST_TIME 70 9976
TEST_TIME 71 10002
TEST_TIME 72 10039
TEST_TIME 73 10058
TEST_TIME 74 10081
TEST_TIME 75 10030
TEST_TIME 76 9977
TEST_TIME 77 9943
TEST_TIME 78 9929
TEST_TIME 79 9900
TEST_TIME 80 9853
TEST_TIME 81 9859
TEST_TIME 82 9805
TEST_TIME 83 9773
TEST_TIME 84 9714
TEST_TIME 85 9697
TEST_TIME 86 9691
TEST_TIME 87 9734
TEST_TIME 88 9765
TEST_TIME 89 9734
TEST_TIME 90 9693
TEST_TIME 91 9794
TEST_TIME 92 9804
TEST_TIME 93 9798
TEST_TIME 94 9857
TEST_TIME 95 9845
TEST_TIME 96 9852
TEST_TIME 97 9820
TEST_TIME 98 9929
TEST_TIME 99 9882
TEST_TIME 100 9821
TEST_TIME 101 9767
TEST_TIME 102 9727
TEST_TIME 103 9742
TEST_TIME 104 9718
TEST_TIME 105 9683
TEST_TIME 106 9678
TEST_TIME 107 9678
TEST_TIME 108 9644
TEST_TIME 109 9646
TEST_TIME 110 9677
TEST_TIME 111 9653
TEST_TIME 112 9633
TEST_TIME 113 9634
TEST_TIME 114 9785
TEST_TIME 115 9785
TEST_TIME 116 9765
TEST_TIME 117 9781
TEST_TIME 118 9804
TEST_TIME 119 9779
TEST_TIME 120 9734

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-08-2022 01:39:13 to 15-08-2022 01:39:16.

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 1612746 1612746 0 0.00 0 11867 6 0 0
doc_list_biud 1612746 1612746 0 0.00 0 22903 25 0 0
sp_add_invoice_to_stock 5482 5319 163 2.97 38 203269 17466 0 163
sp_cancel_adding_invoice 6088 6088 0 0.00 5 60444 692 0 0
sp_cancel_client_order 1867 1867 0 0.00 4 11760 321 0 0
sp_cancel_customer_reserve 594382 594382 0 0.00 0 22672 53 0 0
sp_cancel_pay_from_customer 726 726 0 0.00 2 5065 83 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 2196 73 0 0
sp_cancel_supplier_invoice 6749 6734 15 0.22 1 22946 722 0 15
sp_cancel_supplier_order 425 425 0 0.00 9 875832 93917 0 0
sp_cancel_write_off 11005 11005 0 0.00 1 17320 101 0 0
sp_client_order 22431 22431 0 0.00 3 32038 325 0 0
sp_customer_reserve 479724 479724 0 0.00 0 40209 178 0 0
sp_fill_shopping_cart 34330 34330 0 0.00 1 17858 36 0 0
sp_fill_shopping_cart_clo_res 479723 479723 0 0.00 0 15245 13 0 0
sp_get_clo_for_invoice 5314 5314 0 0.00 0 33599 280 0 0
sp_kill_cost_storno 18250 18250 0 0.00 0 10096 49 0 0
sp_kill_qstorno_ret_qs2qd 1206928 1206928 0 0.00 0 22882 29 0 0
sp_kill_qty_storno 636803 636803 0 0.00 0 22896 62 0 0
sp_lock_selected_doc 22421 22421 0 0.00 0 16457 7 0 0
sp_make_cost_storno 30345 30345 0 0.00 0 11855 47 0 0
sp_multiply_rows_for_pdistr 30345 30345 0 0.00 0 6520 26 0 0
sp_multiply_rows_for_qdistr 22431 22431 0 0.00 0 32027 153 0 0
sp_pay_from_customer 7680 7680 0 0.00 4 14308 95 0 0
sp_pay_to_supplier 6363 6363 0 0.00 4 9331 108 0 0
sp_payment_common 14043 14043 0 0.00 1 14302 22 0 0
sp_qd_handle_on_cancel_clo 1867 1867 0 0.00 0 11727 295 0 0
sp_qd_handle_on_invoice_upd_sts 11400 11400 0 0.00 0 14625 295 0 0
sp_qd_handle_on_reserve_upd_sts 10990 10990 0 0.00 0 13162 61 0 0
sp_reserve_write_off 10990 10990 0 0.00 6 18066 177 0 0
sp_supplier_invoice 5871 5871 0 0.00 6 80805 3021 0 0
sp_supplier_order 6030 6030 0 0.00 4 51460 1207 0 0
srv_aggregate_perf_data 81 81 0 0.00 2905 768792 28519 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1322 121754 40414 0 0
srv_find_qd_qs_mism 1117176 1117176 0 0.00 0 9483 5 0 0
srv_make_invnt_saldo 629 629 0 0.00 38 56784 1219 0 0
srv_make_money_saldo 855 855 0 0.00 0 705 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 121472 289960 232566 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5319 5319 0 0.00 0 16249 172 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 4040 106 0 0
t$perf-norm:sp_cancel_client_order 1867 1867 0 0.00 0 2212 13 0 0
t$perf-norm:sp_cancel_customer_reserve 733 733 0 0.00 0 2608 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 726 726 0 0.00 0 595 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 937 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 6217 34 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 24385 1623 0 0
t$perf-norm:sp_cancel_write_off 1082 1082 0 0.00 0 2896 12 0 0
t$perf-norm:sp_client_order 22431 22431 0 0.00 0 6776 17 0 0
t$perf-norm:sp_customer_reserve 1093 1093 0 0.00 0 953 5 0 0
t$perf-norm:sp_pay_from_customer 7680 7680 0 0.00 0 6853 14 0 0
t$perf-norm:sp_pay_to_supplier 6363 6363 0 0.00 0 8738 12 0 0
t$perf-norm:sp_reserve_write_off 10990 10990 0 0.00 0 13538 11 0 0
t$perf-norm:sp_supplier_invoice 5871 5871 0 0.00 0 5804 21 0 0
t$perf-norm:sp_supplier_order 6030 6030 0 0.00 0 4448 15 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 79 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 1174 22 0 0
t$perf-norm:srv_make_invnt_saldo 629 629 0 0.00 0 2901 25 0 0
t$perf-norm:srv_make_money_saldo 855 855 0 0.00 0 4031 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10950 10624 326 2.97 0 5820 18 0 326
v_all_customers 41348 41348 0 0.00 0 68 1 0 0
v_all_suppliers 32732 32732 0 0.00 0 36 1 0 0
v_all_wares 44862 44862 0 0.00 0 34 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 1430 9 0 0
v_cancel_client_order 3734 3734 0 0.00 0 1840 4 0 0
v_cancel_customer_prepayment 1452 1452 0 0.00 0 2402 9 0 0
v_cancel_customer_reserve 1466 1466 0 0.00 0 1169 4 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 2183 5 0 0
v_cancel_supplier_invoice 866 836 30 3.46 0 5448 29 0 30
v_cancel_supplier_order 850 850 0 0.00 0 1855 10 0 0
v_cancel_write_off 2164 2164 0 0.00 0 6642 17 0 0
v_max_id_clo_ord 6030 6030 0 0.00 0 228 1 0 0
v_max_id_clo_res 1093 1093 0 0.00 0 3632 19 0 0
v_max_id_ord_sup 5869 5869 0 0.00 0 2499 3 0 0
v_max_non_paid_invoice 1896 1896 0 0.00 0 504 2 0 0
v_max_non_paid_realizn 4071 4071 0 0.00 0 1628 3 0 0
v_min_id_clo_ord 6030 6030 0 0.00 0 2802 7 0 0
v_min_id_clo_res 1093 1093 0 0.00 0 2724 8 0 0
v_min_id_ord_sup 5869 5869 0 0.00 0 7944 8 0 0
v_min_non_paid_invoice 6363 6363 0 0.00 0 6500 12 0 0
v_min_non_paid_realizn 7680 7680 0 0.00 0 14299 18 0 0
v_our_firm 10732 10732 0 0.00 0 25 1 0 0
v_reserve_write_off 21980 21980 0 0.00 0 5000 6 0 0
x_lock_depdocs_on_canc_invoice 12404 12404 0 0.00 0 13606 99 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 15366 374 0 0
x_make_qstorno_1000_1200 6030 6030 0 0.00 1 51418 1121 0 0
x_make_qstorno_1200_2000 5871 5871 0 0.00 3 80796 2899 0 0
x_make_qstorno_2100_3300 479724 479724 0 0.00 0 35908 162 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 15-08-2022 01:39:17 to 15-08-2022 01:39:22.

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:26 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:27 26369 8192000000 9903034944 10215002112 980556528 10.690 100 89 178 0 1010331472 5974192 23800752
23:11:00 21492 8192000000 9945642672 10267176960 1021274448 11.084 100 90 180 0 1052984704 8203152 23507104
23:12:34 17462 8192000000 9980081648 10312613888 1064312128 11.498 100 84 167 1 1095356416 9989888 21054400
23:13:53 13690 8192000000 10044469760 10388639744 1118230032 12.010 100 78 156 0 1147418752 11176864 18011856
23:15:12 6418 8192000000 10073860528 10429620224 1163954176 12.440 100 86 172 0 1197416304 13249248 20212880
23:16:26 3879 8192000000 10123662064 10492735488 1209150144 12.861 100 76 152 0 1239817088 13735872 16931072
23:17:31 1554 8192000000 10161105392 10534445056 1251040640 13.248 100 83 165 0 1281586192 12806576 17738976
23:18:34 2166 8192000000 10179630656 10563719168 1276198400 13.478 100 75 150 0 1304772512 12656592 15917520
23:19:39 32496 8192000000 10203541120 10601291776 1301004704 13.704 100 61 122 0 1325200288 10607840 13587744
23:21:12 3784 8192000000 10207244608 10610847744 1308724048 13.774 100 52 105 0 1329193872 8700400 11769424
23:22:18 6624 8192000000 10201043712 10618802176 1317242960 13.852 100 37 75 0 1333933200 8313504 8376736
23:23:25 47956 8192000000 10214905952 10634436608 1322751696 13.902 100 24 47 0 1333628096 5756352 5120048
23:25:17 6399 8192000000 10217368784 10641715200 1329647984 13.964 100 21 42 0 1340724000 6348096 4727920
23:26:26 9095 8192000000 10228225360 10654126080 1340472640 14.062 100 17 34 1 1350047664 5779360 3795664
23:27:36 69332 8192000000 10218851456 10633469952 1310622336 13.792 98 40 79 0 1325641184 5695568 9323280
23:29:46 4559 8192000000 10197012256 10603065344 1289780576 13.602 99 41 80 0 1306476400 6419360 10276464
23:30:54 9373 8192000000 10157761536 10559250432 1224734176 13.005 96 19 37 1 1234157024 5042448 4380400
23:32:04 26203 8192000000 10133651920 10538360832 1218003008 12.943 97 17 34 0 1227050016 5045840 4001168
23:33:56 5177 8192000000 10165599696 10566512640 1265297312 13.379 100 34 68 0 1278475376 5953952 7224112
23:35:05 7862 8192000000 10180161600 10591666176 1283754016 13.547 100 27 52 0 1300426464 10728416 5944032
23:36:14 8416 8192000000 10186758144 10599763968 1295222976 13.652 100 19 38 1 1305392544 5941456 4228112
23:37:23 58545 8192000000 10206519792 10624540672 1312338416 13.807 100 21 42 0 1322558976 5219376 5001184
23:39:29 4228 8192000000 10247951840 10659016704 1341368432 14.070 100 57 111 0 1363133616 9284368 12480816
23:40:44 15026 8192000000 10269338208 10684014592 1352917248 14.174 100 54 108 1 1377355056 11894768 12543040
23:42:06 6238 8192000000 10280021008 10695258112 1367056960 14.301 100 54 105 1 1390968960 12329088 11582912
23:43:15 16017 8192000000 10292618592 10716336128 1384373312 14.456 100 40 80 1 1407563936 14310176 8880448
23:44:43 9292 8192000000 10304927792 10727337984 1393835904 14.540 100 50 98 1 1421180128 16035040 11309184
23:45:59 2997 8192000000 10327903232 10743328768 1403424832 14.625 100 72 144 0 1439817600 19102160 17290608
23:47:05 2997 8192000000 10328633152 10752434176 1409459824 14.679 100 56 112 1 1445499968 22197888 13842256
23:48:16 11581 8192000000 10331470112 10758918144 1406343792 14.651 100 49 98 1 1446016432 27000096 12672544
23:49:34 2508 8192000000 10336831744 10757402624 1405459248 14.644 100 56 110 1 1443312368 24178224 13674896
23:50:39 1799 8192000000 10335397312 10756927488 1401679952 14.610 100 61 122 0 1439896832 23190768 15026112
23:51:48 2354 8192000000 10330854848 10746146816 1389424432 14.501 100 74 147 1 1433478880 26391152 17663296
23:52:55 1320 8192000000 10322465392 10737885184 1382388080 14.438 100 69 139 2 1426601984 28097968 16115936
23:54:03 3085 8192000000 10321882224 10739589120 1383485696 14.448 100 65 125 1 1424664720 25674736 15504288
23:55:10 2966 8192000000 10304152080 10715127808 1360213760 14.239 100 71 141 1 1408859392 31548864 17096768
23:56:17 3165 8192000000 10316678384 10729082880 1372742272 14.352 100 67 129 3 1417083600 28982720 15358608
23:57:27 2493 8192000000 10312459632 10721230848 1363374336 14.268 100 74 146 4 1410871760 29236192 18261232
23:58:35 2046 8192000000 10318361072 10724401152 1369220112 14.320 100 83 159 2 1419003184 29621664 20161408
23:59:40 11246 8192000000 10317488144 10738049024 1370706080 14.333 99 40 80 1 1412800144 31121984 10972080
00:00:55 4119 8192000000 10332598544 10761031680 1391992384 14.524 100 43 86 0 1439881744 35934064 11955296
00:02:07 7545 8192000000 10355900688 10782064640 1402143680 14.614 100 55 110 0 1455988256 39436256 14408320
00:03:26 12178 8192000000 10311217040 10753794048 1382553760 14.439 100 31 62 0 1423789312 33101184 8134368
00:04:40 21255 8192000000 10315452160 10760634368 1381756304 14.432 100 19 36 1 1420408608 33261424 5390880
00:06:22 43686 8192000000 10297702336 10755510272 1363371024 14.268 100 13 26 1 1407640736 40222768 4046944
00:08:08 121753 8192000000 10304083856 10750590976 1349328320 14.141 98 13 26 0 1380712320 28549792 2834208
00:11:13 96738 8192000000 10316479040 10757300224 1364969824 14.282 98 21 40 0 1391162192 21043824 5148544
00:13:55 117217 8192000000 10291822320 10745602048 1365416704 14.286 98 22 44 0 1380243328 9051056 5775568
00:17:00 50811 8192000000 10319109408 10761973760 1393681568 14.539 100 31 62 0 1416808560 16773168 6353824
00:18:56 51241 8192000000 10304565600 10767187968 1395866944 14.558 100 26 51 0 1424828464 21686976 7274544
00:20:50 41089 8192000000 10313790160 10753695744 1374113856 14.364 98 30 58 3 1393598976 12980704 6504416
00:22:35 23296 8192000000 10324546960 10777554944 1403504976 14.626 100 30 57 0 1426147328 16429088 6213264
00:24:06 17726 8192000000 10328057296 10768547840 1403411216 14.625 99 61 116 1 1434886144 17901984 13572944
00:25:24 55989 8192000000 10303881968 10763280384 1397271232 14.571 100 9 18 0 1420418064 21047920 2098912
00:27:24 23145 8192000000 10292478256 10763460608 1379213344 14.410 99 13 26 0 1400422176 18613632 2595200
00:28:51 53668 8192000000 10275856160 10737426432 1369383648 14.322 99 16 32 0 1379774192 7313952 3076592
00:30:52 88344 8192000000 10274415696 10732003328 1355885888 14.200 98 25 48 0 1368312000 6895824 5530288
00:33:29 60815 8192000000 10256765808 10708963328 1329893376 13.966 96 18 36 0 1340381648 6778048 3710224
00:35:48 33516 8192000000 10263091408 10723635200 1345783504 14.110 98 12 24 0 1357947408 9714144 2449760
00:37:23 49334 8192000000 10262918672 10729517056 1369590464 14.323 100 8 16 0 1383567328 11593456 2383408
00:39:14 42767 8192000000 10251826176 10693136384 1291251120 13.616 95 16 33 1 1305258640 10500704 3506816
00:41:00 58363 8192000000 10247576256 10697314304 1310044464 13.786 96 20 39 1 1327688768 12847536 4796768
00:42:58 19648 8192000000 10269290256 10720935936 1361313328 14.249 100 20 40 0 1378000544 12099248 4587968
00:44:20 24824 8192000000 10275989888 10723160064 1356710928 14.208 99 37 74 0 1375889808 10855104 8323776
00:45:55 48366 8192000000 10286169648 10736152576 1350542176 14.152 98 18 36 1 1373018016 18369472 4106368
00:47:43 58162 8192000000 10267023392 10723725312 1346537552 14.116 98 14 28 0 1360781888 11128864 3115472
00:49:47 77544 8192000000 10289131632 10751016960 1363795920 14.271 99 8 16 0 1378539648 13000256 1743472
00:52:05 86843 8192000000 10271991424 10725986304 1335400000 14.016 97 21 42 0 1352809872 12486720 4923152
00:54:40 93416 8192000000 10304477216 10746118144 1371611168 14.341 99 34 67 0 1386762912 7905664 7246080
00:57:15 10896 8192000000 10267711232 10725314560 1356061840 14.202 99 21 42 2 1370568832 10185552 4321440
00:58:27 69091 8192000000 10277024880 10745831424 1379589792 14.413 100 10 20 0 1394915168 12996752 2328624
01:01:15 73661 8192000000 10271282240 10734653440 1357050816 14.211 98 15 30 1 1370488592 10238624 3199152
01:03:29 45319 8192000000 10290999440 10745868288 1374403696 14.366 99 22 44 1 1392544080 12477056 5663328
01:05:23 41735 8192000000 10302218560 10760736768 1382438864 14.438 99 15 31 0 1399464560 13692672 3333024
01:07:06 39302 8192000000 10282740192 10739994624 1341679424 14.073 96 28 56 0 1355624384 6588064 7356896
01:08:45 65929 8192000000 10295194096 10761715712 1390352544 14.509 99 13 26 0 1406097328 12815280 2929504
01:10:53 68093 8192000000 10288465888 10746724352 1360103712 14.238 97 19 38 0 1373004608 8786896 4114000
01:13:04 94845 8192000000 10294873120 10753372160 1338664576 14.045 96 12 24 0 1361719776 20270496 2784704
01:15:39 21079 8192000000 10314295344 10759274496 1389835648 14.504 100 40 80 1 1417235264 18416368 8983248
01:17:01 111534 8192000000 10282296976 10742001664 1330242048 13.969 96 9 18 0 1357334352 24787456 2304848
01:20:13 57820 8192000000 10270873648 10731433984 1365382992 14.286 100 22 44 0 1382906928 12401760 5122176
01:22:47 37950 8192000000 10264228048 10741305344 1364937440 14.282 100 6 12 1 1385307968 18584304 1786224
01:24:25 72209 8192000000 10278743248 10749829120 1371885104 14.344 100 7 14 0 1392105392 18519232 1701056
01:26:48 21382 8192000000 10261424912 10723573760 1338859792 14.047 98 32 59 2 1365175552 18306416 8009344
01:28:28 55642 8192000000 10270179280 10728779776 1349233088 14.141 98 14 28 0 1361501728 8491968 3776672
01:30:24 52311 8192000000 10293695040 10747871232 1348879680 14.137 98 21 42 0 1377169504 23483776 4806048
01:32:31 100897 8192000000 10265166448 10722963456 1288507136 13.591 94 5 10 0 1309184048 19439152 1237760
01:35:22 36319 8192000000 10274450528 10718023680 1333403824 13.998 98 30 60 0 1355817056 16062272 6350960
01:36:59 46771 8192000000 10287631536 10719133696 1321597824 13.891 97 39 78 0 1343341648 13718112 8025712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-08-2022 01:39:23 to 15-08-2022 01:39:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-08-2022 01:39:39 to 15-08-2022 01:39:41.

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          245698
MON$OLDEST_ACTIVE               250005
MON$OLDEST_SNAPSHOT             250005
MON$NEXT_TRANSACTION            250005
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                       2053472
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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 ---
:269: 
:270: mscs-test-161.budzdorov.ru    Mon Aug 15 01:38:26 2022
:271:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:272: 
:273: 
:274: mscs-test-161.budzdorov.ru    Mon Aug 15 01:38:32 2022
:275:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:276: 
:277: 
:278: mscs-test-161.budzdorov.ru    Mon Aug 15 01:38:32 2022
:279:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:280: 
:281: 
:282: mscs-test-161.budzdorov.ru    Mon Aug 15 01:38:32 2022
:283:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:284: 
--- end of diff output ---

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

15.08.22 01:39:42. 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-08-15 01:39:45 No severe PSQL-related problems occured
15.08.22 01:39:46. End of report.