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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-24 01:37:48
DTS_END                         2021-08-24 01:37:48.4970
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50422
TRN_ID                          246501
ATT_ID                          669
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: 10017.42 20523 1202090
customer order: creation 181.97 315 21836
customer order: refuse 15.58 323 1869
order to supplier: creation 48.64 1174 5837
order to supplier: removal 3.67 105423 440
invoice (draft): creation 48.02 3200 5762
invoice (draft): removal 57.63 802 6916
invoice accept: apply 44.06 17840 5287
invoice accept: cancel 51.53 762 6184
customer reserve: creation 4145.78 174 497494
customer reserve: removal 5103.84 59 612461
realization accept: apply 89.62 146 10754
realization accept: cancel 88.75 102 10650
payment from customer: creation 62.48 103 7498
payment from customer: removal 6.08 85 730
payment to supplier: creation 52.77 118 6332
payment to supplier: removal 3.68 50 442
service: total inventory turnovers 5.08 1071 610
service: total monetary turnovers 7.30 35 876
service: aggregate perf. data 0.90 23522 108
service: refresh index statistics 0.03 255149 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-08-2021 01:38:24 to 24-08-2021 01:38:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5518
TEST_TIME 2 4840
TEST_TIME 3 5113
TEST_TIME 4 5857
TEST_TIME 5 5699
TEST_TIME 6 5464
TEST_TIME 7 5425
TEST_TIME 8 5886
TEST_TIME 9 5813
TEST_TIME 10 6017
TEST_TIME 11 5931
TEST_TIME 12 6190
TEST_TIME 13 6129
TEST_TIME 14 6292
TEST_TIME 15 6943
TEST_TIME 16 7253
TEST_TIME 17 7385
TEST_TIME 18 7383
TEST_TIME 19 7857
TEST_TIME 20 8078
TEST_TIME 21 8389
TEST_TIME 22 8406
TEST_TIME 23 8460
TEST_TIME 24 8820
TEST_TIME 25 9076
TEST_TIME 26 9163
TEST_TIME 27 9379
TEST_TIME 28 9748
TEST_TIME 29 9759
TEST_TIME 30 10617
TEST_TIME 31 11395
TEST_TIME 32 11191
TEST_TIME 33 11078
TEST_TIME 34 11228
TEST_TIME 35 11178
TEST_TIME 36 11282
TEST_TIME 37 11328
TEST_TIME 38 11186
TEST_TIME 39 11048
TEST_TIME 40 10939
TEST_TIME 41 10749
TEST_TIME 42 10738
TEST_TIME 43 10795
TEST_TIME 44 10785
TEST_TIME 45 10627
TEST_TIME 46 10584
TEST_TIME 47 10508
TEST_TIME 48 10632
TEST_TIME 49 10611
TEST_TIME 50 10499
TEST_TIME 51 10424
TEST_TIME 52 10473
TEST_TIME 53 10393
TEST_TIME 54 10238
TEST_TIME 55 10295
TEST_TIME 56 10150
TEST_TIME 57 10201
TEST_TIME 58 10108
TEST_TIME 59 10042
TEST_TIME 60 9979
TEST_TIME 61 9964
TEST_TIME 62 9966
TEST_TIME 63 9967
TEST_TIME 64 10021
TEST_TIME 65 10014
TEST_TIME 66 10119
TEST_TIME 67 10093
TEST_TIME 68 10095
TEST_TIME 69 10172
TEST_TIME 70 10231
TEST_TIME 71 10282
TEST_TIME 72 10276
TEST_TIME 73 10282
TEST_TIME 74 10366
TEST_TIME 75 10410
TEST_TIME 76 10305
TEST_TIME 77 10216
TEST_TIME 78 10190
TEST_TIME 79 10180
TEST_TIME 80 10115
TEST_TIME 81 10061
TEST_TIME 82 10007
TEST_TIME 83 9999
TEST_TIME 84 9989
TEST_TIME 85 9966
TEST_TIME 86 9914
TEST_TIME 87 10041
TEST_TIME 88 10037
TEST_TIME 89 10039
TEST_TIME 90 10032
TEST_TIME 91 10100
TEST_TIME 92 10094
TEST_TIME 93 10126
TEST_TIME 94 10115
TEST_TIME 95 10106
TEST_TIME 96 10136
TEST_TIME 97 10171
TEST_TIME 98 10192
TEST_TIME 99 10155
TEST_TIME 100 10089
TEST_TIME 101 10058
TEST_TIME 102 10012
TEST_TIME 103 9991
TEST_TIME 104 9963
TEST_TIME 105 9955
TEST_TIME 106 9931
TEST_TIME 107 9915
TEST_TIME 108 9905
TEST_TIME 109 9909
TEST_TIME 110 9885
TEST_TIME 111 9873
TEST_TIME 112 9860
TEST_TIME 113 9995
TEST_TIME 114 9978
TEST_TIME 115 9974
TEST_TIME 116 9954
TEST_TIME 117 9989
TEST_TIME 118 10038
TEST_TIME 119 10038
TEST_TIME 120 10023

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-08-2021 01:38:28 to 24-08-2021 01:38:31.

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 1662668 1662668 0 0.00 0 15683 6 0 0
doc_list_biud 1662668 1662668 0 0.00 0 28263 27 0 0
sp_add_invoice_to_stock 5467 5287 180 3.29 23 226664 17841 0 180
sp_cancel_adding_invoice 6184 6184 0 0.00 5 60986 763 0 0
sp_cancel_client_order 1869 1869 0 0.00 3 15265 324 0 0
sp_cancel_customer_reserve 612461 612461 0 0.00 1 22357 59 0 0
sp_cancel_pay_from_customer 730 730 0 0.00 3 9748 85 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 3 1320 50 0 0
sp_cancel_supplier_invoice 6929 6916 13 0.18 2 15720 802 0 13
sp_cancel_supplier_order 440 440 0 0.00 7 977528 105424 0 0
sp_cancel_write_off 10650 10650 0 0.00 1 15661 103 0 0
sp_client_order 21836 21836 0 0.00 4 17324 315 0 0
sp_customer_reserve 497494 497494 0 0.00 0 67249 175 0 0
sp_fill_shopping_cart 33431 33431 0 0.00 1 13765 35 0 0
sp_fill_shopping_cart_clo_res 497480 497480 0 0.00 0 13500 11 0 0
sp_get_clo_for_invoice 5273 5273 0 0.00 0 26614 252 0 0
sp_kill_cost_storno 18006 18006 0 0.00 0 6072 53 0 0
sp_kill_qstorno_ret_qs2qd 1242928 1242928 0 0.00 0 18497 32 0 0
sp_kill_qty_storno 654547 654547 0 0.00 0 28261 69 0 0
sp_lock_selected_doc 22203 22203 0 0.00 0 7775 5 0 0
sp_make_cost_storno 29857 29857 0 0.00 0 15682 44 0 0
sp_multiply_rows_for_pdistr 29857 29857 0 0.00 0 8742 27 0 0
sp_multiply_rows_for_qdistr 21836 21836 0 0.00 0 13567 151 0 0
sp_pay_from_customer 7498 7498 0 0.00 4 17644 103 0 0
sp_pay_to_supplier 6332 6332 0 0.00 4 9588 119 0 0
sp_payment_common 13830 13830 0 0.00 2 17590 27 0 0
sp_qd_handle_on_cancel_clo 1869 1869 0 0.00 0 15259 293 0 0
sp_qd_handle_on_invoice_upd_sts 11457 11457 0 0.00 0 28261 326 0 0
sp_qd_handle_on_reserve_upd_sts 10754 10754 0 0.00 0 14067 46 0 0
sp_reserve_write_off 10754 10754 0 0.00 6 15702 147 0 0
sp_supplier_invoice 5762 5762 0 0.00 7 108347 3200 0 0
sp_supplier_order 5837 5837 0 0.00 5 43694 1174 0 0
srv_aggregate_perf_data 108 108 0 0.00 2878 436801 23523 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1607 95599 38264 0 0
srv_find_qd_qs_mism 1151112 1151112 0 0.00 0 12379 5 0 0
srv_make_invnt_saldo 610 610 0 0.00 25 84965 1071 0 0
srv_make_money_saldo 876 876 0 0.00 1 9401 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 110251 446242 255149 0 0
t$perf-abend:sp_add_invoice_to_stock 180 180 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5287 5287 0 0.00 0 15663 175 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 1 1517 71 0 0
t$perf-norm:sp_cancel_client_order 1869 1869 0 0.00 0 6162 21 0 0
t$perf-norm:sp_cancel_customer_reserve 737 737 0 0.00 0 2557 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 730 730 0 0.00 0 6687 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 1195 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 447 447 0 0.00 0 4594 39 0 0
t$perf-norm:sp_cancel_supplier_order 440 440 0 0.00 0 30768 1471 0 0
t$perf-norm:sp_cancel_write_off 1093 1093 0 0.00 0 746 4 0 0
t$perf-norm:sp_client_order 21836 21836 0 0.00 0 9730 17 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 4055 17 0 0
t$perf-norm:sp_pay_from_customer 7498 7498 0 0.00 0 9896 9 0 0
t$perf-norm:sp_pay_to_supplier 6332 6332 0 0.00 0 3933 7 0 0
t$perf-norm:sp_reserve_write_off 10754 10754 0 0.00 0 9893 12 0 0
t$perf-norm:sp_supplier_invoice 5762 5762 0 0.00 0 9370 18 0 0
t$perf-norm:sp_supplier_order 5837 5837 0 0.00 0 3929 16 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 199 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 6 1 0 0
t$perf-norm:srv_make_invnt_saldo 610 610 0 0.00 0 3094 22 0 0
t$perf-norm:srv_make_money_saldo 876 876 0 0.00 0 6996 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10904 10544 360 3.30 0 4063 16 0 360
v_all_customers 39910 39910 0 0.00 0 26 1 0 0
v_all_suppliers 32578 32578 0 0.00 0 129 1 0 0
v_all_wares 43672 43672 0 0.00 0 133 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 311 6 0 0
v_cancel_client_order 3738 3738 0 0.00 0 3044 5 0 0
v_cancel_customer_prepayment 1460 1460 0 0.00 0 5089 13 0 0
v_cancel_customer_reserve 1474 1474 0 0.00 0 3023 7 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 335 3 0 0
v_cancel_supplier_invoice 920 894 26 2.82 0 3451 18 0 26
v_cancel_supplier_order 880 880 0 0.00 0 824 4 0 0
v_cancel_write_off 2186 2186 0 0.00 0 3487 15 0 0
v_max_id_clo_ord 5836 5836 0 0.00 0 1292 2 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 4396 16 0 0
v_max_id_ord_sup 5759 5759 0 0.00 0 6551 6 0 0
v_max_non_paid_invoice 1638 1638 0 0.00 0 356 2 0 0
v_max_non_paid_realizn 3889 3889 0 0.00 0 1224 3 0 0
v_min_id_clo_ord 5836 5836 0 0.00 0 3166 6 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 4531 7 0 0
v_min_id_ord_sup 5759 5759 0 0.00 0 5810 4 0 0
v_min_non_paid_invoice 6332 6332 0 0.00 0 9580 16 0 0
v_min_non_paid_realizn 7498 7498 0 0.00 0 4929 14 0 0
v_our_firm 10980 10980 0 0.00 0 20 1 0 0
v_reserve_write_off 21506 21506 0 0.00 0 2783 5 0 0
x_lock_depdocs_on_canc_invoice 12653 12653 0 0.00 0 14394 122 0 0
x_lock_depdocs_on_canc_sup_ord 440 440 0 0.00 0 17812 371 0 0
x_make_qstorno_1000_1200 5837 5837 0 0.00 1 43677 1093 0 0
x_make_qstorno_1200_2000 5762 5762 0 0.00 3 108335 3087 0 0
x_make_qstorno_2100_3300 497494 497494 0 0.00 0 67247 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-08-2021 01:38:32 to 24-08-2021 01:38:36.

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:07:52 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:53 26002 8192000000 10373387648 10694959104 1429415296 14.856 100 88 175 0 1464569280 6147280 29006704
23:10:26 14582 8192000000 10436312112 10770620416 1494540624 15.429 100 84 168 0 1529182992 8024672 26617696
23:11:47 16770 8192000000 10485294496 10831712256 1543936400 15.858 100 71 142 0 1574548672 8891360 21720912
23:13:07 5384 8192000000 10553343568 10905948160 1612653920 16.447 100 78 156 2 1645865968 10531008 22681040
23:14:18 13229 8192000000 10630965520 10997174272 1683307936 17.045 100 77 154 0 1717649184 12050272 22290976
23:15:34 6926 8192000000 10707886688 11084292096 1770292208 17.769 100 82 164 0 1805824224 13040976 22491040
23:16:42 3588 8192000000 10750679536 11131854848 1810318448 18.098 100 93 185 1 1851179280 14914752 25946080
23:17:47 1472 8192000000 10791448784 11178070016 1852805888 18.445 100 89 177 0 1889423904 11458944 25159072
23:18:50 36244 8192000000 10809049280 11205541888 1872169440 18.602 100 59 119 1 1897740160 9018736 16551984
23:20:27 9096 8192000000 10799269600 11215273984 1876922160 18.640 100 17 32 2 1886282144 4998112 4361872
23:21:38 8075 8192000000 10793870736 11221561344 1884952960 18.705 100 16 32 1 1895321648 5818032 4550656
23:22:46 40623 8192000000 10828181920 11236356096 1895813792 18.793 100 49 99 3 1917232272 5811568 15606912
23:24:29 5006 8192000000 10819236432 11243458560 1900766432 18.832 100 40 78 2 1919379760 7583296 11030032
23:25:39 4976 8192000000 10822337424 11257487360 1906130432 18.876 100 32 62 2 1926053744 10739248 9184064
23:26:45 2813 8192000000 10833126656 11251671040 1907935936 18.890 100 42 86 4 1925799280 5338800 12524544
23:27:50 15429 8192000000 10808461824 11234709504 1861869600 18.518 98 32 64 0 1876633792 6185696 8578496
23:29:08 10305 8192000000 10748656640 11177558016 1804072800 18.047 97 15 30 0 1813551136 5036192 4442144
23:30:21 10796 8192000000 10692450512 11107028992 1726453952 17.406 95 25 50 2 1740177968 6219984 7504032
23:31:34 56520 8192000000 10683103248 11095986176 1670353216 16.936 93 36 72 3 1685105456 4837408 9914832
23:33:35 8382 8192000000 10757786832 11180961792 1841730272 18.355 100 23 47 2 1855224672 5997840 7496560
23:34:47 8912 8192000000 10773341088 11204517888 1860317024 18.506 100 15 30 1 1870365440 5120832 4927584
23:35:59 9331 8192000000 10783695216 11218026496 1874141328 18.618 100 19 36 1 1885185104 5919456 5124320
23:37:08 31227 8192000000 10814205920 11249606656 1899705056 18.824 100 29 56 1 1913204176 5588320 7910800
23:38:46 1605 8192000000 10885037392 11289808896 1934935360 19.106 100 75 146 3 1967454432 11204784 21314288
23:39:51 9963 8192000000 10896721328 11315445760 1952395424 19.246 100 60 120 0 1983100304 14292864 16412016
23:41:15 14230 8192000000 10904256304 11330572288 1966392960 19.357 100 51 102 0 1995687008 14914048 14380000
23:42:40 28713 8192000000 10897310896 11340419072 1973072384 19.410 100 43 86 3 1998887456 13142560 12672512
23:44:18 16146 8192000000 10919402816 11360673792 1992405040 19.563 100 47 94 1 2021780864 15010192 14365632
23:45:37 13031 8192000000 10938119216 11370786816 1988760896 19.534 100 48 96 0 2021031552 17953456 14317200
23:47:03 1979 8192000000 10957393200 11393470464 2013846768 19.732 100 60 119 0 2050763472 18690400 18226304
23:48:08 3098 8192000000 10986233376 11411275776 2023246576 19.806 100 77 149 2 2068234432 23956784 21031072
23:49:16 9196 8192000000 10988927488 11420639232 2031987472 19.874 100 71 142 1 2081542576 27981248 21573856
23:50:36 4151 8192000000 11008221936 11434979328 2035089216 19.899 100 80 160 1 2093196512 33974656 24132640
23:51:50 2035 8192000000 11018018256 11436793856 2013147312 19.726 99 92 185 0 2088103744 46103040 28853392
23:52:58 2014 8192000000 10984059264 11400327168 1995841168 19.590 100 87 174 2 2064860816 41809728 27209920
23:54:05 3479 8192000000 10979280832 11405012992 1996161136 19.592 100 76 152 2 2063391056 43730432 23499488
23:55:13 3979 8192000000 10979506704 11401162752 1991866656 19.559 100 80 160 1 2064699328 48230320 24602352
23:56:21 6341 8192000000 10941707376 11383193600 1942995760 19.171 98 40 80 0 2004591408 48575440 13020208
23:57:33 5603 8192000000 10989956384 11419660288 1976924464 19.440 99 62 121 1 2053151232 56832288 19394480
23:58:47 2370 8192000000 10975373920 11395252224 1975462624 19.429 100 68 136 1 2056159120 59059984 21636512
00:00:17 4705 8192000000 11015294000 11432083456 1997197200 19.601 100 82 164 0 2087647328 64417888 26032240
00:01:25 10207 8192000000 11003015264 11449966592 2013245840 19.727 100 38 76 3 2089468976 63337600 12885536
00:02:42 14080 8192000000 10977408640 11422461952 1984042448 19.497 100 46 92 1 2060333040 61977472 14313120
00:03:57 18946 8192000000 10957442256 11403075584 1943423200 19.174 98 32 64 1 2015603552 61172032 11008320
00:05:26 23282 8192000000 10938348400 11394928640 1956882224 19.281 100 24 48 5 2030955616 64611344 9462048
00:06:58 56898 8192000000 10916110960 11400626176 1937876448 19.130 99 21 42 1 2013906384 67490016 8539920
00:08:56 40141 8192000000 10875373808 11306577920 1916731584 18.961 98 35 63 5 1933414720 8851728 7831408
00:11:20 66657 8192000000 10903441504 11361849344 1945052576 19.187 98 12 24 0 1958930640 10791008 3087056
00:13:31 74020 8192000000 10918386656 11400785920 1983923264 19.496 100 8 16 1 2005231056 18897712 2410080
00:15:49 37714 8192000000 10945159888 11381444608 1993007504 19.568 100 59 116 2 2020676416 10903808 16765104
00:17:44 83671 8192000000 10932563728 11392954368 1978637504 19.454 99 37 73 0 2003382960 13940400 10805056
00:20:42 89562 8192000000 10916765536 11394453504 1955819200 19.273 98 7 14 0 1979214400 21765696 1629504
00:23:27 27792 8192000000 10928006400 11392028672 1994306624 19.578 100 33 66 0 2015978080 12475376 9196080
00:25:01 15716 8192000000 10929070064 11384045568 1985461088 19.508 100 36 73 3 2007627296 11530656 10635552
00:26:52 16783 8192000000 10905633728 11382837248 1979957520 19.464 100 11 21 2 1992019360 9208224 2853616
00:28:15 27220 8192000000 10910108320 11390484480 1994936640 19.583 100 21 43 0 2009764736 9198224 5629872
00:29:46 31996 8192000000 10915546672 11383533568 1959771264 19.304 98 22 44 1 1975405520 9460080 6174176
00:31:23 79690 8192000000 10883033344 11366309888 1926139936 19.036 97 11 22 0 1937594320 8359344 3095040
00:34:20 21933 8192000000 10888684528 11373404160 1952913520 19.250 99 17 33 0 1970893632 12941120 5038992
00:36:02 54268 8192000000 10902169488 11389399040 1975228800 19.427 99 14 28 1 1988110816 8979856 3902160
00:37:57 49842 8192000000 10917001328 11400777728 1973038112 19.410 99 7 14 0 1987961136 13203680 1719344
00:39:54 20172 8192000000 10861300512 11330621440 1903220112 18.852 97 29 58 1 1918457776 7625744 7611920
00:41:22 35701 8192000000 10853960240 11321597952 1839703024 18.338 94 15 30 0 1853961056 9819184 4438848
00:43:00 37780 8192000000 10859167424 11327455232 1911417632 18.918 99 24 48 0 1933160592 14801936 6941024
00:44:38 32350 8192000000 10872826080 11330998272 1917267344 18.965 99 30 60 1 1937800336 12205264 8327728
00:46:11 45041 8192000000 10810556640 11269713920 1856098624 18.472 96 25 50 0 1883471344 20156208 7216512
00:47:59 92114 8192000000 10857547920 11330449408 1909420352 18.902 99 12 23 0 1925541424 13190496 2930576
00:50:34 64007 8192000000 10857092720 11324198912 1895143680 18.787 98 23 46 1 1919110240 17580192 6386368
00:52:39 90389 8192000000 10862298864 11326955520 1927193760 19.044 100 12 24 2 1946260672 15673056 3393856
00:55:11 12974 8192000000 10889435536 11348455424 1948816416 19.217 100 36 72 0 1970662272 11170864 10674992
00:56:26 51395 8192000000 10847688416 11332997120 1889609328 18.743 97 6 12 0 1898308464 7413856 1285280
00:58:18 73394 8192000000 10857855840 11330146304 1898244976 18.812 98 11 22 0 1911297952 9918560 3134416
01:01:00 95596 8192000000 10869215280 11354488832 1889386528 18.741 97 10 20 0 1906098656 14133056 2579072
01:03:36 87276 8192000000 10888699840 11390119936 1968267712 19.372 100 20 40 0 1997879072 23781792 5829568
01:06:03 86533 8192000000 10878393856 11361304576 1899989376 18.826 97 27 55 0 1920409920 13011920 7408624
01:08:35 50904 8192000000 10890408432 11359293440 1948480528 19.214 99 17 34 2 1961164608 8203072 4481008
01:10:31 59758 8192000000 10906180080 11378954240 1977437296 19.444 100 29 56 1 1996132656 10272848 8422512
01:12:40 54177 8192000000 10907892592 11382538240 1963515824 19.334 99 33 66 0 1984706832 12205184 8985824
01:14:41 31941 8192000000 10886685696 11352313856 1901798064 18.841 96 17 34 0 1914541728 8054992 4688672
01:16:20 49444 8192000000 10867684224 11344343040 1930200352 19.068 98 13 26 0 1944077296 10700112 3176832
01:18:35 42600 8192000000 10910444976 11390906368 1986363872 19.515 100 27 54 3 2003563456 9806560 7393024
01:20:26 41979 8192000000 10879119024 11366928384 1951385088 19.238 99 6 12 0 1959775040 7100112 1289840
01:22:09 70190 8192000000 10871157488 11352424448 1880114000 18.666 95 13 26 1 1891701408 7868000 3719408
01:24:37 58999 8192000000 10871696512 11368517632 1953014624 19.250 100 10 20 0 1970433472 14175536 3243312
01:26:58 35508 8192000000 10895994864 11350204416 1896867328 18.801 97 20 40 1 1909654096 7316256 5470512
01:28:35 28668 8192000000 10879746384 11349504000 1930426432 19.070 99 17 34 2 1948390960 12996352 4968176
01:30:04 48230 8192000000 10878539024 11381731328 1953638256 19.255 100 9 18 2 1978344448 21954080 2752112
01:32:04 70935 8192000000 10854541968 11306409984 1794156608 17.966 93 28 56 0 1811241616 9489360 7595648
01:34:15 60342 8192000000 10854252912 11324170240 1874630432 18.622 97 21 42 0 1899776656 19627888 5518336
01:36:23 62143 8192000000 10880619840 11348885504 1882857008 18.688 97 18 36 2 1898531936 11008384 4666544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-08-2021 01:38:38 to 24-08-2021 01:38:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 180 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-08-2021 01:38:54 to 24-08-2021 01:38:56.

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          245781
MON$OLDEST_ACTIVE               247283
MON$OLDEST_SNAPSHOT             247283
MON$NEXT_TRANSACTION            247283
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                       2069600
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:389: 
:390: mscs-test-161.budzdorov.ru    Tue Aug 24 01:37:49 2021
:391:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:392: 
:393: 
:394: mscs-test-161.budzdorov.ru    Tue Aug 24 01:37:51 2021
:395:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:396: 
:397: 
:398: mscs-test-161.budzdorov.ru    Tue Aug 24 01:37:52 2021
:399:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:400: 
:401: 
:402: mscs-test-161.budzdorov.ru    Tue Aug 24 01:37:53 2021
:403:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:404: 
--- end of diff output ---

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

24.08.21 01:38:57. 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-08-24 01:38:59 No severe PSQL-related problems occured
24.08.21 01:39:01. End of report.