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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15768196 kB
MemAvailable 30593500 kB
Buffers 0 kB
Cached 15981656 kB
SwapCached 2112 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-10-04 01:38:30
DTS_END                         2022-10-04 01:38:30.7330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46792
TRN_ID                          243217
ATT_ID                          670
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: 8833.84 21221 1060061
customer order: creation 177.40 324 21288
customer order: refuse 14.48 329 1737
order to supplier: creation 45.93 1132 5511
order to supplier: removal 3.17 96945 380
invoice (draft): creation 45.88 3245 5505
invoice (draft): removal 47.95 720 5754
invoice accept: apply 42.78 17336 5134
invoice accept: cancel 43.16 886 5179
customer reserve: creation 3897.45 174 467694
customer reserve: removal 4220.65 58 506478
realization accept: apply 84.31 161 10117
realization accept: cancel 79.78 98 9574
payment from customer: creation 59.94 114 7193
payment from customer: removal 5.76 65 691
payment to supplier: creation 49.70 131 5964
payment to supplier: removal 3.59 72 431
service: total inventory turnovers 4.89 1215 587
service: total monetary turnovers 6.28 43 753
service: aggregate perf. data 0.73 24186 87
service: refresh index statistics 0.03 277182 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-10-2022 01:40:11 to 04-10-2022 01:40:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3640
TEST_TIME 2 3704
TEST_TIME 3 3876
TEST_TIME 4 3821
TEST_TIME 5 4991
TEST_TIME 6 4752
TEST_TIME 7 4940
TEST_TIME 8 5548
TEST_TIME 9 5649
TEST_TIME 10 5683
TEST_TIME 11 6001
TEST_TIME 12 6130
TEST_TIME 13 6283
TEST_TIME 14 6156
TEST_TIME 15 6422
TEST_TIME 16 7404
TEST_TIME 17 7774
TEST_TIME 18 7914
TEST_TIME 19 7978
TEST_TIME 20 8048
TEST_TIME 21 8183
TEST_TIME 22 8085
TEST_TIME 23 8099
TEST_TIME 24 8386
TEST_TIME 25 9009
TEST_TIME 26 9049
TEST_TIME 27 9249
TEST_TIME 28 9659
TEST_TIME 29 9725
TEST_TIME 30 9704
TEST_TIME 31 10081
TEST_TIME 32 9945
TEST_TIME 33 9754
TEST_TIME 34 9580
TEST_TIME 35 9411
TEST_TIME 36 9517
TEST_TIME 37 9519
TEST_TIME 38 9387
TEST_TIME 39 9338
TEST_TIME 40 9260
TEST_TIME 41 9172
TEST_TIME 42 9174
TEST_TIME 43 9138
TEST_TIME 44 9048
TEST_TIME 45 8945
TEST_TIME 46 8969
TEST_TIME 47 8981
TEST_TIME 48 8862
TEST_TIME 49 8737
TEST_TIME 50 8812
TEST_TIME 51 8705
TEST_TIME 52 8696
TEST_TIME 53 9001
TEST_TIME 54 9022
TEST_TIME 55 9067
TEST_TIME 56 9001
TEST_TIME 57 9003
TEST_TIME 58 9034
TEST_TIME 59 9073
TEST_TIME 60 9068
TEST_TIME 61 9086
TEST_TIME 62 9193
TEST_TIME 63 9222
TEST_TIME 64 9229
TEST_TIME 65 9219
TEST_TIME 66 9220
TEST_TIME 67 9194
TEST_TIME 68 9113
TEST_TIME 69 9102
TEST_TIME 70 9037
TEST_TIME 71 9024
TEST_TIME 72 9033
TEST_TIME 73 9009
TEST_TIME 74 8982
TEST_TIME 75 8935
TEST_TIME 76 8865
TEST_TIME 77 8886
TEST_TIME 78 8859
TEST_TIME 79 8867
TEST_TIME 80 8912
TEST_TIME 81 8880
TEST_TIME 82 8935
TEST_TIME 83 8911
TEST_TIME 84 8913
TEST_TIME 85 8924
TEST_TIME 86 8959
TEST_TIME 87 8915
TEST_TIME 88 9010
TEST_TIME 89 9072
TEST_TIME 90 9056
TEST_TIME 91 9006
TEST_TIME 92 8958
TEST_TIME 93 8953
TEST_TIME 94 8952
TEST_TIME 95 8963
TEST_TIME 96 8948
TEST_TIME 97 8914
TEST_TIME 98 8927
TEST_TIME 99 8940
TEST_TIME 100 9057
TEST_TIME 101 9041
TEST_TIME 102 9025
TEST_TIME 103 9043
TEST_TIME 104 9032
TEST_TIME 105 9055
TEST_TIME 106 9045
TEST_TIME 107 9055
TEST_TIME 108 9134
TEST_TIME 109 9164
TEST_TIME 110 9088
TEST_TIME 111 9048
TEST_TIME 112 9034
TEST_TIME 113 9034
TEST_TIME 114 9007
TEST_TIME 115 8975
TEST_TIME 116 8948
TEST_TIME 117 8921
TEST_TIME 118 8908
TEST_TIME 119 8884
TEST_TIME 120 8835

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-10-2022 01:40:14 to 04-10-2022 01:40:18.

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 1493039 1493039 0 0.00 0 26691 6 0 0
doc_list_biud 1493039 1493039 0 0.00 0 30086 25 0 0
sp_add_invoice_to_stock 5255 5134 121 2.30 36 174722 17336 0 121
sp_cancel_adding_invoice 5179 5179 0 0.00 3 72173 886 0 0
sp_cancel_client_order 1737 1737 0 0.00 3 9139 329 0 0
sp_cancel_customer_reserve 506478 506478 0 0.00 0 28476 58 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 2 4518 66 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 2 3152 73 0 0
sp_cancel_supplier_invoice 5765 5754 11 0.19 1 14967 720 0 11
sp_cancel_supplier_order 380 380 0 0.00 6 870360 96945 0 0
sp_cancel_write_off 9574 9574 0 0.00 1 8525 99 0 0
sp_client_order 21288 21288 0 0.00 3 29571 325 0 0
sp_customer_reserve 467694 467694 0 0.00 0 67721 174 0 0
sp_fill_shopping_cart 32304 32304 0 0.00 1 27214 37 0 0
sp_fill_shopping_cart_clo_res 467692 467692 0 0.00 0 17240 13 0 0
sp_get_clo_for_invoice 5132 5132 0 0.00 0 31260 300 0 0
sp_kill_cost_storno 15875 15875 0 0.00 0 6560 50 0 0
sp_kill_qstorno_ret_qs2qd 1028664 1028664 0 0.00 0 23844 31 0 0
sp_kill_qty_storno 544351 544351 0 0.00 0 30085 68 0 0
sp_lock_selected_doc 21013 21013 0 0.00 0 7274 4 0 0
sp_make_cost_storno 28406 28406 0 0.00 0 26655 56 0 0
sp_multiply_rows_for_pdistr 28406 28406 0 0.00 0 7867 24 0 0
sp_multiply_rows_for_qdistr 21288 21288 0 0.00 0 13661 135 0 0
sp_pay_from_customer 7193 7193 0 0.00 4 23654 114 0 0
sp_pay_to_supplier 5964 5964 0 0.00 5 20636 131 0 0
sp_payment_common 13157 13157 0 0.00 1 13955 30 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 0 9130 304 0 0
sp_qd_handle_on_invoice_upd_sts 10311 10311 0 0.00 0 30083 347 0 0
sp_qd_handle_on_reserve_upd_sts 10117 10117 0 0.00 0 23775 57 0 0
sp_reserve_write_off 10117 10117 0 0.00 6 24877 161 0 0
sp_supplier_invoice 5505 5505 0 0.00 5 81039 3246 0 0
sp_supplier_order 5511 5511 0 0.00 5 23206 1132 0 0
srv_aggregate_perf_data 87 87 0 0.00 2855 290795 24187 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2569 141738 46547 0 0
srv_find_qd_qs_mism 1011556 1011556 0 0.00 0 10512 6 0 0
srv_make_invnt_saldo 587 587 0 0.00 42 167470 1215 0 0
srv_make_money_saldo 753 753 0 0.00 0 13941 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 180224 374562 277182 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5134 5134 0 0.00 0 16895 163 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 6396 158 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 4172 15 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 1742 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 2555 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 667 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 4924 34 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 27306 1615 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 2563 13 0 0
t$perf-norm:sp_client_order 21288 21288 0 0.00 0 8518 16 0 0
t$perf-norm:sp_customer_reserve 1087 1087 0 0.00 0 4462 11 0 0
t$perf-norm:sp_pay_from_customer 7193 7193 0 0.00 0 5528 6 0 0
t$perf-norm:sp_pay_to_supplier 5964 5964 0 0.00 0 5838 8 0 0
t$perf-norm:sp_reserve_write_off 10117 10117 0 0.00 0 10320 11 0 0
t$perf-norm:sp_supplier_invoice 5505 5505 0 0.00 0 4999 17 0 0
t$perf-norm:sp_supplier_order 5511 5511 0 0.00 0 15640 17 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 1325 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 587 587 0 0.00 0 994 22 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 1508 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10506 10264 242 2.30 0 7466 18 0 242
v_all_customers 38574 38574 0 0.00 0 171 0 0 0
v_all_suppliers 30378 30378 0 0.00 0 17 1 0 0
v_all_wares 42576 42576 0 0.00 0 179 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 1914 9 0 0
v_cancel_client_order 3474 3474 0 0.00 0 2047 5 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 2115 6 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 1763 5 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 1149 4 0 0
v_cancel_supplier_invoice 796 774 22 2.76 0 474 13 0 22
v_cancel_supplier_order 760 760 0 0.00 0 1521 6 0 0
v_cancel_write_off 2062 2062 0 0.00 0 2977 15 0 0
v_max_id_clo_ord 5510 5510 0 0.00 0 1435 1 0 0
v_max_id_clo_res 1087 1087 0 0.00 0 2509 13 0 0
v_max_id_ord_sup 5504 5504 0 0.00 0 5222 4 0 0
v_max_non_paid_invoice 1789 1789 0 0.00 0 256 2 0 0
v_max_non_paid_realizn 3938 3938 0 0.00 0 1945 3 0 0
v_min_id_clo_ord 5510 5510 0 0.00 0 5028 8 0 0
v_min_id_clo_res 1087 1087 0 0.00 0 2909 7 0 0
v_min_id_ord_sup 5504 5504 0 0.00 0 6335 11 0 0
v_min_non_paid_invoice 5964 5964 0 0.00 0 6766 21 0 0
v_min_non_paid_realizn 7193 7193 0 0.00 0 13951 26 0 0
v_our_firm 10512 10512 0 0.00 0 20 1 0 0
v_reserve_write_off 20234 20234 0 0.00 0 4754 5 0 0
x_lock_depdocs_on_canc_invoice 10546 10546 0 0.00 0 28440 117 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 11511 338 0 0
x_make_qstorno_1000_1200 5511 5511 0 0.00 1 23189 1062 0 0
x_make_qstorno_1200_2000 5505 5505 0 0.00 1 80922 3102 0 0
x_make_qstorno_2100_3300 467694 467694 0 0.00 0 67721 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-10-2022 01:40:19 to 04-10-2022 01:40:23.

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:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:35 15440 8192000000 9888402160 10195267584 975940128 10.645 100 88 176 0 1004700640 5694992 23065520
23:10:55 28046 8192000000 9935300640 10250973184 1019682992 11.069 100 90 180 0 1050826144 7856672 23286480
23:12:28 19816 8192000000 9975624640 10304159744 1059898864 11.456 100 87 174 0 1091105600 9602176 21604560
23:13:57 9515 8192000000 10029450736 10372870144 1110344976 11.936 100 85 170 0 1141508672 11259456 19904240
23:15:11 5278 8192000000 10070120480 10426335232 1159738336 12.401 100 78 156 0 1189937632 12813360 17385936
23:16:20 3173 8192000000 10117010032 10480947200 1203855568 12.812 100 81 160 0 1234957840 12947280 18154992
23:17:27 1825 8192000000 10160961344 10535329792 1248425056 13.224 100 83 160 1 1278909632 13300784 17183792
23:18:30 1963 8192000000 10187505696 10571018240 1273683312 13.455 100 72 142 0 1301715648 12546144 15486192
23:19:35 1306 8192000000 10200441696 10588450816 1290661280 13.610 100 78 155 1 1319770816 12509120 16600416
23:20:37 2326 8192000000 10197320400 10601332736 1303720976 13.729 100 48 95 2 1324345232 10302160 10322096
23:21:43 4104 8192000000 10196401632 10611593216 1313964096 13.822 100 19 38 0 1324464880 6247264 4253520
23:22:48 20236 8192000000 10223375504 10628706304 1319464832 13.872 100 36 69 0 1334772000 6217424 9089744
23:24:11 8009 8192000000 10212554880 10631909376 1329633440 13.964 100 30 60 0 1343636960 6559152 7444368
23:25:21 7858 8192000000 10222101392 10643722240 1332270624 13.988 100 8 16 0 1339327824 5360864 1696336
23:26:32 61795 8192000000 10235725664 10649427968 1320613456 13.882 98 34 67 2 1334214672 5814416 7786800
23:28:38 7294 8192000000 10222606304 10636214272 1302051472 13.714 98 34 68 1 1324545472 15067680 7426320
23:29:48 9309 8192000000 10184681376 10593447936 1256868944 13.301 96 21 43 0 1267233280 5239696 5124640
23:30:58 71110 8192000000 10124903824 10528088064 1133402832 12.153 89 32 63 0 1146377904 5477056 7498016
23:33:12 3369 8192000000 10144910832 10541281280 1242624448 13.170 99 34 68 0 1256613920 6294864 7694608
23:34:18 7118 8192000000 10171074064 10577506304 1269694736 13.419 99 19 39 0 1279239456 4901760 4642960
23:35:27 53417 8192000000 10198581184 10604961792 1304943696 13.740 100 28 54 0 1316240704 4425504 6871504
23:37:30 6142 8192000000 10204456864 10618281984 1311000688 13.795 100 31 62 0 1323766032 5859632 6905712
23:38:43 34958 8192000000 10243284416 10656235520 1342205744 14.077 100 34 65 0 1354622640 5421680 6995216
23:40:27 14880 8192000000 10264177120 10680446976 1358645936 14.225 100 45 84 2 1379114848 10296832 10172080
23:41:46 18649 8192000000 10287901120 10704588800 1373471504 14.358 100 49 98 1 1397536976 12941696 11123776
23:43:07 35207 8192000000 10283789280 10714292224 1376262048 14.383 100 32 64 0 1402548640 18018560 8268032
23:44:55 15638 8192000000 10303289328 10728484864 1388938576 14.496 100 42 83 0 1413259056 14582736 9737744
23:46:14 7986 8192000000 10304266320 10725072896 1378061920 14.399 99 51 100 0 1403939520 13865520 12012080
23:47:26 7624 8192000000 10300794736 10721333248 1383265184 14.446 99 61 122 0 1413024384 14823424 14935776
23:48:41 9911 8192000000 10318950144 10734559232 1391436144 14.519 100 66 131 1 1426082816 19492944 15153728
23:49:54 6559 8192000000 10325767680 10747654144 1400966720 14.604 100 60 117 0 1436296880 21290592 14039568
23:51:09 2810 8192000000 10336384384 10749493248 1401460688 14.608 100 71 139 0 1444788832 26641888 16686256
23:52:17 4983 8192000000 10343820400 10762977280 1403919728 14.630 100 62 122 1 1453928384 34309072 15699584
23:53:28 10952 8192000000 10337158368 10763030528 1397008192 14.568 100 47 94 1 1448983744 39399968 12575584
23:54:42 4758 8192000000 10311029472 10729832448 1343820080 14.092 98 56 112 2 1404054000 45575584 14658336
23:55:55 6751 8192000000 10309238480 10722701312 1358160464 14.221 99 65 126 0 1405011104 31711104 15139536
23:57:10 9064 8192000000 10294886656 10708516864 1347478464 14.125 99 55 107 1 1392334432 31979840 12876128
23:58:25 11873 8192000000 10299177760 10722185216 1365856704 14.290 100 36 71 0 1407422896 32682416 8883776
23:59:39 18924 8192000000 10316982416 10747899904 1376661472 14.387 100 36 72 0 1429080736 42984960 9434304
00:01:06 21594 8192000000 10344310112 10770608128 1396570560 14.564 100 34 68 1 1447092912 41245104 9277248
00:02:46 2568 8192000000 10352832496 10768842752 1393835120 14.540 100 60 120 1 1452259264 43318672 15105472
00:03:53 30371 8192000000 10305806832 10736103424 1349001440 14.138 98 21 42 0 1392254304 37365616 5887248
00:05:29 8928 8192000000 10306590352 10739249152 1359342576 14.231 99 25 50 0 1406016608 39594368 7079664
00:06:47 44084 8192000000 10276977504 10728251392 1336980848 14.030 98 17 34 0 1378396176 36439200 4976128
00:09:24 19923 8192000000 10268163264 10706870272 1362063680 14.256 100 11 21 0 1371259760 7292512 1903568
00:10:49 115678 8192000000 10283046528 10725539840 1346174464 14.113 98 21 40 0 1366959280 16377168 4407648
00:13:45 38939 8192000000 10274614368 10734333952 1375072720 14.372 100 11 22 0 1390397968 12973568 2351680
00:15:26 85254 8192000000 10273650096 10721378304 1366736192 14.298 99 37 76 1 1382447712 6835712 8875808
00:17:53 37115 8192000000 10300632112 10735091712 1378929360 14.407 100 34 68 0 1394416800 7674864 7812576
00:19:41 72728 8192000000 10288546208 10745139200 1391318544 14.518 100 18 36 1 1406421088 10722848 4379696
00:21:55 9293 8192000000 10318225136 10762014720 1400537472 14.600 100 49 95 1 1423059008 10793104 11728432
00:23:10 60457 8192000000 10318116816 10764480512 1376911104 14.389 98 42 85 0 1394357648 7401104 10045440
00:25:10 90353 8192000000 10304394688 10769997824 1376066544 14.381 98 12 24 0 1395705616 16888384 2750688
00:27:45 83190 8192000000 10325917488 10790838272 1417336752 14.749 100 24 48 0 1443663536 20661504 5665280
00:30:09 57902 8192000000 10326105632 10798731264 1415756736 14.735 100 18 36 0 1443083968 22469696 4857536
00:32:27 57555 8192000000 10311239520 10780844032 1381836848 14.433 98 6 11 0 1395924384 12795120 1292416
00:34:45 20197 8192000000 10318253872 10765656064 1392610944 14.529 99 40 80 0 1410886736 9840944 8434848
00:36:07 28047 8192000000 10314017568 10767962112 1404256480 14.633 100 37 74 0 1424097360 11726672 8114208
00:37:35 105390 8192000000 10298501152 10747822080 1304560912 13.737 93 16 32 0 1317324608 9416752 3346944
00:40:26 141738 8192000000 10268318016 10715631616 1257686384 13.309 90 13 26 0 1279892224 19387552 2818288
00:43:49 56486 8192000000 10279906416 10720190464 1345136944 14.104 98 41 82 1 1364784896 8994608 10653344
00:45:46 108887 8192000000 10264643584 10702684160 1326081696 13.932 97 15 30 0 1344168432 14668048 3418688
00:48:40 51490 8192000000 10277784480 10719260672 1359592240 14.234 99 31 60 0 1379958384 13882576 6483568
00:50:32 77766 8192000000 10264923424 10717499392 1349723136 14.145 99 14 27 0 1362942368 10124448 3094784
00:52:51 43422 8192000000 10261469984 10713255936 1350905280 14.156 99 7 14 0 1363766336 11240896 1620160
00:54:38 72131 8192000000 10242044656 10700001280 1320210960 13.879 97 7 14 0 1336250192 14630064 1409168
00:56:56 41818 8192000000 10245122304 10682380288 1299011216 13.686 96 28 57 2 1315409904 9615744 6782944
00:58:40 46410 8192000000 10255815056 10700509184 1337445488 14.034 99 14 28 0 1352676208 11929632 3301088
01:00:29 70607 8192000000 10261132320 10706608128 1329877712 13.966 98 17 35 1 1341625840 8274592 3473536
01:02:42 22464 8192000000 10272422848 10709819392 1348816672 14.137 99 32 64 0 1365770960 10078832 6875456
01:04:05 53868 8192000000 10278114288 10726367232 1342152256 14.077 98 34 68 0 1363805632 14230544 7422832
01:06:03 45719 8192000000 10287291760 10745987072 1343124928 14.086 98 20 40 1 1374361456 26649680 4586848
01:07:49 122365 8192000000 10303735568 10752049152 1379894176 14.416 99 32 64 0 1403343280 15968144 7480960
01:11:43 49874 8192000000 10296100704 10764201984 1394871232 14.549 100 10 20 0 1413894960 17204224 1819504
01:13:34 70391 8192000000 10300342704 10760978432 1393266208 14.535 100 13 26 0 1410879360 14421728 3191424
01:15:45 63508 8192000000 10325434416 10780053504 1395286464 14.553 99 37 74 0 1423321184 19689568 8345152
01:17:59 80065 8192000000 10297967936 10766536704 1355275120 14.195 97 7 15 0 1379288096 22082032 1930944
01:20:21 96782 8192000000 10276806000 10709250048 1223411760 12.993 88 28 54 0 1239702944 9815344 6475840
01:23:03 39706 8192000000 10277910992 10730737664 1365893648 14.290 100 24 44 0 1381114256 10509648 4710960
01:25:03 20548 8192000000 10272818432 10706952192 1329000928 13.958 97 36 72 1 1346211744 9089328 8121488
01:26:27 59881 8192000000 10280954928 10737909760 1320149648 13.878 96 19 38 0 1343844112 19033024 4661440
01:28:29 114019 8192000000 10264735296 10717696000 1348784720 14.137 99 15 30 0 1363383280 11624832 2973728
01:31:24 53312 8192000000 10250745328 10711969792 1345945360 14.111 98 6 12 0 1356075264 8863904 1266000
01:33:17 60527 8192000000 10244403232 10694094848 1306921824 13.758 96 10 20 0 1315860464 6610912 2327728
01:35:18 78091 8192000000 10248144064 10700886016 1340773840 14.064 98 10 20 0 1353053200 9949232 2330128
01:38:13 25934 8192000000 9077783104 9316618240 249277520 2.953 18 7 14 0 257420528 6414544 1728464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-10-2022 01:40:25 to 04-10-2022 01:40:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-10-2022 01:40:41 to 04-10-2022 01:40:43.

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          243615
MON$OLDEST_ACTIVE               243616
MON$OLDEST_SNAPSHOT             243616
MON$NEXT_TRANSACTION            243616
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                       2010560
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.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 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 ---
:177: 
:178: mscs-test-161.budzdorov.ru    Tue Oct  4 01:38:44 2022
:179:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:180: 
--- end of diff output ---

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

04.10.22 01:40:44. 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-10-04 01:40:47 No severe PSQL-related problems occured
04.10.22 01:40:48. End of report.